[16:09:26.631] New invocation is queued and will start shortly
[16:09:26.631] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[16:09:27.700] Starting the invocation (attempt 1)
[16:09:27.785] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:09:27.786] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:09:27.786] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9030701894172027152",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"88.0.4305.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"c41d8ab3-0a34-47ee-ad6c-8132f4b4ae09\",\"title\":\"publish_tarball/0\"}]},\"version\":\"88.0.4305.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:9030701894172027152",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[16:09:28.490] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1603814967886307",
"id": "8865271832192130736",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4305.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"c41d8ab3-0a34-47ee-ad6c-8132f4b4ae09\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4305.0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603814968214167",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9030701894172027152",
"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/8865271832192130736/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1603814968214426",
"url": "https://ci.chromium.org/b/8865271832192130736",
"utcnow_ts": "1603814968481908"
}
}
[16:09:28.490] Task URL: https://ci.chromium.org/b/8865271832192130736
[16:09:28.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:2:0) after 1m0s
[16:10:21.323] Received PubSub notification, asking Buildbucket for the build status
[16:10:21.441] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:28.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:2:0)
[16:10:28.604] Timer tick, asking Buildbucket for the build status
[16:10:28.983] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:28.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:4:0) after 1m0s
[16:11:29.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:4:0)
[16:11:29.092] Timer tick, asking Buildbucket for the build status
[16:11:29.419] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:29.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:5:0) after 1m0s
[16:12:29.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:5:0)
[16:12:29.461] Timer tick, asking Buildbucket for the build status
[16:12:29.545] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:29.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:6:0) after 1m0s
[16:13:29.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:6:0)
[16:13:29.626] Timer tick, asking Buildbucket for the build status
[16:13:30.119] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:30.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:7:0) after 1m0s
[16:14:30.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:7:0)
[16:14:30.274] Timer tick, asking Buildbucket for the build status
[16:14:30.430] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:30.430] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:8:0) after 1m0s
[16:15:30.576] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:8:0)
[16:15:30.576] Timer tick, asking Buildbucket for the build status
[16:15:30.712] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:30.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:9:0) after 1m0s
[16:16:30.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:9:0)
[16:16:30.814] Timer tick, asking Buildbucket for the build status
[16:16:30.983] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:30.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:10:0) after 1m0s
[16:17:31.056] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:10:0)
[16:17:31.056] Timer tick, asking Buildbucket for the build status
[16:17:31.165] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:31.165] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:11:0) after 1m0s
[16:18:31.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:11:0)
[16:18:31.310] Timer tick, asking Buildbucket for the build status
[16:18:32.334] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:32.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:12:0) after 1m0s
[16:19:32.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:12:0)
[16:19:32.366] Timer tick, asking Buildbucket for the build status
[16:19:32.481] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:32.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:13:0) after 1m0s
[16:20:32.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:13:0)
[16:20:32.544] Timer tick, asking Buildbucket for the build status
[16:20:32.708] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:32.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:14:0) after 1m0s
[16:21:32.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:14:0)
[16:21:32.831] Timer tick, asking Buildbucket for the build status
[16:21:33.300] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:33.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:15:0) after 1m0s
[16:22:33.413] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:15:0)
[16:22:33.413] Timer tick, asking Buildbucket for the build status
[16:22:33.703] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:33.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:16:0) after 1m0s
[16:23:33.889] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:16:0)
[16:23:33.889] Timer tick, asking Buildbucket for the build status
[16:23:33.985] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:33.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:17:0) after 1m0s
[16:24:34.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:17:0)
[16:24:34.164] Timer tick, asking Buildbucket for the build status
[16:24:34.534] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:34.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:18:0) after 1m0s
[16:25:34.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:18:0)
[16:25:34.561] Timer tick, asking Buildbucket for the build status
[16:25:34.651] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:34.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:19:0) after 1m0s
[16:26:34.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:19:0)
[16:26:34.674] Timer tick, asking Buildbucket for the build status
[16:26:34.823] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:34.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:20:0) after 1m0s
[16:27:34.904] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:20:0)
[16:27:34.904] Timer tick, asking Buildbucket for the build status
[16:27:35.126] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:35.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:21:0) after 1m0s
[16:28:35.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:21:0)
[16:28:35.499] Timer tick, asking Buildbucket for the build status
[16:28:36.034] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:36.034] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:22:0) after 1m0s
[16:29:36.072] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:22:0)
[16:29:36.072] Timer tick, asking Buildbucket for the build status
[16:29:36.196] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:36.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:23:0) after 1m0s
[16:30:36.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:23:0)
[16:30:36.353] Timer tick, asking Buildbucket for the build status
[16:30:36.584] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:36.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:24:0) after 1m0s
[16:31:36.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:24:0)
[16:31:36.710] Timer tick, asking Buildbucket for the build status
[16:31:36.835] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:36.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:25:0) after 1m0s
[16:32:36.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:25:0)
[16:32:36.903] Timer tick, asking Buildbucket for the build status
[16:32:37.220] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:37.220] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:26:0) after 1m0s
[16:33:37.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:26:0)
[16:33:37.326] Timer tick, asking Buildbucket for the build status
[16:33:37.539] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:37.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:27:0) after 1m0s
[16:34:37.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:27:0)
[16:34:37.673] Timer tick, asking Buildbucket for the build status
[16:34:39.059] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:39.059] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:28:0) after 1m0s
[16:35:39.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:28:0)
[16:35:39.217] Timer tick, asking Buildbucket for the build status
[16:35:39.296] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:39.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:29:0) after 1m0s
[16:36:39.380] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:29:0)
[16:36:39.380] Timer tick, asking Buildbucket for the build status
[16:36:39.612] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:39.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:30:0) after 1m0s
[16:37:39.634] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:30:0)
[16:37:39.634] Timer tick, asking Buildbucket for the build status
[16:37:39.776] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:39.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:31:0) after 1m0s
[16:38:39.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:31:0)
[16:38:39.781] Timer tick, asking Buildbucket for the build status
[16:38:39.924] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:39.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:32:0) after 1m0s
[16:39:39.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:32:0)
[16:39:39.946] Timer tick, asking Buildbucket for the build status
[16:39:40.116] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:40.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:33:0) after 1m0s
[16:40:40.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:33:0)
[16:40:40.143] Timer tick, asking Buildbucket for the build status
[16:40:40.211] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:40.211] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:34:0) after 1m0s
[16:41:40.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:34:0)
[16:41:40.245] Timer tick, asking Buildbucket for the build status
[16:41:40.326] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:40.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:35:0) after 1m0s
[16:42:40.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:35:0)
[16:42:40.333] Timer tick, asking Buildbucket for the build status
[16:42:40.570] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:40.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:36:0) after 1m0s
[16:43:40.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:36:0)
[16:43:40.580] Timer tick, asking Buildbucket for the build status
[16:43:41.226] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:41.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:37:0) after 1m0s
[16:44:41.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:37:0)
[16:44:41.275] Timer tick, asking Buildbucket for the build status
[16:44:41.388] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:41.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:38:0) after 1m0s
[16:45:41.454] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:38:0)
[16:45:41.454] Timer tick, asking Buildbucket for the build status
[16:45:42.336] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:42.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:39:0) after 1m0s
[16:46:42.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:39:0)
[16:46:42.402] Timer tick, asking Buildbucket for the build status
[16:46:42.561] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:42.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:40:0) after 1m0s
[16:47:43.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:40:0)
[16:47:43.038] Timer tick, asking Buildbucket for the build status
[16:47:43.110] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:43.110] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:41:0) after 1m0s
[16:48:43.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:41:0)
[16:48:43.154] Timer tick, asking Buildbucket for the build status
[16:48:43.241] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:43.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:42:0) after 1m0s
[16:49:43.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:42:0)
[16:49:43.310] Timer tick, asking Buildbucket for the build status
[16:49:43.674] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:43.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:43:0) after 1m0s
[16:50:43.690] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:43:0)
[16:50:43.690] Timer tick, asking Buildbucket for the build status
[16:50:43.774] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:43.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:44:0) after 1m0s
[16:51:43.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:44:0)
[16:51:43.835] Timer tick, asking Buildbucket for the build status
[16:51:43.990] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:43.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:45:0) after 1m0s
[16:52:44.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:45:0)
[16:52:44.044] Timer tick, asking Buildbucket for the build status
[16:52:44.324] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:44.324] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:46:0) after 1m0s
[16:53:44.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:46:0)
[16:53:44.499] Timer tick, asking Buildbucket for the build status
[16:53:44.563] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:44.563] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:47:0) after 1m0s
[16:54:44.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:47:0)
[16:54:44.740] Timer tick, asking Buildbucket for the build status
[16:54:45.273] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:45.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:48:0) after 1m0s
[16:55:45.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:48:0)
[16:55:45.402] Timer tick, asking Buildbucket for the build status
[16:55:45.519] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:45.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:49:0) after 1m0s
[16:56:45.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:49:0)
[16:56:45.794] Timer tick, asking Buildbucket for the build status
[16:56:46.023] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:46.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:50:0) after 1m0s
[16:57:46.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:50:0)
[16:57:46.137] Timer tick, asking Buildbucket for the build status
[16:57:46.464] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:46.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:51:0) after 1m0s
[16:58:46.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:51:0)
[16:58:46.550] Timer tick, asking Buildbucket for the build status
[16:58:46.889] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:46.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:52:0) after 1m0s
[16:59:46.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:52:0)
[16:59:46.978] Timer tick, asking Buildbucket for the build status
[16:59:47.104] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:47.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:53:0) after 1m0s
[17:00:47.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:53:0)
[17:00:47.278] Timer tick, asking Buildbucket for the build status
[17:00:47.354] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:47.354] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:54:0) after 1m0s
[17:01:47.470] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:54:0)
[17:01:47.470] Timer tick, asking Buildbucket for the build status
[17:01:48.036] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:48.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:55:0) after 1m0s
[17:02:48.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:55:0)
[17:02:48.048] Timer tick, asking Buildbucket for the build status
[17:02:48.126] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:48.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:56:0) after 1m0s
[17:03:48.207] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:56:0)
[17:03:48.207] Timer tick, asking Buildbucket for the build status
[17:03:48.319] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:48.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:57:0) after 1m0s
[17:04:48.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:57:0)
[17:04:48.473] Timer tick, asking Buildbucket for the build status
[17:04:48.621] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:48.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:58:0) after 1m0s
[17:05:48.754] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:58:0)
[17:05:48.754] Timer tick, asking Buildbucket for the build status
[17:05:48.852] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:48.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:59:0) after 1m0s
[17:06:49.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:59:0)
[17:06:49.138] Timer tick, asking Buildbucket for the build status
[17:06:49.295] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:49.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:60:0) after 1m0s
[17:07:49.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:60:0)
[17:07:49.501] Timer tick, asking Buildbucket for the build status
[17:07:49.770] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:49.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:61:0) after 1m0s
[17:08:49.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:61:0)
[17:08:49.855] Timer tick, asking Buildbucket for the build status
[17:08:50.117] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:50.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:62:0) after 1m0s
[17:09:50.186] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:62:0)
[17:09:50.186] Timer tick, asking Buildbucket for the build status
[17:09:50.368] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:50.368] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:63:0) after 1m0s
[17:10:50.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:63:0)
[17:10:50.400] Timer tick, asking Buildbucket for the build status
[17:10:50.461] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:50.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:64:0) after 1m0s
[17:11:51.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:64:0)
[17:11:51.364] Timer tick, asking Buildbucket for the build status
[17:11:51.493] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:51.493] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:65:0) after 1m0s
[17:12:51.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:65:0)
[17:12:51.616] Timer tick, asking Buildbucket for the build status
[17:12:51.719] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:51.719] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:66:0) after 1m0s
[17:13:51.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:66:0)
[17:13:51.896] Timer tick, asking Buildbucket for the build status
[17:13:52.836] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:52.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:67:0) after 1m0s
[17:14:52.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:67:0)
[17:14:52.908] Timer tick, asking Buildbucket for the build status
[17:14:53.016] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:53.016] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:68:0) after 1m0s
[17:15:53.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:68:0)
[17:15:53.088] Timer tick, asking Buildbucket for the build status
[17:15:53.251] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:53.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:69:0) after 1m0s
[17:16:53.298] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:69:0)
[17:16:53.298] Timer tick, asking Buildbucket for the build status
[17:16:53.450] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:53.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:70:0) after 1m0s
[17:17:53.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:70:0)
[17:17:53.550] Timer tick, asking Buildbucket for the build status
[17:17:53.609] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:53.609] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:71:0) after 1m0s
[17:18:53.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:71:0)
[17:18:53.687] Timer tick, asking Buildbucket for the build status
[17:18:53.772] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:53.772] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:72:0) after 1m0s
[17:19:53.898] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:72:0)
[17:19:53.898] Timer tick, asking Buildbucket for the build status
[17:19:54.009] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:54.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:73:0) after 1m0s
[17:20:54.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:73:0)
[17:20:54.128] Timer tick, asking Buildbucket for the build status
[17:20:54.327] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:54.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:74:0) after 1m0s
[17:21:54.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:74:0)
[17:21:54.421] Timer tick, asking Buildbucket for the build status
[17:21:54.844] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:54.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:75:0) after 1m0s
[17:22:54.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:75:0)
[17:22:54.886] Timer tick, asking Buildbucket for the build status
[17:22:55.043] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:55.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:76:0) after 1m0s
[17:23:55.116] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:76:0)
[17:23:55.116] Timer tick, asking Buildbucket for the build status
[17:23:55.188] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:55.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:77:0) after 1m0s
[17:24:55.303] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:77:0)
[17:24:55.303] Timer tick, asking Buildbucket for the build status
[17:24:55.463] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:55.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:78:0) after 1m0s
[17:25:55.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:78:0)
[17:25:55.563] Timer tick, asking Buildbucket for the build status
[17:25:55.654] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:55.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:79:0) after 1m0s
[17:26:55.866] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:79:0)
[17:26:55.867] Timer tick, asking Buildbucket for the build status
[17:26:55.927] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:55.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:80:0) after 1m0s
[17:27:56.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:80:0)
[17:27:56.039] Timer tick, asking Buildbucket for the build status
[17:27:56.301] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:56.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:81:0) after 1m0s
[17:28:56.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:81:0)
[17:28:56.479] Timer tick, asking Buildbucket for the build status
[17:28:56.552] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:56.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:82:0) after 1m0s
[17:29:56.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:82:0)
[17:29:56.632] Timer tick, asking Buildbucket for the build status
[17:29:56.843] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:56.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:83:0) after 1m0s
[17:30:56.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:83:0)
[17:30:56.936] Timer tick, asking Buildbucket for the build status
[17:30:56.994] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:56.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:84:0) after 1m0s
[17:31:57.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:84:0)
[17:31:57.148] Timer tick, asking Buildbucket for the build status
[17:31:57.886] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:57.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:85:0) after 1m0s
[17:32:57.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:85:0)
[17:32:57.962] Timer tick, asking Buildbucket for the build status
[17:32:58.076] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:58.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:86:0) after 1m0s
[17:33:58.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:86:0)
[17:33:58.155] Timer tick, asking Buildbucket for the build status
[17:33:58.291] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:58.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:87:0) after 1m0s
[17:34:58.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:87:0)
[17:34:58.575] Timer tick, asking Buildbucket for the build status
[17:34:58.739] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:58.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:88:0) after 1m0s
[17:35:59.041] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:88:0)
[17:35:59.041] Timer tick, asking Buildbucket for the build status
[17:35:59.150] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:59.150] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:89:0) after 1m0s
[17:36:59.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:89:0)
[17:36:59.147] Timer tick, asking Buildbucket for the build status
[17:36:59.223] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:59.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:90:0) after 1m0s
[17:37:59.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:90:0)
[17:37:59.325] Timer tick, asking Buildbucket for the build status
[17:37:59.601] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:59.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:91:0) after 1m0s
[17:38:59.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:91:0)
[17:38:59.740] Timer tick, asking Buildbucket for the build status
[17:38:59.812] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:59.812] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:92:0) after 1m0s
[17:39:59.857] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:92:0)
[17:39:59.857] Timer tick, asking Buildbucket for the build status
[17:40:00.222] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:00.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:93:0) after 1m0s
[17:41:00.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:93:0)
[17:41:00.240] Timer tick, asking Buildbucket for the build status
[17:41:00.447] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:00.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:94:0) after 1m0s
[17:42:00.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:94:0)
[17:42:00.496] Timer tick, asking Buildbucket for the build status
[17:42:00.596] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:00.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:95:0) after 1m0s
[17:43:00.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:95:0)
[17:43:00.901] Timer tick, asking Buildbucket for the build status
[17:43:01.169] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:01.169] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:96:0) after 1m0s
[17:44:01.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:96:0)
[17:44:01.489] Timer tick, asking Buildbucket for the build status
[17:44:02.242] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:02.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:97:0) after 1m0s
[17:45:02.300] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:97:0)
[17:45:02.300] Timer tick, asking Buildbucket for the build status
[17:45:03.031] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:03.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:98:0) after 1m0s
[17:46:03.101] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:98:0)
[17:46:03.101] Timer tick, asking Buildbucket for the build status
[17:46:03.195] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:03.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:99:0) after 1m0s
[17:47:03.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:99:0)
[17:47:03.296] Timer tick, asking Buildbucket for the build status
[17:47:03.362] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:03.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:100:0) after 1m0s
[17:48:03.404] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:100:0)
[17:48:03.404] Timer tick, asking Buildbucket for the build status
[17:48:03.785] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:03.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:101:0) after 1m0s
[17:49:03.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:101:0)
[17:49:03.851] Timer tick, asking Buildbucket for the build status
[17:49:03.976] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:03.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:102:0) after 1m0s
[17:50:04.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:102:0)
[17:50:04.087] Timer tick, asking Buildbucket for the build status
[17:50:04.382] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:04.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:103:0) after 1m0s
[17:51:04.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:103:0)
[17:51:04.436] Timer tick, asking Buildbucket for the build status
[17:51:04.529] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:04.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:104:0) after 1m0s
[17:52:04.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:104:0)
[17:52:04.631] Timer tick, asking Buildbucket for the build status
[17:52:04.880] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:04.880] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:105:0) after 1m0s
[17:53:04.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:105:0)
[17:53:04.906] Timer tick, asking Buildbucket for the build status
[17:53:04.984] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:04.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:106:0) after 1m0s
[17:54:05.063] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:106:0)
[17:54:05.063] Timer tick, asking Buildbucket for the build status
[17:54:05.392] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:05.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:107:0) after 1m0s
[17:55:05.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:107:0)
[17:55:05.554] Timer tick, asking Buildbucket for the build status
[17:55:05.708] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:05.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:108:0) after 1m0s
[17:56:05.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:108:0)
[17:56:05.757] Timer tick, asking Buildbucket for the build status
[17:56:06.077] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:06.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:109:0) after 1m0s
[17:57:06.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:109:0)
[17:57:06.178] Timer tick, asking Buildbucket for the build status
[17:57:06.252] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:06.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:110:0) after 1m0s
[17:58:06.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:110:0)
[17:58:06.326] Timer tick, asking Buildbucket for the build status
[17:58:06.408] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:06.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:111:0) after 1m0s
[17:59:06.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:111:0)
[17:59:06.541] Timer tick, asking Buildbucket for the build status
[17:59:06.630] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:06.630] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:112:0) after 1m0s
[18:00:06.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:112:0)
[18:00:06.760] Timer tick, asking Buildbucket for the build status
[18:00:06.959] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:06.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:113:0) after 1m0s
[18:01:07.075] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:113:0)
[18:01:07.075] Timer tick, asking Buildbucket for the build status
[18:01:07.404] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:07.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:114:0) after 1m0s
[18:02:07.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:114:0)
[18:02:07.552] Timer tick, asking Buildbucket for the build status
[18:02:07.922] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:07.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:115:0) after 1m0s
[18:03:07.989] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:115:0)
[18:03:07.989] Timer tick, asking Buildbucket for the build status
[18:03:08.210] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:08.211] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:116:0) after 1m0s
[18:04:08.212] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:116:0)
[18:04:08.212] Timer tick, asking Buildbucket for the build status
[18:04:08.286] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:08.286] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:117:0) after 1m0s
[18:05:08.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:117:0)
[18:05:08.337] Timer tick, asking Buildbucket for the build status
[18:05:08.497] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:08.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:118:0) after 1m0s
[18:06:08.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:118:0)
[18:06:08.594] Timer tick, asking Buildbucket for the build status
[18:06:09.192] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:09.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:119:0) after 1m0s
[18:07:09.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:119:0)
[18:07:09.275] Timer tick, asking Buildbucket for the build status
[18:07:10.055] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:10.055] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:120:0) after 1m0s
[18:08:10.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:120:0)
[18:08:10.035] Timer tick, asking Buildbucket for the build status
[18:08:10.201] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:10.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:121:0) after 1m0s
[18:09:10.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:121:0)
[18:09:10.263] Timer tick, asking Buildbucket for the build status
[18:09:10.325] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:10.325] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:122:0) after 1m0s
[18:10:10.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:122:0)
[18:10:10.361] Timer tick, asking Buildbucket for the build status
[18:10:10.942] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:10.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:123:0) after 1m0s
[18:11:10.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:123:0)
[18:11:10.984] Timer tick, asking Buildbucket for the build status
[18:11:11.707] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:11.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:124:0) after 1m0s
[18:12:11.755] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:124:0)
[18:12:11.755] Timer tick, asking Buildbucket for the build status
[18:12:12.067] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:12.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:125:0) after 1m0s
[18:13:12.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:125:0)
[18:13:12.234] Timer tick, asking Buildbucket for the build status
[18:13:12.509] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:12.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:126:0) after 1m0s
[18:14:12.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:126:0)
[18:14:12.643] Timer tick, asking Buildbucket for the build status
[18:14:12.857] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:12.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:127:0) after 1m0s
[18:15:12.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:127:0)
[18:15:12.867] Timer tick, asking Buildbucket for the build status
[18:15:13.146] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:13.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:128:0) after 1m0s
[18:16:13.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:128:0)
[18:16:13.156] Timer tick, asking Buildbucket for the build status
[18:16:13.221] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:13.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:129:0) after 1m0s
[18:17:13.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:129:0)
[18:17:13.274] Timer tick, asking Buildbucket for the build status
[18:17:13.569] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:13.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:130:0) after 1m0s
[18:18:13.690] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:130:0)
[18:18:13.690] Timer tick, asking Buildbucket for the build status
[18:18:15.404] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:15.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:131:0) after 1m0s
[18:19:15.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:131:0)
[18:19:15.475] Timer tick, asking Buildbucket for the build status
[18:19:15.628] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:15.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:132:0) after 1m0s
[18:20:15.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:132:0)
[18:20:15.632] Timer tick, asking Buildbucket for the build status
[18:20:16.157] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:16.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:133:0) after 1m0s
[18:21:16.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:133:0)
[18:21:16.358] Timer tick, asking Buildbucket for the build status
[18:21:16.618] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:16.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:134:0) after 1m0s
[18:22:16.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:134:0)
[18:22:16.667] Timer tick, asking Buildbucket for the build status
[18:22:16.990] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:16.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:135:0) after 1m0s
[18:23:16.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:135:0)
[18:23:16.991] Timer tick, asking Buildbucket for the build status
[18:23:17.155] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:17.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:136:0) after 1m0s
[18:24:17.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:136:0)
[18:24:17.184] Timer tick, asking Buildbucket for the build status
[18:24:17.310] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:17.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:137:0) after 1m0s
[18:25:17.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:137:0)
[18:25:17.378] Timer tick, asking Buildbucket for the build status
[18:25:17.720] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:17.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:138:0) after 1m0s
[18:26:17.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:138:0)
[18:26:17.722] Timer tick, asking Buildbucket for the build status
[18:26:17.890] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:17.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:139:0) after 1m0s
[18:27:17.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:139:0)
[18:27:17.908] Timer tick, asking Buildbucket for the build status
[18:27:17.992] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:17.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:140:0) after 1m0s
[18:28:18.067] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:140:0)
[18:28:18.067] Timer tick, asking Buildbucket for the build status
[18:28:18.130] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:18.130] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:141:0) after 1m0s
[18:29:18.173] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:141:0)
[18:29:18.173] Timer tick, asking Buildbucket for the build status
[18:29:18.267] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:18.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:142:0) after 1m0s
[18:30:18.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:142:0)
[18:30:18.323] Timer tick, asking Buildbucket for the build status
[18:30:18.717] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:18.717] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:143:0) after 1m0s
[18:31:18.877] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:143:0)
[18:31:18.877] Timer tick, asking Buildbucket for the build status
[18:31:18.955] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:18.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:144:0) after 1m0s
[18:32:19.024] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:144:0)
[18:32:19.025] Timer tick, asking Buildbucket for the build status
[18:32:19.088] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:19.088] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:145:0) after 1m0s
[18:33:19.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:145:0)
[18:33:19.180] Timer tick, asking Buildbucket for the build status
[18:33:19.626] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:19.626] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:146:0) after 1m0s
[18:34:19.665] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:146:0)
[18:34:19.665] Timer tick, asking Buildbucket for the build status
[18:34:19.826] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:19.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:147:0) after 1m0s
[18:35:19.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:147:0)
[18:35:19.929] Timer tick, asking Buildbucket for the build status
[18:35:20.147] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:20.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:148:0) after 1m0s
[18:36:20.231] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:148:0)
[18:36:20.231] Timer tick, asking Buildbucket for the build status
[18:36:20.344] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:20.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:149:0) after 1m0s
[18:37:20.351] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:149:0)
[18:37:20.351] Timer tick, asking Buildbucket for the build status
[18:37:20.725] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:20.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:150:0) after 1m0s
[18:38:20.813] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:150:0)
[18:38:20.813] Timer tick, asking Buildbucket for the build status
[18:38:20.991] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:20.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:151:0) after 1m0s
[18:39:21.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:151:0)
[18:39:21.139] Timer tick, asking Buildbucket for the build status
[18:39:21.407] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:21.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:152:0) after 1m0s
[18:40:21.488] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:152:0)
[18:40:21.488] Timer tick, asking Buildbucket for the build status
[18:40:21.834] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:21.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:153:0) after 1m0s
[18:41:22.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:153:0)
[18:41:22.013] Timer tick, asking Buildbucket for the build status
[18:41:22.105] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:22.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:154:0) after 1m0s
[18:42:22.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:154:0)
[18:42:22.234] Timer tick, asking Buildbucket for the build status
[18:42:22.314] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:22.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:155:0) after 1m0s
[18:43:22.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:155:0)
[18:43:22.554] Timer tick, asking Buildbucket for the build status
[18:43:22.628] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:22.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:156:0) after 1m0s
[18:44:22.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:156:0)
[18:44:22.807] Timer tick, asking Buildbucket for the build status
[18:44:23.628] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:23.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:157:0) after 1m0s
[18:45:23.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:157:0)
[18:45:23.758] Timer tick, asking Buildbucket for the build status
[18:45:23.904] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:23.904] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:158:0) after 1m0s
[18:46:23.975] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:158:0)
[18:46:23.975] Timer tick, asking Buildbucket for the build status
[18:46:24.076] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:24.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:159:0) after 1m0s
[18:47:24.181] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:159:0)
[18:47:24.181] Timer tick, asking Buildbucket for the build status
[18:47:24.269] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:24.270] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:160:0) after 1m0s
[18:48:24.434] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:160:0)
[18:48:24.434] Timer tick, asking Buildbucket for the build status
[18:48:24.641] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:24.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:161:0) after 1m0s
[18:49:25.030] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:161:0)
[18:49:25.030] Timer tick, asking Buildbucket for the build status
[18:49:25.275] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:25.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:162:0) after 1m0s
[18:50:25.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:162:0)
[18:50:25.262] Timer tick, asking Buildbucket for the build status
[18:50:25.377] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:25.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:163:0) after 1m0s
[18:51:25.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:163:0)
[18:51:25.405] Timer tick, asking Buildbucket for the build status
[18:51:25.473] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:25.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:164:0) after 1m0s
[18:52:25.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:164:0)
[18:52:25.580] Timer tick, asking Buildbucket for the build status
[18:52:25.686] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:25.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:165:0) after 1m0s
[18:53:25.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:165:0)
[18:53:25.876] Timer tick, asking Buildbucket for the build status
[18:53:25.941] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:25.941] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:166:0) after 1m0s
[18:54:25.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:166:0)
[18:54:25.972] Timer tick, asking Buildbucket for the build status
[18:54:26.157] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:26.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:167:0) after 1m0s
[18:55:26.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:167:0)
[18:55:26.312] Timer tick, asking Buildbucket for the build status
[18:55:26.520] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:26.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:168:0) after 1m0s
[18:56:26.577] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:168:0)
[18:56:26.577] Timer tick, asking Buildbucket for the build status
[18:56:26.821] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:26.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:169:0) after 1m0s
[18:57:26.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:169:0)
[18:57:26.927] Timer tick, asking Buildbucket for the build status
[18:57:26.984] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:26.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:170:0) after 1m0s
[18:58:27.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:170:0)
[18:58:27.066] Timer tick, asking Buildbucket for the build status
[18:58:27.293] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:27.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:171:0) after 1m0s
[18:59:27.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:171:0)
[18:59:27.336] Timer tick, asking Buildbucket for the build status
[18:59:27.404] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:27.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:172:0) after 1m0s
[19:00:27.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:172:0)
[19:00:27.507] Timer tick, asking Buildbucket for the build status
[19:00:27.698] Build 8865271832192130736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:27.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030701894172027152:173:0) after 1m0s
[19:01:26.329] Received PubSub notification, asking Buildbucket for the build status
[19:01:26.412] Build 8865271832192130736: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:01:26.412] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1603825285303683",
"created_by": "project:infra",
"created_ts": "1603814967886307",
"id": "8865271832192130736",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4305.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"c41d8ab3-0a34-47ee-ad6c-8132f4b4ae09\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4305.0\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-dsno\"], \"image\": [\"chrome-xenial-20102500-5da31bca5f9\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5613-2fddb4d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603815019868008",
"status": "COMPLETED",
"status_changed_ts": "1603825285854235",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9030701894172027152",
"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/8865271832192130736/+/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:4f83a3d87f380410",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1603825285869145",
"url": "https://ci.chromium.org/b/8865271832192130736",
"utcnow_ts": "1603825286401082"
}
}
[19:01:26.412] Invocation finished in 2h51m59.797347475s with status SUCCEEDED