[02:36:32.121] New invocation is queued and will start shortly
[02:36:32.121] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[02:36:33.218] Starting the invocation (attempt 1)
[02:36:33.284] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:36:33.284] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:36:33.284] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9059109888435543632",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"81.0.4000.3\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"300c3fac-1e88-452c-b3eb-3c0c49af9763\",\"title\":\"publish_tarball/0\"}]},\"version\":\"81.0.4000.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:9059109888435543632",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[02:36:36.515] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1576722993679746",
"id": "8893679826138660240",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"81.0.4000.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"300c3fac-1e88-452c-b3eb-3c0c49af9763\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"81.0.4000.3\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576722995557104",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9059109888435543632",
"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/8893679826138660240/+/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": "1576722995557252",
"url": "https://ci.chromium.org/b/8893679826138660240",
"utcnow_ts": "1576722996490626"
}
}
[02:36:36.515] Task URL: https://ci.chromium.org/b/8893679826138660240
[02:36:36.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:2:0) after 1m0s
[02:37:36.658] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:2:0)
[02:37:36.658] Timer tick, asking Buildbucket for the build status
[02:37:37.156] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:37.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:3:0) after 1m0s
[02:38:37.303] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:3:0)
[02:38:37.303] Timer tick, asking Buildbucket for the build status
[02:38:37.709] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:38:37.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:4:0) after 1m0s
[02:39:37.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:4:0)
[02:39:37.740] Timer tick, asking Buildbucket for the build status
[02:39:38.121] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:39:38.121] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:5:0) after 1m0s
[02:40:38.142] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:5:0)
[02:40:38.142] Timer tick, asking Buildbucket for the build status
[02:40:38.883] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:40:38.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:6:0) after 1m0s
[02:41:39.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:6:0)
[02:41:39.138] Timer tick, asking Buildbucket for the build status
[02:41:39.357] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:41:39.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:7:0) after 1m0s
[02:42:39.557] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:7:0)
[02:42:39.557] Timer tick, asking Buildbucket for the build status
[02:42:39.894] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:42:39.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:8:0) after 1m0s
[02:43:40.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:8:0)
[02:43:40.087] Timer tick, asking Buildbucket for the build status
[02:43:40.384] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:43:40.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:9:0) after 1m0s
[02:44:41.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:9:0)
[02:44:41.148] Timer tick, asking Buildbucket for the build status
[02:44:41.459] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:44:41.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:10:0) after 1m0s
[02:45:41.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:10:0)
[02:45:41.566] Timer tick, asking Buildbucket for the build status
[02:45:43.222] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:45:43.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:11:0) after 1m0s
[02:46:43.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:11:0)
[02:46:43.385] Timer tick, asking Buildbucket for the build status
[02:46:44.143] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:46:44.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:12:0) after 1m0s
[02:47:44.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:12:0)
[02:47:44.195] Timer tick, asking Buildbucket for the build status
[02:47:44.545] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:47:44.545] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:13:0) after 1m0s
[02:48:44.630] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:13:0)
[02:48:44.630] Timer tick, asking Buildbucket for the build status
[02:48:44.951] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:48:44.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:14:0) after 1m0s
[02:49:45.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:14:0)
[02:49:45.061] Timer tick, asking Buildbucket for the build status
[02:49:45.265] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:49:45.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:15:0) after 1m0s
[02:50:45.290] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:15:0)
[02:50:45.290] Timer tick, asking Buildbucket for the build status
[02:50:45.490] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:50:45.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:16:0) after 1m0s
[02:51:45.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:16:0)
[02:51:45.573] Timer tick, asking Buildbucket for the build status
[02:51:45.846] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:51:45.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:17:0) after 1m0s
[02:52:45.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:17:0)
[02:52:45.910] Timer tick, asking Buildbucket for the build status
[02:52:46.042] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:52:46.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:18:0) after 1m0s
[02:53:46.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:18:0)
[02:53:46.362] Timer tick, asking Buildbucket for the build status
[02:53:46.909] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:53:46.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:19:0) after 1m0s
[02:54:47.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:19:0)
[02:54:47.179] Timer tick, asking Buildbucket for the build status
[02:54:47.581] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:54:47.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:20:0) after 1m0s
[02:55:47.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:20:0)
[02:55:47.946] Timer tick, asking Buildbucket for the build status
[02:55:48.271] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:55:48.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:21:0) after 1m0s
[02:56:48.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:21:0)
[02:56:48.325] Timer tick, asking Buildbucket for the build status
[02:56:48.594] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:56:48.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:22:0) after 1m0s
[02:57:48.707] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:22:0)
[02:57:48.707] Timer tick, asking Buildbucket for the build status
[02:57:48.862] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:57:48.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:23:0) after 1m0s
[02:58:48.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:23:0)
[02:58:48.874] Timer tick, asking Buildbucket for the build status
[02:58:49.080] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:58:49.080] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:24:0) after 1m0s
[02:59:49.100] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:24:0)
[02:59:49.100] Timer tick, asking Buildbucket for the build status
[02:59:49.181] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:59:49.181] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:25:0) after 1m0s
[03:00:49.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:25:0)
[03:00:49.378] Timer tick, asking Buildbucket for the build status
[03:00:49.817] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:00:49.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:26:0) after 1m0s
[03:01:50.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:26:0)
[03:01:50.107] Timer tick, asking Buildbucket for the build status
[03:01:50.521] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:01:50.521] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:27:0) after 1m0s
[03:02:50.787] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:27:0)
[03:02:50.787] Timer tick, asking Buildbucket for the build status
[03:02:51.315] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:02:51.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:28:0) after 1m0s
[03:03:51.467] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:28:0)
[03:03:51.467] Timer tick, asking Buildbucket for the build status
[03:03:52.400] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:03:52.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:29:0) after 1m0s
[03:04:52.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:29:0)
[03:04:52.458] Timer tick, asking Buildbucket for the build status
[03:04:53.126] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:04:53.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:30:0) after 1m0s
[03:05:53.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:30:0)
[03:05:53.183] Timer tick, asking Buildbucket for the build status
[03:05:53.930] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:05:53.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:31:0) after 1m0s
[03:06:53.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:31:0)
[03:06:53.985] Timer tick, asking Buildbucket for the build status
[03:06:56.171] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:06:56.171] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:32:0) after 1m0s
[03:07:56.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:32:0)
[03:07:56.219] Timer tick, asking Buildbucket for the build status
[03:07:57.264] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:07:57.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:33:0) after 1m0s
[03:08:57.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:33:0)
[03:08:57.373] Timer tick, asking Buildbucket for the build status
[03:08:58.470] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:08:58.470] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:34:0) after 1m0s
[03:09:58.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:34:0)
[03:09:58.546] Timer tick, asking Buildbucket for the build status
[03:09:58.733] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:09:58.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:35:0) after 1m0s
[03:10:58.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:35:0)
[03:10:58.814] Timer tick, asking Buildbucket for the build status
[03:10:58.925] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:10:58.925] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:36:0) after 1m0s
[03:11:58.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:36:0)
[03:11:58.986] Timer tick, asking Buildbucket for the build status
[03:11:59.200] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:11:59.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:37:0) after 1m0s
[03:12:59.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:37:0)
[03:12:59.234] Timer tick, asking Buildbucket for the build status
[03:12:59.421] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:12:59.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:38:0) after 1m0s
[03:13:59.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:38:0)
[03:13:59.493] Timer tick, asking Buildbucket for the build status
[03:13:59.671] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:13:59.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:39:0) after 1m0s
[03:14:59.771] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:39:0)
[03:14:59.771] Timer tick, asking Buildbucket for the build status
[03:14:59.885] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:14:59.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:40:0) after 1m0s
[03:16:00.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:40:0)
[03:16:00.038] Timer tick, asking Buildbucket for the build status
[03:16:00.356] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:16:00.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:41:0) after 1m0s
[03:17:00.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:41:0)
[03:17:00.676] Timer tick, asking Buildbucket for the build status
[03:17:00.774] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:17:00.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:42:0) after 1m0s
[03:18:01.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:42:0)
[03:18:01.013] Timer tick, asking Buildbucket for the build status
[03:18:01.668] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:18:01.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:43:0) after 1m0s
[03:19:01.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:43:0)
[03:19:01.728] Timer tick, asking Buildbucket for the build status
[03:19:02.851] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:19:02.851] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:44:0) after 1m0s
[03:20:02.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:44:0)
[03:20:02.986] Timer tick, asking Buildbucket for the build status
[03:20:03.198] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:20:03.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:45:0) after 1m0s
[03:21:03.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:45:0)
[03:21:03.277] Timer tick, asking Buildbucket for the build status
[03:21:03.624] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:21:03.624] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:46:0) after 1m0s
[03:22:03.681] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:46:0)
[03:22:03.681] Timer tick, asking Buildbucket for the build status
[03:22:04.397] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:22:04.397] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:47:0) after 1m0s
[03:23:04.456] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:47:0)
[03:23:04.456] Timer tick, asking Buildbucket for the build status
[03:23:05.218] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:23:05.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:48:0) after 1m0s
[03:24:05.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:48:0)
[03:24:05.274] Timer tick, asking Buildbucket for the build status
[03:24:05.623] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:24:05.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:49:0) after 1m0s
[03:25:05.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:49:0)
[03:25:05.743] Timer tick, asking Buildbucket for the build status
[03:25:05.895] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:25:05.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:50:0) after 1m0s
[03:26:05.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:50:0)
[03:26:05.950] Timer tick, asking Buildbucket for the build status
[03:26:06.398] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:26:06.398] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:51:0) after 1m0s
[03:27:06.577] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:51:0)
[03:27:06.577] Timer tick, asking Buildbucket for the build status
[03:27:06.814] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:27:06.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:52:0) after 1m0s
[03:28:06.980] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:52:0)
[03:28:06.981] Timer tick, asking Buildbucket for the build status
[03:28:07.264] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:28:07.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:53:0) after 1m0s
[03:29:07.315] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:53:0)
[03:29:07.315] Timer tick, asking Buildbucket for the build status
[03:29:08.492] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:29:08.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:54:0) after 1m0s
[03:30:08.525] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:54:0)
[03:30:08.525] Timer tick, asking Buildbucket for the build status
[03:30:08.804] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:08.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:55:0) after 1m0s
[03:31:09.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:55:0)
[03:31:09.009] Timer tick, asking Buildbucket for the build status
[03:31:09.150] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:31:09.150] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:56:0) after 1m0s
[03:32:09.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:56:0)
[03:32:09.172] Timer tick, asking Buildbucket for the build status
[03:32:09.469] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:32:09.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:57:0) after 1m0s
[03:33:09.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:57:0)
[03:33:09.556] Timer tick, asking Buildbucket for the build status
[03:33:09.805] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:33:09.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:58:0) after 1m0s
[03:34:09.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:58:0)
[03:34:09.854] Timer tick, asking Buildbucket for the build status
[03:34:10.302] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:34:10.302] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:59:0) after 1m0s
[03:35:10.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:59:0)
[03:35:10.322] Timer tick, asking Buildbucket for the build status
[03:35:11.052] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:35:11.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:60:0) after 1m0s
[03:36:11.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:60:0)
[03:36:11.141] Timer tick, asking Buildbucket for the build status
[03:36:11.582] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:36:11.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:61:0) after 1m0s
[03:37:11.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:61:0)
[03:37:11.592] Timer tick, asking Buildbucket for the build status
[03:37:11.813] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:37:11.813] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:62:0) after 1m0s
[03:38:11.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:62:0)
[03:38:11.924] Timer tick, asking Buildbucket for the build status
[03:38:12.127] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:38:12.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:63:0) after 1m0s
[03:39:12.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:63:0)
[03:39:12.266] Timer tick, asking Buildbucket for the build status
[03:39:12.425] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:12.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:64:0) after 1m0s
[03:40:12.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:64:0)
[03:40:12.461] Timer tick, asking Buildbucket for the build status
[03:40:12.767] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:40:12.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:65:0) after 1m0s
[03:41:12.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:65:0)
[03:41:12.926] Timer tick, asking Buildbucket for the build status
[03:41:13.032] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:13.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:66:0) after 1m0s
[03:42:13.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:66:0)
[03:42:13.194] Timer tick, asking Buildbucket for the build status
[03:42:13.364] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:13.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:67:0) after 1m0s
[03:43:13.395] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:67:0)
[03:43:13.395] Timer tick, asking Buildbucket for the build status
[03:43:13.508] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:13.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:68:0) after 1m0s
[03:44:13.625] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:68:0)
[03:44:13.625] Timer tick, asking Buildbucket for the build status
[03:44:13.923] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:44:13.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:69:0) after 1m0s
[03:45:14.110] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:69:0)
[03:45:14.110] Timer tick, asking Buildbucket for the build status
[03:45:14.260] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:45:14.260] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:70:0) after 1m0s
[03:46:14.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:70:0)
[03:46:14.461] Timer tick, asking Buildbucket for the build status
[03:46:14.720] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:46:14.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:71:0) after 1m0s
[03:47:14.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:71:0)
[03:47:14.799] Timer tick, asking Buildbucket for the build status
[03:47:14.954] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:47:14.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:72:0) after 1m0s
[03:48:15.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:72:0)
[03:48:15.133] Timer tick, asking Buildbucket for the build status
[03:48:15.385] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:48:15.385] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:73:0) after 1m0s
[03:49:15.539] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:73:0)
[03:49:15.539] Timer tick, asking Buildbucket for the build status
[03:49:16.430] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:49:16.430] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:74:0) after 1m0s
[03:50:16.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:74:0)
[03:50:16.831] Timer tick, asking Buildbucket for the build status
[03:50:16.995] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:50:16.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:75:0) after 1m0s
[03:51:17.067] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:75:0)
[03:51:17.067] Timer tick, asking Buildbucket for the build status
[03:51:17.988] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:51:17.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:76:0) after 1m0s
[03:52:18.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:76:0)
[03:52:18.011] Timer tick, asking Buildbucket for the build status
[03:52:18.096] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:52:18.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:77:0) after 1m0s
[03:53:18.116] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:77:0)
[03:53:18.116] Timer tick, asking Buildbucket for the build status
[03:53:18.442] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:53:18.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:78:0) after 1m0s
[03:54:18.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:78:0)
[03:54:18.618] Timer tick, asking Buildbucket for the build status
[03:54:18.794] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:54:18.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:79:0) after 1m0s
[03:55:18.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:79:0)
[03:55:18.923] Timer tick, asking Buildbucket for the build status
[03:55:19.028] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:55:19.028] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:80:0) after 1m0s
[03:56:19.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:80:0)
[03:56:19.383] Timer tick, asking Buildbucket for the build status
[03:56:19.656] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:56:19.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:81:0) after 1m0s
[03:57:19.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:81:0)
[03:57:19.684] Timer tick, asking Buildbucket for the build status
[03:57:21.114] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:57:21.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:82:0) after 1m0s
[03:58:21.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:82:0)
[03:58:21.191] Timer tick, asking Buildbucket for the build status
[03:58:21.386] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:58:21.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:83:0) after 1m0s
[03:59:21.413] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:83:0)
[03:59:21.413] Timer tick, asking Buildbucket for the build status
[03:59:21.672] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:59:21.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:84:0) after 1m0s
[04:00:21.759] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:84:0)
[04:00:21.759] Timer tick, asking Buildbucket for the build status
[04:00:22.151] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:00:22.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:85:0) after 1m0s
[04:01:22.190] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:85:0)
[04:01:22.190] Timer tick, asking Buildbucket for the build status
[04:01:22.398] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:01:22.398] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:86:0) after 1m0s
[04:02:22.480] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:86:0)
[04:02:22.480] Timer tick, asking Buildbucket for the build status
[04:02:22.695] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:02:22.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:87:0) after 1m0s
[04:03:22.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:87:0)
[04:03:22.744] Timer tick, asking Buildbucket for the build status
[04:03:22.850] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:03:22.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:88:0) after 1m0s
[04:04:22.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:88:0)
[04:04:22.969] Timer tick, asking Buildbucket for the build status
[04:04:23.071] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:04:23.071] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:89:0) after 1m0s
[04:05:23.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:89:0)
[04:05:23.219] Timer tick, asking Buildbucket for the build status
[04:05:23.888] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:05:23.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:90:0) after 1m0s
[04:06:23.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:90:0)
[04:06:23.882] Timer tick, asking Buildbucket for the build status
[04:06:23.974] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:06:23.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:91:0) after 1m0s
[04:07:24.030] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:91:0)
[04:07:24.030] Timer tick, asking Buildbucket for the build status
[04:07:24.843] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:07:24.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:92:0) after 1m0s
[04:08:24.823] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:92:0)
[04:08:24.823] Timer tick, asking Buildbucket for the build status
[04:08:25.003] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:08:25.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:93:0) after 1m0s
[04:09:25.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:93:0)
[04:09:25.027] Timer tick, asking Buildbucket for the build status
[04:09:25.574] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:09:25.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:94:0) after 1m0s
[04:10:25.566] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:94:0)
[04:10:25.566] Timer tick, asking Buildbucket for the build status
[04:10:25.726] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:10:25.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:95:0) after 1m0s
[04:11:25.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:95:0)
[04:11:25.756] Timer tick, asking Buildbucket for the build status
[04:11:25.946] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:11:25.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:96:0) after 1m0s
[04:12:25.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:96:0)
[04:12:25.978] Timer tick, asking Buildbucket for the build status
[04:12:26.236] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:12:26.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:97:0) after 1m0s
[04:13:26.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:97:0)
[04:13:26.316] Timer tick, asking Buildbucket for the build status
[04:13:26.440] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:13:26.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:98:0) after 1m0s
[04:14:26.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:98:0)
[04:14:26.588] Timer tick, asking Buildbucket for the build status
[04:14:26.702] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:14:26.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:99:0) after 1m0s
[04:15:26.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:99:0)
[04:15:26.919] Timer tick, asking Buildbucket for the build status
[04:15:27.062] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:15:27.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:100:0) after 1m0s
[04:16:27.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:100:0)
[04:16:27.532] Timer tick, asking Buildbucket for the build status
[04:16:27.796] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:16:27.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:101:0) after 1m0s
[04:17:27.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:101:0)
[04:17:27.846] Timer tick, asking Buildbucket for the build status
[04:17:28.141] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:17:28.141] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:102:0) after 1m0s
[04:18:28.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:102:0)
[04:18:28.198] Timer tick, asking Buildbucket for the build status
[04:18:28.621] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:18:28.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:103:0) after 1m0s
[04:19:28.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:103:0)
[04:19:28.669] Timer tick, asking Buildbucket for the build status
[04:19:28.951] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:19:28.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:104:0) after 1m0s
[04:20:29.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:104:0)
[04:20:29.136] Timer tick, asking Buildbucket for the build status
[04:20:29.556] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:20:29.556] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:105:0) after 1m0s
[04:21:29.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:105:0)
[04:21:29.623] Timer tick, asking Buildbucket for the build status
[04:21:29.944] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:21:29.944] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:106:0) after 1m0s
[04:22:30.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:106:0)
[04:22:30.154] Timer tick, asking Buildbucket for the build status
[04:22:30.698] Build 8893679826138660240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:22:30.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:107:0) after 1m0s
[04:23:30.267] Received PubSub notification, asking Buildbucket for the build status
[04:23:30.618] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:30.808] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:107:0)
[04:23:30.808] Timer tick, asking Buildbucket for the build status
[04:23:31.108] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:31.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:109:0) after 1m0s
[04:24:31.273] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:109:0)
[04:24:31.273] Timer tick, asking Buildbucket for the build status
[04:24:31.449] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:31.449] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:110:0) after 1m0s
[04:25:31.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:110:0)
[04:25:31.602] Timer tick, asking Buildbucket for the build status
[04:25:31.823] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:31.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:111:0) after 1m0s
[04:26:31.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:111:0)
[04:26:31.963] Timer tick, asking Buildbucket for the build status
[04:26:32.490] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:32.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:112:0) after 1m0s
[04:27:32.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:112:0)
[04:27:32.582] Timer tick, asking Buildbucket for the build status
[04:27:32.736] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:32.736] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:113:0) after 1m0s
[04:28:32.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:113:0)
[04:28:32.862] Timer tick, asking Buildbucket for the build status
[04:28:33.141] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:33.141] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:114:0) after 1m0s
[04:29:33.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:114:0)
[04:29:33.156] Timer tick, asking Buildbucket for the build status
[04:29:33.254] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:33.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:115:0) after 1m0s
[04:30:33.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:115:0)
[04:30:33.291] Timer tick, asking Buildbucket for the build status
[04:30:34.027] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:34.027] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:116:0) after 1m0s
[04:31:34.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:116:0)
[04:31:34.136] Timer tick, asking Buildbucket for the build status
[04:31:34.322] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:34.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:117:0) after 1m0s
[04:32:34.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:117:0)
[04:32:34.373] Timer tick, asking Buildbucket for the build status
[04:32:34.851] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:34.851] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:118:0) after 1m0s
[04:33:34.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:118:0)
[04:33:34.914] Timer tick, asking Buildbucket for the build status
[04:33:35.095] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:35.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:119:0) after 1m0s
[04:34:35.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:119:0)
[04:34:35.135] Timer tick, asking Buildbucket for the build status
[04:34:35.276] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:35.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:120:0) after 1m0s
[04:35:35.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:120:0)
[04:35:35.384] Timer tick, asking Buildbucket for the build status
[04:35:35.487] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:35.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:121:0) after 1m0s
[04:36:35.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:121:0)
[04:36:35.558] Timer tick, asking Buildbucket for the build status
[04:36:36.048] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:36.048] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:122:0) after 1m0s
[04:37:36.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:122:0)
[04:37:36.088] Timer tick, asking Buildbucket for the build status
[04:37:36.283] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:36.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:123:0) after 1m0s
[04:38:36.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:123:0)
[04:38:36.385] Timer tick, asking Buildbucket for the build status
[04:38:36.869] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:36.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:124:0) after 1m0s
[04:39:36.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:124:0)
[04:39:36.918] Timer tick, asking Buildbucket for the build status
[04:39:37.841] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:37.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:125:0) after 1m0s
[04:40:37.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:125:0)
[04:40:37.932] Timer tick, asking Buildbucket for the build status
[04:40:38.360] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:38.360] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:126:0) after 1m0s
[04:41:38.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:126:0)
[04:41:38.428] Timer tick, asking Buildbucket for the build status
[04:41:38.541] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:38.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:127:0) after 1m0s
[04:42:38.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:127:0)
[04:42:38.657] Timer tick, asking Buildbucket for the build status
[04:42:38.794] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:38.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:128:0) after 1m0s
[04:43:38.826] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:128:0)
[04:43:38.826] Timer tick, asking Buildbucket for the build status
[04:43:39.347] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:39.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:129:0) after 1m0s
[04:44:39.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:129:0)
[04:44:39.400] Timer tick, asking Buildbucket for the build status
[04:44:39.556] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:39.556] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:130:0) after 1m0s
[04:45:39.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:130:0)
[04:45:39.655] Timer tick, asking Buildbucket for the build status
[04:45:39.959] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:39.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:131:0) after 1m0s
[04:46:39.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:131:0)
[04:46:39.957] Timer tick, asking Buildbucket for the build status
[04:46:40.320] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:40.320] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:132:0) after 1m0s
[04:47:40.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:132:0)
[04:47:40.358] Timer tick, asking Buildbucket for the build status
[04:47:40.429] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:40.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:133:0) after 1m0s
[04:48:40.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:133:0)
[04:48:40.457] Timer tick, asking Buildbucket for the build status
[04:48:40.586] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:40.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:134:0) after 1m0s
[04:49:40.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:134:0)
[04:49:40.631] Timer tick, asking Buildbucket for the build status
[04:49:40.781] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:40.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:135:0) after 1m0s
[04:50:40.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:135:0)
[04:50:40.825] Timer tick, asking Buildbucket for the build status
[04:50:41.090] Build 8893679826138660240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:41.090] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9059109888435543632:136:0) after 1m0s
[04:51:27.339] Received PubSub notification, asking Buildbucket for the build status
[04:51:27.539] Build 8893679826138660240: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:51:27.539] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1576731085529629",
"created_by": "project:infra",
"created_ts": "1576722993679746",
"failure_reason": "BUILD_FAILURE",
"id": "8893679826138660240",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"81.0.4000.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"300c3fac-1e88-452c-b3eb-3c0c49af9763\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"81.0.4000.3\"}}",
"project": "infra",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('Bootstrap gn.') (retcode: 1)\"}, \"properties\": {\"$gatekeeper\": {\"group\": \"chromium.infra.cron\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"81.0.4000.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"300c3fac-1e88-452c-b3eb-3c0c49af9763\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-qu4e\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"81.0.4000.3\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-qu4e\"], \"image\": [\"chrome-xenial-19121500-b03549ec744\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4733-6bd8c41\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('Bootstrap gn.') (retcode: 1)\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576729409245215",
"status": "COMPLETED",
"status_changed_ts": "1576729409769669",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9059109888435543632",
"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/8893679826138660240/+/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:4934d58474383510",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1576731086778279",
"url": "https://ci.chromium.org/b/8893679826138660240",
"utcnow_ts": "1576731087523773"
}
}
[04:51:27.540] Invocation finished in 2h14m55.434104564s with status FAILED