[12:00:57.877] New invocation is queued and will start shortly
[12:00:57.877] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[12:00:59.500] Starting the invocation (attempt 1)
[12:00:59.581] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:00:59.582] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:00:59.582] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9048112144684794976",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"84.0.4117.3\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"231e3452-a874-4180-aba5-2b09dfae71ce\",\"title\":\"publish_tarball/0\"}]},\"version\":\"84.0.4117.3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9048112144684794976",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[12:01:00.511] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1587211259702335",
"id": "8882682082104893728",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"84.0.4117.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"231e3452-a874-4180-aba5-2b09dfae71ce\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"84.0.4117.3\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1587211260114785",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9048112144684794976",
"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/8882682082104893728/+/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": "1587211260115082",
"url": "https://ci.chromium.org/b/8882682082104893728",
"utcnow_ts": "1587211260479104"
}
}
[12:01:00.511] Task URL: https://ci.chromium.org/b/8882682082104893728
[12:01:00.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:2:0) after 1m0s
[12:01:11.642] Received PubSub notification, asking Buildbucket for the build status
[12:01:11.769] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:00.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:2:0)
[12:02:00.578] Timer tick, asking Buildbucket for the build status
[12:02:00.778] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:00.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:4:0) after 1m0s
[12:03:00.968] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:4:0)
[12:03:00.968] Timer tick, asking Buildbucket for the build status
[12:03:01.112] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:01.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:5:0) after 1m0s
[12:04:01.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:5:0)
[12:04:01.274] Timer tick, asking Buildbucket for the build status
[12:04:01.496] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:01.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:6:0) after 1m0s
[12:05:01.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:6:0)
[12:05:01.619] Timer tick, asking Buildbucket for the build status
[12:05:02.437] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:02.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:7:0) after 1m0s
[12:06:02.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:7:0)
[12:06:02.469] Timer tick, asking Buildbucket for the build status
[12:06:02.557] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:02.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:8:0) after 1m0s
[12:07:02.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:8:0)
[12:07:02.590] Timer tick, asking Buildbucket for the build status
[12:07:03.072] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:03.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:9:0) after 1m0s
[12:08:03.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:9:0)
[12:08:03.087] Timer tick, asking Buildbucket for the build status
[12:08:03.402] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:03.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:10:0) after 1m0s
[12:09:03.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:10:0)
[12:09:03.472] Timer tick, asking Buildbucket for the build status
[12:09:03.552] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:03.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:11:0) after 1m0s
[12:10:03.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:11:0)
[12:10:03.554] Timer tick, asking Buildbucket for the build status
[12:10:03.725] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:03.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:12:0) after 1m0s
[12:11:03.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:12:0)
[12:11:03.832] Timer tick, asking Buildbucket for the build status
[12:11:03.966] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:03.966] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:13:0) after 1m0s
[12:12:03.975] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:13:0)
[12:12:03.975] Timer tick, asking Buildbucket for the build status
[12:12:04.089] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:04.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:14:0) after 1m0s
[12:13:04.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:14:0)
[12:13:04.106] Timer tick, asking Buildbucket for the build status
[12:13:04.283] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:04.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:15:0) after 1m0s
[12:14:04.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:15:0)
[12:14:04.397] Timer tick, asking Buildbucket for the build status
[12:14:04.927] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:04.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:16:0) after 1m0s
[12:15:04.964] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:16:0)
[12:15:04.964] Timer tick, asking Buildbucket for the build status
[12:15:05.091] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:05.092] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:17:0) after 1m0s
[12:16:05.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:17:0)
[12:16:05.114] Timer tick, asking Buildbucket for the build status
[12:16:05.251] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:05.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:18:0) after 1m0s
[12:17:05.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:18:0)
[12:17:05.270] Timer tick, asking Buildbucket for the build status
[12:17:05.377] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:05.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:19:0) after 1m0s
[12:18:05.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:19:0)
[12:18:05.389] Timer tick, asking Buildbucket for the build status
[12:18:05.683] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:05.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:20:0) after 1m0s
[12:19:05.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:20:0)
[12:19:05.747] Timer tick, asking Buildbucket for the build status
[12:19:06.226] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:06.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:21:0) after 1m0s
[12:20:06.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:21:0)
[12:20:06.274] Timer tick, asking Buildbucket for the build status
[12:20:06.682] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:06.682] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:22:0) after 1m0s
[12:21:06.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:22:0)
[12:21:06.742] Timer tick, asking Buildbucket for the build status
[12:21:06.859] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:06.859] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:23:0) after 1m0s
[12:22:06.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:23:0)
[12:22:06.896] Timer tick, asking Buildbucket for the build status
[12:22:07.077] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:07.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:24:0) after 1m0s
[12:23:07.101] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:24:0)
[12:23:07.101] Timer tick, asking Buildbucket for the build status
[12:23:07.391] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:07.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:25:0) after 1m0s
[12:24:07.411] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:25:0)
[12:24:07.411] Timer tick, asking Buildbucket for the build status
[12:24:07.544] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:07.544] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:26:0) after 1m0s
[12:25:07.577] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:26:0)
[12:25:07.577] Timer tick, asking Buildbucket for the build status
[12:25:07.734] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:07.734] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:27:0) after 1m0s
[12:26:07.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:27:0)
[12:26:07.761] Timer tick, asking Buildbucket for the build status
[12:26:07.876] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:07.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:28:0) after 1m0s
[12:27:07.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:28:0)
[12:27:07.924] Timer tick, asking Buildbucket for the build status
[12:27:08.013] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:08.013] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:29:0) after 1m0s
[12:28:08.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:29:0)
[12:28:08.215] Timer tick, asking Buildbucket for the build status
[12:28:08.413] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:08.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:30:0) after 1m0s
[12:29:08.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:30:0)
[12:29:08.532] Timer tick, asking Buildbucket for the build status
[12:29:09.009] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:09.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:31:0) after 1m0s
[12:30:09.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:31:0)
[12:30:09.270] Timer tick, asking Buildbucket for the build status
[12:30:09.439] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:09.439] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:32:0) after 1m0s
[12:31:09.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:32:0)
[12:31:09.579] Timer tick, asking Buildbucket for the build status
[12:31:09.705] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:09.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:33:0) after 1m0s
[12:32:09.727] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:33:0)
[12:32:09.727] Timer tick, asking Buildbucket for the build status
[12:32:09.945] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:09.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:34:0) after 1m0s
[12:33:10.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:34:0)
[12:33:10.118] Timer tick, asking Buildbucket for the build status
[12:33:10.221] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:10.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:35:0) after 1m0s
[12:34:10.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:35:0)
[12:34:10.339] Timer tick, asking Buildbucket for the build status
[12:34:10.499] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:10.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:36:0) after 1m0s
[12:35:10.879] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:36:0)
[12:35:10.879] Timer tick, asking Buildbucket for the build status
[12:35:11.350] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:11.350] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:37:0) after 1m0s
[12:36:11.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:37:0)
[12:36:11.414] Timer tick, asking Buildbucket for the build status
[12:36:11.641] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:11.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:38:0) after 1m0s
[12:37:11.754] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:38:0)
[12:37:11.754] Timer tick, asking Buildbucket for the build status
[12:37:12.364] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:12.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:39:0) after 1m0s
[12:38:12.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:39:0)
[12:38:12.410] Timer tick, asking Buildbucket for the build status
[12:38:12.850] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:12.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:40:0) after 1m0s
[12:39:13.166] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:40:0)
[12:39:13.166] Timer tick, asking Buildbucket for the build status
[12:39:13.320] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:13.320] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:41:0) after 1m0s
[12:40:13.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:41:0)
[12:40:13.442] Timer tick, asking Buildbucket for the build status
[12:40:13.588] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:13.588] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:42:0) after 1m0s
[12:41:13.716] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:42:0)
[12:41:13.716] Timer tick, asking Buildbucket for the build status
[12:41:13.845] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:13.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:43:0) after 1m0s
[12:42:13.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:43:0)
[12:42:13.872] Timer tick, asking Buildbucket for the build status
[12:42:14.352] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:14.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:44:0) after 1m0s
[12:43:14.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:44:0)
[12:43:14.479] Timer tick, asking Buildbucket for the build status
[12:43:16.327] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:16.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:45:0) after 1m0s
[12:44:16.476] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:45:0)
[12:44:16.476] Timer tick, asking Buildbucket for the build status
[12:44:16.614] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:16.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:46:0) after 1m0s
[12:45:16.715] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:46:0)
[12:45:16.715] Timer tick, asking Buildbucket for the build status
[12:45:16.822] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:16.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:47:0) after 1m0s
[12:46:16.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:47:0)
[12:46:16.883] Timer tick, asking Buildbucket for the build status
[12:46:16.998] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:16.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:48:0) after 1m0s
[12:47:17.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:48:0)
[12:47:17.069] Timer tick, asking Buildbucket for the build status
[12:47:17.202] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:17.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:49:0) after 1m0s
[12:48:17.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:49:0)
[12:48:17.278] Timer tick, asking Buildbucket for the build status
[12:48:17.377] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:17.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:50:0) after 1m0s
[12:49:17.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:50:0)
[12:49:17.397] Timer tick, asking Buildbucket for the build status
[12:49:17.566] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:17.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:51:0) after 1m0s
[12:50:17.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:51:0)
[12:50:17.590] Timer tick, asking Buildbucket for the build status
[12:50:18.016] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:18.016] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:52:0) after 1m0s
[12:51:18.130] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:52:0)
[12:51:18.130] Timer tick, asking Buildbucket for the build status
[12:51:18.313] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:18.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:53:0) after 1m0s
[12:52:18.390] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:53:0)
[12:52:18.390] Timer tick, asking Buildbucket for the build status
[12:52:18.547] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:18.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:54:0) after 1m0s
[12:53:18.576] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:54:0)
[12:53:18.576] Timer tick, asking Buildbucket for the build status
[12:53:18.668] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:18.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:55:0) after 1m0s
[12:54:18.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:55:0)
[12:54:18.841] Timer tick, asking Buildbucket for the build status
[12:54:18.987] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:18.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:56:0) after 1m0s
[12:55:19.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:56:0)
[12:55:19.038] Timer tick, asking Buildbucket for the build status
[12:55:19.326] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:19.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:57:0) after 1m0s
[12:56:19.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:57:0)
[12:56:19.394] Timer tick, asking Buildbucket for the build status
[12:56:19.527] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:19.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:58:0) after 1m0s
[12:57:19.606] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:58:0)
[12:57:19.606] Timer tick, asking Buildbucket for the build status
[12:57:19.762] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:19.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:59:0) after 1m0s
[12:58:19.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:59:0)
[12:58:19.790] Timer tick, asking Buildbucket for the build status
[12:58:19.996] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:19.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:60:0) after 1m0s
[12:59:20.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:60:0)
[12:59:20.069] Timer tick, asking Buildbucket for the build status
[12:59:20.485] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:20.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:61:0) after 1m0s
[13:00:20.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:61:0)
[13:00:20.589] Timer tick, asking Buildbucket for the build status
[13:00:21.363] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:21.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:62:0) after 1m0s
[13:01:21.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:62:0)
[13:01:21.389] Timer tick, asking Buildbucket for the build status
[13:01:21.506] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:21.506] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:63:0) after 1m0s
[13:02:21.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:63:0)
[13:02:21.545] Timer tick, asking Buildbucket for the build status
[13:02:21.703] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:21.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:64:0) after 1m0s
[13:03:21.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:64:0)
[13:03:21.789] Timer tick, asking Buildbucket for the build status
[13:03:21.899] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:21.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:65:0) after 1m0s
[13:04:21.912] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:65:0)
[13:04:21.912] Timer tick, asking Buildbucket for the build status
[13:04:22.075] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:22.075] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:66:0) after 1m0s
[13:05:22.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:66:0)
[13:05:22.104] Timer tick, asking Buildbucket for the build status
[13:05:22.480] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:22.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:67:0) after 1m0s
[13:06:22.509] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:67:0)
[13:06:22.509] Timer tick, asking Buildbucket for the build status
[13:06:22.658] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:22.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:68:0) after 1m0s
[13:07:22.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:68:0)
[13:07:22.685] Timer tick, asking Buildbucket for the build status
[13:07:22.797] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:22.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:69:0) after 1m0s
[13:08:22.839] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:69:0)
[13:08:22.839] Timer tick, asking Buildbucket for the build status
[13:08:22.971] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:22.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:70:0) after 1m0s
[13:09:23.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:70:0)
[13:09:23.015] Timer tick, asking Buildbucket for the build status
[13:09:23.207] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:23.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:71:0) after 1m0s
[13:10:23.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:71:0)
[13:10:23.263] Timer tick, asking Buildbucket for the build status
[13:10:23.575] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:23.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:72:0) after 1m0s
[13:11:23.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:72:0)
[13:11:23.607] Timer tick, asking Buildbucket for the build status
[13:11:24.034] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:24.034] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:73:0) after 1m0s
[13:12:24.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:73:0)
[13:12:24.088] Timer tick, asking Buildbucket for the build status
[13:12:24.244] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:24.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:74:0) after 1m0s
[13:13:24.269] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:74:0)
[13:13:24.269] Timer tick, asking Buildbucket for the build status
[13:13:25.399] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:25.399] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:75:0) after 1m0s
[13:14:25.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:75:0)
[13:14:25.422] Timer tick, asking Buildbucket for the build status
[13:14:25.790] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:25.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:76:0) after 1m0s
[13:15:25.813] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:76:0)
[13:15:25.813] Timer tick, asking Buildbucket for the build status
[13:15:26.289] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:26.289] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:77:0) after 1m0s
[13:16:26.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:77:0)
[13:16:26.310] Timer tick, asking Buildbucket for the build status
[13:16:26.467] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:26.467] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:78:0) after 1m0s
[13:17:26.571] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:78:0)
[13:17:26.571] Timer tick, asking Buildbucket for the build status
[13:17:26.692] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:26.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:79:0) after 1m0s
[13:18:26.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:79:0)
[13:18:26.719] Timer tick, asking Buildbucket for the build status
[13:18:27.928] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:27.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:80:0) after 1m0s
[13:19:28.080] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:80:0)
[13:19:28.080] Timer tick, asking Buildbucket for the build status
[13:19:28.165] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:28.165] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:81:0) after 1m0s
[13:20:28.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:81:0)
[13:20:28.276] Timer tick, asking Buildbucket for the build status
[13:20:28.555] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:28.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:82:0) after 1m0s
[13:21:28.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:82:0)
[13:21:28.735] Timer tick, asking Buildbucket for the build status
[13:21:28.794] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:28.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:83:0) after 1m0s
[13:22:28.968] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:83:0)
[13:22:28.968] Timer tick, asking Buildbucket for the build status
[13:22:29.185] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:29.185] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:84:0) after 1m0s
[13:23:29.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:84:0)
[13:23:29.334] Timer tick, asking Buildbucket for the build status
[13:23:29.404] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:29.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:85:0) after 1m0s
[13:24:29.525] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:85:0)
[13:24:29.525] Timer tick, asking Buildbucket for the build status
[13:24:29.856] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:29.856] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:86:0) after 1m0s
[13:25:29.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:86:0)
[13:25:29.887] Timer tick, asking Buildbucket for the build status
[13:25:30.041] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:30.041] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:87:0) after 1m0s
[13:26:30.159] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:87:0)
[13:26:30.159] Timer tick, asking Buildbucket for the build status
[13:26:30.224] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:30.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:88:0) after 1m0s
[13:27:30.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:88:0)
[13:27:30.297] Timer tick, asking Buildbucket for the build status
[13:27:30.489] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:30.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:89:0) after 1m0s
[13:28:30.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:89:0)
[13:28:30.496] Timer tick, asking Buildbucket for the build status
[13:28:31.064] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:31.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:90:0) after 1m0s
[13:29:31.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:90:0)
[13:29:31.232] Timer tick, asking Buildbucket for the build status
[13:29:31.576] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:31.576] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:91:0) after 1m0s
[13:30:31.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:91:0)
[13:30:31.703] Timer tick, asking Buildbucket for the build status
[13:30:31.986] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:31.986] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:92:0) after 1m0s
[13:31:32.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:92:0)
[13:31:32.066] Timer tick, asking Buildbucket for the build status
[13:31:32.217] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:32.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:93:0) after 1m0s
[13:32:32.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:93:0)
[13:32:32.356] Timer tick, asking Buildbucket for the build status
[13:32:32.483] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:32.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:94:0) after 1m0s
[13:33:32.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:94:0)
[13:33:32.578] Timer tick, asking Buildbucket for the build status
[13:33:32.745] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:32.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:95:0) after 1m0s
[13:34:32.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:95:0)
[13:34:32.906] Timer tick, asking Buildbucket for the build status
[13:34:33.093] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:33.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:96:0) after 1m0s
[13:35:33.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:96:0)
[13:35:33.248] Timer tick, asking Buildbucket for the build status
[13:35:33.531] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:33.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:97:0) after 1m0s
[13:36:33.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:97:0)
[13:36:33.610] Timer tick, asking Buildbucket for the build status
[13:36:33.741] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:33.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:98:0) after 1m0s
[13:37:33.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:98:0)
[13:37:33.804] Timer tick, asking Buildbucket for the build status
[13:37:33.923] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:33.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:99:0) after 1m0s
[13:38:33.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:99:0)
[13:38:33.979] Timer tick, asking Buildbucket for the build status
[13:38:34.148] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:34.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:100:0) after 1m0s
[13:39:34.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:100:0)
[13:39:34.178] Timer tick, asking Buildbucket for the build status
[13:39:34.330] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:34.330] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:101:0) after 1m0s
[13:40:34.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:101:0)
[13:40:34.522] Timer tick, asking Buildbucket for the build status
[13:40:34.893] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:34.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:102:0) after 1m0s
[13:41:35.034] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:102:0)
[13:41:35.034] Timer tick, asking Buildbucket for the build status
[13:41:35.244] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:35.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:103:0) after 1m0s
[13:42:35.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:103:0)
[13:42:35.278] Timer tick, asking Buildbucket for the build status
[13:42:35.572] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:35.572] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:104:0) after 1m0s
[13:43:35.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:104:0)
[13:43:35.597] Timer tick, asking Buildbucket for the build status
[13:43:35.704] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:35.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:105:0) after 1m0s
[13:44:35.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:105:0)
[13:44:35.870] Timer tick, asking Buildbucket for the build status
[13:44:35.981] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:35.981] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:106:0) after 1m0s
[13:45:36.003] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:106:0)
[13:45:36.003] Timer tick, asking Buildbucket for the build status
[13:45:36.337] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:36.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:107:0) after 1m0s
[13:46:36.420] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:107:0)
[13:46:36.420] Timer tick, asking Buildbucket for the build status
[13:46:36.682] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:36.682] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:108:0) after 1m0s
[13:47:36.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:108:0)
[13:47:36.860] Timer tick, asking Buildbucket for the build status
[13:47:37.135] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:37.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:109:0) after 1m0s
[13:48:37.368] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:109:0)
[13:48:37.368] Timer tick, asking Buildbucket for the build status
[13:48:37.547] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:37.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:110:0) after 1m0s
[13:49:37.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:110:0)
[13:49:37.563] Timer tick, asking Buildbucket for the build status
[13:49:37.733] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:37.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:111:0) after 1m0s
[13:50:37.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:111:0)
[13:50:37.805] Timer tick, asking Buildbucket for the build status
[13:50:37.959] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:37.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:112:0) after 1m0s
[13:51:38.169] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:112:0)
[13:51:38.169] Timer tick, asking Buildbucket for the build status
[13:51:38.336] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:38.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:113:0) after 1m0s
[13:52:38.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:113:0)
[13:52:38.515] Timer tick, asking Buildbucket for the build status
[13:52:38.730] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:38.730] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:114:0) after 1m0s
[13:53:38.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:114:0)
[13:53:38.868] Timer tick, asking Buildbucket for the build status
[13:53:39.037] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:39.037] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:115:0) after 1m0s
[13:54:39.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:115:0)
[13:54:39.065] Timer tick, asking Buildbucket for the build status
[13:54:39.396] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:39.396] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:116:0) after 1m0s
[13:55:39.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:116:0)
[13:55:39.427] Timer tick, asking Buildbucket for the build status
[13:55:40.475] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:40.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:117:0) after 1m0s
[13:56:40.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:117:0)
[13:56:40.619] Timer tick, asking Buildbucket for the build status
[13:56:40.728] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:40.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:118:0) after 1m0s
[13:57:40.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:118:0)
[13:57:40.811] Timer tick, asking Buildbucket for the build status
[13:57:40.922] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:40.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:119:0) after 1m0s
[13:58:41.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:119:0)
[13:58:41.068] Timer tick, asking Buildbucket for the build status
[13:58:41.381] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:41.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:120:0) after 1m0s
[13:59:41.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:120:0)
[13:59:41.546] Timer tick, asking Buildbucket for the build status
[13:59:41.890] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:41.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:121:0) after 1m0s
[14:00:42.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:121:0)
[14:00:42.018] Timer tick, asking Buildbucket for the build status
[14:00:42.361] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:42.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:122:0) after 1m0s
[14:01:42.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:122:0)
[14:01:42.421] Timer tick, asking Buildbucket for the build status
[14:01:42.682] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:42.682] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:123:0) after 1m0s
[14:02:42.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:123:0)
[14:02:42.735] Timer tick, asking Buildbucket for the build status
[14:02:42.979] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:42.979] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:124:0) after 1m0s
[14:03:43.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:124:0)
[14:03:43.164] Timer tick, asking Buildbucket for the build status
[14:03:43.381] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:43.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:125:0) after 1m0s
[14:04:43.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:125:0)
[14:04:43.581] Timer tick, asking Buildbucket for the build status
[14:04:43.728] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:43.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:126:0) after 1m0s
[14:05:43.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:126:0)
[14:05:43.935] Timer tick, asking Buildbucket for the build status
[14:05:44.328] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:44.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:127:0) after 1m0s
[14:06:44.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:127:0)
[14:06:44.499] Timer tick, asking Buildbucket for the build status
[14:06:44.699] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:44.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:128:0) after 1m0s
[14:07:44.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:128:0)
[14:07:44.881] Timer tick, asking Buildbucket for the build status
[14:07:44.985] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:44.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:129:0) after 1m0s
[14:08:45.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:129:0)
[14:08:45.146] Timer tick, asking Buildbucket for the build status
[14:08:45.372] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:45.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:130:0) after 1m0s
[14:09:45.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:130:0)
[14:09:45.599] Timer tick, asking Buildbucket for the build status
[14:09:45.746] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:45.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:131:0) after 1m0s
[14:10:45.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:131:0)
[14:10:45.861] Timer tick, asking Buildbucket for the build status
[14:10:46.014] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:46.014] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:132:0) after 1m0s
[14:11:46.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:132:0)
[14:11:46.102] Timer tick, asking Buildbucket for the build status
[14:11:46.208] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:46.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:133:0) after 1m0s
[14:12:46.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:133:0)
[14:12:46.436] Timer tick, asking Buildbucket for the build status
[14:12:46.600] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:46.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:134:0) after 1m0s
[14:13:46.778] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:134:0)
[14:13:46.778] Timer tick, asking Buildbucket for the build status
[14:13:46.975] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:46.975] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:135:0) after 1m0s
[14:14:46.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:135:0)
[14:14:46.998] Timer tick, asking Buildbucket for the build status
[14:14:47.178] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:47.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:136:0) after 1m0s
[14:15:47.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:136:0)
[14:15:47.477] Timer tick, asking Buildbucket for the build status
[14:15:47.585] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:47.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:137:0) after 1m0s
[14:16:47.608] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:137:0)
[14:16:47.608] Timer tick, asking Buildbucket for the build status
[14:16:47.684] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:47.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:138:0) after 1m0s
[14:17:47.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:138:0)
[14:17:47.766] Timer tick, asking Buildbucket for the build status
[14:17:47.963] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:47.963] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:139:0) after 1m0s
[14:18:47.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:139:0)
[14:18:47.988] Timer tick, asking Buildbucket for the build status
[14:18:48.195] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:48.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:140:0) after 1m0s
[14:19:48.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:140:0)
[14:19:48.232] Timer tick, asking Buildbucket for the build status
[14:19:48.397] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:48.397] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:141:0) after 1m0s
[14:20:48.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:141:0)
[14:20:48.458] Timer tick, asking Buildbucket for the build status
[14:20:48.793] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:48.793] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:142:0) after 1m0s
[14:21:48.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:142:0)
[14:21:48.786] Timer tick, asking Buildbucket for the build status
[14:21:48.895] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:48.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:143:0) after 1m0s
[14:22:49.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:143:0)
[14:22:49.011] Timer tick, asking Buildbucket for the build status
[14:22:49.124] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:49.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:144:0) after 1m0s
[14:23:49.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:144:0)
[14:23:49.147] Timer tick, asking Buildbucket for the build status
[14:23:49.232] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:49.232] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:145:0) after 1m0s
[14:24:49.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:145:0)
[14:24:49.358] Timer tick, asking Buildbucket for the build status
[14:24:49.692] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:49.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:146:0) after 1m0s
[14:25:49.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:146:0)
[14:25:49.817] Timer tick, asking Buildbucket for the build status
[14:25:49.984] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:49.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:147:0) after 1m0s
[14:26:50.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:147:0)
[14:26:50.106] Timer tick, asking Buildbucket for the build status
[14:26:50.254] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:50.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:148:0) after 1m0s
[14:27:50.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:148:0)
[14:27:50.369] Timer tick, asking Buildbucket for the build status
[14:27:50.573] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:50.573] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:149:0) after 1m0s
[14:28:50.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:149:0)
[14:28:50.617] Timer tick, asking Buildbucket for the build status
[14:28:50.832] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:50.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:150:0) after 1m0s
[14:29:50.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:150:0)
[14:29:50.895] Timer tick, asking Buildbucket for the build status
[14:29:51.248] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:51.248] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:151:0) after 1m0s
[14:30:51.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:151:0)
[14:30:51.283] Timer tick, asking Buildbucket for the build status
[14:30:51.372] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:51.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:152:0) after 1m0s
[14:31:51.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:152:0)
[14:31:51.398] Timer tick, asking Buildbucket for the build status
[14:31:51.620] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:51.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:153:0) after 1m0s
[14:32:51.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:153:0)
[14:32:51.717] Timer tick, asking Buildbucket for the build status
[14:32:51.917] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:51.917] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:154:0) after 1m0s
[14:33:52.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:154:0)
[14:33:52.021] Timer tick, asking Buildbucket for the build status
[14:33:52.159] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:52.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:155:0) after 1m0s
[14:34:52.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:155:0)
[14:34:52.208] Timer tick, asking Buildbucket for the build status
[14:34:52.473] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:52.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:156:0) after 1m0s
[14:35:52.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:156:0)
[14:35:52.497] Timer tick, asking Buildbucket for the build status
[14:35:52.696] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:52.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:157:0) after 1m0s
[14:36:52.731] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:157:0)
[14:36:52.731] Timer tick, asking Buildbucket for the build status
[14:36:52.846] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:52.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:158:0) after 1m0s
[14:37:52.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:158:0)
[14:37:52.881] Timer tick, asking Buildbucket for the build status
[14:37:52.976] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:52.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:159:0) after 1m0s
[14:38:53.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:159:0)
[14:38:53.008] Timer tick, asking Buildbucket for the build status
[14:38:53.194] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:53.194] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:160:0) after 1m0s
[14:39:54.352] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:160:0)
[14:39:54.352] Timer tick, asking Buildbucket for the build status
[14:39:54.489] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:54.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:161:0) after 1m0s
[14:40:54.633] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:161:0)
[14:40:54.633] Timer tick, asking Buildbucket for the build status
[14:40:55.004] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:55.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:162:0) after 1m0s
[14:41:55.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:162:0)
[14:41:55.093] Timer tick, asking Buildbucket for the build status
[14:41:55.538] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:55.538] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:163:0) after 1m0s
[14:42:55.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:163:0)
[14:42:55.781] Timer tick, asking Buildbucket for the build status
[14:42:55.971] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:55.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:164:0) after 1m0s
[14:43:56.041] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:164:0)
[14:43:56.041] Timer tick, asking Buildbucket for the build status
[14:43:56.373] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:56.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:165:0) after 1m0s
[14:44:56.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:165:0)
[14:44:56.485] Timer tick, asking Buildbucket for the build status
[14:44:56.634] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:56.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:166:0) after 1m0s
[14:45:56.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:166:0)
[14:45:56.710] Timer tick, asking Buildbucket for the build status
[14:45:56.930] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:56.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:167:0) after 1m0s
[14:46:56.974] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:167:0)
[14:46:56.974] Timer tick, asking Buildbucket for the build status
[14:46:57.121] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:57.121] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:168:0) after 1m0s
[14:47:57.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:168:0)
[14:47:57.211] Timer tick, asking Buildbucket for the build status
[14:47:57.363] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:57.363] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:169:0) after 1m0s
[14:48:57.471] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:169:0)
[14:48:57.471] Timer tick, asking Buildbucket for the build status
[14:48:57.574] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:57.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:170:0) after 1m0s
[14:49:57.608] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:170:0)
[14:49:57.608] Timer tick, asking Buildbucket for the build status
[14:49:57.751] Build 8882682082104893728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:57.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048112144684794976:171:0) after 1m0s
[14:50:15.235] Received PubSub notification, asking Buildbucket for the build status
[14:50:15.373] Build 8882682082104893728: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:50:15.373] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1587221413946208",
"created_by": "project:infra",
"created_ts": "1587211259702335",
"id": "8882682082104893728",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"84.0.4117.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"231e3452-a874-4180-aba5-2b09dfae71ce\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"84.0.4117.3\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$gatekeeper\": {\"group\": \"chromium.infra.cron\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"84.0.4117.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"231e3452-a874-4180-aba5-2b09dfae71ce\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-8zad\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"84.0.4117.3\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"vpython\"], \"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-8zad\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4985-2a273fe\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1587211270523200",
"status": "COMPLETED",
"status_changed_ts": "1587221414442107",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9048112144684794976",
"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/8882682082104893728/+/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:4ba5fb5af12d6a10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1587221414503578",
"url": "https://ci.chromium.org/b/8882682082104893728",
"utcnow_ts": "1587221415359373"
}
}
[14:50:15.373] Invocation finished in 2h49m17.514523815s with status SUCCEEDED