[00:01:08.473] New invocation is queued and will start shortly
[00:01:11.324] Starting the invocation (attempt 1)
[00:01:11.365] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:01:11.365] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:01:11.365] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9052596683419841792",
"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\":\"688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\",\"title\":\"688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9052596683419841792",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f",
"gitiles_ref:refs/heads/master"
]
}
[00:01:12.043] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1582934471526615",
"id": "8887166619543885712",
"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\": \"688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\", \"title\": \"688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582934471861029",
"tags": [
"builder:linux",
"buildset:commit/git/688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052596683419841792",
"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/8887166619543885712/+/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": "1582934471861144",
"url": "https://ci.chromium.org/b/8887166619543885712",
"utcnow_ts": "1582934472019408"
}
}
[00:01:12.043] Task URL: https://ci.chromium.org/b/8887166619543885712
[00:01:12.043] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:2:0) after 1m0s
[00:02:12.046] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:2:0)
[00:02:12.046] Timer tick, asking Buildbucket for the build status
[00:02:12.188] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:12.188] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:3:0) after 1m0s
[00:03:12.219] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:3:0)
[00:03:12.219] Timer tick, asking Buildbucket for the build status
[00:03:12.605] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:12.605] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:4:0) after 1m0s
[00:04:12.629] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:4:0)
[00:04:12.629] Timer tick, asking Buildbucket for the build status
[00:04:12.775] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:12.775] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:5:0) after 1m0s
[00:05:12.762] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:5:0)
[00:05:12.762] Timer tick, asking Buildbucket for the build status
[00:05:12.868] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:12.868] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:6:0) after 1m0s
[00:06:12.967] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:6:0)
[00:06:12.967] Timer tick, asking Buildbucket for the build status
[00:06:13.404] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:13.404] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:7:0) after 1m0s
[00:07:13.464] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:7:0)
[00:07:13.464] Timer tick, asking Buildbucket for the build status
[00:07:14.552] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:14.552] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:8:0) after 1m0s
[00:08:14.637] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:8:0)
[00:08:14.637] Timer tick, asking Buildbucket for the build status
[00:08:14.993] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:14.993] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:9:0) after 1m0s
[00:09:15.044] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:9:0)
[00:09:15.044] Timer tick, asking Buildbucket for the build status
[00:09:15.587] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:15.587] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:10:0) after 1m0s
[00:10:15.574] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:10:0)
[00:10:15.574] Timer tick, asking Buildbucket for the build status
[00:10:15.859] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:15.859] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:11:0) after 1m0s
[00:11:15.909] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:11:0)
[00:11:15.909] Timer tick, asking Buildbucket for the build status
[00:11:16.100] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:16.100] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:12:0) after 1m0s
[00:12:16.141] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:12:0)
[00:12:16.141] Timer tick, asking Buildbucket for the build status
[00:12:16.254] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:16.254] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:13:0) after 1m0s
[00:13:16.349] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:13:0)
[00:13:16.349] Timer tick, asking Buildbucket for the build status
[00:13:16.471] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:16.471] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:14:0) after 1m0s
[00:14:16.535] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:14:0)
[00:14:16.535] Timer tick, asking Buildbucket for the build status
[00:14:16.684] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:16.684] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:15:0) after 1m0s
[00:15:16.682] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:15:0)
[00:15:16.682] Timer tick, asking Buildbucket for the build status
[00:15:17.112] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:17.112] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:16:0) after 1m0s
[00:16:17.155] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:16:0)
[00:16:17.155] Timer tick, asking Buildbucket for the build status
[00:16:17.278] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:17.278] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:17:0) after 1m0s
[00:17:17.371] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:17:0)
[00:17:17.371] Timer tick, asking Buildbucket for the build status
[00:17:17.503] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:17.503] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:18:0) after 1m0s
[00:18:17.528] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:18:0)
[00:18:17.528] Timer tick, asking Buildbucket for the build status
[00:18:17.972] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:17.972] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:19:0) after 1m0s
[00:19:17.992] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:19:0)
[00:19:17.992] Timer tick, asking Buildbucket for the build status
[00:19:18.185] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:18.185] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:20:0) after 1m0s
[00:20:18.220] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:20:0)
[00:20:18.220] Timer tick, asking Buildbucket for the build status
[00:20:18.339] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:18.340] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:21:0) after 1m0s
[00:21:18.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:21:0)
[00:21:18.427] Timer tick, asking Buildbucket for the build status
[00:21:18.620] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:18.620] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:22:0) after 1m0s
[00:22:18.656] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:22:0)
[00:22:18.656] Timer tick, asking Buildbucket for the build status
[00:22:18.819] Build 8887166619543885712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:18.820] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:23:0) after 1m0s
[00:22:43.277] Received PubSub notification, asking Buildbucket for the build status
[00:22:43.542] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:18.839] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:23:0)
[00:23:18.839] Timer tick, asking Buildbucket for the build status
[00:23:19.752] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:19.752] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:25:0) after 1m0s
[00:24:19.782] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:25:0)
[00:24:19.782] Timer tick, asking Buildbucket for the build status
[00:24:19.905] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:19.905] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:26:0) after 1m0s
[00:25:19.924] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:26:0)
[00:25:19.925] Timer tick, asking Buildbucket for the build status
[00:25:20.033] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:20.033] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:27:0) after 1m0s
[00:26:20.178] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:27:0)
[00:26:20.179] Timer tick, asking Buildbucket for the build status
[00:26:20.589] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:20.589] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:28:0) after 1m0s
[00:27:20.632] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:28:0)
[00:27:20.632] Timer tick, asking Buildbucket for the build status
[00:27:20.853] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:20.853] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:29:0) after 1m0s
[00:28:20.979] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:29:0)
[00:28:20.979] Timer tick, asking Buildbucket for the build status
[00:28:21.267] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:21.267] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:30:0) after 1m0s
[00:29:21.381] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:30:0)
[00:29:21.381] Timer tick, asking Buildbucket for the build status
[00:29:21.754] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:21.754] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:31:0) after 1m0s
[00:30:21.774] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:31:0)
[00:30:21.774] Timer tick, asking Buildbucket for the build status
[00:30:21.924] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:21.924] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:32:0) after 1m0s
[00:31:21.935] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:32:0)
[00:31:21.935] Timer tick, asking Buildbucket for the build status
[00:31:22.126] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:22.126] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:33:0) after 1m0s
[00:32:22.155] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:33:0)
[00:32:22.155] Timer tick, asking Buildbucket for the build status
[00:32:22.399] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:22.399] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:34:0) after 1m0s
[00:33:22.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:34:0)
[00:33:22.427] Timer tick, asking Buildbucket for the build status
[00:33:22.753] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:22.753] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:35:0) after 1m0s
[00:34:22.779] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:35:0)
[00:34:22.779] Timer tick, asking Buildbucket for the build status
[00:34:22.957] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:22.957] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:36:0) after 1m0s
[00:35:22.986] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:36:0)
[00:35:22.986] Timer tick, asking Buildbucket for the build status
[00:35:23.341] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:23.341] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:37:0) after 1m0s
[00:36:23.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:37:0)
[00:36:23.393] Timer tick, asking Buildbucket for the build status
[00:36:23.597] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:23.597] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:38:0) after 1m0s
[00:37:23.691] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:38:0)
[00:37:23.691] Timer tick, asking Buildbucket for the build status
[00:37:23.787] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:23.787] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:39:0) after 1m0s
[00:38:23.851] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:39:0)
[00:38:23.851] Timer tick, asking Buildbucket for the build status
[00:38:24.348] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:24.349] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:40:0) after 1m0s
[00:39:24.488] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:40:0)
[00:39:24.488] Timer tick, asking Buildbucket for the build status
[00:39:24.629] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:24.629] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:41:0) after 1m0s
[00:40:24.679] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:41:0)
[00:40:24.679] Timer tick, asking Buildbucket for the build status
[00:40:24.826] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:24.826] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:42:0) after 1m0s
[00:41:24.860] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:42:0)
[00:41:24.860] Timer tick, asking Buildbucket for the build status
[00:41:25.353] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:25.353] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:43:0) after 1m0s
[00:42:25.394] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:43:0)
[00:42:25.394] Timer tick, asking Buildbucket for the build status
[00:42:25.589] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:25.589] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:44:0) after 1m0s
[00:43:25.601] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:44:0)
[00:43:25.601] Timer tick, asking Buildbucket for the build status
[00:43:26.473] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:26.473] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:45:0) after 1m0s
[00:44:26.592] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:45:0)
[00:44:26.592] Timer tick, asking Buildbucket for the build status
[00:44:27.737] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:27.737] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:46:0) after 1m0s
[00:45:27.777] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:46:0)
[00:45:27.777] Timer tick, asking Buildbucket for the build status
[00:45:27.937] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:27.937] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:47:0) after 1m0s
[00:46:28.066] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:47:0)
[00:46:28.066] Timer tick, asking Buildbucket for the build status
[00:46:28.170] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:28.170] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:48:0) after 1m0s
[00:47:28.276] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:48:0)
[00:47:28.276] Timer tick, asking Buildbucket for the build status
[00:47:28.433] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:28.433] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:49:0) after 1m0s
[00:48:28.501] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:49:0)
[00:48:28.501] Timer tick, asking Buildbucket for the build status
[00:48:28.674] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:28.674] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:50:0) after 1m0s
[00:49:28.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:50:0)
[00:49:28.781] Timer tick, asking Buildbucket for the build status
[00:49:28.966] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:28.966] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:51:0) after 1m0s
[00:50:29.028] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:51:0)
[00:50:29.028] Timer tick, asking Buildbucket for the build status
[00:50:29.135] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:29.135] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:52:0) after 1m0s
[00:51:29.230] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:52:0)
[00:51:29.230] Timer tick, asking Buildbucket for the build status
[00:51:29.361] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:29.361] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:53:0) after 1m0s
[00:52:29.394] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:53:0)
[00:52:29.394] Timer tick, asking Buildbucket for the build status
[00:52:29.638] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:29.638] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:54:0) after 1m0s
[00:53:29.731] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:54:0)
[00:53:29.731] Timer tick, asking Buildbucket for the build status
[00:53:30.320] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:30.320] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:55:0) after 1m0s
[00:54:30.357] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:55:0)
[00:54:30.357] Timer tick, asking Buildbucket for the build status
[00:54:30.462] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:30.462] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:56:0) after 1m0s
[00:55:30.535] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:56:0)
[00:55:30.535] Timer tick, asking Buildbucket for the build status
[00:55:30.641] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:30.641] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:57:0) after 1m0s
[00:56:30.730] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:57:0)
[00:56:30.730] Timer tick, asking Buildbucket for the build status
[00:56:30.828] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:30.828] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:58:0) after 1m0s
[00:57:30.965] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:58:0)
[00:57:30.965] Timer tick, asking Buildbucket for the build status
[00:57:31.541] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:31.541] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:59:0) after 1m0s
[00:58:31.538] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:59:0)
[00:58:31.538] Timer tick, asking Buildbucket for the build status
[00:58:32.391] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:32.391] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:60:0) after 1m0s
[00:59:32.475] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:60:0)
[00:59:32.475] Timer tick, asking Buildbucket for the build status
[00:59:32.653] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:32.653] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:61:0) after 1m0s
[01:00:32.742] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:61:0)
[01:00:32.742] Timer tick, asking Buildbucket for the build status
[01:00:32.992] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:32.992] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:62:0) after 1m0s
[01:01:33.053] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:62:0)
[01:01:33.053] Timer tick, asking Buildbucket for the build status
[01:01:33.185] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:33.185] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:63:0) after 1m0s
[01:02:33.363] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:63:0)
[01:02:33.363] Timer tick, asking Buildbucket for the build status
[01:02:33.529] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:33.529] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:64:0) after 1m0s
[01:03:33.611] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:64:0)
[01:03:33.611] Timer tick, asking Buildbucket for the build status
[01:03:34.575] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:34.575] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:65:0) after 1m0s
[01:04:34.634] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:65:0)
[01:04:34.634] Timer tick, asking Buildbucket for the build status
[01:04:34.763] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:34.763] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:66:0) after 1m0s
[01:05:34.775] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:66:0)
[01:05:34.775] Timer tick, asking Buildbucket for the build status
[01:05:34.979] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:34.979] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:67:0) after 1m0s
[01:06:35.030] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:67:0)
[01:06:35.030] Timer tick, asking Buildbucket for the build status
[01:06:35.205] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:35.205] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:68:0) after 1m0s
[01:07:35.260] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:68:0)
[01:07:35.260] Timer tick, asking Buildbucket for the build status
[01:07:35.790] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:35.790] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:69:0) after 1m0s
[01:08:35.844] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:69:0)
[01:08:35.844] Timer tick, asking Buildbucket for the build status
[01:08:36.508] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:36.508] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:70:0) after 1m0s
[01:09:36.625] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:70:0)
[01:09:36.625] Timer tick, asking Buildbucket for the build status
[01:09:36.969] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:36.969] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:71:0) after 1m0s
[01:10:36.998] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:71:0)
[01:10:36.998] Timer tick, asking Buildbucket for the build status
[01:10:38.345] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:38.345] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:72:0) after 1m0s
[01:11:38.432] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:72:0)
[01:11:38.432] Timer tick, asking Buildbucket for the build status
[01:11:38.615] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:38.615] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:73:0) after 1m0s
[01:12:38.769] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:73:0)
[01:12:38.769] Timer tick, asking Buildbucket for the build status
[01:12:38.928] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:38.928] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:74:0) after 1m0s
[01:13:38.978] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:74:0)
[01:13:38.978] Timer tick, asking Buildbucket for the build status
[01:13:43.099] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:43.099] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:75:0) after 1m0s
[01:14:43.804] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:75:0)
[01:14:43.804] Timer tick, asking Buildbucket for the build status
[01:14:44.006] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:44.006] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:76:0) after 1m0s
[01:15:44.134] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:76:0)
[01:15:44.134] Timer tick, asking Buildbucket for the build status
[01:15:44.301] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:44.301] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:77:0) after 1m0s
[01:16:44.526] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:77:0)
[01:16:44.526] Timer tick, asking Buildbucket for the build status
[01:16:44.630] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:44.630] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:78:0) after 1m0s
[01:17:44.755] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:78:0)
[01:17:44.755] Timer tick, asking Buildbucket for the build status
[01:17:44.946] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:44.946] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:79:0) after 1m0s
[01:18:44.982] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:79:0)
[01:18:44.982] Timer tick, asking Buildbucket for the build status
[01:18:45.365] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:45.365] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:80:0) after 1m0s
[01:19:45.819] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:80:0)
[01:19:45.819] Timer tick, asking Buildbucket for the build status
[01:19:46.123] Build 8887166619543885712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:46.123] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9052596683419841792:81:0) after 1m0s
[01:19:52.469] Received PubSub notification, asking Buildbucket for the build status
[01:19:52.539] Build 8887166619543885712: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:19:52.539] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1582939191446123",
"created_by": "project:emscripten-releases",
"created_ts": "1582934471526615",
"id": "8887166619543885712",
"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\": \"688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\", \"title\": \"688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\"}}",
"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\": \"688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\", \"title\": \"688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-1-ru3b\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_e437bc7da551d62da6a64b09ca05a66344db3b0846e125cb83052108c473fa36_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-xenial-1-ru3b\"], \"image\": [\"chrome-xenial-20022300-4c0e34b5b5e\"], \"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\": [\"4840-d3c86f9\"], \"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": "1582935762282359",
"status": "COMPLETED",
"status_changed_ts": "1582939191938817",
"tags": [
"builder:linux",
"buildset:commit/git/688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/688cdf30ae23d8cdd66cb6f1a920a9f3ef46570f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052596683419841792",
"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/8887166619543885712/+/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:4aa710bfafe00010",
"user_agent:luci-scheduler"
],
"updated_ts": "1582939191949095",
"url": "https://ci.chromium.org/b/8887166619543885712",
"utcnow_ts": "1582939192529251"
}
}
[01:19:52.539] Invocation finished in 1h18m44.092902904s with status SUCCEEDED