[06:13:08.401] New invocation is queued and will start shortly
[06:13:09.478] Starting the invocation (attempt 1)
[06:13:09.517] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:13:09.517] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:13:09.517] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9061179991079875168",
"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\":\"ec3fab585da10436cafc66e9e0a28783872c7e48\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@ec3fab585da10436cafc66e9e0a28783872c7e48\",\"title\":\"ec3fab585da10436cafc66e9e0a28783872c7e48\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/ec3fab585da10436cafc66e9e0a28783872c7e48\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"ec3fab585da10436cafc66e9e0a28783872c7e48\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9061179991079875168",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/ec3fab585da10436cafc66e9e0a28783872c7e48",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/ec3fab585da10436cafc66e9e0a28783872c7e48",
"gitiles_ref:refs/heads/master"
]
}
[06:13:11.034] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1574748789672208",
"id": "8895749929078928960",
"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\": \"ec3fab585da10436cafc66e9e0a28783872c7e48\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@ec3fab585da10436cafc66e9e0a28783872c7e48\", \"title\": \"ec3fab585da10436cafc66e9e0a28783872c7e48\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/ec3fab585da10436cafc66e9e0a28783872c7e48\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"ec3fab585da10436cafc66e9e0a28783872c7e48\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1574748790490504",
"tags": [
"builder:linux",
"buildset:commit/git/ec3fab585da10436cafc66e9e0a28783872c7e48",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/ec3fab585da10436cafc66e9e0a28783872c7e48",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061179991079875168",
"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/8895749929078928960/+/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": "1574748790490868",
"url": "https://ci.chromium.org/b/8895749929078928960",
"utcnow_ts": "1574748791005052"
}
}
[06:13:11.034] Task URL: https://ci.chromium.org/b/8895749929078928960
[06:13:11.034] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:2:0) after 1m0s
[06:14:02.708] Received PubSub notification, asking Buildbucket for the build status
[06:14:02.874] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:11.009] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:2:0)
[06:14:11.009] Timer tick, asking Buildbucket for the build status
[06:14:11.185] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:11.185] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:4:0) after 1m0s
[06:15:11.288] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:4:0)
[06:15:11.288] Timer tick, asking Buildbucket for the build status
[06:15:11.482] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:11.482] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:5:0) after 1m0s
[06:16:11.572] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:5:0)
[06:16:11.572] Timer tick, asking Buildbucket for the build status
[06:16:11.728] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:11.728] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:6:0) after 1m0s
[06:17:11.835] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:6:0)
[06:17:11.835] Timer tick, asking Buildbucket for the build status
[06:17:11.965] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:11.965] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:7:0) after 1m0s
[06:18:12.079] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:7:0)
[06:18:12.079] Timer tick, asking Buildbucket for the build status
[06:18:12.533] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:12.533] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:8:0) after 1m0s
[06:19:12.668] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:8:0)
[06:19:12.668] Timer tick, asking Buildbucket for the build status
[06:19:12.778] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:12.778] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:9:0) after 1m0s
[06:20:12.974] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:9:0)
[06:20:12.974] Timer tick, asking Buildbucket for the build status
[06:20:13.087] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:13.087] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:10:0) after 1m0s
[06:21:13.172] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:10:0)
[06:21:13.172] Timer tick, asking Buildbucket for the build status
[06:21:13.397] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:13.397] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:11:0) after 1m0s
[06:22:13.433] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:11:0)
[06:22:13.433] Timer tick, asking Buildbucket for the build status
[06:22:13.986] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:13.986] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:12:0) after 1m0s
[06:23:14.152] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:12:0)
[06:23:14.152] Timer tick, asking Buildbucket for the build status
[06:23:14.471] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:14.472] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:13:0) after 1m0s
[06:24:14.520] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:13:0)
[06:24:14.520] Timer tick, asking Buildbucket for the build status
[06:24:14.770] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:14.770] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:14:0) after 1m0s
[06:25:14.797] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:14:0)
[06:25:14.797] Timer tick, asking Buildbucket for the build status
[06:25:14.986] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:14.986] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:15:0) after 1m0s
[06:26:15.191] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:15:0)
[06:26:15.191] Timer tick, asking Buildbucket for the build status
[06:26:15.318] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:15.318] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:16:0) after 1m0s
[06:27:15.470] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:16:0)
[06:27:15.470] Timer tick, asking Buildbucket for the build status
[06:27:15.610] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:15.610] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:17:0) after 1m0s
[06:28:15.701] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:17:0)
[06:28:15.701] Timer tick, asking Buildbucket for the build status
[06:28:15.797] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:15.797] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:18:0) after 1m0s
[06:29:15.945] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:18:0)
[06:29:15.945] Timer tick, asking Buildbucket for the build status
[06:29:16.168] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:16.168] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:19:0) after 1m0s
[06:30:16.487] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:19:0)
[06:30:16.487] Timer tick, asking Buildbucket for the build status
[06:30:17.103] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:17.103] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:20:0) after 1m0s
[06:31:17.153] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:20:0)
[06:31:17.153] Timer tick, asking Buildbucket for the build status
[06:31:17.272] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:17.272] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:21:0) after 1m0s
[06:32:17.325] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:21:0)
[06:32:17.325] Timer tick, asking Buildbucket for the build status
[06:32:17.488] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:17.488] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:22:0) after 1m0s
[06:33:17.567] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:22:0)
[06:33:17.567] Timer tick, asking Buildbucket for the build status
[06:33:17.687] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:17.687] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:23:0) after 1m0s
[06:34:17.719] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:23:0)
[06:34:17.719] Timer tick, asking Buildbucket for the build status
[06:34:17.886] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:17.886] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:24:0) after 1m0s
[06:35:17.964] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:24:0)
[06:35:17.964] Timer tick, asking Buildbucket for the build status
[06:35:18.440] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:18.440] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:25:0) after 1m0s
[06:36:18.495] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:25:0)
[06:36:18.495] Timer tick, asking Buildbucket for the build status
[06:36:18.619] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:18.619] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:26:0) after 1m0s
[06:37:18.774] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:26:0)
[06:37:18.774] Timer tick, asking Buildbucket for the build status
[06:37:19.071] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:19.071] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:27:0) after 1m0s
[06:38:19.236] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:27:0)
[06:38:19.236] Timer tick, asking Buildbucket for the build status
[06:38:19.518] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:19.518] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:28:0) after 1m0s
[06:39:19.597] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:28:0)
[06:39:19.597] Timer tick, asking Buildbucket for the build status
[06:39:19.945] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:19.945] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:29:0) after 1m0s
[06:40:20.027] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:29:0)
[06:40:20.027] Timer tick, asking Buildbucket for the build status
[06:40:20.210] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:20.210] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:30:0) after 1m0s
[06:41:20.361] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:30:0)
[06:41:20.361] Timer tick, asking Buildbucket for the build status
[06:41:20.473] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:20.473] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:31:0) after 1m0s
[06:42:20.613] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:31:0)
[06:42:20.613] Timer tick, asking Buildbucket for the build status
[06:42:20.782] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:20.782] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:32:0) after 1m0s
[06:43:20.836] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:32:0)
[06:43:20.836] Timer tick, asking Buildbucket for the build status
[06:43:21.130] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:21.130] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:33:0) after 1m0s
[06:44:21.178] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:33:0)
[06:44:21.178] Timer tick, asking Buildbucket for the build status
[06:44:21.329] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:21.329] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:34:0) after 1m0s
[06:45:21.354] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:34:0)
[06:45:21.354] Timer tick, asking Buildbucket for the build status
[06:45:21.472] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:21.472] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:35:0) after 1m0s
[06:46:21.642] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:35:0)
[06:46:21.642] Timer tick, asking Buildbucket for the build status
[06:46:22.977] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:22.977] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:36:0) after 1m0s
[06:47:23.082] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:36:0)
[06:47:23.082] Timer tick, asking Buildbucket for the build status
[06:47:23.211] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:23.211] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:37:0) after 1m0s
[06:48:23.242] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:37:0)
[06:48:23.242] Timer tick, asking Buildbucket for the build status
[06:48:23.633] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:23.633] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:38:0) after 1m0s
[06:49:23.697] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:38:0)
[06:49:23.697] Timer tick, asking Buildbucket for the build status
[06:49:23.821] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:23.821] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:39:0) after 1m0s
[06:50:23.863] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:39:0)
[06:50:23.863] Timer tick, asking Buildbucket for the build status
[06:50:23.960] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:23.960] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:40:0) after 1m0s
[06:51:24.076] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:40:0)
[06:51:24.076] Timer tick, asking Buildbucket for the build status
[06:51:25.314] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:25.314] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:41:0) after 1m0s
[06:52:25.425] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:41:0)
[06:52:25.425] Timer tick, asking Buildbucket for the build status
[06:52:25.600] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:25.600] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:42:0) after 1m0s
[06:53:25.638] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:42:0)
[06:53:25.638] Timer tick, asking Buildbucket for the build status
[06:53:25.737] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:25.737] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:43:0) after 1m0s
[06:54:25.764] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:43:0)
[06:54:25.764] Timer tick, asking Buildbucket for the build status
[06:54:25.926] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:25.926] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:44:0) after 1m0s
[06:55:25.954] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:44:0)
[06:55:25.954] Timer tick, asking Buildbucket for the build status
[06:55:26.162] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:26.162] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:45:0) after 1m0s
[06:56:26.189] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:45:0)
[06:56:26.189] Timer tick, asking Buildbucket for the build status
[06:56:26.305] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:26.305] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:46:0) after 1m0s
[06:57:26.377] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:46:0)
[06:57:26.377] Timer tick, asking Buildbucket for the build status
[06:57:26.603] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:26.603] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:47:0) after 1m0s
[06:58:26.946] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:47:0)
[06:58:26.946] Timer tick, asking Buildbucket for the build status
[06:58:27.323] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:27.323] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:48:0) after 1m0s
[06:59:27.482] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:48:0)
[06:59:27.482] Timer tick, asking Buildbucket for the build status
[06:59:27.602] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:27.602] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:49:0) after 1m0s
[07:00:27.720] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:49:0)
[07:00:27.720] Timer tick, asking Buildbucket for the build status
[07:00:27.872] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:27.872] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:50:0) after 1m0s
[07:01:27.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:50:0)
[07:01:27.969] Timer tick, asking Buildbucket for the build status
[07:01:28.092] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:28.092] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:51:0) after 1m0s
[07:02:28.132] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:51:0)
[07:02:28.132] Timer tick, asking Buildbucket for the build status
[07:02:28.285] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:28.285] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:52:0) after 1m0s
[07:03:28.315] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:52:0)
[07:03:28.315] Timer tick, asking Buildbucket for the build status
[07:03:28.582] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:28.582] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:53:0) after 1m0s
[07:04:28.674] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:53:0)
[07:04:28.674] Timer tick, asking Buildbucket for the build status
[07:04:28.901] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:28.901] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:54:0) after 1m0s
[07:05:28.955] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:54:0)
[07:05:28.955] Timer tick, asking Buildbucket for the build status
[07:05:29.219] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:29.219] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:55:0) after 1m0s
[07:06:29.288] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:55:0)
[07:06:29.288] Timer tick, asking Buildbucket for the build status
[07:06:29.629] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:29.629] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:56:0) after 1m0s
[07:07:29.754] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:56:0)
[07:07:29.754] Timer tick, asking Buildbucket for the build status
[07:07:30.235] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:30.235] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:57:0) after 1m0s
[07:08:30.245] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:57:0)
[07:08:30.245] Timer tick, asking Buildbucket for the build status
[07:08:30.767] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:30.767] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:58:0) after 1m0s
[07:09:30.874] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:58:0)
[07:09:30.874] Timer tick, asking Buildbucket for the build status
[07:09:31.065] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:31.065] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:59:0) after 1m0s
[07:10:31.086] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:59:0)
[07:10:31.086] Timer tick, asking Buildbucket for the build status
[07:10:31.187] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:31.187] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:60:0) after 1m0s
[07:11:31.238] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:60:0)
[07:11:31.238] Timer tick, asking Buildbucket for the build status
[07:11:31.487] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:31.487] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:61:0) after 1m0s
[07:12:31.517] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:61:0)
[07:12:31.517] Timer tick, asking Buildbucket for the build status
[07:12:31.647] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:31.647] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:62:0) after 1m0s
[07:13:31.695] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:62:0)
[07:13:31.695] Timer tick, asking Buildbucket for the build status
[07:13:31.852] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:31.852] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:63:0) after 1m0s
[07:14:31.880] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:63:0)
[07:14:31.880] Timer tick, asking Buildbucket for the build status
[07:14:33.290] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:33.290] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:64:0) after 1m0s
[07:15:33.345] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:64:0)
[07:15:33.345] Timer tick, asking Buildbucket for the build status
[07:15:33.526] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:33.526] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:65:0) after 1m0s
[07:16:33.564] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:65:0)
[07:16:33.564] Timer tick, asking Buildbucket for the build status
[07:16:33.847] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:33.847] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:66:0) after 1m0s
[07:17:33.959] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:66:0)
[07:17:33.959] Timer tick, asking Buildbucket for the build status
[07:17:34.088] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:34.088] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:67:0) after 1m0s
[07:18:34.198] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:67:0)
[07:18:34.198] Timer tick, asking Buildbucket for the build status
[07:18:34.286] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:34.286] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:68:0) after 1m0s
[07:19:34.308] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:68:0)
[07:19:34.308] Timer tick, asking Buildbucket for the build status
[07:19:34.958] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:34.958] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:69:0) after 1m0s
[07:20:35.131] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:69:0)
[07:20:35.131] Timer tick, asking Buildbucket for the build status
[07:20:35.465] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:35.466] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:70:0) after 1m0s
[07:21:35.468] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:70:0)
[07:21:35.468] Timer tick, asking Buildbucket for the build status
[07:21:35.608] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:35.608] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:71:0) after 1m0s
[07:22:35.637] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:71:0)
[07:22:35.637] Timer tick, asking Buildbucket for the build status
[07:22:35.865] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:35.865] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:72:0) after 1m0s
[07:23:35.904] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:72:0)
[07:23:35.904] Timer tick, asking Buildbucket for the build status
[07:23:36.067] Build 8895749929078928960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:36.068] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061179991079875168:73:0) after 1m0s
[07:24:33.263] Received PubSub notification, asking Buildbucket for the build status
[07:24:33.333] Build 8895749929078928960: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:24:33.333] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1574753072411604",
"created_by": "project:emscripten-releases",
"created_ts": "1574748789672208",
"id": "8895749929078928960",
"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\": \"ec3fab585da10436cafc66e9e0a28783872c7e48\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@ec3fab585da10436cafc66e9e0a28783872c7e48\", \"title\": \"ec3fab585da10436cafc66e9e0a28783872c7e48\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/ec3fab585da10436cafc66e9e0a28783872c7e48\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"ec3fab585da10436cafc66e9e0a28783872c7e48\"}}",
"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\": \"ec3fab585da10436cafc66e9e0a28783872c7e48\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@ec3fab585da10436cafc66e9e0a28783872c7e48\", \"title\": \"ec3fab585da10436cafc66e9e0a28783872c7e48\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/ec3fab585da10436cafc66e9e0a28783872c7e48\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-1-5fwu\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"ec3fab585da10436cafc66e9e0a28783872c7e48\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"ec3fab585da10436cafc66e9e0a28783872c7e48\"}, \"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-1-5fwu\"], \"image\": [\"chrome-xenial-19112404-1fe53dcaaf2\"], \"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\": [\"4656-a09e2f8\"], \"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": "1574748841423247",
"status": "COMPLETED",
"status_changed_ts": "1574753072803190",
"tags": [
"builder:linux",
"buildset:commit/git/ec3fab585da10436cafc66e9e0a28783872c7e48",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/ec3fab585da10436cafc66e9e0a28783872c7e48",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061179991079875168",
"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/8895749929078928960/+/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:48bf292ac3076310",
"user_agent:luci-scheduler"
],
"updated_ts": "1574753072819963",
"url": "https://ci.chromium.org/b/8895749929078928960",
"utcnow_ts": "1574753073323337"
}
}
[07:24:33.333] Invocation finished in 1h11m24.953366546s with status SUCCEEDED