[22:55:26.645] New invocation is queued and will start shortly
[22:55:26.645] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[22:55:27.688] Starting the invocation (attempt 1)
[22:55:27.730] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:55:27.730] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:55:27.730] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9083222591356590528",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"74.0.3729.37\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9083222591356590528",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[22:55:28.121] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "project:infra",
"created_ts": "1553727327821719",
"id": "8917792529460810656",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3729.37\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553727327940989",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9083222591356590528",
"scheduler_job_id:infra/Build From Tarball",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8917792529460810656/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1553727327941097",
"url": "https://ci.chromium.org/b/8917792529460810656",
"utcnow_ts": "1553727328113484"
}
}
[22:55:28.121] Task URL: https://ci.chromium.org/b/8917792529460810656
[22:55:28.121] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:2:0) after 1m0s
[22:55:48.523] Received PubSub notification, asking Buildbucket for the build status
[22:55:48.718] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:28.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:2:0)
[22:56:28.131] Timer tick, asking Buildbucket for the build status
[22:56:28.204] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:28.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:4:0) after 1m0s
[22:57:28.226] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:4:0)
[22:57:28.226] Timer tick, asking Buildbucket for the build status
[22:57:28.457] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:28.457] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:5:0) after 1m0s
[22:58:28.474] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:5:0)
[22:58:28.474] Timer tick, asking Buildbucket for the build status
[22:58:28.697] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:28.697] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:6:0) after 1m0s
[22:59:28.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:6:0)
[22:59:28.709] Timer tick, asking Buildbucket for the build status
[22:59:28.783] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:28.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:7:0) after 1m0s
[23:00:28.813] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:7:0)
[23:00:28.813] Timer tick, asking Buildbucket for the build status
[23:00:28.883] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:28.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:8:0) after 1m0s
[23:01:28.913] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:8:0)
[23:01:28.913] Timer tick, asking Buildbucket for the build status
[23:01:29.059] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:29.059] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:9:0) after 1m0s
[23:02:29.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:9:0)
[23:02:29.097] Timer tick, asking Buildbucket for the build status
[23:02:29.168] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:29.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:10:0) after 1m0s
[23:03:29.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:10:0)
[23:03:29.177] Timer tick, asking Buildbucket for the build status
[23:03:29.240] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:29.240] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:11:0) after 1m0s
[23:04:29.218] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:11:0)
[23:04:29.218] Timer tick, asking Buildbucket for the build status
[23:04:29.296] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:29.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:12:0) after 1m0s
[23:05:29.313] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:12:0)
[23:05:29.313] Timer tick, asking Buildbucket for the build status
[23:05:29.413] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:29.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:13:0) after 1m0s
[23:06:29.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:13:0)
[23:06:29.431] Timer tick, asking Buildbucket for the build status
[23:06:29.487] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:29.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:14:0) after 1m0s
[23:07:29.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:14:0)
[23:07:29.502] Timer tick, asking Buildbucket for the build status
[23:07:29.672] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:29.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:15:0) after 1m0s
[23:08:29.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:15:0)
[23:08:29.719] Timer tick, asking Buildbucket for the build status
[23:08:29.894] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:29.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:16:0) after 1m0s
[23:09:29.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:16:0)
[23:09:29.927] Timer tick, asking Buildbucket for the build status
[23:09:30.066] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:30.066] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:17:0) after 1m0s
[23:10:30.089] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:17:0)
[23:10:30.089] Timer tick, asking Buildbucket for the build status
[23:10:30.131] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:30.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:18:0) after 1m0s
[23:11:30.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:18:0)
[23:11:30.148] Timer tick, asking Buildbucket for the build status
[23:11:30.341] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:30.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:19:0) after 1m0s
[23:12:30.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:19:0)
[23:12:30.359] Timer tick, asking Buildbucket for the build status
[23:12:30.481] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:30.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:20:0) after 1m0s
[23:13:30.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:20:0)
[23:13:30.502] Timer tick, asking Buildbucket for the build status
[23:13:30.581] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:30.581] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:21:0) after 1m0s
[23:14:30.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:21:0)
[23:14:30.624] Timer tick, asking Buildbucket for the build status
[23:14:30.765] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:30.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:22:0) after 1m0s
[23:15:30.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:22:0)
[23:15:30.783] Timer tick, asking Buildbucket for the build status
[23:15:31.273] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:31.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:23:0) after 1m0s
[23:16:31.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:23:0)
[23:16:31.308] Timer tick, asking Buildbucket for the build status
[23:16:31.542] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:31.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:24:0) after 1m0s
[23:17:31.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:24:0)
[23:17:31.598] Timer tick, asking Buildbucket for the build status
[23:17:31.863] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:31.863] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:25:0) after 1m0s
[23:18:31.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:25:0)
[23:18:31.880] Timer tick, asking Buildbucket for the build status
[23:18:31.933] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:31.933] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:26:0) after 1m0s
[23:19:31.947] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:26:0)
[23:19:31.947] Timer tick, asking Buildbucket for the build status
[23:19:32.037] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:32.037] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:27:0) after 1m0s
[23:20:32.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:27:0)
[23:20:32.042] Timer tick, asking Buildbucket for the build status
[23:20:32.084] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:32.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:28:0) after 1m0s
[23:21:32.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:28:0)
[23:21:32.061] Timer tick, asking Buildbucket for the build status
[23:21:32.156] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:32.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:29:0) after 1m0s
[23:22:32.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:29:0)
[23:22:32.178] Timer tick, asking Buildbucket for the build status
[23:22:32.250] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:32.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:30:0) after 1m0s
[23:23:32.239] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:30:0)
[23:23:32.239] Timer tick, asking Buildbucket for the build status
[23:23:32.314] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:32.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:31:0) after 1m0s
[23:24:32.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:31:0)
[23:24:32.391] Timer tick, asking Buildbucket for the build status
[23:24:32.488] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:32.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:32:0) after 1m0s
[23:25:32.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:32:0)
[23:25:32.492] Timer tick, asking Buildbucket for the build status
[23:25:32.548] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:32.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:33:0) after 1m0s
[23:26:32.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:33:0)
[23:26:32.585] Timer tick, asking Buildbucket for the build status
[23:26:32.632] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:32.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:34:0) after 1m0s
[23:27:32.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:34:0)
[23:27:32.654] Timer tick, asking Buildbucket for the build status
[23:27:32.812] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:32.812] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:35:0) after 1m0s
[23:28:32.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:35:0)
[23:28:32.842] Timer tick, asking Buildbucket for the build status
[23:28:32.893] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:32.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:36:0) after 1m0s
[23:29:32.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:36:0)
[23:29:32.884] Timer tick, asking Buildbucket for the build status
[23:29:32.956] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:32.956] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:37:0) after 1m0s
[23:30:32.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:37:0)
[23:30:32.954] Timer tick, asking Buildbucket for the build status
[23:30:32.999] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:32.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:38:0) after 1m0s
[23:31:32.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:38:0)
[23:31:32.986] Timer tick, asking Buildbucket for the build status
[23:31:33.026] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:33.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:39:0) after 1m0s
[23:32:33.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:39:0)
[23:32:33.068] Timer tick, asking Buildbucket for the build status
[23:32:33.117] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:33.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:40:0) after 1m0s
[23:33:33.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:40:0)
[23:33:33.136] Timer tick, asking Buildbucket for the build status
[23:33:33.200] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:33.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:41:0) after 1m0s
[23:34:33.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:41:0)
[23:34:33.176] Timer tick, asking Buildbucket for the build status
[23:34:33.219] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:33.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:42:0) after 1m0s
[23:35:33.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:42:0)
[23:35:33.274] Timer tick, asking Buildbucket for the build status
[23:35:33.392] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:33.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:43:0) after 1m0s
[23:36:33.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:43:0)
[23:36:33.414] Timer tick, asking Buildbucket for the build status
[23:36:33.466] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:33.466] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:44:0) after 1m0s
[23:37:33.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:44:0)
[23:37:33.487] Timer tick, asking Buildbucket for the build status
[23:37:33.738] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:33.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:45:0) after 1m0s
[23:38:37.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:45:0)
[23:38:37.341] Timer tick, asking Buildbucket for the build status
[23:38:37.379] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:37.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:46:0) after 1m0s
[23:39:37.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:46:0)
[23:39:37.402] Timer tick, asking Buildbucket for the build status
[23:39:37.448] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:37.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:47:0) after 1m0s
[23:40:37.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:47:0)
[23:40:37.457] Timer tick, asking Buildbucket for the build status
[23:40:37.495] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:37.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:48:0) after 1m0s
[23:41:37.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:48:0)
[23:41:37.514] Timer tick, asking Buildbucket for the build status
[23:41:37.577] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:37.577] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:49:0) after 1m0s
[23:42:37.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:49:0)
[23:42:37.597] Timer tick, asking Buildbucket for the build status
[23:42:37.717] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:37.717] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:50:0) after 1m0s
[23:43:37.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:50:0)
[23:43:37.737] Timer tick, asking Buildbucket for the build status
[23:43:37.779] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:37.779] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:51:0) after 1m0s
[23:44:37.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:51:0)
[23:44:37.774] Timer tick, asking Buildbucket for the build status
[23:44:37.818] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:37.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:52:0) after 1m0s
[23:45:37.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:52:0)
[23:45:37.848] Timer tick, asking Buildbucket for the build status
[23:45:38.153] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:38.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:53:0) after 1m0s
[23:46:38.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:53:0)
[23:46:38.156] Timer tick, asking Buildbucket for the build status
[23:46:38.269] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:38.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:54:0) after 1m0s
[23:47:38.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:54:0)
[23:47:38.287] Timer tick, asking Buildbucket for the build status
[23:47:38.348] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:38.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:55:0) after 1m0s
[23:48:38.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:55:0)
[23:48:38.370] Timer tick, asking Buildbucket for the build status
[23:48:38.441] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:38.441] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:56:0) after 1m0s
[23:49:38.462] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:56:0)
[23:49:38.462] Timer tick, asking Buildbucket for the build status
[23:49:38.530] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:38.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:57:0) after 1m0s
[23:50:38.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:57:0)
[23:50:38.567] Timer tick, asking Buildbucket for the build status
[23:50:38.636] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:38.636] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:58:0) after 1m0s
[23:51:38.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:58:0)
[23:51:38.651] Timer tick, asking Buildbucket for the build status
[23:51:39.229] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:39.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:59:0) after 1m0s
[23:52:39.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:59:0)
[23:52:39.248] Timer tick, asking Buildbucket for the build status
[23:52:39.301] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:39.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:60:0) after 1m0s
[23:53:39.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:60:0)
[23:53:39.341] Timer tick, asking Buildbucket for the build status
[23:53:39.405] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:39.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:61:0) after 1m0s
[23:54:39.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:61:0)
[23:54:39.385] Timer tick, asking Buildbucket for the build status
[23:54:39.431] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:39.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:62:0) after 1m0s
[23:55:39.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:62:0)
[23:55:39.451] Timer tick, asking Buildbucket for the build status
[23:55:39.538] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:39.538] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:63:0) after 1m0s
[23:56:39.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:63:0)
[23:56:39.562] Timer tick, asking Buildbucket for the build status
[23:56:39.694] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:39.694] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:64:0) after 1m0s
[23:57:39.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:64:0)
[23:57:39.714] Timer tick, asking Buildbucket for the build status
[23:57:39.916] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:39.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:65:0) after 1m0s
[23:58:39.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:65:0)
[23:58:39.936] Timer tick, asking Buildbucket for the build status
[23:58:39.976] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:39.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:66:0) after 1m0s
[23:59:40.000] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:66:0)
[23:59:40.000] Timer tick, asking Buildbucket for the build status
[23:59:40.071] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:40.071] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:67:0) after 1m0s
[00:00:40.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:67:0)
[00:00:40.048] Timer tick, asking Buildbucket for the build status
[00:00:40.230] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:40.230] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:68:0) after 1m0s
[00:01:40.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:68:0)
[00:01:40.221] Timer tick, asking Buildbucket for the build status
[00:01:40.474] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:40.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:69:0) after 1m0s
[00:02:40.508] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:69:0)
[00:02:40.508] Timer tick, asking Buildbucket for the build status
[00:02:40.762] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:40.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:70:0) after 1m0s
[00:03:40.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:70:0)
[00:03:40.738] Timer tick, asking Buildbucket for the build status
[00:03:40.787] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:40.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:71:0) after 1m0s
[00:04:40.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:71:0)
[00:04:40.801] Timer tick, asking Buildbucket for the build status
[00:04:41.400] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:41.401] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:72:0) after 1m0s
[00:05:41.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:72:0)
[00:05:41.424] Timer tick, asking Buildbucket for the build status
[00:05:42.048] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:42.048] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:73:0) after 1m0s
[00:06:42.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:73:0)
[00:06:42.066] Timer tick, asking Buildbucket for the build status
[00:06:42.124] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:42.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:74:0) after 1m0s
[00:07:42.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:74:0)
[00:07:42.148] Timer tick, asking Buildbucket for the build status
[00:07:42.273] Build 8917792529460810656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:42.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083222591356590528:75:0) after 1m0s
[00:08:14.032] Received PubSub notification, asking Buildbucket for the build status
[00:08:14.131] Build 8917792529460810656: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:08:14.132] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1553731693800518",
"created_by": "project:infra",
"created_ts": "1553727327821719",
"failure_reason": "BUILD_FAILURE",
"id": "8917792529460810656",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3729.37\"}}",
"project": "infra",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('Build chrome.') failed with return_code 1\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"build-from-tarball-0-f7cu\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"74.0.3729.37\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Build From Tarball\"], \"caches\": [\"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"build-from-tarball-0-f7cu\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4221-9c50278\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('Build chrome.') failed with return_code 1\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553727345426897",
"status": "COMPLETED",
"status_changed_ts": "1553727348285724",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9083222591356590528",
"scheduler_job_id:infra/Build From Tarball",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8917792529460810656/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:43da2f183fc43a10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1553731693844436",
"url": "https://ci.chromium.org/b/8917792529460810656",
"utcnow_ts": "1553731694116881"
}
}
[00:08:14.132] Invocation finished in 1h12m47.501561307s with status FAILED