[11:41:08.329] New invocation is queued and will start shortly
[11:41:08.329] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[11:41:09.401] Starting the invocation (attempt 1)
[11:41:09.448] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:41:09.448] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:41:09.448] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9085529939138708656",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"74.0.3721.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:9085529939138708656",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[11:41:10.307] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551526869546590",
"id": "8920099877197297424",
"lease_expiration_ts": "1551645670057852",
"lease_key": "643328793",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3721.3\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551526870058659",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9085529939138708656",
"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/8920099877197297424/+/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:435706c347295110",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1551526870058773",
"url": "https://ci.chromium.org/b/8920099877197297424",
"utcnow_ts": "1551526870265454"
}
}
[11:41:10.307] Task URL: https://ci.chromium.org/b/8920099877197297424
[11:41:10.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:2:0) after 1m0s
[11:41:37.306] Received PubSub notification, asking Buildbucket for the build status
[11:41:37.483] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:10.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:2:0)
[11:42:10.327] Timer tick, asking Buildbucket for the build status
[11:42:10.514] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:10.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:4:0) after 1m0s
[11:43:10.531] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:4:0)
[11:43:10.531] Timer tick, asking Buildbucket for the build status
[11:43:10.607] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:10.607] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:5:0) after 1m0s
[11:44:10.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:5:0)
[11:44:10.626] Timer tick, asking Buildbucket for the build status
[11:44:10.722] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:10.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:6:0) after 1m0s
[11:45:10.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:6:0)
[11:45:10.744] Timer tick, asking Buildbucket for the build status
[11:45:10.786] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:10.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:7:0) after 1m0s
[11:46:10.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:7:0)
[11:46:10.802] Timer tick, asking Buildbucket for the build status
[11:46:10.861] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:10.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:8:0) after 1m0s
[11:47:10.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:8:0)
[11:47:10.831] Timer tick, asking Buildbucket for the build status
[11:47:10.900] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:10.900] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:9:0) after 1m0s
[11:48:10.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:9:0)
[11:48:10.883] Timer tick, asking Buildbucket for the build status
[11:48:11.010] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:11.010] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:10:0) after 1m0s
[11:49:11.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:10:0)
[11:49:11.029] Timer tick, asking Buildbucket for the build status
[11:49:11.087] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:11.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:11:0) after 1m0s
[11:50:11.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:11:0)
[11:50:11.108] Timer tick, asking Buildbucket for the build status
[11:50:11.174] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:11.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:12:0) after 1m0s
[11:51:11.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:12:0)
[11:51:11.167] Timer tick, asking Buildbucket for the build status
[11:51:11.224] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:11.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:13:0) after 1m0s
[11:52:11.250] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:13:0)
[11:52:11.250] Timer tick, asking Buildbucket for the build status
[11:52:11.296] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:11.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:14:0) after 1m0s
[11:53:11.300] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:14:0)
[11:53:11.300] Timer tick, asking Buildbucket for the build status
[11:53:11.341] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:11.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:15:0) after 1m0s
[11:54:11.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:15:0)
[11:54:11.359] Timer tick, asking Buildbucket for the build status
[11:54:11.574] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:11.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:16:0) after 1m0s
[11:55:11.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:16:0)
[11:55:11.579] Timer tick, asking Buildbucket for the build status
[11:55:11.749] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:11.749] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:17:0) after 1m0s
[11:56:11.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:17:0)
[11:56:11.770] Timer tick, asking Buildbucket for the build status
[11:56:11.823] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:11.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:18:0) after 1m0s
[11:57:11.839] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:18:0)
[11:57:11.839] Timer tick, asking Buildbucket for the build status
[11:57:13.375] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:13.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:19:0) after 1m0s
[11:58:13.395] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:19:0)
[11:58:13.395] Timer tick, asking Buildbucket for the build status
[11:58:13.476] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:13.476] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:20:0) after 1m0s
[11:59:13.495] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:20:0)
[11:59:13.495] Timer tick, asking Buildbucket for the build status
[11:59:13.646] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:13.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:21:0) after 1m0s
[12:00:13.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:21:0)
[12:00:13.671] Timer tick, asking Buildbucket for the build status
[12:00:13.750] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:13.750] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:22:0) after 1m0s
[12:01:13.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:22:0)
[12:01:13.790] Timer tick, asking Buildbucket for the build status
[12:01:13.901] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:13.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:23:0) after 1m0s
[12:02:13.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:23:0)
[12:02:13.929] Timer tick, asking Buildbucket for the build status
[12:02:14.003] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:14.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:24:0) after 1m0s
[12:03:14.012] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:24:0)
[12:03:14.012] Timer tick, asking Buildbucket for the build status
[12:03:14.114] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:14.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:25:0) after 1m0s
[12:04:14.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:25:0)
[12:04:14.133] Timer tick, asking Buildbucket for the build status
[12:04:14.342] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:14.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:26:0) after 1m0s
[12:05:14.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:26:0)
[12:05:14.343] Timer tick, asking Buildbucket for the build status
[12:05:14.546] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:14.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:27:0) after 1m0s
[12:06:14.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:27:0)
[12:06:14.529] Timer tick, asking Buildbucket for the build status
[12:06:14.750] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:14.750] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:28:0) after 1m0s
[12:07:14.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:28:0)
[12:07:14.863] Timer tick, asking Buildbucket for the build status
[12:07:14.905] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:14.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:29:0) after 1m0s
[12:08:14.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:29:0)
[12:08:14.924] Timer tick, asking Buildbucket for the build status
[12:08:15.069] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:15.069] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:30:0) after 1m0s
[12:09:15.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:30:0)
[12:09:15.107] Timer tick, asking Buildbucket for the build status
[12:09:15.264] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:15.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:31:0) after 1m0s
[12:10:15.285] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:31:0)
[12:10:15.285] Timer tick, asking Buildbucket for the build status
[12:10:15.344] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:15.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:32:0) after 1m0s
[12:11:15.368] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:32:0)
[12:11:15.368] Timer tick, asking Buildbucket for the build status
[12:11:15.582] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:15.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:33:0) after 1m0s
[12:12:15.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:33:0)
[12:12:15.603] Timer tick, asking Buildbucket for the build status
[12:12:15.686] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:15.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:34:0) after 1m0s
[12:13:15.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:34:0)
[12:13:15.701] Timer tick, asking Buildbucket for the build status
[12:13:15.762] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:15.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:35:0) after 1m0s
[12:14:15.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:35:0)
[12:14:15.769] Timer tick, asking Buildbucket for the build status
[12:14:15.819] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:15.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:36:0) after 1m0s
[12:15:15.823] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:36:0)
[12:15:15.823] Timer tick, asking Buildbucket for the build status
[12:15:15.882] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:15.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:37:0) after 1m0s
[12:16:15.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:37:0)
[12:16:15.875] Timer tick, asking Buildbucket for the build status
[12:16:15.948] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:15.948] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:38:0) after 1m0s
[12:17:15.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:38:0)
[12:17:15.951] Timer tick, asking Buildbucket for the build status
[12:17:16.066] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:16.066] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:39:0) after 1m0s
[12:18:16.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:39:0)
[12:18:16.066] Timer tick, asking Buildbucket for the build status
[12:18:16.139] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:16.139] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:40:0) after 1m0s
[12:19:16.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:40:0)
[12:19:16.118] Timer tick, asking Buildbucket for the build status
[12:19:16.208] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:16.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:41:0) after 1m0s
[12:20:16.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:41:0)
[12:20:16.225] Timer tick, asking Buildbucket for the build status
[12:20:16.310] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:16.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:42:0) after 1m0s
[12:21:16.328] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:42:0)
[12:21:16.328] Timer tick, asking Buildbucket for the build status
[12:21:16.396] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:16.396] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:43:0) after 1m0s
[12:22:16.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:43:0)
[12:22:16.416] Timer tick, asking Buildbucket for the build status
[12:22:16.473] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:16.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:44:0) after 1m0s
[12:23:16.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:44:0)
[12:23:16.491] Timer tick, asking Buildbucket for the build status
[12:23:16.688] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:16.688] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:45:0) after 1m0s
[12:24:16.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:45:0)
[12:24:16.701] Timer tick, asking Buildbucket for the build status
[12:24:16.911] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:16.911] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:46:0) after 1m0s
[12:25:16.930] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:46:0)
[12:25:16.930] Timer tick, asking Buildbucket for the build status
[12:25:16.992] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:16.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:47:0) after 1m0s
[12:26:17.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:47:0)
[12:26:17.001] Timer tick, asking Buildbucket for the build status
[12:26:17.168] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:17.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:48:0) after 1m0s
[12:27:17.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:48:0)
[12:27:17.189] Timer tick, asking Buildbucket for the build status
[12:27:17.261] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:17.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:49:0) after 1m0s
[12:28:17.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:49:0)
[12:28:17.283] Timer tick, asking Buildbucket for the build status
[12:28:17.329] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:17.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:50:0) after 1m0s
[12:29:17.351] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:50:0)
[12:29:17.351] Timer tick, asking Buildbucket for the build status
[12:29:17.450] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:17.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:51:0) after 1m0s
[12:30:17.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:51:0)
[12:30:17.466] Timer tick, asking Buildbucket for the build status
[12:30:17.701] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:17.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:52:0) after 1m0s
[12:31:17.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:52:0)
[12:31:17.683] Timer tick, asking Buildbucket for the build status
[12:31:17.760] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:17.760] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:53:0) after 1m0s
[12:32:17.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:53:0)
[12:32:17.854] Timer tick, asking Buildbucket for the build status
[12:32:17.909] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:17.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:54:0) after 1m0s
[12:33:17.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:54:0)
[12:33:17.930] Timer tick, asking Buildbucket for the build status
[12:33:18.140] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:18.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:55:0) after 1m0s
[12:34:18.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:55:0)
[12:34:18.141] Timer tick, asking Buildbucket for the build status
[12:34:18.288] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:18.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:56:0) after 1m0s
[12:35:18.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:56:0)
[12:35:18.327] Timer tick, asking Buildbucket for the build status
[12:35:18.419] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:18.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:57:0) after 1m0s
[12:36:18.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:57:0)
[12:36:18.442] Timer tick, asking Buildbucket for the build status
[12:36:18.530] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:18.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:58:0) after 1m0s
[12:37:18.548] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:58:0)
[12:37:18.548] Timer tick, asking Buildbucket for the build status
[12:37:18.676] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:18.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:59:0) after 1m0s
[12:38:18.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:59:0)
[12:38:18.692] Timer tick, asking Buildbucket for the build status
[12:38:18.765] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:18.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:60:0) after 1m0s
[12:39:18.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:60:0)
[12:39:18.803] Timer tick, asking Buildbucket for the build status
[12:39:18.967] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:18.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:61:0) after 1m0s
[12:40:18.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:61:0)
[12:40:18.982] Timer tick, asking Buildbucket for the build status
[12:40:19.085] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:19.086] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:62:0) after 1m0s
[12:41:19.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:62:0)
[12:41:19.112] Timer tick, asking Buildbucket for the build status
[12:41:19.378] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:19.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:63:0) after 1m0s
[12:42:19.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:63:0)
[12:42:19.400] Timer tick, asking Buildbucket for the build status
[12:42:19.438] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:19.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:64:0) after 1m0s
[12:43:19.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:64:0)
[12:43:19.408] Timer tick, asking Buildbucket for the build status
[12:43:19.452] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:19.452] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:65:0) after 1m0s
[12:44:19.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:65:0)
[12:44:19.446] Timer tick, asking Buildbucket for the build status
[12:44:19.491] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:19.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:66:0) after 1m0s
[12:45:19.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:66:0)
[12:45:19.515] Timer tick, asking Buildbucket for the build status
[12:45:19.579] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:19.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:67:0) after 1m0s
[12:46:19.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:67:0)
[12:46:19.584] Timer tick, asking Buildbucket for the build status
[12:46:19.648] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:19.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:68:0) after 1m0s
[12:47:19.649] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:68:0)
[12:47:19.649] Timer tick, asking Buildbucket for the build status
[12:47:19.783] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:19.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:69:0) after 1m0s
[12:48:19.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:69:0)
[12:48:19.804] Timer tick, asking Buildbucket for the build status
[12:48:19.866] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:19.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:70:0) after 1m0s
[12:49:19.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:70:0)
[12:49:19.888] Timer tick, asking Buildbucket for the build status
[12:49:20.104] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:20.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:71:0) after 1m0s
[12:50:20.122] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:71:0)
[12:50:20.122] Timer tick, asking Buildbucket for the build status
[12:50:20.202] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:20.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:72:0) after 1m0s
[12:51:20.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:72:0)
[12:51:20.222] Timer tick, asking Buildbucket for the build status
[12:51:20.274] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:20.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:73:0) after 1m0s
[12:52:20.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:73:0)
[12:52:20.293] Timer tick, asking Buildbucket for the build status
[12:52:20.489] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:20.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:74:0) after 1m0s
[12:53:20.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:74:0)
[12:53:20.510] Timer tick, asking Buildbucket for the build status
[12:53:20.556] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:20.556] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:75:0) after 1m0s
[12:54:20.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:75:0)
[12:54:20.586] Timer tick, asking Buildbucket for the build status
[12:54:20.626] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:20.626] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:76:0) after 1m0s
[12:55:20.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:76:0)
[12:55:20.604] Timer tick, asking Buildbucket for the build status
[12:55:20.802] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:20.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:77:0) after 1m0s
[12:56:20.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:77:0)
[12:56:20.798] Timer tick, asking Buildbucket for the build status
[12:56:20.858] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:20.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:78:0) after 1m0s
[12:57:20.879] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:78:0)
[12:57:20.879] Timer tick, asking Buildbucket for the build status
[12:57:20.944] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:20.944] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:79:0) after 1m0s
[12:58:20.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:79:0)
[12:58:20.962] Timer tick, asking Buildbucket for the build status
[12:58:21.040] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:21.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:80:0) after 1m0s
[12:59:21.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:80:0)
[12:59:21.064] Timer tick, asking Buildbucket for the build status
[12:59:21.148] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:21.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:81:0) after 1m0s
[13:00:21.173] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:81:0)
[13:00:21.173] Timer tick, asking Buildbucket for the build status
[13:00:21.283] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:21.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:82:0) after 1m0s
[13:01:21.303] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:82:0)
[13:01:21.303] Timer tick, asking Buildbucket for the build status
[13:01:21.414] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:21.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:83:0) after 1m0s
[13:02:21.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:83:0)
[13:02:21.416] Timer tick, asking Buildbucket for the build status
[13:02:21.677] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:21.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:84:0) after 1m0s
[13:03:21.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:84:0)
[13:03:21.696] Timer tick, asking Buildbucket for the build status
[13:03:21.883] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:21.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:85:0) after 1m0s
[13:04:21.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:85:0)
[13:04:21.888] Timer tick, asking Buildbucket for the build status
[13:04:22.009] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:22.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:86:0) after 1m0s
[13:05:21.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:86:0)
[13:05:21.991] Timer tick, asking Buildbucket for the build status
[13:05:22.262] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:22.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:87:0) after 1m0s
[13:06:22.290] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:87:0)
[13:06:22.290] Timer tick, asking Buildbucket for the build status
[13:06:22.337] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:22.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:88:0) after 1m0s
[13:07:22.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:88:0)
[13:07:22.327] Timer tick, asking Buildbucket for the build status
[13:07:22.493] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:22.493] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:89:0) after 1m0s
[13:08:22.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:89:0)
[13:08:22.514] Timer tick, asking Buildbucket for the build status
[13:08:22.585] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:22.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:90:0) after 1m0s
[13:09:22.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:90:0)
[13:09:22.604] Timer tick, asking Buildbucket for the build status
[13:09:22.779] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:22.779] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:91:0) after 1m0s
[13:10:22.819] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:91:0)
[13:10:22.819] Timer tick, asking Buildbucket for the build status
[13:10:22.866] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:22.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:92:0) after 1m0s
[13:11:22.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:92:0)
[13:11:22.859] Timer tick, asking Buildbucket for the build status
[13:11:22.979] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:22.979] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:93:0) after 1m0s
[13:12:22.989] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:93:0)
[13:12:22.989] Timer tick, asking Buildbucket for the build status
[13:12:23.103] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:23.103] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:94:0) after 1m0s
[13:13:23.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:94:0)
[13:13:23.096] Timer tick, asking Buildbucket for the build status
[13:13:23.312] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:23.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:95:0) after 1m0s
[13:14:23.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:95:0)
[13:14:23.324] Timer tick, asking Buildbucket for the build status
[13:14:23.402] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:23.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:96:0) after 1m0s
[13:15:23.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:96:0)
[13:15:23.426] Timer tick, asking Buildbucket for the build status
[13:15:23.523] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:23.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:97:0) after 1m0s
[13:16:23.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:97:0)
[13:16:23.513] Timer tick, asking Buildbucket for the build status
[13:16:23.764] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:23.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:98:0) after 1m0s
[13:17:23.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:98:0)
[13:17:23.803] Timer tick, asking Buildbucket for the build status
[13:17:23.897] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:23.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:99:0) after 1m0s
[13:18:23.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:99:0)
[13:18:23.917] Timer tick, asking Buildbucket for the build status
[13:18:24.004] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:24.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:100:0) after 1m0s
[13:19:24.025] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:100:0)
[13:19:24.025] Timer tick, asking Buildbucket for the build status
[13:19:24.092] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:24.092] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:101:0) after 1m0s
[13:20:24.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:101:0)
[13:20:24.151] Timer tick, asking Buildbucket for the build status
[13:20:24.336] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:24.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:102:0) after 1m0s
[13:21:24.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:102:0)
[13:21:24.375] Timer tick, asking Buildbucket for the build status
[13:21:24.442] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:24.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:103:0) after 1m0s
[13:22:24.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:103:0)
[13:22:24.426] Timer tick, asking Buildbucket for the build status
[13:22:24.540] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:24.540] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:104:0) after 1m0s
[13:23:24.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:104:0)
[13:23:24.561] Timer tick, asking Buildbucket for the build status
[13:23:24.608] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:24.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:105:0) after 1m0s
[13:24:24.629] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:105:0)
[13:24:24.629] Timer tick, asking Buildbucket for the build status
[13:24:24.697] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:24.697] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:106:0) after 1m0s
[13:25:24.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:106:0)
[13:25:24.763] Timer tick, asking Buildbucket for the build status
[13:25:24.989] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:24.989] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:107:0) after 1m0s
[13:26:24.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:107:0)
[13:26:24.987] Timer tick, asking Buildbucket for the build status
[13:26:25.399] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:25.399] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:108:0) after 1m0s
[13:27:25.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:108:0)
[13:27:25.409] Timer tick, asking Buildbucket for the build status
[13:27:25.803] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:25.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:109:0) after 1m0s
[13:28:25.826] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:109:0)
[13:28:25.826] Timer tick, asking Buildbucket for the build status
[13:28:26.148] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:26.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:110:0) after 1m0s
[13:29:26.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:110:0)
[13:29:26.180] Timer tick, asking Buildbucket for the build status
[13:29:26.238] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:26.238] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:111:0) after 1m0s
[13:30:26.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:111:0)
[13:30:26.275] Timer tick, asking Buildbucket for the build status
[13:30:26.385] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:26.385] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:112:0) after 1m0s
[13:31:26.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:112:0)
[13:31:26.397] Timer tick, asking Buildbucket for the build status
[13:31:26.458] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:26.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:113:0) after 1m0s
[13:32:26.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:113:0)
[13:32:26.477] Timer tick, asking Buildbucket for the build status
[13:32:26.536] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:26.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:114:0) after 1m0s
[13:33:26.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:114:0)
[13:33:26.514] Timer tick, asking Buildbucket for the build status
[13:33:26.585] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:26.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:115:0) after 1m0s
[13:34:26.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:115:0)
[13:34:26.591] Timer tick, asking Buildbucket for the build status
[13:34:26.658] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:26.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:116:0) after 1m0s
[13:35:26.681] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:116:0)
[13:35:26.681] Timer tick, asking Buildbucket for the build status
[13:35:26.877] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:26.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:117:0) after 1m0s
[13:36:26.898] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:117:0)
[13:36:26.898] Timer tick, asking Buildbucket for the build status
[13:36:27.007] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:27.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:118:0) after 1m0s
[13:37:27.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:118:0)
[13:37:27.038] Timer tick, asking Buildbucket for the build status
[13:37:27.102] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:27.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:119:0) after 1m0s
[13:38:27.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:119:0)
[13:38:27.093] Timer tick, asking Buildbucket for the build status
[13:38:27.138] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:27.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:120:0) after 1m0s
[13:39:27.158] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:120:0)
[13:39:27.158] Timer tick, asking Buildbucket for the build status
[13:39:27.197] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:27.197] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:121:0) after 1m0s
[13:40:27.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:121:0)
[13:40:27.216] Timer tick, asking Buildbucket for the build status
[13:40:27.272] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:27.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:122:0) after 1m0s
[13:41:27.273] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:122:0)
[13:41:27.273] Timer tick, asking Buildbucket for the build status
[13:41:27.493] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:27.493] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:123:0) after 1m0s
[13:42:27.518] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:123:0)
[13:42:27.518] Timer tick, asking Buildbucket for the build status
[13:42:27.644] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:27.644] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:124:0) after 1m0s
[13:43:27.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:124:0)
[13:43:27.674] Timer tick, asking Buildbucket for the build status
[13:43:27.880] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:27.880] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:125:0) after 1m0s
[13:44:27.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:125:0)
[13:44:27.903] Timer tick, asking Buildbucket for the build status
[13:44:27.959] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:27.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:126:0) after 1m0s
[13:45:27.981] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:126:0)
[13:45:27.981] Timer tick, asking Buildbucket for the build status
[13:45:28.064] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:28.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:127:0) after 1m0s
[13:46:28.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:127:0)
[13:46:28.052] Timer tick, asking Buildbucket for the build status
[13:46:28.108] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:28.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:128:0) after 1m0s
[13:47:28.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:128:0)
[13:47:28.088] Timer tick, asking Buildbucket for the build status
[13:47:28.173] Build 8920099877197297424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:28.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085529939138708656:129:0) after 1m0s
[13:48:06.671] Received PubSub notification, asking Buildbucket for the build status
[13:48:06.707] Build 8920099877197297424: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:48:06.707] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1551534485991143",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551526869546590",
"id": "8920099877197297424",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3721.3\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-32\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Build From Tarball\"], \"kvm\": [\"1\"], \"id\": [\"gce-trusty-32-0258d129-us-east1-b-8v8p\"], \"server_version\": [\"4143-92e279d\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"32\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"pool\": [\"luci.infra.cron\"], \"caches\": [\"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildername\": \"Build From Tarball\", \"recipe\": \"build_from_tarball\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1551526869546590, \"tags\": [\"builder:Build From Tarball\", \"parent_buildername:publish_tarball\", \"scheduler_invocation_id:9085529939138708656\", \"scheduler_job_id:infra/Build From Tarball\", \"user_agent:luci-scheduler\", \"user_agent:recipe\"], \"bucket\": \"luci.infra.cron\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"infra\", \"id\": \"8920099877197297424\"}}, \"version\": \"74.0.3721.3\", \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Build From Tarball\", \"key\": \"builder\"}, {\"value\": \"publish_tarball\", \"key\": \"parent_buildername\"}, {\"value\": \"9085529939138708656\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"infra/Build From Tarball\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}, {\"value\": \"recipe\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"infra\", \"builder\": \"Build From Tarball\", \"bucket\": \"cron\"}, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"ca0db2d762966d4b32d49d86567d9d7697438caf\"}, \"recipe\": {\"name\": \"build_from_tarball\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/infra/infra\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8920099877197297424\", \"input\": {}, \"createTime\": \"2019-03-02T11:41:09.546590Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-32-0258d129-us-east1-b-8v8p\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551526896618168",
"status": "COMPLETED",
"status_changed_ts": "1551534486431874",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9085529939138708656",
"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/8920099877197297424/+/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:435706c347295110",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1551534486433131",
"url": "https://ci.chromium.org/b/8920099877197297424",
"utcnow_ts": "1551534486698547"
}
}
[13:48:06.707] Invocation finished in 2h6m58.396832129s with status SUCCEEDED