[02:29:06.108] New invocation is queued and will start shortly
[02:29:06.108] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[02:29:07.259] Starting the invocation (attempt 1)
[02:29:07.307] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:29:07.307] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:29:07.307] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9093990188177070992",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"71.0.3578.71\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9093990188177070992",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[02:29:08.049] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary": true,
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1543458547383193",
"id": "8928560126177074880",
"lease_expiration_ts": "1543577347934473",
"lease_key": "824536821",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"71.0.3578.71\"}}",
"project": "infra",
"result_details_json": "null",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1543458547938586",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9093990188177070992",
"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:8928560126177074880",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:1",
"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/8928560126177074880/+/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:9093990188177070992",
"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:025d0f6bd5ffcb2eaab805943923c4673ea30ea0",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:user_agent:recipe",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:41761defca1e4710",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1543458547938756",
"url": "https://ci.chromium.org/b/8928560126177074880",
"utcnow_ts": "1543458548039649"
}
}
[02:29:08.049] Task URL: https://ci.chromium.org/b/8928560126177074880
[02:29:08.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:2:0) after 1m0s
[02:30:06.432] Received PubSub notification, asking Buildbucket for the build status
[02:30:06.569] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:08.071] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:2:0)
[02:30:08.071] Timer tick, asking Buildbucket for the build status
[02:30:08.124] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:08.125] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:4:0) after 1m0s
[02:31:08.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:4:0)
[02:31:08.143] Timer tick, asking Buildbucket for the build status
[02:31:08.203] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:08.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:5:0) after 1m0s
[02:32:08.290] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:5:0)
[02:32:08.290] Timer tick, asking Buildbucket for the build status
[02:32:08.369] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:08.369] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:6:0) after 1m0s
[02:33:08.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:6:0)
[02:33:08.422] Timer tick, asking Buildbucket for the build status
[02:33:08.486] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:08.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:7:0) after 1m0s
[02:34:08.508] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:7:0)
[02:34:08.508] Timer tick, asking Buildbucket for the build status
[02:34:08.549] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:08.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:8:0) after 1m0s
[02:35:08.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:8:0)
[02:35:08.586] Timer tick, asking Buildbucket for the build status
[02:35:08.782] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:08.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:9:0) after 1m0s
[02:36:08.879] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:9:0)
[02:36:08.879] Timer tick, asking Buildbucket for the build status
[02:36:08.933] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:08.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:10:0) after 1m0s
[02:37:08.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:10:0)
[02:37:08.950] Timer tick, asking Buildbucket for the build status
[02:37:09.024] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:09.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:11:0) after 1m0s
[02:38:09.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:11:0)
[02:38:09.045] Timer tick, asking Buildbucket for the build status
[02:38:09.085] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:09.085] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:12:0) after 1m0s
[02:39:09.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:12:0)
[02:39:09.106] Timer tick, asking Buildbucket for the build status
[02:39:09.140] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:09.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:13:0) after 1m0s
[02:40:09.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:13:0)
[02:40:09.296] Timer tick, asking Buildbucket for the build status
[02:40:09.360] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:09.360] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:14:0) after 1m0s
[02:41:09.380] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:14:0)
[02:41:09.380] Timer tick, asking Buildbucket for the build status
[02:41:09.514] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:09.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:15:0) after 1m0s
[02:42:09.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:15:0)
[02:42:09.569] Timer tick, asking Buildbucket for the build status
[02:42:09.617] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:09.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:16:0) after 1m0s
[02:43:09.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:16:0)
[02:43:09.639] Timer tick, asking Buildbucket for the build status
[02:43:09.683] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:09.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:17:0) after 1m0s
[02:44:09.741] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:17:0)
[02:44:09.741] Timer tick, asking Buildbucket for the build status
[02:44:09.812] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:09.812] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:18:0) after 1m0s
[02:45:09.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:18:0)
[02:45:09.868] Timer tick, asking Buildbucket for the build status
[02:45:10.018] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:10.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:19:0) after 1m0s
[02:46:10.063] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:19:0)
[02:46:10.063] Timer tick, asking Buildbucket for the build status
[02:46:10.108] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:10.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:20:0) after 1m0s
[02:47:10.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:20:0)
[02:47:10.129] Timer tick, asking Buildbucket for the build status
[02:47:10.214] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:10.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:21:0) after 1m0s
[02:48:10.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:21:0)
[02:48:10.302] Timer tick, asking Buildbucket for the build status
[02:48:10.383] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:10.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:22:0) after 1m0s
[02:49:10.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:22:0)
[02:49:10.405] Timer tick, asking Buildbucket for the build status
[02:49:10.480] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:10.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:23:0) after 1m0s
[02:50:10.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:23:0)
[02:50:10.506] Timer tick, asking Buildbucket for the build status
[02:50:10.543] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:10.543] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:24:0) after 1m0s
[02:51:10.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:24:0)
[02:51:10.565] Timer tick, asking Buildbucket for the build status
[02:51:10.662] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:10.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:25:0) after 1m0s
[02:52:10.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:25:0)
[02:52:10.789] Timer tick, asking Buildbucket for the build status
[02:52:10.829] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:10.829] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:26:0) after 1m0s
[02:53:10.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:26:0)
[02:53:10.845] Timer tick, asking Buildbucket for the build status
[02:53:10.951] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:10.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:27:0) after 1m0s
[02:54:11.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:27:0)
[02:54:11.069] Timer tick, asking Buildbucket for the build status
[02:54:11.216] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:11.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:28:0) after 1m0s
[02:55:11.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:28:0)
[02:55:11.234] Timer tick, asking Buildbucket for the build status
[02:55:11.316] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:11.316] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:29:0) after 1m0s
[02:56:11.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:29:0)
[02:56:11.334] Timer tick, asking Buildbucket for the build status
[02:56:11.381] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:11.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:30:0) after 1m0s
[02:57:11.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:30:0)
[02:57:11.433] Timer tick, asking Buildbucket for the build status
[02:57:11.496] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:11.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:31:0) after 1m0s
[02:58:11.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:31:0)
[02:58:11.564] Timer tick, asking Buildbucket for the build status
[02:58:11.727] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:11.727] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:32:0) after 1m0s
[02:59:11.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:32:0)
[02:59:11.756] Timer tick, asking Buildbucket for the build status
[02:59:11.792] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:11.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:33:0) after 1m0s
[03:00:11.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:33:0)
[03:00:11.841] Timer tick, asking Buildbucket for the build status
[03:00:11.920] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:11.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:34:0) after 1m0s
[03:01:11.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:34:0)
[03:01:11.939] Timer tick, asking Buildbucket for the build status
[03:01:11.972] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:11.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:35:0) after 1m0s
[03:02:11.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:35:0)
[03:02:11.992] Timer tick, asking Buildbucket for the build status
[03:02:12.090] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:12.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:36:0) after 1m0s
[03:03:12.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:36:0)
[03:03:12.108] Timer tick, asking Buildbucket for the build status
[03:03:12.404] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:12.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:37:0) after 1m0s
[03:04:12.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:37:0)
[03:04:12.426] Timer tick, asking Buildbucket for the build status
[03:04:12.505] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:12.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:38:0) after 1m0s
[03:05:12.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:38:0)
[03:05:12.595] Timer tick, asking Buildbucket for the build status
[03:05:12.729] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:12.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:39:0) after 1m0s
[03:06:12.755] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:39:0)
[03:06:12.755] Timer tick, asking Buildbucket for the build status
[03:06:12.901] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:12.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:40:0) after 1m0s
[03:07:12.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:40:0)
[03:07:12.920] Timer tick, asking Buildbucket for the build status
[03:07:13.001] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:13.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:41:0) after 1m0s
[03:08:13.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:41:0)
[03:08:13.022] Timer tick, asking Buildbucket for the build status
[03:08:13.110] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:13.110] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:42:0) after 1m0s
[03:09:13.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:42:0)
[03:09:13.147] Timer tick, asking Buildbucket for the build status
[03:09:13.216] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:13.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:43:0) after 1m0s
[03:10:13.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:43:0)
[03:10:13.266] Timer tick, asking Buildbucket for the build status
[03:10:13.359] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:13.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:44:0) after 1m0s
[03:11:13.380] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:44:0)
[03:11:13.380] Timer tick, asking Buildbucket for the build status
[03:11:13.442] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:13.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:45:0) after 1m0s
[03:12:13.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:45:0)
[03:12:13.460] Timer tick, asking Buildbucket for the build status
[03:12:13.531] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:13.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:46:0) after 1m0s
[03:13:13.612] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:46:0)
[03:13:13.612] Timer tick, asking Buildbucket for the build status
[03:13:13.806] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:13.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:47:0) after 1m0s
[03:14:13.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:47:0)
[03:14:13.840] Timer tick, asking Buildbucket for the build status
[03:14:13.920] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:13.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:48:0) after 1m0s
[03:15:14.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:48:0)
[03:15:14.093] Timer tick, asking Buildbucket for the build status
[03:15:14.165] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:14.165] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:49:0) after 1m0s
[03:16:14.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:49:0)
[03:16:14.235] Timer tick, asking Buildbucket for the build status
[03:16:14.407] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:14.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:50:0) after 1m0s
[03:17:14.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:50:0)
[03:17:14.425] Timer tick, asking Buildbucket for the build status
[03:17:14.475] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:14.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:51:0) after 1m0s
[03:18:14.576] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:51:0)
[03:18:14.576] Timer tick, asking Buildbucket for the build status
[03:18:14.733] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:14.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:52:0) after 1m0s
[03:19:14.754] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:52:0)
[03:19:14.754] Timer tick, asking Buildbucket for the build status
[03:19:14.815] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:14.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:53:0) after 1m0s
[03:20:14.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:53:0)
[03:20:14.837] Timer tick, asking Buildbucket for the build status
[03:20:14.934] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:14.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:54:0) after 1m0s
[03:21:14.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:54:0)
[03:21:14.973] Timer tick, asking Buildbucket for the build status
[03:21:15.019] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:15.019] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:55:0) after 1m0s
[03:22:15.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:55:0)
[03:22:15.046] Timer tick, asking Buildbucket for the build status
[03:22:15.112] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:15.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:56:0) after 1m0s
[03:23:15.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:56:0)
[03:23:15.141] Timer tick, asking Buildbucket for the build status
[03:23:15.183] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:15.183] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:57:0) after 1m0s
[03:24:15.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:57:0)
[03:24:15.282] Timer tick, asking Buildbucket for the build status
[03:24:15.344] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:15.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:58:0) after 1m0s
[03:25:15.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:58:0)
[03:25:15.364] Timer tick, asking Buildbucket for the build status
[03:25:15.549] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:15.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:59:0) after 1m0s
[03:26:15.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:59:0)
[03:26:15.573] Timer tick, asking Buildbucket for the build status
[03:26:15.628] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:15.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:60:0) after 1m0s
[03:27:15.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:60:0)
[03:27:15.760] Timer tick, asking Buildbucket for the build status
[03:27:15.862] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:15.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:61:0) after 1m0s
[03:28:15.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:61:0)
[03:28:15.901] Timer tick, asking Buildbucket for the build status
[03:28:15.963] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:15.963] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:62:0) after 1m0s
[03:29:15.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:62:0)
[03:29:15.984] Timer tick, asking Buildbucket for the build status
[03:29:16.061] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:16.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:63:0) after 1m0s
[03:30:16.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:63:0)
[03:30:16.102] Timer tick, asking Buildbucket for the build status
[03:30:16.217] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:16.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:64:0) after 1m0s
[03:31:16.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:64:0)
[03:31:16.240] Timer tick, asking Buildbucket for the build status
[03:31:16.319] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:16.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:65:0) after 1m0s
[03:32:16.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:65:0)
[03:32:16.342] Timer tick, asking Buildbucket for the build status
[03:32:16.427] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:16.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:66:0) after 1m0s
[03:33:16.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:66:0)
[03:33:16.468] Timer tick, asking Buildbucket for the build status
[03:33:16.534] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:16.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:67:0) after 1m0s
[03:34:16.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:67:0)
[03:34:16.558] Timer tick, asking Buildbucket for the build status
[03:34:16.648] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:16.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:68:0) after 1m0s
[03:35:16.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:68:0)
[03:35:16.757] Timer tick, asking Buildbucket for the build status
[03:35:16.846] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:16.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:69:0) after 1m0s
[03:36:16.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:69:0)
[03:36:16.868] Timer tick, asking Buildbucket for the build status
[03:36:16.929] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:16.929] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:70:0) after 1m0s
[03:37:16.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:70:0)
[03:37:16.949] Timer tick, asking Buildbucket for the build status
[03:37:17.129] Build 8928560126177074880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:17.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9093990188177070992:71:0) after 1m0s
[03:38:16.304] Received PubSub notification, asking Buildbucket for the build status
[03:38:16.359] Build 8928560126177074880: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[03:38:16.359] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1543462695457617",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1543458547383193",
"failure_reason": "BUILD_FAILURE",
"id": "8928560126177074880",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"71.0.3578.71\"}}",
"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\"], \"id\": [\"gce-trusty-e833d7b0-us-west1-c-m47f\"], \"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\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"pool\": [\"luci.infra.cron\"], \"caches\": [\"vpython\"]}}, \"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\": 1543458547383193, \"tags\": [\"builder:Build From Tarball\", \"parent_buildername:publish_tarball\", \"scheduler_invocation_id:9093990188177070992\", \"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\": \"8928560126177074880\"}}, \"version\": \"71.0.3578.71\", \"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": "1543458605585327",
"status": "COMPLETED",
"status_changed_ts": "1543462696004668",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9093990188177070992",
"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:8928560126177074880",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:1",
"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/8928560126177074880/+/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:9093990188177070992",
"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:025d0f6bd5ffcb2eaab805943923c4673ea30ea0",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:user_agent:recipe",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:41761defca1e4710",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1543462696005857",
"url": "https://ci.chromium.org/b/8928560126177074880",
"utcnow_ts": "1543462696335152"
}
}
[03:38:16.359] Invocation finished in 1h9m10.266244229s with status FAILED