[10:48:56.483] New invocation is queued and will start shortly
[10:48:56.483] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[10:48:57.690] Starting the invocation (attempt 1)
[10:48:57.729] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:48:57.729] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:48:57.729] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9095317695491771248",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"72.0.3605.3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9095317695491771248",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[10:48:59.638] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1542192537914325",
"id": "8929887633322568608",
"lease_expiration_ts": "1542311339520400",
"lease_key": "1106881924",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"72.0.3605.3\"}}",
"project": "infra",
"result_details_json": "null",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1542192539521535",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9095317695491771248",
"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:8929887633322568608",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:392c1547cd3d35e7fd3a5e1012873ccdb987dbae",
"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/8929887633322568608/+/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:9095317695491771248",
"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:a1041a62bbbee31a2d89a68ede33a996491a1e3a",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:user_agent:recipe",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:412aa82e579c1e10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1542192539521631",
"url": "https://ci.chromium.org/b/8929887633322568608",
"utcnow_ts": "1542192539621356"
}
}
[10:48:59.638] Task URL: https://ci.chromium.org/b/8929887633322568608
[10:48:59.638] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:2:0) after 1m0s
[10:49:51.241] Received PubSub notification, asking Buildbucket for the build status
[10:49:51.365] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:59.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:2:0)
[10:49:59.653] Timer tick, asking Buildbucket for the build status
[10:49:59.699] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:59.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:4:0) after 1m0s
[10:50:59.718] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:4:0)
[10:50:59.718] Timer tick, asking Buildbucket for the build status
[10:50:59.786] Build 8929887633322568608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:50:59.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:5:0) after 1m0s
[10:51:23.073] Received PubSub notification, asking Buildbucket for the build status
[10:51:23.124] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:59.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:5:0)
[10:51:59.803] Timer tick, asking Buildbucket for the build status
[10:51:59.936] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:59.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:7:0) after 1m0s
[10:52:59.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:7:0)
[10:52:59.954] Timer tick, asking Buildbucket for the build status
[10:52:59.993] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:59.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:8:0) after 1m0s
[10:54:00.019] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:8:0)
[10:54:00.019] Timer tick, asking Buildbucket for the build status
[10:54:00.118] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:00.118] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:9:0) after 1m0s
[10:55:00.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:9:0)
[10:55:00.133] Timer tick, asking Buildbucket for the build status
[10:55:00.225] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:00.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:10:0) after 1m0s
[10:56:00.253] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:10:0)
[10:56:00.253] Timer tick, asking Buildbucket for the build status
[10:56:00.313] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:00.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:11:0) after 1m0s
[10:57:00.380] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:11:0)
[10:57:00.380] Timer tick, asking Buildbucket for the build status
[10:57:00.463] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:00.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:12:0) after 1m0s
[10:58:00.490] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:12:0)
[10:58:00.490] Timer tick, asking Buildbucket for the build status
[10:58:00.649] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:00.649] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:13:0) after 1m0s
[10:59:00.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:13:0)
[10:59:00.676] Timer tick, asking Buildbucket for the build status
[10:59:00.831] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:00.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:14:0) after 1m0s
[11:00:00.856] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:14:0)
[11:00:00.856] Timer tick, asking Buildbucket for the build status
[11:00:01.018] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:01.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:15:0) after 1m0s
[11:01:01.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:15:0)
[11:01:01.153] Timer tick, asking Buildbucket for the build status
[11:01:01.309] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:01.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:16:0) after 1m0s
[11:02:01.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:16:0)
[11:02:01.325] Timer tick, asking Buildbucket for the build status
[11:02:01.435] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:01.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:17:0) after 1m0s
[11:03:01.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:17:0)
[11:03:01.455] Timer tick, asking Buildbucket for the build status
[11:03:01.683] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:01.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:18:0) after 1m0s
[11:04:01.700] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:18:0)
[11:04:01.700] Timer tick, asking Buildbucket for the build status
[11:04:01.788] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:01.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:19:0) after 1m0s
[11:05:01.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:19:0)
[11:05:01.833] Timer tick, asking Buildbucket for the build status
[11:05:01.938] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:01.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:20:0) after 1m0s
[11:06:01.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:20:0)
[11:06:01.990] Timer tick, asking Buildbucket for the build status
[11:06:02.163] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:02.164] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:21:0) after 1m0s
[11:07:02.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:21:0)
[11:07:02.184] Timer tick, asking Buildbucket for the build status
[11:07:02.476] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:02.477] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:22:0) after 1m0s
[11:08:02.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:22:0)
[11:08:02.504] Timer tick, asking Buildbucket for the build status
[11:08:02.548] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:02.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:23:0) after 1m0s
[11:09:02.680] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:23:0)
[11:09:02.680] Timer tick, asking Buildbucket for the build status
[11:09:02.778] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:02.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:24:0) after 1m0s
[11:10:02.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:24:0)
[11:10:02.859] Timer tick, asking Buildbucket for the build status
[11:10:02.896] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:02.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:25:0) after 1m0s
[11:11:02.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:25:0)
[11:11:02.985] Timer tick, asking Buildbucket for the build status
[11:11:03.074] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:03.074] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:26:0) after 1m0s
[11:12:03.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:26:0)
[11:12:03.261] Timer tick, asking Buildbucket for the build status
[11:12:03.328] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:03.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:27:0) after 1m0s
[11:13:03.393] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:27:0)
[11:13:03.393] Timer tick, asking Buildbucket for the build status
[11:13:03.461] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:03.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:28:0) after 1m0s
[11:14:03.530] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:28:0)
[11:14:03.530] Timer tick, asking Buildbucket for the build status
[11:14:03.617] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:03.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:29:0) after 1m0s
[11:15:03.644] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:29:0)
[11:15:03.644] Timer tick, asking Buildbucket for the build status
[11:15:03.753] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:03.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:30:0) after 1m0s
[11:16:03.773] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:30:0)
[11:16:03.773] Timer tick, asking Buildbucket for the build status
[11:16:03.912] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:03.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:31:0) after 1m0s
[11:17:03.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:31:0)
[11:17:03.984] Timer tick, asking Buildbucket for the build status
[11:17:04.080] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:04.080] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:32:0) after 1m0s
[11:18:04.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:32:0)
[11:18:04.127] Timer tick, asking Buildbucket for the build status
[11:18:04.172] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:04.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:33:0) after 1m0s
[11:19:04.207] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:33:0)
[11:19:04.208] Timer tick, asking Buildbucket for the build status
[11:19:04.445] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:04.445] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:34:0) after 1m0s
[11:20:04.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:34:0)
[11:20:04.475] Timer tick, asking Buildbucket for the build status
[11:20:04.538] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:04.538] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:35:0) after 1m0s
[11:21:04.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:35:0)
[11:21:04.579] Timer tick, asking Buildbucket for the build status
[11:21:04.633] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:04.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:36:0) after 1m0s
[11:22:04.652] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:36:0)
[11:22:04.652] Timer tick, asking Buildbucket for the build status
[11:22:04.774] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:04.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:37:0) after 1m0s
[11:23:04.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:37:0)
[11:23:04.807] Timer tick, asking Buildbucket for the build status
[11:23:04.864] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:04.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:38:0) after 1m0s
[11:24:04.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:38:0)
[11:24:04.883] Timer tick, asking Buildbucket for the build status
[11:24:05.041] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:05.041] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:39:0) after 1m0s
[11:25:05.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:39:0)
[11:25:05.104] Timer tick, asking Buildbucket for the build status
[11:25:05.180] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:05.180] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:40:0) after 1m0s
[11:26:05.202] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:40:0)
[11:26:05.202] Timer tick, asking Buildbucket for the build status
[11:26:05.305] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:05.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:41:0) after 1m0s
[11:27:05.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:41:0)
[11:27:05.325] Timer tick, asking Buildbucket for the build status
[11:27:05.390] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:05.390] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:42:0) after 1m0s
[11:28:05.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:42:0)
[11:28:05.409] Timer tick, asking Buildbucket for the build status
[11:28:05.614] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:05.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:43:0) after 1m0s
[11:29:05.634] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:43:0)
[11:29:05.634] Timer tick, asking Buildbucket for the build status
[11:29:05.686] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:05.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:44:0) after 1m0s
[11:30:05.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:44:0)
[11:30:05.745] Timer tick, asking Buildbucket for the build status
[11:30:05.828] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:05.828] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:45:0) after 1m0s
[11:31:05.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:45:0)
[11:31:05.852] Timer tick, asking Buildbucket for the build status
[11:31:05.910] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:05.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:46:0) after 1m0s
[11:32:05.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:46:0)
[11:32:05.938] Timer tick, asking Buildbucket for the build status
[11:32:06.025] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:06.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:47:0) after 1m0s
[11:33:06.083] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:47:0)
[11:33:06.083] Timer tick, asking Buildbucket for the build status
[11:33:06.141] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:06.141] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:48:0) after 1m0s
[11:34:06.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:48:0)
[11:34:06.192] Timer tick, asking Buildbucket for the build status
[11:34:06.302] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:06.302] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:49:0) after 1m0s
[11:35:06.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:49:0)
[11:35:06.366] Timer tick, asking Buildbucket for the build status
[11:35:06.462] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:06.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:50:0) after 1m0s
[11:36:06.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:50:0)
[11:36:06.482] Timer tick, asking Buildbucket for the build status
[11:36:06.529] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:06.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:51:0) after 1m0s
[11:37:06.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:51:0)
[11:37:06.560] Timer tick, asking Buildbucket for the build status
[11:37:06.623] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:06.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:52:0) after 1m0s
[11:38:06.649] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:52:0)
[11:38:06.649] Timer tick, asking Buildbucket for the build status
[11:38:06.734] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:06.734] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:53:0) after 1m0s
[11:39:06.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:53:0)
[11:39:06.863] Timer tick, asking Buildbucket for the build status
[11:39:06.960] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:06.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:54:0) after 1m0s
[11:40:07.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:54:0)
[11:40:07.062] Timer tick, asking Buildbucket for the build status
[11:40:07.108] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:07.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:55:0) after 1m0s
[11:41:07.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:55:0)
[11:41:07.134] Timer tick, asking Buildbucket for the build status
[11:41:07.191] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:07.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:56:0) after 1m0s
[11:42:07.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:56:0)
[11:42:07.228] Timer tick, asking Buildbucket for the build status
[11:42:07.469] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:07.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:57:0) after 1m0s
[11:43:07.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:57:0)
[11:43:07.494] Timer tick, asking Buildbucket for the build status
[11:43:07.560] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:07.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:58:0) after 1m0s
[11:44:07.621] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:58:0)
[11:44:07.621] Timer tick, asking Buildbucket for the build status
[11:44:07.666] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:07.666] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:59:0) after 1m0s
[11:45:07.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:59:0)
[11:45:07.689] Timer tick, asking Buildbucket for the build status
[11:45:07.742] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:07.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:60:0) after 1m0s
[11:46:07.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:60:0)
[11:46:07.776] Timer tick, asking Buildbucket for the build status
[11:46:07.927] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:07.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:61:0) after 1m0s
[11:47:07.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:61:0)
[11:47:07.962] Timer tick, asking Buildbucket for the build status
[11:47:08.072] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:08.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:62:0) after 1m0s
[11:48:08.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:62:0)
[11:48:08.170] Timer tick, asking Buildbucket for the build status
[11:48:08.317] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:08.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:63:0) after 1m0s
[11:49:08.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:63:0)
[11:49:08.386] Timer tick, asking Buildbucket for the build status
[11:49:08.517] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:08.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:64:0) after 1m0s
[11:50:08.537] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:64:0)
[11:50:08.537] Timer tick, asking Buildbucket for the build status
[11:50:08.609] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:08.609] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:65:0) after 1m0s
[11:51:08.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:65:0)
[11:51:08.638] Timer tick, asking Buildbucket for the build status
[11:51:08.695] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:08.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:66:0) after 1m0s
[11:52:08.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:66:0)
[11:52:08.719] Timer tick, asking Buildbucket for the build status
[11:52:08.808] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:08.808] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:67:0) after 1m0s
[11:53:08.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:67:0)
[11:53:08.831] Timer tick, asking Buildbucket for the build status
[11:53:08.940] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:08.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:68:0) after 1m0s
[11:54:09.000] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:68:0)
[11:54:09.000] Timer tick, asking Buildbucket for the build status
[11:54:09.220] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:09.220] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:69:0) after 1m0s
[11:55:09.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:69:0)
[11:55:09.252] Timer tick, asking Buildbucket for the build status
[11:55:09.291] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:09.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:70:0) after 1m0s
[11:56:09.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:70:0)
[11:56:09.311] Timer tick, asking Buildbucket for the build status
[11:56:09.389] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:09.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:71:0) after 1m0s
[11:57:09.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:71:0)
[11:57:09.532] Timer tick, asking Buildbucket for the build status
[11:57:09.594] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:09.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:72:0) after 1m0s
[11:58:09.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:72:0)
[11:58:09.616] Timer tick, asking Buildbucket for the build status
[11:58:09.677] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:09.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:73:0) after 1m0s
[11:59:09.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:73:0)
[11:59:09.714] Timer tick, asking Buildbucket for the build status
[11:59:09.770] Build 8929887633322568608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:09.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095317695491771248:74:0) after 1m0s
[11:59:55.338] Received PubSub notification, asking Buildbucket for the build status
[11:59:55.406] Build 8929887633322568608: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[11:59:55.406] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1542196794040342",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1542192537914325",
"failure_reason": "BUILD_FAILURE",
"id": "8929887633322568608",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"72.0.3605.3\"}}",
"project": "infra",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure Bootstrap gn.\"}, \"swarming\": {\"bot_dimensions\": {\"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\": [\"3844-124e68f\"], \"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-hnkl\"], \"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\": 1542192537914325, \"tags\": [\"builder:Build From Tarball\", \"parent_buildername:publish_tarball\", \"scheduler_invocation_id:9095317695491771248\", \"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\": \"8929887633322568608\"}}, \"version\": \"72.0.3605.3\", \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-e833d7b0-us-west1-c-hnkl\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1542192590103172",
"status": "COMPLETED",
"status_changed_ts": "1542196794772122",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9095317695491771248",
"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:8929887633322568608",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:392c1547cd3d35e7fd3a5e1012873ccdb987dbae",
"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/8929887633322568608/+/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:9095317695491771248",
"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:a1041a62bbbee31a2d89a68ede33a996491a1e3a",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:user_agent:recipe",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:412aa82e579c1e10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1542196794773048",
"url": "https://ci.chromium.org/b/8929887633322568608",
"utcnow_ts": "1542196795379681"
}
}
[11:59:55.406] Invocation finished in 1h10m58.94430824s with status FAILED