[10:56:04.549] New invocation is queued and will start shortly
[10:56:04.549] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[10:56:05.643] Starting the invocation (attempt 1)
[10:56:05.678] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:56:05.678] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:56:05.678] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9083992625827352912",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"75.0.3738.0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9083992625827352912",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[10:56:06.540] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "project:infra",
"created_ts": "1552992965806239",
"id": "8918562563845636240",
"lease_expiration_ts": "1553111766391570",
"lease_key": "219538204",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"75.0.3738.0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552992966393121",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9083992625827352912",
"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/8918562563845636240/+/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:43ae699dfb833610",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1552992966393213",
"url": "https://ci.chromium.org/b/8918562563845636240",
"utcnow_ts": "1552992966529049"
}
}
[10:56:06.540] Task URL: https://ci.chromium.org/b/8918562563845636240
[10:56:06.540] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:2:0) after 1m0s
[10:56:42.352] Received PubSub notification, asking Buildbucket for the build status
[10:56:42.411] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:06.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:2:0)
[10:57:06.514] Timer tick, asking Buildbucket for the build status
[10:57:06.588] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:06.588] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:4:0) after 1m0s
[10:58:06.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:4:0)
[10:58:06.573] Timer tick, asking Buildbucket for the build status
[10:58:06.659] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:06.659] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:5:0) after 1m0s
[10:59:06.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:5:0)
[10:59:06.668] Timer tick, asking Buildbucket for the build status
[10:59:06.745] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:06.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:6:0) after 1m0s
[11:00:06.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:6:0)
[11:00:06.763] Timer tick, asking Buildbucket for the build status
[11:00:06.851] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:06.851] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:7:0) after 1m0s
[11:01:06.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:7:0)
[11:01:06.874] Timer tick, asking Buildbucket for the build status
[11:01:06.913] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:06.913] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:8:0) after 1m0s
[11:02:06.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:8:0)
[11:02:06.934] Timer tick, asking Buildbucket for the build status
[11:02:07.028] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:07.028] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:9:0) after 1m0s
[11:03:07.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:9:0)
[11:03:07.062] Timer tick, asking Buildbucket for the build status
[11:03:07.327] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:07.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:10:0) after 1m0s
[11:04:07.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:10:0)
[11:04:07.348] Timer tick, asking Buildbucket for the build status
[11:04:07.389] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:07.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:11:0) after 1m0s
[11:05:07.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:11:0)
[11:05:07.370] Timer tick, asking Buildbucket for the build status
[11:05:07.433] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:07.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:12:0) after 1m0s
[11:06:07.453] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:12:0)
[11:06:07.453] Timer tick, asking Buildbucket for the build status
[11:06:07.510] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:07.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:13:0) after 1m0s
[11:07:07.525] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:13:0)
[11:07:07.525] Timer tick, asking Buildbucket for the build status
[11:07:07.600] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:07.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:14:0) after 1m0s
[11:08:07.620] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:14:0)
[11:08:07.620] Timer tick, asking Buildbucket for the build status
[11:08:07.777] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:07.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:15:0) after 1m0s
[11:09:07.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:15:0)
[11:09:07.775] Timer tick, asking Buildbucket for the build status
[11:09:07.829] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:07.829] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:16:0) after 1m0s
[11:10:07.847] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:16:0)
[11:10:07.847] Timer tick, asking Buildbucket for the build status
[11:10:07.891] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:07.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:17:0) after 1m0s
[11:11:07.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:17:0)
[11:11:07.876] Timer tick, asking Buildbucket for the build status
[11:11:07.932] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:07.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:18:0) after 1m0s
[11:12:07.951] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:18:0)
[11:12:07.951] Timer tick, asking Buildbucket for the build status
[11:12:07.991] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:07.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:19:0) after 1m0s
[11:13:08.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:19:0)
[11:13:08.008] Timer tick, asking Buildbucket for the build status
[11:13:08.065] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:08.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:20:0) after 1m0s
[11:14:08.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:20:0)
[11:14:08.065] Timer tick, asking Buildbucket for the build status
[11:14:08.110] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:08.110] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:21:0) after 1m0s
[11:15:08.103] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:21:0)
[11:15:08.103] Timer tick, asking Buildbucket for the build status
[11:15:08.298] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:08.298] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:22:0) after 1m0s
[11:16:08.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:22:0)
[11:16:08.281] Timer tick, asking Buildbucket for the build status
[11:16:08.575] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:08.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:23:0) after 1m0s
[11:17:08.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:23:0)
[11:17:08.593] Timer tick, asking Buildbucket for the build status
[11:17:09.061] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:09.061] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:24:0) after 1m0s
[11:18:09.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:24:0)
[11:18:09.046] Timer tick, asking Buildbucket for the build status
[11:18:09.099] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:09.099] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:25:0) after 1m0s
[11:19:09.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:25:0)
[11:19:09.104] Timer tick, asking Buildbucket for the build status
[11:19:09.185] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:09.185] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:26:0) after 1m0s
[11:20:09.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:26:0)
[11:20:09.199] Timer tick, asking Buildbucket for the build status
[11:20:09.402] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:09.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:27:0) after 1m0s
[11:21:09.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:27:0)
[11:21:09.436] Timer tick, asking Buildbucket for the build status
[11:21:09.501] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:09.501] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:28:0) after 1m0s
[11:22:09.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:28:0)
[11:22:09.519] Timer tick, asking Buildbucket for the build status
[11:22:09.557] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:09.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:29:0) after 1m0s
[11:23:09.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:29:0)
[11:23:09.595] Timer tick, asking Buildbucket for the build status
[11:23:09.669] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:09.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:30:0) after 1m0s
[11:24:09.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:30:0)
[11:24:09.689] Timer tick, asking Buildbucket for the build status
[11:24:09.738] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:09.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:31:0) after 1m0s
[11:25:09.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:31:0)
[11:25:09.774] Timer tick, asking Buildbucket for the build status
[11:25:09.834] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:09.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:32:0) after 1m0s
[11:26:09.856] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:32:0)
[11:26:09.856] Timer tick, asking Buildbucket for the build status
[11:26:09.902] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:09.902] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:33:0) after 1m0s
[11:27:09.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:33:0)
[11:27:09.881] Timer tick, asking Buildbucket for the build status
[11:27:09.941] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:09.941] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:34:0) after 1m0s
[11:28:09.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:34:0)
[11:28:09.962] Timer tick, asking Buildbucket for the build status
[11:28:10.004] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:10.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:35:0) after 1m0s
[11:29:10.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:35:0)
[11:29:10.013] Timer tick, asking Buildbucket for the build status
[11:29:10.100] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:10.100] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:36:0) after 1m0s
[11:30:10.120] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:36:0)
[11:30:10.120] Timer tick, asking Buildbucket for the build status
[11:30:10.259] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:10.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:37:0) after 1m0s
[11:31:10.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:37:0)
[11:31:10.276] Timer tick, asking Buildbucket for the build status
[11:31:10.331] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:10.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:38:0) after 1m0s
[11:32:10.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:38:0)
[11:32:10.333] Timer tick, asking Buildbucket for the build status
[11:32:10.790] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:10.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:39:0) after 1m0s
[11:33:10.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:39:0)
[11:33:10.775] Timer tick, asking Buildbucket for the build status
[11:33:11.021] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:11.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:40:0) after 1m0s
[11:34:11.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:40:0)
[11:34:11.059] Timer tick, asking Buildbucket for the build status
[11:34:11.263] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:11.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:41:0) after 1m0s
[11:35:11.295] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:41:0)
[11:35:11.296] Timer tick, asking Buildbucket for the build status
[11:35:11.364] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:11.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:42:0) after 1m0s
[11:36:11.357] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:42:0)
[11:36:11.357] Timer tick, asking Buildbucket for the build status
[11:36:11.422] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:11.422] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:43:0) after 1m0s
[11:37:11.418] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:43:0)
[11:37:11.418] Timer tick, asking Buildbucket for the build status
[11:37:11.459] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:11.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:44:0) after 1m0s
[11:38:11.480] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:44:0)
[11:38:11.480] Timer tick, asking Buildbucket for the build status
[11:38:11.540] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:11.540] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:45:0) after 1m0s
[11:39:11.527] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:45:0)
[11:39:11.527] Timer tick, asking Buildbucket for the build status
[11:39:11.591] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:11.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:46:0) after 1m0s
[11:40:11.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:46:0)
[11:40:11.617] Timer tick, asking Buildbucket for the build status
[11:40:11.780] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:11.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:47:0) after 1m0s
[11:41:11.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:47:0)
[11:41:11.783] Timer tick, asking Buildbucket for the build status
[11:41:11.865] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:11.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:48:0) after 1m0s
[11:42:11.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:48:0)
[11:42:11.885] Timer tick, asking Buildbucket for the build status
[11:42:12.102] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:12.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:49:0) after 1m0s
[11:43:12.089] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:49:0)
[11:43:12.089] Timer tick, asking Buildbucket for the build status
[11:43:12.140] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:12.141] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:50:0) after 1m0s
[11:44:12.227] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:50:0)
[11:44:12.227] Timer tick, asking Buildbucket for the build status
[11:44:12.395] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:12.395] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:51:0) after 1m0s
[11:45:12.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:51:0)
[11:45:12.419] Timer tick, asking Buildbucket for the build status
[11:45:12.458] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:12.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:52:0) after 1m0s
[11:46:12.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:52:0)
[11:46:12.440] Timer tick, asking Buildbucket for the build status
[11:46:12.507] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:12.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:53:0) after 1m0s
[11:47:12.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:53:0)
[11:47:12.528] Timer tick, asking Buildbucket for the build status
[11:47:12.589] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:12.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:54:0) after 1m0s
[11:48:12.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:54:0)
[11:48:12.607] Timer tick, asking Buildbucket for the build status
[11:48:12.690] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:12.690] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:55:0) after 1m0s
[11:49:12.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:55:0)
[11:49:12.710] Timer tick, asking Buildbucket for the build status
[11:49:12.776] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:12.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:56:0) after 1m0s
[11:50:12.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:56:0)
[11:50:12.795] Timer tick, asking Buildbucket for the build status
[11:50:12.963] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:12.963] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:57:0) after 1m0s
[11:51:12.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:57:0)
[11:51:12.943] Timer tick, asking Buildbucket for the build status
[11:51:13.054] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:13.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:58:0) after 1m0s
[11:52:13.054] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:58:0)
[11:52:13.054] Timer tick, asking Buildbucket for the build status
[11:52:13.126] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:13.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:59:0) after 1m0s
[11:53:13.157] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:59:0)
[11:53:13.157] Timer tick, asking Buildbucket for the build status
[11:53:13.280] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:13.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:60:0) after 1m0s
[11:54:13.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:60:0)
[11:54:13.263] Timer tick, asking Buildbucket for the build status
[11:54:13.336] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:13.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:61:0) after 1m0s
[11:55:13.320] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:61:0)
[11:55:13.320] Timer tick, asking Buildbucket for the build status
[11:55:13.370] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:13.370] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:62:0) after 1m0s
[11:56:13.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:62:0)
[11:56:13.366] Timer tick, asking Buildbucket for the build status
[11:56:13.427] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:13.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:63:0) after 1m0s
[11:57:13.411] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:63:0)
[11:57:13.411] Timer tick, asking Buildbucket for the build status
[11:57:13.458] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:13.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:64:0) after 1m0s
[11:58:13.449] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:64:0)
[11:58:13.449] Timer tick, asking Buildbucket for the build status
[11:58:13.585] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:13.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:65:0) after 1m0s
[11:59:13.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:65:0)
[11:59:13.598] Timer tick, asking Buildbucket for the build status
[11:59:13.667] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:13.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:66:0) after 1m0s
[12:00:13.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:66:0)
[12:00:13.655] Timer tick, asking Buildbucket for the build status
[12:00:13.733] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:13.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:67:0) after 1m0s
[12:01:13.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:67:0)
[12:01:13.756] Timer tick, asking Buildbucket for the build status
[12:01:13.821] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:13.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:68:0) after 1m0s
[12:02:13.839] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:68:0)
[12:02:13.839] Timer tick, asking Buildbucket for the build status
[12:02:13.926] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:13.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:69:0) after 1m0s
[12:03:13.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:69:0)
[12:03:13.910] Timer tick, asking Buildbucket for the build status
[12:03:14.064] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:14.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:70:0) after 1m0s
[12:04:14.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:70:0)
[12:04:14.088] Timer tick, asking Buildbucket for the build status
[12:04:14.239] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:14.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:71:0) after 1m0s
[12:05:14.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:71:0)
[12:05:14.238] Timer tick, asking Buildbucket for the build status
[12:05:14.283] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:14.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:72:0) after 1m0s
[12:06:14.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:72:0)
[12:06:14.261] Timer tick, asking Buildbucket for the build status
[12:06:14.325] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:14.325] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:73:0) after 1m0s
[12:07:14.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:73:0)
[12:07:14.369] Timer tick, asking Buildbucket for the build status
[12:07:14.422] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:14.422] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:74:0) after 1m0s
[12:08:14.395] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:74:0)
[12:08:14.395] Timer tick, asking Buildbucket for the build status
[12:08:14.476] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:14.476] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:75:0) after 1m0s
[12:09:14.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:75:0)
[12:09:14.478] Timer tick, asking Buildbucket for the build status
[12:09:14.649] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:14.649] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:76:0) after 1m0s
[12:10:14.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:76:0)
[12:10:14.648] Timer tick, asking Buildbucket for the build status
[12:10:14.788] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:14.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:77:0) after 1m0s
[12:11:14.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:77:0)
[12:11:14.810] Timer tick, asking Buildbucket for the build status
[12:11:15.009] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:15.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:78:0) after 1m0s
[12:12:14.997] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:78:0)
[12:12:14.997] Timer tick, asking Buildbucket for the build status
[12:12:15.073] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:15.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:79:0) after 1m0s
[12:13:15.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:79:0)
[12:13:15.096] Timer tick, asking Buildbucket for the build status
[12:13:15.169] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:15.169] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:80:0) after 1m0s
[12:14:15.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:80:0)
[12:14:15.189] Timer tick, asking Buildbucket for the build status
[12:14:15.269] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:15.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:81:0) after 1m0s
[12:15:15.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:81:0)
[12:15:15.281] Timer tick, asking Buildbucket for the build status
[12:15:15.339] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:15.339] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:82:0) after 1m0s
[12:16:15.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:82:0)
[12:16:15.322] Timer tick, asking Buildbucket for the build status
[12:16:15.703] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:15.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:83:0) after 1m0s
[12:17:15.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:83:0)
[12:17:15.724] Timer tick, asking Buildbucket for the build status
[12:17:15.892] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:15.892] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:84:0) after 1m0s
[12:18:15.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:84:0)
[12:18:15.918] Timer tick, asking Buildbucket for the build status
[12:18:16.015] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:16.015] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:85:0) after 1m0s
[12:19:15.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:85:0)
[12:19:15.996] Timer tick, asking Buildbucket for the build status
[12:19:16.058] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:16.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:86:0) after 1m0s
[12:20:16.073] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:86:0)
[12:20:16.073] Timer tick, asking Buildbucket for the build status
[12:20:16.265] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:16.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:87:0) after 1m0s
[12:21:16.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:87:0)
[12:21:16.287] Timer tick, asking Buildbucket for the build status
[12:21:16.325] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:16.325] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:88:0) after 1m0s
[12:22:16.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:88:0)
[12:22:16.428] Timer tick, asking Buildbucket for the build status
[12:22:16.491] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:16.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:89:0) after 1m0s
[12:23:16.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:89:0)
[12:23:16.528] Timer tick, asking Buildbucket for the build status
[12:23:16.594] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:16.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:90:0) after 1m0s
[12:24:16.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:90:0)
[12:24:16.591] Timer tick, asking Buildbucket for the build status
[12:24:16.629] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:16.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:91:0) after 1m0s
[12:25:16.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:91:0)
[12:25:16.607] Timer tick, asking Buildbucket for the build status
[12:25:16.759] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:16.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:92:0) after 1m0s
[12:26:16.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:92:0)
[12:26:16.765] Timer tick, asking Buildbucket for the build status
[12:26:16.832] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:16.832] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:93:0) after 1m0s
[12:27:16.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:93:0)
[12:27:16.812] Timer tick, asking Buildbucket for the build status
[12:27:16.970] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:16.970] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:94:0) after 1m0s
[12:28:16.975] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:94:0)
[12:28:16.975] Timer tick, asking Buildbucket for the build status
[12:28:17.086] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:17.086] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:95:0) after 1m0s
[12:29:17.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:95:0)
[12:29:17.093] Timer tick, asking Buildbucket for the build status
[12:29:17.360] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:17.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:96:0) after 1m0s
[12:30:17.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:96:0)
[12:30:17.385] Timer tick, asking Buildbucket for the build status
[12:30:17.430] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:17.430] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:97:0) after 1m0s
[12:31:17.434] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:97:0)
[12:31:17.434] Timer tick, asking Buildbucket for the build status
[12:31:17.575] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:17.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:98:0) after 1m0s
[12:32:17.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:98:0)
[12:32:17.596] Timer tick, asking Buildbucket for the build status
[12:32:17.782] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:17.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:99:0) after 1m0s
[12:33:17.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:99:0)
[12:33:17.779] Timer tick, asking Buildbucket for the build status
[12:33:18.109] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:18.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:100:0) after 1m0s
[12:34:18.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:100:0)
[12:34:18.156] Timer tick, asking Buildbucket for the build status
[12:34:18.219] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:18.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:101:0) after 1m0s
[12:35:18.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:101:0)
[12:35:18.243] Timer tick, asking Buildbucket for the build status
[12:35:18.303] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:18.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:102:0) after 1m0s
[12:36:18.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:102:0)
[12:36:18.283] Timer tick, asking Buildbucket for the build status
[12:36:18.438] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:18.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:103:0) after 1m0s
[12:37:18.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:103:0)
[12:37:18.461] Timer tick, asking Buildbucket for the build status
[12:37:18.674] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:18.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:104:0) after 1m0s
[12:38:18.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:104:0)
[12:38:18.697] Timer tick, asking Buildbucket for the build status
[12:38:18.748] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:18.748] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:105:0) after 1m0s
[12:39:18.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:105:0)
[12:39:18.769] Timer tick, asking Buildbucket for the build status
[12:39:18.827] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:18.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:106:0) after 1m0s
[12:40:18.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:106:0)
[12:40:18.851] Timer tick, asking Buildbucket for the build status
[12:40:18.900] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:18.900] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:107:0) after 1m0s
[12:41:18.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:107:0)
[12:41:18.872] Timer tick, asking Buildbucket for the build status
[12:41:18.937] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:18.937] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:108:0) after 1m0s
[12:42:18.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:108:0)
[12:42:18.957] Timer tick, asking Buildbucket for the build status
[12:42:19.019] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:19.019] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:109:0) after 1m0s
[12:43:19.041] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:109:0)
[12:43:19.041] Timer tick, asking Buildbucket for the build status
[12:43:19.181] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:19.181] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:110:0) after 1m0s
[12:44:19.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:110:0)
[12:44:19.184] Timer tick, asking Buildbucket for the build status
[12:44:19.232] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:19.232] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:111:0) after 1m0s
[12:45:19.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:111:0)
[12:45:19.256] Timer tick, asking Buildbucket for the build status
[12:45:19.371] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:19.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:112:0) after 1m0s
[12:46:19.380] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:112:0)
[12:46:19.380] Timer tick, asking Buildbucket for the build status
[12:46:19.548] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:19.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:113:0) after 1m0s
[12:47:19.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:113:0)
[12:47:19.568] Timer tick, asking Buildbucket for the build status
[12:47:19.769] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:19.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:114:0) after 1m0s
[12:48:19.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:114:0)
[12:48:19.776] Timer tick, asking Buildbucket for the build status
[12:48:19.890] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:19.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:115:0) after 1m0s
[12:49:19.913] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:115:0)
[12:49:19.913] Timer tick, asking Buildbucket for the build status
[12:49:19.976] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:19.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:116:0) after 1m0s
[12:50:19.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:116:0)
[12:50:19.993] Timer tick, asking Buildbucket for the build status
[12:50:20.064] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:20.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:117:0) after 1m0s
[12:51:20.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:117:0)
[12:51:20.085] Timer tick, asking Buildbucket for the build status
[12:51:20.150] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:20.150] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:118:0) after 1m0s
[12:52:20.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:118:0)
[12:52:20.146] Timer tick, asking Buildbucket for the build status
[12:52:20.284] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:20.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:119:0) after 1m0s
[12:53:20.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:119:0)
[12:53:20.306] Timer tick, asking Buildbucket for the build status
[12:53:20.883] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:20.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:120:0) after 1m0s
[12:54:20.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:120:0)
[12:54:20.907] Timer tick, asking Buildbucket for the build status
[12:54:20.972] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:20.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:121:0) after 1m0s
[12:55:20.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:121:0)
[12:55:20.994] Timer tick, asking Buildbucket for the build status
[12:55:21.049] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:21.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:122:0) after 1m0s
[12:56:21.076] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:122:0)
[12:56:21.076] Timer tick, asking Buildbucket for the build status
[12:56:21.136] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:21.136] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:123:0) after 1m0s
[12:57:21.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:123:0)
[12:57:21.167] Timer tick, asking Buildbucket for the build status
[12:57:21.208] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:21.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:124:0) after 1m0s
[12:58:21.227] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:124:0)
[12:58:21.227] Timer tick, asking Buildbucket for the build status
[12:58:21.297] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:21.297] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:125:0) after 1m0s
[12:59:21.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:125:0)
[12:59:21.311] Timer tick, asking Buildbucket for the build status
[12:59:21.440] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:21.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:126:0) after 1m0s
[13:00:21.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:126:0)
[13:00:21.475] Timer tick, asking Buildbucket for the build status
[13:00:21.543] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:21.543] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:127:0) after 1m0s
[13:01:21.547] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:127:0)
[13:01:21.547] Timer tick, asking Buildbucket for the build status
[13:01:21.602] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:21.602] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:128:0) after 1m0s
[13:02:21.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:128:0)
[13:02:21.623] Timer tick, asking Buildbucket for the build status
[13:02:21.773] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:21.773] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:129:0) after 1m0s
[13:03:21.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:129:0)
[13:03:21.789] Timer tick, asking Buildbucket for the build status
[13:03:21.842] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:21.842] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:130:0) after 1m0s
[13:04:21.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:130:0)
[13:04:21.863] Timer tick, asking Buildbucket for the build status
[13:04:21.927] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:21.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:131:0) after 1m0s
[13:05:21.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:131:0)
[13:05:21.946] Timer tick, asking Buildbucket for the build status
[13:05:22.017] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:22.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:132:0) after 1m0s
[13:06:22.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:132:0)
[13:06:22.002] Timer tick, asking Buildbucket for the build status
[13:06:22.191] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:22.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:133:0) after 1m0s
[13:07:22.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:133:0)
[13:07:22.228] Timer tick, asking Buildbucket for the build status
[13:07:22.271] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:22.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:134:0) after 1m0s
[13:08:22.269] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:134:0)
[13:08:22.269] Timer tick, asking Buildbucket for the build status
[13:08:22.329] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:22.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:135:0) after 1m0s
[13:09:22.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:135:0)
[13:09:22.349] Timer tick, asking Buildbucket for the build status
[13:09:22.428] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:22.428] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:136:0) after 1m0s
[13:10:22.415] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:136:0)
[13:10:22.415] Timer tick, asking Buildbucket for the build status
[13:10:22.608] Build 8918562563845636240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:22.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083992625827352912:137:0) after 1m0s
[13:10:29.088] Received PubSub notification, asking Buildbucket for the build status
[13:10:29.193] Build 8918562563845636240: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:10:29.193] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1553001027965787",
"created_by": "project:infra",
"created_ts": "1552992965806239",
"id": "8918562563845636240",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"75.0.3738.0\"}}",
"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\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"4201-b04f7f8\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"32\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"build-from-tarball-0-72pp\"], \"pool\": [\"luci.infra.cron\"]}}, \"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}, \"version\": \"75.0.3738.0\", \"path_config\": \"generic\", \"bot_id\": \"build-from-tarball-0-72pp\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552993001750743",
"status": "COMPLETED",
"status_changed_ts": "1553001028746454",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9083992625827352912",
"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/8918562563845636240/+/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:43ae699dfb833610",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1553001028750956",
"url": "https://ci.chromium.org/b/8918562563845636240",
"utcnow_ts": "1553001029171761"
}
}
[13:10:29.193] Invocation finished in 2h14m24.661347505s with status SUCCEEDED