[08:08:19.092] New invocation is queued and will start shortly
[08:08:19.092] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[08:08:20.189] Starting the invocation (attempt 1)
[08:08:20.230] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:08:20.232] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:08:20.232] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9027833061055757040",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"89.0.4339.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"ccae434e-097a-423a-baac-f680a996ec0e\",\"title\":\"publish_tarball/0\"}]},\"version\":\"89.0.4339.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:9027833061055757040",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[08:08:20.738] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1606550900326781",
"id": "8862402999097789952",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4339.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"ccae434e-097a-423a-baac-f680a996ec0e\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4339.0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606550900529309",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9027833061055757040",
"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/8862402999097789952/+/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": "1606550900529429",
"url": "https://ci.chromium.org/b/8862402999097789952",
"utcnow_ts": "1606550900715732"
}
}
[08:08:20.738] Task URL: https://ci.chromium.org/b/8862402999097789952
[08:08:20.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:2:0) after 1m0s
[08:08:43.563] Received PubSub notification, asking Buildbucket for the build status
[08:08:43.720] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:20.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:2:0)
[08:09:20.756] Timer tick, asking Buildbucket for the build status
[08:09:20.869] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:20.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:4:0) after 1m0s
[08:10:20.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:4:0)
[08:10:20.893] Timer tick, asking Buildbucket for the build status
[08:10:20.997] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:20.997] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:5:0) after 1m0s
[08:11:21.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:5:0)
[08:11:21.037] Timer tick, asking Buildbucket for the build status
[08:11:21.178] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:21.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:6:0) after 1m0s
[08:12:21.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:6:0)
[08:12:21.195] Timer tick, asking Buildbucket for the build status
[08:12:21.514] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:21.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:7:0) after 1m0s
[08:13:21.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:7:0)
[08:13:21.562] Timer tick, asking Buildbucket for the build status
[08:13:21.636] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:21.636] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:8:0) after 1m0s
[08:14:21.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:8:0)
[08:14:21.656] Timer tick, asking Buildbucket for the build status
[08:14:22.051] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:22.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:9:0) after 1m0s
[08:15:22.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:9:0)
[08:15:22.154] Timer tick, asking Buildbucket for the build status
[08:15:22.217] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:22.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:10:0) after 1m0s
[08:16:22.237] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:10:0)
[08:16:22.237] Timer tick, asking Buildbucket for the build status
[08:16:22.319] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:22.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:11:0) after 1m0s
[08:17:22.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:11:0)
[08:17:22.494] Timer tick, asking Buildbucket for the build status
[08:17:22.583] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:22.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:12:0) after 1m0s
[08:18:22.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:12:0)
[08:18:22.906] Timer tick, asking Buildbucket for the build status
[08:18:22.996] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:22.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:13:0) after 1m0s
[08:19:30.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:13:0)
[08:19:30.046] Timer tick, asking Buildbucket for the build status
[08:19:30.157] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:30.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:14:0) after 1m0s
[08:20:30.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:14:0)
[08:20:30.194] Timer tick, asking Buildbucket for the build status
[08:20:30.455] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:30.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:15:0) after 1m0s
[08:21:30.450] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:15:0)
[08:21:30.450] Timer tick, asking Buildbucket for the build status
[08:21:30.566] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:30.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:16:0) after 1m0s
[08:22:30.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:16:0)
[08:22:30.602] Timer tick, asking Buildbucket for the build status
[08:22:30.668] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:30.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:17:0) after 1m0s
[08:23:30.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:17:0)
[08:23:30.660] Timer tick, asking Buildbucket for the build status
[08:23:30.795] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:30.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:18:0) after 1m0s
[08:24:30.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:18:0)
[08:24:30.772] Timer tick, asking Buildbucket for the build status
[08:24:30.819] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:30.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:19:0) after 1m0s
[08:25:30.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:19:0)
[08:25:30.837] Timer tick, asking Buildbucket for the build status
[08:25:30.893] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:30.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:20:0) after 1m0s
[08:26:30.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:20:0)
[08:26:30.871] Timer tick, asking Buildbucket for the build status
[08:26:31.010] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:31.010] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:21:0) after 1m0s
[08:27:31.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:21:0)
[08:27:31.029] Timer tick, asking Buildbucket for the build status
[08:27:31.101] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:31.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:22:0) after 1m0s
[08:28:31.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:22:0)
[08:28:31.106] Timer tick, asking Buildbucket for the build status
[08:28:31.249] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:31.249] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:23:0) after 1m0s
[08:29:31.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:23:0)
[08:29:31.274] Timer tick, asking Buildbucket for the build status
[08:29:32.136] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:32.136] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:24:0) after 1m0s
[08:30:32.158] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:24:0)
[08:30:32.158] Timer tick, asking Buildbucket for the build status
[08:30:32.388] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:32.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:25:0) after 1m0s
[08:31:32.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:25:0)
[08:31:32.406] Timer tick, asking Buildbucket for the build status
[08:31:32.490] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:32.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:26:0) after 1m0s
[08:32:32.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:26:0)
[08:32:32.504] Timer tick, asking Buildbucket for the build status
[08:32:32.637] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:32.637] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:27:0) after 1m0s
[08:33:32.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:27:0)
[08:33:32.708] Timer tick, asking Buildbucket for the build status
[08:33:32.819] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:32.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:28:0) after 1m0s
[08:34:32.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:28:0)
[08:34:32.938] Timer tick, asking Buildbucket for the build status
[08:34:33.040] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:33.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:29:0) after 1m0s
[08:35:33.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:29:0)
[08:35:33.048] Timer tick, asking Buildbucket for the build status
[08:35:33.114] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:33.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:30:0) after 1m0s
[08:36:33.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:30:0)
[08:36:33.137] Timer tick, asking Buildbucket for the build status
[08:36:33.237] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:33.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:31:0) after 1m0s
[08:37:33.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:31:0)
[08:37:33.238] Timer tick, asking Buildbucket for the build status
[08:37:33.363] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:33.363] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:32:0) after 1m0s
[08:38:33.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:32:0)
[08:38:33.385] Timer tick, asking Buildbucket for the build status
[08:38:33.653] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:33.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:33:0) after 1m0s
[08:39:33.707] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:33:0)
[08:39:33.707] Timer tick, asking Buildbucket for the build status
[08:39:33.770] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:33.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:34:0) after 1m0s
[08:40:33.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:34:0)
[08:40:33.789] Timer tick, asking Buildbucket for the build status
[08:40:33.897] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:33.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:35:0) after 1m0s
[08:41:33.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:35:0)
[08:41:33.920] Timer tick, asking Buildbucket for the build status
[08:41:34.144] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:34.144] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:36:0) after 1m0s
[08:42:34.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:36:0)
[08:42:34.435] Timer tick, asking Buildbucket for the build status
[08:42:34.574] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:34.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:37:0) after 1m0s
[08:43:34.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:37:0)
[08:43:34.614] Timer tick, asking Buildbucket for the build status
[08:43:34.728] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:34.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:38:0) after 1m0s
[08:44:34.791] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:38:0)
[08:44:34.791] Timer tick, asking Buildbucket for the build status
[08:44:35.096] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:35.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:39:0) after 1m0s
[08:45:35.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:39:0)
[08:45:35.195] Timer tick, asking Buildbucket for the build status
[08:45:35.275] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:35.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:40:0) after 1m0s
[08:46:35.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:40:0)
[08:46:35.445] Timer tick, asking Buildbucket for the build status
[08:46:35.722] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:35.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:41:0) after 1m0s
[08:47:35.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:41:0)
[08:47:35.878] Timer tick, asking Buildbucket for the build status
[08:47:35.941] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:35.941] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:42:0) after 1m0s
[08:48:36.070] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:42:0)
[08:48:36.070] Timer tick, asking Buildbucket for the build status
[08:48:36.150] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:36.150] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:43:0) after 1m0s
[08:49:36.140] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:43:0)
[08:49:36.140] Timer tick, asking Buildbucket for the build status
[08:49:37.906] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:37.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:44:0) after 1m0s
[08:50:38.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:44:0)
[08:50:38.087] Timer tick, asking Buildbucket for the build status
[08:50:38.407] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:38.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:45:0) after 1m0s
[08:51:38.467] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:45:0)
[08:51:38.467] Timer tick, asking Buildbucket for the build status
[08:51:38.794] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:38.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:46:0) after 1m0s
[08:52:38.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:46:0)
[08:52:38.881] Timer tick, asking Buildbucket for the build status
[08:52:38.959] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:38.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:47:0) after 1m0s
[08:53:39.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:47:0)
[08:53:39.096] Timer tick, asking Buildbucket for the build status
[08:53:39.235] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:39.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:48:0) after 1m0s
[08:54:39.250] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:48:0)
[08:54:39.250] Timer tick, asking Buildbucket for the build status
[08:54:39.321] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:39.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:49:0) after 1m0s
[08:55:39.321] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:49:0)
[08:55:39.321] Timer tick, asking Buildbucket for the build status
[08:55:39.400] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:39.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:50:0) after 1m0s
[08:56:39.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:50:0)
[08:56:39.424] Timer tick, asking Buildbucket for the build status
[08:56:39.492] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:39.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:51:0) after 1m0s
[08:57:39.512] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:51:0)
[08:57:39.512] Timer tick, asking Buildbucket for the build status
[08:57:39.623] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:39.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:52:0) after 1m0s
[08:58:39.647] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:52:0)
[08:58:39.647] Timer tick, asking Buildbucket for the build status
[08:58:40.153] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:40.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:53:0) after 1m0s
[08:59:40.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:53:0)
[08:59:40.256] Timer tick, asking Buildbucket for the build status
[08:59:40.668] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:40.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:54:0) after 1m0s
[09:00:40.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:54:0)
[09:00:40.723] Timer tick, asking Buildbucket for the build status
[09:00:40.844] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:40.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:55:0) after 1m0s
[09:01:40.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:55:0)
[09:01:40.902] Timer tick, asking Buildbucket for the build status
[09:01:41.261] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:41.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:56:0) after 1m0s
[09:02:41.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:56:0)
[09:02:41.333] Timer tick, asking Buildbucket for the build status
[09:02:41.481] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:41.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:57:0) after 1m0s
[09:03:41.548] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:57:0)
[09:03:41.548] Timer tick, asking Buildbucket for the build status
[09:03:41.733] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:41.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:58:0) after 1m0s
[09:04:41.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:58:0)
[09:04:41.720] Timer tick, asking Buildbucket for the build status
[09:04:41.780] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:41.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:59:0) after 1m0s
[09:05:41.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:59:0)
[09:05:41.832] Timer tick, asking Buildbucket for the build status
[09:05:41.907] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:41.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:60:0) after 1m0s
[09:06:42.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:60:0)
[09:06:42.029] Timer tick, asking Buildbucket for the build status
[09:06:42.122] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:42.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:61:0) after 1m0s
[09:07:42.157] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:61:0)
[09:07:42.157] Timer tick, asking Buildbucket for the build status
[09:07:42.234] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:42.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:62:0) after 1m0s
[09:08:42.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:62:0)
[09:08:42.310] Timer tick, asking Buildbucket for the build status
[09:08:42.405] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:42.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:63:0) after 1m0s
[09:09:42.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:63:0)
[09:09:42.425] Timer tick, asking Buildbucket for the build status
[09:09:42.519] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:42.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:64:0) after 1m0s
[09:10:42.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:64:0)
[09:10:42.526] Timer tick, asking Buildbucket for the build status
[09:10:42.583] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:42.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:65:0) after 1m0s
[09:11:42.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:65:0)
[09:11:42.610] Timer tick, asking Buildbucket for the build status
[09:11:42.764] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:42.764] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:66:0) after 1m0s
[09:12:42.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:66:0)
[09:12:42.784] Timer tick, asking Buildbucket for the build status
[09:12:42.839] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:42.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:67:0) after 1m0s
[09:13:42.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:67:0)
[09:13:42.865] Timer tick, asking Buildbucket for the build status
[09:13:42.971] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:42.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:68:0) after 1m0s
[09:14:43.056] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:68:0)
[09:14:43.056] Timer tick, asking Buildbucket for the build status
[09:14:43.166] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:43.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:69:0) after 1m0s
[09:15:43.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:69:0)
[09:15:43.345] Timer tick, asking Buildbucket for the build status
[09:15:43.403] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:43.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:70:0) after 1m0s
[09:16:43.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:70:0)
[09:16:43.648] Timer tick, asking Buildbucket for the build status
[09:16:43.741] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:43.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:71:0) after 1m0s
[09:17:43.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:71:0)
[09:17:43.840] Timer tick, asking Buildbucket for the build status
[09:17:44.098] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:44.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:72:0) after 1m0s
[09:18:44.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:72:0)
[09:18:44.211] Timer tick, asking Buildbucket for the build status
[09:18:44.322] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:44.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:73:0) after 1m0s
[09:19:44.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:73:0)
[09:19:44.364] Timer tick, asking Buildbucket for the build status
[09:19:44.432] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:44.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:74:0) after 1m0s
[09:20:44.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:74:0)
[09:20:44.469] Timer tick, asking Buildbucket for the build status
[09:20:44.570] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:44.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:75:0) after 1m0s
[09:21:44.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:75:0)
[09:21:44.599] Timer tick, asking Buildbucket for the build status
[09:21:44.673] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:44.673] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:76:0) after 1m0s
[09:22:44.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:76:0)
[09:22:44.714] Timer tick, asking Buildbucket for the build status
[09:22:44.875] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:44.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:77:0) after 1m0s
[09:23:44.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:77:0)
[09:23:44.915] Timer tick, asking Buildbucket for the build status
[09:23:45.206] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:45.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:78:0) after 1m0s
[09:24:45.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:78:0)
[09:24:45.210] Timer tick, asking Buildbucket for the build status
[09:24:45.264] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:45.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:79:0) after 1m0s
[09:25:45.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:79:0)
[09:25:45.356] Timer tick, asking Buildbucket for the build status
[09:25:45.422] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:45.422] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:80:0) after 1m0s
[09:26:45.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:80:0)
[09:26:45.430] Timer tick, asking Buildbucket for the build status
[09:26:45.497] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:45.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:81:0) after 1m0s
[09:27:45.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:81:0)
[09:27:45.588] Timer tick, asking Buildbucket for the build status
[09:27:45.652] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:45.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:82:0) after 1m0s
[09:28:45.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:82:0)
[09:28:45.696] Timer tick, asking Buildbucket for the build status
[09:28:45.834] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:45.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:83:0) after 1m0s
[09:29:45.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:83:0)
[09:29:45.861] Timer tick, asking Buildbucket for the build status
[09:29:45.980] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:45.980] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:84:0) after 1m0s
[09:30:45.997] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:84:0)
[09:30:45.997] Timer tick, asking Buildbucket for the build status
[09:30:46.105] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:46.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:85:0) after 1m0s
[09:31:46.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:85:0)
[09:31:46.143] Timer tick, asking Buildbucket for the build status
[09:31:46.233] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:46.233] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:86:0) after 1m0s
[09:32:46.254] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:86:0)
[09:32:46.254] Timer tick, asking Buildbucket for the build status
[09:32:46.474] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:46.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:87:0) after 1m0s
[09:33:46.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:87:0)
[09:33:46.519] Timer tick, asking Buildbucket for the build status
[09:33:46.708] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:46.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:88:0) after 1m0s
[09:34:46.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:88:0)
[09:34:46.730] Timer tick, asking Buildbucket for the build status
[09:34:46.932] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:46.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:89:0) after 1m0s
[09:35:46.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:89:0)
[09:35:46.959] Timer tick, asking Buildbucket for the build status
[09:35:47.142] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:47.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:90:0) after 1m0s
[09:36:47.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:90:0)
[09:36:47.165] Timer tick, asking Buildbucket for the build status
[09:36:47.378] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:47.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:91:0) after 1m0s
[09:37:47.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:91:0)
[09:37:47.378] Timer tick, asking Buildbucket for the build status
[09:37:47.456] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:47.456] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:92:0) after 1m0s
[09:38:47.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:92:0)
[09:38:47.479] Timer tick, asking Buildbucket for the build status
[09:38:47.586] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:47.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:93:0) after 1m0s
[09:39:47.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:93:0)
[09:39:47.601] Timer tick, asking Buildbucket for the build status
[09:39:47.666] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:47.666] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:94:0) after 1m0s
[09:40:47.716] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:94:0)
[09:40:47.716] Timer tick, asking Buildbucket for the build status
[09:40:47.839] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:47.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:95:0) after 1m0s
[09:41:47.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:95:0)
[09:41:47.858] Timer tick, asking Buildbucket for the build status
[09:41:47.918] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:47.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:96:0) after 1m0s
[09:42:48.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:96:0)
[09:42:48.001] Timer tick, asking Buildbucket for the build status
[09:42:48.207] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:48.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:97:0) after 1m0s
[09:43:48.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:97:0)
[09:43:48.228] Timer tick, asking Buildbucket for the build status
[09:43:48.344] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:48.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:98:0) after 1m0s
[09:44:48.412] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:98:0)
[09:44:48.412] Timer tick, asking Buildbucket for the build status
[09:44:48.545] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:48.545] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:99:0) after 1m0s
[09:45:48.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:99:0)
[09:45:48.591] Timer tick, asking Buildbucket for the build status
[09:45:48.656] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:48.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:100:0) after 1m0s
[09:46:48.677] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:100:0)
[09:46:48.677] Timer tick, asking Buildbucket for the build status
[09:46:48.762] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:48.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:101:0) after 1m0s
[09:47:48.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:101:0)
[09:47:48.784] Timer tick, asking Buildbucket for the build status
[09:47:48.854] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:48.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:102:0) after 1m0s
[09:48:48.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:102:0)
[09:48:48.868] Timer tick, asking Buildbucket for the build status
[09:48:48.975] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:48.975] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:103:0) after 1m0s
[09:49:49.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:103:0)
[09:49:49.005] Timer tick, asking Buildbucket for the build status
[09:49:49.064] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:49.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:104:0) after 1m0s
[09:50:49.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:104:0)
[09:50:49.097] Timer tick, asking Buildbucket for the build status
[09:50:49.176] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:49.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:105:0) after 1m0s
[09:51:49.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:105:0)
[09:51:49.308] Timer tick, asking Buildbucket for the build status
[09:51:49.419] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:49.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:106:0) after 1m0s
[09:52:49.539] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:106:0)
[09:52:49.539] Timer tick, asking Buildbucket for the build status
[09:52:49.629] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:49.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:107:0) after 1m0s
[09:53:49.718] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:107:0)
[09:53:49.718] Timer tick, asking Buildbucket for the build status
[09:53:49.806] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:49.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:108:0) after 1m0s
[09:54:49.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:108:0)
[09:54:49.782] Timer tick, asking Buildbucket for the build status
[09:54:50.219] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:50.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:109:0) after 1m0s
[09:55:51.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:109:0)
[09:55:51.586] Timer tick, asking Buildbucket for the build status
[09:55:51.678] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:51.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:110:0) after 1m0s
[09:56:51.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:110:0)
[09:56:51.795] Timer tick, asking Buildbucket for the build status
[09:56:51.860] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:51.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:111:0) after 1m0s
[09:57:51.981] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:111:0)
[09:57:51.981] Timer tick, asking Buildbucket for the build status
[09:57:52.052] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:52.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:112:0) after 1m0s
[09:58:52.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:112:0)
[09:58:52.341] Timer tick, asking Buildbucket for the build status
[09:58:52.829] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:52.829] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:113:0) after 1m0s
[09:59:52.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:113:0)
[09:59:52.881] Timer tick, asking Buildbucket for the build status
[09:59:52.993] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:52.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:114:0) after 1m0s
[10:00:53.209] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:114:0)
[10:00:53.209] Timer tick, asking Buildbucket for the build status
[10:00:53.412] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:53.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:115:0) after 1m0s
[10:01:57.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:115:0)
[10:01:57.610] Timer tick, asking Buildbucket for the build status
[10:01:57.669] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:57.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:116:0) after 1m0s
[10:02:57.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:116:0)
[10:02:57.733] Timer tick, asking Buildbucket for the build status
[10:02:57.791] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:57.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:117:0) after 1m0s
[10:03:57.844] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:117:0)
[10:03:57.844] Timer tick, asking Buildbucket for the build status
[10:03:57.934] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:57.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:118:0) after 1m0s
[10:04:57.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:118:0)
[10:04:57.994] Timer tick, asking Buildbucket for the build status
[10:04:58.085] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:58.085] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:119:0) after 1m0s
[10:05:58.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:119:0)
[10:05:58.118] Timer tick, asking Buildbucket for the build status
[10:05:58.169] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:58.169] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:120:0) after 1m0s
[10:06:58.260] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:120:0)
[10:06:58.260] Timer tick, asking Buildbucket for the build status
[10:06:58.339] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:58.339] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:121:0) after 1m0s
[10:07:58.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:121:0)
[10:07:58.361] Timer tick, asking Buildbucket for the build status
[10:07:58.414] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:58.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:122:0) after 1m0s
[10:08:58.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:122:0)
[10:08:58.423] Timer tick, asking Buildbucket for the build status
[10:08:58.511] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:58.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:123:0) after 1m0s
[10:09:58.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:123:0)
[10:09:58.543] Timer tick, asking Buildbucket for the build status
[10:09:58.646] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:58.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:124:0) after 1m0s
[10:10:58.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:124:0)
[10:10:58.668] Timer tick, asking Buildbucket for the build status
[10:10:58.724] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:58.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:125:0) after 1m0s
[10:11:58.754] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:125:0)
[10:11:58.754] Timer tick, asking Buildbucket for the build status
[10:11:59.115] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:59.115] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:126:0) after 1m0s
[10:12:59.140] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:126:0)
[10:12:59.140] Timer tick, asking Buildbucket for the build status
[10:12:59.391] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:59.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:127:0) after 1m0s
[10:13:59.453] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:127:0)
[10:13:59.453] Timer tick, asking Buildbucket for the build status
[10:13:59.569] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:59.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:128:0) after 1m0s
[10:14:59.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:128:0)
[10:14:59.593] Timer tick, asking Buildbucket for the build status
[10:14:59.668] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:59.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:129:0) after 1m0s
[10:15:59.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:129:0)
[10:15:59.722] Timer tick, asking Buildbucket for the build status
[10:15:59.790] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:59.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:130:0) after 1m0s
[10:16:59.828] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:130:0)
[10:16:59.828] Timer tick, asking Buildbucket for the build status
[10:16:59.891] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:59.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:131:0) after 1m0s
[10:17:59.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:131:0)
[10:17:59.945] Timer tick, asking Buildbucket for the build status
[10:17:59.999] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:59.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:132:0) after 1m0s
[10:18:59.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:132:0)
[10:18:59.986] Timer tick, asking Buildbucket for the build status
[10:19:00.135] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:00.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:133:0) after 1m0s
[10:20:00.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:133:0)
[10:20:00.163] Timer tick, asking Buildbucket for the build status
[10:20:01.574] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:01.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:134:0) after 1m0s
[10:21:01.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:134:0)
[10:21:01.598] Timer tick, asking Buildbucket for the build status
[10:21:01.657] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:01.657] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:135:0) after 1m0s
[10:22:01.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:135:0)
[10:22:01.766] Timer tick, asking Buildbucket for the build status
[10:22:02.019] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:02.019] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:136:0) after 1m0s
[10:23:02.043] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:136:0)
[10:23:02.043] Timer tick, asking Buildbucket for the build status
[10:23:02.128] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:02.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:137:0) after 1m0s
[10:24:02.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:137:0)
[10:24:02.199] Timer tick, asking Buildbucket for the build status
[10:24:02.820] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:02.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:138:0) after 1m0s
[10:25:02.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:138:0)
[10:25:02.855] Timer tick, asking Buildbucket for the build status
[10:25:03.060] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:03.060] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:139:0) after 1m0s
[10:26:03.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:139:0)
[10:26:03.055] Timer tick, asking Buildbucket for the build status
[10:26:03.146] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:03.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:140:0) after 1m0s
[10:27:03.200] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:140:0)
[10:27:03.200] Timer tick, asking Buildbucket for the build status
[10:27:03.410] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:03.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:141:0) after 1m0s
[10:28:03.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:141:0)
[10:28:03.683] Timer tick, asking Buildbucket for the build status
[10:28:03.744] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:03.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:142:0) after 1m0s
[10:29:03.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:142:0)
[10:29:03.834] Timer tick, asking Buildbucket for the build status
[10:29:04.104] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:04.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:143:0) after 1m0s
[10:30:04.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:143:0)
[10:30:04.178] Timer tick, asking Buildbucket for the build status
[10:30:04.249] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:04.249] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:144:0) after 1m0s
[10:31:04.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:144:0)
[10:31:04.451] Timer tick, asking Buildbucket for the build status
[10:31:04.543] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:04.543] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:145:0) after 1m0s
[10:32:05.516] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:145:0)
[10:32:05.516] Timer tick, asking Buildbucket for the build status
[10:32:05.844] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:05.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:146:0) after 1m0s
[10:33:05.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:146:0)
[10:33:05.869] Timer tick, asking Buildbucket for the build status
[10:33:05.922] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:05.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:147:0) after 1m0s
[10:34:05.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:147:0)
[10:34:05.914] Timer tick, asking Buildbucket for the build status
[10:34:06.049] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:06.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:148:0) after 1m0s
[10:35:06.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:148:0)
[10:35:06.093] Timer tick, asking Buildbucket for the build status
[10:35:06.162] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:06.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:149:0) after 1m0s
[10:36:06.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:149:0)
[10:36:06.213] Timer tick, asking Buildbucket for the build status
[10:36:06.265] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:06.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:150:0) after 1m0s
[10:37:06.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:150:0)
[10:37:06.521] Timer tick, asking Buildbucket for the build status
[10:37:06.612] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:06.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:151:0) after 1m0s
[10:38:07.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:151:0)
[10:38:07.219] Timer tick, asking Buildbucket for the build status
[10:38:07.334] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:07.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:152:0) after 1m0s
[10:39:07.418] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:152:0)
[10:39:07.418] Timer tick, asking Buildbucket for the build status
[10:39:07.734] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:07.734] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:153:0) after 1m0s
[10:40:07.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:153:0)
[10:40:07.902] Timer tick, asking Buildbucket for the build status
[10:40:07.953] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:07.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:154:0) after 1m0s
[10:41:08.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:154:0)
[10:41:08.305] Timer tick, asking Buildbucket for the build status
[10:41:08.381] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:08.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:155:0) after 1m0s
[10:42:08.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:155:0)
[10:42:08.563] Timer tick, asking Buildbucket for the build status
[10:42:08.623] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:08.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:156:0) after 1m0s
[10:43:08.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:156:0)
[10:43:08.693] Timer tick, asking Buildbucket for the build status
[10:43:08.867] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:08.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:157:0) after 1m0s
[10:44:08.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:157:0)
[10:44:08.910] Timer tick, asking Buildbucket for the build status
[10:44:09.111] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:09.111] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:158:0) after 1m0s
[10:45:09.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:158:0)
[10:45:09.168] Timer tick, asking Buildbucket for the build status
[10:45:09.216] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:09.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:159:0) after 1m0s
[10:46:09.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:159:0)
[10:46:09.310] Timer tick, asking Buildbucket for the build status
[10:46:09.391] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:09.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:160:0) after 1m0s
[10:47:09.412] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:160:0)
[10:47:09.412] Timer tick, asking Buildbucket for the build status
[10:47:09.505] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:09.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:161:0) after 1m0s
[10:48:09.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:161:0)
[10:48:09.485] Timer tick, asking Buildbucket for the build status
[10:48:09.555] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:09.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:162:0) after 1m0s
[10:49:09.587] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:162:0)
[10:49:09.587] Timer tick, asking Buildbucket for the build status
[10:49:09.740] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:09.740] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:163:0) after 1m0s
[10:50:09.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:163:0)
[10:50:09.770] Timer tick, asking Buildbucket for the build status
[10:50:09.866] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:09.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:164:0) after 1m0s
[10:51:09.856] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:164:0)
[10:51:09.856] Timer tick, asking Buildbucket for the build status
[10:51:09.942] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:09.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:165:0) after 1m0s
[10:52:10.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:165:0)
[10:52:10.004] Timer tick, asking Buildbucket for the build status
[10:52:10.227] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:10.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:166:0) after 1m0s
[10:53:10.250] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:166:0)
[10:53:10.250] Timer tick, asking Buildbucket for the build status
[10:53:10.384] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:10.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:167:0) after 1m0s
[10:54:10.404] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:167:0)
[10:54:10.404] Timer tick, asking Buildbucket for the build status
[10:54:10.678] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:10.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:168:0) after 1m0s
[10:55:10.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:168:0)
[10:55:10.689] Timer tick, asking Buildbucket for the build status
[10:55:10.758] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:10.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:169:0) after 1m0s
[10:56:10.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:169:0)
[10:56:10.817] Timer tick, asking Buildbucket for the build status
[10:56:10.945] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:10.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:170:0) after 1m0s
[10:57:11.057] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:170:0)
[10:57:11.057] Timer tick, asking Buildbucket for the build status
[10:57:11.111] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:11.111] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:171:0) after 1m0s
[10:58:11.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:171:0)
[10:58:11.156] Timer tick, asking Buildbucket for the build status
[10:58:11.294] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:11.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:172:0) after 1m0s
[10:59:11.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:172:0)
[10:59:11.519] Timer tick, asking Buildbucket for the build status
[10:59:11.767] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:11.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:173:0) after 1m0s
[11:00:11.856] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:173:0)
[11:00:11.856] Timer tick, asking Buildbucket for the build status
[11:00:12.726] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:12.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:174:0) after 1m0s
[11:01:14.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:174:0)
[11:01:14.436] Timer tick, asking Buildbucket for the build status
[11:01:14.610] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:14.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:175:0) after 1m0s
[11:02:14.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:175:0)
[11:02:14.596] Timer tick, asking Buildbucket for the build status
[11:02:14.663] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:14.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:176:0) after 1m0s
[11:03:14.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:176:0)
[11:03:14.723] Timer tick, asking Buildbucket for the build status
[11:03:14.841] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:14.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:177:0) after 1m0s
[11:04:14.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:177:0)
[11:04:14.963] Timer tick, asking Buildbucket for the build status
[11:04:15.182] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:15.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:178:0) after 1m0s
[11:05:15.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:178:0)
[11:05:15.234] Timer tick, asking Buildbucket for the build status
[11:05:15.322] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:15.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:179:0) after 1m0s
[11:06:15.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:179:0)
[11:06:15.399] Timer tick, asking Buildbucket for the build status
[11:06:15.460] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:15.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:180:0) after 1m0s
[11:07:15.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:180:0)
[11:07:15.549] Timer tick, asking Buildbucket for the build status
[11:07:15.803] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:15.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:181:0) after 1m0s
[11:08:15.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:181:0)
[11:08:15.841] Timer tick, asking Buildbucket for the build status
[11:08:16.254] Build 8862402999097789952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:16.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027833061055757040:182:0) after 1m0s
[11:08:29.176] Received PubSub notification, asking Buildbucket for the build status
[11:08:29.429] Build 8862402999097789952: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:08:29.429] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1606561708020607",
"created_by": "project:infra",
"created_ts": "1606550900326781",
"id": "8862402999097789952",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4339.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"ccae434e-097a-423a-baac-f680a996ec0e\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4339.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-z1su\"], \"image\": [\"chrome-xenial-20112200-f7cdea8df03\"], \"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\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606550923071023",
"status": "COMPLETED",
"status_changed_ts": "1606561708578317",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9027833061055757040",
"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/8862402999097789952/+/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:5026b6d9d7b49510",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1606561708745004",
"url": "https://ci.chromium.org/b/8862402999097789952",
"utcnow_ts": "1606561709417893"
}
}
[11:08:29.429] Invocation finished in 3h0m10.354014747s with status SUCCEEDED