[20:42:12.161] New invocation is queued and will start shortly
[20:42:12.161] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[20:42:13.356] Starting the invocation (attempt 1)
[20:42:13.408] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:42:13.408] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:42:13.408] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9094012013191398896",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"71.0.3578.75\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9094012013191398896",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[20:42:15.414] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1543437733529747",
"id": "8928581951085433760",
"lease_expiration_ts": "1543556535138817",
"lease_key": "15110459",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"71.0.3578.75\"}}",
"project": "infra",
"result_details_json": "null",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1543437735153152",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9094012013191398896",
"scheduler_job_id:infra/Build From Tarball",
"swarming_dimension:builder:Build From Tarball",
"swarming_dimension:caches:builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.infra.cron",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:buildbucket_bucket:infra/cron",
"swarming_tag:buildbucket_build_id:8928581951085433760",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
"swarming_tag:builder:Build From Tarball",
"swarming_tag:caches:builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2",
"swarming_tag:cpu:x86-64",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8928581951085433760/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:parent_buildername:publish_tarball",
"swarming_tag:pool:luci.infra.cron",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_tag:scheduler_invocation_id:9094012013191398896",
"swarming_tag:scheduler_job_id:infra/Build From Tarball",
"swarming_tag:service_account:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:45412c4de3871bddd781c01a3b94745418493754",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:user_agent:recipe",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4174e05a4936c710",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1543437735153280",
"url": "https://ci.chromium.org/b/8928581951085433760",
"utcnow_ts": "1543437735399080"
}
}
[20:42:15.414] Task URL: https://ci.chromium.org/b/8928581951085433760
[20:42:15.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:2:0) after 1m0s
[20:42:46.677] Received PubSub notification, asking Buildbucket for the build status
[20:42:46.718] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:15.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:2:0)
[20:43:15.430] Timer tick, asking Buildbucket for the build status
[20:43:15.517] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:15.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:4:0) after 1m0s
[20:44:15.547] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:4:0)
[20:44:15.547] Timer tick, asking Buildbucket for the build status
[20:44:15.613] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:15.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:5:0) after 1m0s
[20:45:15.630] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:5:0)
[20:45:15.630] Timer tick, asking Buildbucket for the build status
[20:45:15.751] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:15.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:6:0) after 1m0s
[20:46:15.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:6:0)
[20:46:15.772] Timer tick, asking Buildbucket for the build status
[20:46:15.920] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:15.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:7:0) after 1m0s
[20:47:15.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:7:0)
[20:47:15.937] Timer tick, asking Buildbucket for the build status
[20:47:16.023] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:16.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:8:0) after 1m0s
[20:48:16.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:8:0)
[20:48:16.233] Timer tick, asking Buildbucket for the build status
[20:48:16.438] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:16.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:9:0) after 1m0s
[20:49:16.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:9:0)
[20:49:16.598] Timer tick, asking Buildbucket for the build status
[20:49:16.672] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:16.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:10:0) after 1m0s
[20:50:16.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:10:0)
[20:50:16.932] Timer tick, asking Buildbucket for the build status
[20:50:16.987] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:16.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:11:0) after 1m0s
[20:51:17.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:11:0)
[20:51:17.215] Timer tick, asking Buildbucket for the build status
[20:51:17.262] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:17.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:12:0) after 1m0s
[20:52:17.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:12:0)
[20:52:17.519] Timer tick, asking Buildbucket for the build status
[20:52:17.683] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:17.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:13:0) after 1m0s
[20:53:17.904] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:13:0)
[20:53:17.904] Timer tick, asking Buildbucket for the build status
[20:53:17.947] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:17.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:14:0) after 1m0s
[20:54:17.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:14:0)
[20:54:17.972] Timer tick, asking Buildbucket for the build status
[20:54:18.014] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:18.014] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:15:0) after 1m0s
[20:55:18.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:15:0)
[20:55:18.040] Timer tick, asking Buildbucket for the build status
[20:55:18.078] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:18.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:16:0) after 1m0s
[20:56:18.099] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:16:0)
[20:56:18.099] Timer tick, asking Buildbucket for the build status
[20:56:18.158] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:18.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:17:0) after 1m0s
[20:57:18.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:17:0)
[20:57:18.215] Timer tick, asking Buildbucket for the build status
[20:57:18.281] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:18.281] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:18:0) after 1m0s
[20:58:18.298] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:18:0)
[20:58:18.298] Timer tick, asking Buildbucket for the build status
[20:58:18.469] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:18.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:19:0) after 1m0s
[20:59:18.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:19:0)
[20:59:18.493] Timer tick, asking Buildbucket for the build status
[20:59:18.536] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:18.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:20:0) after 1m0s
[21:00:18.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:20:0)
[21:00:18.565] Timer tick, asking Buildbucket for the build status
[21:00:18.664] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:18.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:21:0) after 1m0s
[21:01:18.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:21:0)
[21:01:18.687] Timer tick, asking Buildbucket for the build status
[21:01:18.725] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:18.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:22:0) after 1m0s
[21:02:18.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:22:0)
[21:02:18.756] Timer tick, asking Buildbucket for the build status
[21:02:18.845] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:18.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:23:0) after 1m0s
[21:03:19.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:23:0)
[21:03:19.046] Timer tick, asking Buildbucket for the build status
[21:03:19.090] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:19.090] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:24:0) after 1m0s
[21:04:19.121] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:24:0)
[21:04:19.121] Timer tick, asking Buildbucket for the build status
[21:04:19.169] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:19.170] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:25:0) after 1m0s
[21:05:19.190] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:25:0)
[21:05:19.190] Timer tick, asking Buildbucket for the build status
[21:05:19.228] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:19.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:26:0) after 1m0s
[21:06:19.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:26:0)
[21:06:19.280] Timer tick, asking Buildbucket for the build status
[21:06:19.424] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:19.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:27:0) after 1m0s
[21:07:19.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:27:0)
[21:07:19.499] Timer tick, asking Buildbucket for the build status
[21:07:19.553] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:19.553] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:28:0) after 1m0s
[21:08:19.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:28:0)
[21:08:19.573] Timer tick, asking Buildbucket for the build status
[21:08:19.641] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:19.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:29:0) after 1m0s
[21:09:19.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:29:0)
[21:09:19.676] Timer tick, asking Buildbucket for the build status
[21:09:19.726] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:19.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:30:0) after 1m0s
[21:10:19.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:30:0)
[21:10:19.756] Timer tick, asking Buildbucket for the build status
[21:10:19.815] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:19.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:31:0) after 1m0s
[21:11:19.839] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:31:0)
[21:11:19.839] Timer tick, asking Buildbucket for the build status
[21:11:19.873] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:19.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:32:0) after 1m0s
[21:12:19.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:32:0)
[21:12:19.892] Timer tick, asking Buildbucket for the build status
[21:12:19.957] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:19.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:33:0) after 1m0s
[21:13:20.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:33:0)
[21:13:20.081] Timer tick, asking Buildbucket for the build status
[21:13:20.165] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:20.165] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:34:0) after 1m0s
[21:14:20.206] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:34:0)
[21:14:20.206] Timer tick, asking Buildbucket for the build status
[21:14:20.275] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:20.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:35:0) after 1m0s
[21:15:20.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:35:0)
[21:15:20.294] Timer tick, asking Buildbucket for the build status
[21:15:20.421] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:20.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:36:0) after 1m0s
[21:16:20.490] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:36:0)
[21:16:20.490] Timer tick, asking Buildbucket for the build status
[21:16:20.637] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:20.637] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:37:0) after 1m0s
[21:17:20.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:37:0)
[21:17:20.659] Timer tick, asking Buildbucket for the build status
[21:17:20.738] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:20.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:38:0) after 1m0s
[21:18:20.759] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:38:0)
[21:18:20.759] Timer tick, asking Buildbucket for the build status
[21:18:20.850] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:20.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:39:0) after 1m0s
[21:19:20.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:39:0)
[21:19:20.868] Timer tick, asking Buildbucket for the build status
[21:19:20.911] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:20.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:40:0) after 1m0s
[21:20:20.977] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:40:0)
[21:20:20.977] Timer tick, asking Buildbucket for the build status
[21:20:21.039] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:21.039] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:41:0) after 1m0s
[21:21:21.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:41:0)
[21:21:21.305] Timer tick, asking Buildbucket for the build status
[21:21:21.605] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:21.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:42:0) after 1m0s
[21:22:21.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:42:0)
[21:22:21.627] Timer tick, asking Buildbucket for the build status
[21:22:21.822] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:21.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:43:0) after 1m0s
[21:23:21.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:43:0)
[21:23:21.845] Timer tick, asking Buildbucket for the build status
[21:23:21.896] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:21.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:44:0) after 1m0s
[21:24:21.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:44:0)
[21:24:21.914] Timer tick, asking Buildbucket for the build status
[21:24:22.024] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:22.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:45:0) after 1m0s
[21:25:22.090] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:45:0)
[21:25:22.090] Timer tick, asking Buildbucket for the build status
[21:25:22.389] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:22.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:46:0) after 1m0s
[21:26:22.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:46:0)
[21:26:22.408] Timer tick, asking Buildbucket for the build status
[21:26:22.467] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:22.467] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:47:0) after 1m0s
[21:27:22.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:47:0)
[21:27:22.546] Timer tick, asking Buildbucket for the build status
[21:27:22.615] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:22.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:48:0) after 1m0s
[21:28:22.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:48:0)
[21:28:22.683] Timer tick, asking Buildbucket for the build status
[21:28:22.742] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:22.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:49:0) after 1m0s
[21:29:22.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:49:0)
[21:29:22.763] Timer tick, asking Buildbucket for the build status
[21:29:22.807] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:22.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:50:0) after 1m0s
[21:30:22.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:50:0)
[21:30:22.881] Timer tick, asking Buildbucket for the build status
[21:30:22.939] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:22.939] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:51:0) after 1m0s
[21:31:23.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:51:0)
[21:31:23.015] Timer tick, asking Buildbucket for the build status
[21:31:23.077] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:23.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:52:0) after 1m0s
[21:32:23.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:52:0)
[21:32:23.097] Timer tick, asking Buildbucket for the build status
[21:32:23.256] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:23.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:53:0) after 1m0s
[21:33:23.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:53:0)
[21:33:23.281] Timer tick, asking Buildbucket for the build status
[21:33:23.316] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:23.316] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:54:0) after 1m0s
[21:34:23.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:54:0)
[21:34:23.336] Timer tick, asking Buildbucket for the build status
[21:34:23.440] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:23.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:55:0) after 1m0s
[21:35:23.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:55:0)
[21:35:23.458] Timer tick, asking Buildbucket for the build status
[21:35:23.492] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:23.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:56:0) after 1m0s
[21:36:23.518] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:56:0)
[21:36:23.519] Timer tick, asking Buildbucket for the build status
[21:36:23.573] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:23.573] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:57:0) after 1m0s
[21:37:23.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:57:0)
[21:37:23.592] Timer tick, asking Buildbucket for the build status
[21:37:23.643] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:23.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:58:0) after 1m0s
[21:38:23.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:58:0)
[21:38:23.663] Timer tick, asking Buildbucket for the build status
[21:38:23.724] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:23.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:59:0) after 1m0s
[21:39:23.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:59:0)
[21:39:23.745] Timer tick, asking Buildbucket for the build status
[21:39:23.795] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:23.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:60:0) after 1m0s
[21:40:23.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:60:0)
[21:40:23.815] Timer tick, asking Buildbucket for the build status
[21:40:23.854] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:23.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:61:0) after 1m0s
[21:41:23.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:61:0)
[21:41:23.893] Timer tick, asking Buildbucket for the build status
[21:41:23.992] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:23.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:62:0) after 1m0s
[21:42:24.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:62:0)
[21:42:24.074] Timer tick, asking Buildbucket for the build status
[21:42:24.114] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:24.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:63:0) after 1m0s
[21:43:24.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:63:0)
[21:43:24.262] Timer tick, asking Buildbucket for the build status
[21:43:24.370] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:24.370] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:64:0) after 1m0s
[21:44:24.390] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:64:0)
[21:44:24.390] Timer tick, asking Buildbucket for the build status
[21:44:24.471] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:24.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:65:0) after 1m0s
[21:45:24.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:65:0)
[21:45:24.502] Timer tick, asking Buildbucket for the build status
[21:45:24.668] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:24.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:66:0) after 1m0s
[21:46:24.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:66:0)
[21:46:24.691] Timer tick, asking Buildbucket for the build status
[21:46:24.767] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:24.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:67:0) after 1m0s
[21:47:24.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:67:0)
[21:47:24.793] Timer tick, asking Buildbucket for the build status
[21:47:25.040] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:25.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:68:0) after 1m0s
[21:48:25.090] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:68:0)
[21:48:25.090] Timer tick, asking Buildbucket for the build status
[21:48:25.150] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:25.150] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:69:0) after 1m0s
[21:49:25.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:69:0)
[21:49:25.266] Timer tick, asking Buildbucket for the build status
[21:49:25.333] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:25.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:70:0) after 1m0s
[21:50:25.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:70:0)
[21:50:25.370] Timer tick, asking Buildbucket for the build status
[21:50:25.462] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:25.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:71:0) after 1m0s
[21:51:25.500] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:71:0)
[21:51:25.500] Timer tick, asking Buildbucket for the build status
[21:51:25.536] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:25.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:72:0) after 1m0s
[21:52:25.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:72:0)
[21:52:25.563] Timer tick, asking Buildbucket for the build status
[21:52:25.631] Build 8928581951085433760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:25.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094012013191398896:73:0) after 1m0s
[21:52:26.829] Received PubSub notification, asking Buildbucket for the build status
[21:52:26.924] Build 8928581951085433760: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:52:26.924] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1543441945944712",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1543437733529747",
"failure_reason": "BUILD_FAILURE",
"id": "8928581951085433760",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"71.0.3578.75\"}}",
"project": "infra",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure Bootstrap gn.\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Build From Tarball\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"3885-d07efe8\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-e833d7b0-us-west1-c-m47f\"], \"pool\": [\"luci.infra.cron\"]}}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildername\": \"Build From Tarball\", \"recipe\": \"build_from_tarball\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1543437733529747, \"tags\": [\"builder:Build From Tarball\", \"parent_buildername:publish_tarball\", \"scheduler_invocation_id:9094012013191398896\", \"scheduler_job_id:infra/Build From Tarball\", \"user_agent:luci-scheduler\", \"user_agent:recipe\"], \"bucket\": \"luci.infra.cron\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"infra\", \"id\": \"8928581951085433760\"}}, \"version\": \"71.0.3578.75\", \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-e833d7b0-us-west1-c-m47f\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1543437765937525",
"status": "COMPLETED",
"status_changed_ts": "1543441946579856",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9094012013191398896",
"scheduler_job_id:infra/Build From Tarball",
"swarming_dimension:builder:Build From Tarball",
"swarming_dimension:caches:builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.infra.cron",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:buildbucket_bucket:infra/cron",
"swarming_tag:buildbucket_build_id:8928581951085433760",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
"swarming_tag:builder:Build From Tarball",
"swarming_tag:caches:builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2",
"swarming_tag:cpu:x86-64",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8928581951085433760/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:parent_buildername:publish_tarball",
"swarming_tag:pool:luci.infra.cron",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_tag:scheduler_invocation_id:9094012013191398896",
"swarming_tag:scheduler_job_id:infra/Build From Tarball",
"swarming_tag:service_account:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:45412c4de3871bddd781c01a3b94745418493754",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:user_agent:recipe",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4174e05a4936c710",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1543441946581045",
"url": "https://ci.chromium.org/b/8928581951085433760",
"utcnow_ts": "1543441946884306"
}
}
[21:52:26.924] Invocation finished in 1h10m14.78650609s with status FAILED