[13:44:42.349] New invocation is queued and will start shortly
[13:44:42.349] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[13:44:43.450] Starting the invocation (attempt 1)
[13:44:43.519] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:44:43.519] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:44:43.519] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9084435001376350576",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"75.0.3733.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:9084435001376350576",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[13:44:44.563] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552571083621409",
"id": "8919004939379567984",
"lease_expiration_ts": "1552689884365163",
"lease_key": "496390756",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"75.0.3733.0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552571084371722",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9084435001376350576",
"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/8919004939379567984/+/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:43954434da9d3810",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1552571084371807",
"url": "https://ci.chromium.org/b/8919004939379567984",
"utcnow_ts": "1552571084550961"
}
}
[13:44:44.563] Task URL: https://ci.chromium.org/b/8919004939379567984
[13:44:44.563] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:2:0) after 1m0s
[13:45:37.552] Received PubSub notification, asking Buildbucket for the build status
[13:45:37.598] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:44.577] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:2:0)
[13:45:44.577] Timer tick, asking Buildbucket for the build status
[13:45:44.649] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:44.649] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:4:0) after 1m0s
[13:46:44.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:4:0)
[13:46:44.648] Timer tick, asking Buildbucket for the build status
[13:46:44.721] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:44.721] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:5:0) after 1m0s
[13:47:44.706] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:5:0)
[13:47:44.706] Timer tick, asking Buildbucket for the build status
[13:47:44.777] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:44.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:6:0) after 1m0s
[13:48:44.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:6:0)
[13:48:44.774] Timer tick, asking Buildbucket for the build status
[13:48:44.848] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:44.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:7:0) after 1m0s
[13:49:44.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:7:0)
[13:49:44.872] Timer tick, asking Buildbucket for the build status
[13:49:44.936] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:44.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:8:0) after 1m0s
[13:50:44.958] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:8:0)
[13:50:44.958] Timer tick, asking Buildbucket for the build status
[13:50:44.996] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:44.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:9:0) after 1m0s
[13:51:44.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:9:0)
[13:51:44.998] Timer tick, asking Buildbucket for the build status
[13:51:45.077] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:45.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:10:0) after 1m0s
[13:52:45.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:10:0)
[13:52:45.094] Timer tick, asking Buildbucket for the build status
[13:52:45.173] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:45.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:11:0) after 1m0s
[13:53:45.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:11:0)
[13:53:45.184] Timer tick, asking Buildbucket for the build status
[13:53:45.222] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:45.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:12:0) after 1m0s
[13:54:45.242] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:12:0)
[13:54:45.242] Timer tick, asking Buildbucket for the build status
[13:54:45.336] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:45.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:13:0) after 1m0s
[13:55:45.355] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:13:0)
[13:55:45.355] Timer tick, asking Buildbucket for the build status
[13:55:45.406] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:45.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:14:0) after 1m0s
[13:56:45.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:14:0)
[13:56:45.396] Timer tick, asking Buildbucket for the build status
[13:56:45.599] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:45.599] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:15:0) after 1m0s
[13:57:45.620] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:15:0)
[13:57:45.620] Timer tick, asking Buildbucket for the build status
[13:57:45.663] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:45.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:16:0) after 1m0s
[13:58:45.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:16:0)
[13:58:45.689] Timer tick, asking Buildbucket for the build status
[13:58:45.922] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:45.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:17:0) after 1m0s
[13:59:45.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:17:0)
[13:59:45.939] Timer tick, asking Buildbucket for the build status
[13:59:46.209] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:46.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:18:0) after 1m0s
[14:00:46.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:18:0)
[14:00:46.210] Timer tick, asking Buildbucket for the build status
[14:00:46.251] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:46.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:19:0) after 1m0s
[14:01:46.253] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:19:0)
[14:01:46.253] Timer tick, asking Buildbucket for the build status
[14:01:46.388] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:46.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:20:0) after 1m0s
[14:02:46.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:20:0)
[14:02:46.423] Timer tick, asking Buildbucket for the build status
[14:02:46.464] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:46.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:21:0) after 1m0s
[14:03:46.483] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:21:0)
[14:03:46.483] Timer tick, asking Buildbucket for the build status
[14:03:46.525] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:46.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:22:0) after 1m0s
[14:04:46.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:22:0)
[14:04:46.545] Timer tick, asking Buildbucket for the build status
[14:04:46.639] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:46.639] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:23:0) after 1m0s
[14:05:46.665] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:23:0)
[14:05:46.665] Timer tick, asking Buildbucket for the build status
[14:05:46.717] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:46.717] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:24:0) after 1m0s
[14:06:46.725] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:24:0)
[14:06:46.725] Timer tick, asking Buildbucket for the build status
[14:06:46.803] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:46.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:25:0) after 1m0s
[14:07:46.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:25:0)
[14:07:46.817] Timer tick, asking Buildbucket for the build status
[14:07:46.939] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:46.939] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:26:0) after 1m0s
[14:08:46.948] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:26:0)
[14:08:46.948] Timer tick, asking Buildbucket for the build status
[14:08:47.009] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:47.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:27:0) after 1m0s
[14:09:46.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:27:0)
[14:09:46.984] Timer tick, asking Buildbucket for the build status
[14:09:47.018] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:47.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:28:0) after 1m0s
[14:10:47.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:28:0)
[14:10:47.040] Timer tick, asking Buildbucket for the build status
[14:10:47.091] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:47.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:29:0) after 1m0s
[14:12:45.773] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:29:0)
[14:12:45.773] Timer tick, asking Buildbucket for the build status
[14:12:45.887] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:45.887] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:30:0) after 1m0s
[14:13:45.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:30:0)
[14:13:45.906] Timer tick, asking Buildbucket for the build status
[14:13:46.013] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:46.013] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:31:0) after 1m0s
[14:14:45.999] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:31:0)
[14:14:45.999] Timer tick, asking Buildbucket for the build status
[14:14:46.157] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:46.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:32:0) after 1m0s
[14:15:46.132] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:32:0)
[14:15:46.132] Timer tick, asking Buildbucket for the build status
[14:15:46.174] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:46.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:33:0) after 1m0s
[14:16:46.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:33:0)
[14:16:46.193] Timer tick, asking Buildbucket for the build status
[14:16:46.280] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:46.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:34:0) after 1m0s
[14:17:46.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:34:0)
[14:17:46.291] Timer tick, asking Buildbucket for the build status
[14:17:46.364] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:46.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:35:0) after 1m0s
[14:18:46.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:35:0)
[14:18:46.384] Timer tick, asking Buildbucket for the build status
[14:18:46.495] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:46.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:36:0) after 1m0s
[14:19:46.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:36:0)
[14:19:46.511] Timer tick, asking Buildbucket for the build status
[14:19:46.586] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:46.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:37:0) after 1m0s
[14:20:46.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:37:0)
[14:20:46.607] Timer tick, asking Buildbucket for the build status
[14:20:46.708] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:46.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:38:0) after 1m0s
[14:21:46.700] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:38:0)
[14:21:46.700] Timer tick, asking Buildbucket for the build status
[14:21:46.811] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:46.811] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:39:0) after 1m0s
[14:22:46.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:39:0)
[14:22:46.786] Timer tick, asking Buildbucket for the build status
[14:22:46.832] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:46.832] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:40:0) after 1m0s
[14:23:46.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:40:0)
[14:23:46.817] Timer tick, asking Buildbucket for the build status
[14:23:46.867] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:46.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:41:0) after 1m0s
[14:24:46.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:41:0)
[14:24:46.840] Timer tick, asking Buildbucket for the build status
[14:24:46.901] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:46.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:42:0) after 1m0s
[14:25:46.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:42:0)
[14:25:46.953] Timer tick, asking Buildbucket for the build status
[14:25:47.082] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:47.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:43:0) after 1m0s
[14:26:47.076] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:43:0)
[14:26:47.076] Timer tick, asking Buildbucket for the build status
[14:26:47.154] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:47.154] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:44:0) after 1m0s
[14:27:47.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:44:0)
[14:27:47.144] Timer tick, asking Buildbucket for the build status
[14:27:47.289] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:47.289] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:45:0) after 1m0s
[14:28:47.289] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:45:0)
[14:28:47.289] Timer tick, asking Buildbucket for the build status
[14:28:47.390] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:47.390] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:46:0) after 1m0s
[14:29:47.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:46:0)
[14:29:47.410] Timer tick, asking Buildbucket for the build status
[14:29:47.503] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:47.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:47:0) after 1m0s
[14:30:47.476] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:47:0)
[14:30:47.476] Timer tick, asking Buildbucket for the build status
[14:30:47.523] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:47.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:48:0) after 1m0s
[14:31:47.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:48:0)
[14:31:47.540] Timer tick, asking Buildbucket for the build status
[14:31:47.629] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:47.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:49:0) after 1m0s
[14:32:47.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:49:0)
[14:32:47.648] Timer tick, asking Buildbucket for the build status
[14:32:47.760] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:47.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:50:0) after 1m0s
[14:33:47.731] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:50:0)
[14:33:47.731] Timer tick, asking Buildbucket for the build status
[14:33:48.012] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:48.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:51:0) after 1m0s
[14:34:48.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:51:0)
[14:34:48.004] Timer tick, asking Buildbucket for the build status
[14:34:48.037] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:48.037] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:52:0) after 1m0s
[14:35:48.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:52:0)
[14:35:48.046] Timer tick, asking Buildbucket for the build status
[14:35:48.100] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:48.100] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:53:0) after 1m0s
[14:36:48.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:53:0)
[14:36:48.123] Timer tick, asking Buildbucket for the build status
[14:36:48.172] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:48.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:54:0) after 1m0s
[14:37:48.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:54:0)
[14:37:48.203] Timer tick, asking Buildbucket for the build status
[14:37:48.260] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:48.260] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:55:0) after 1m0s
[14:38:48.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:55:0)
[14:38:48.280] Timer tick, asking Buildbucket for the build status
[14:38:48.355] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:48.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:56:0) after 1m0s
[14:39:48.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:56:0)
[14:39:48.348] Timer tick, asking Buildbucket for the build status
[14:39:48.396] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:48.396] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:57:0) after 1m0s
[14:40:48.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:57:0)
[14:40:48.399] Timer tick, asking Buildbucket for the build status
[14:40:48.433] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:48.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:58:0) after 1m0s
[14:41:48.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:58:0)
[14:41:48.435] Timer tick, asking Buildbucket for the build status
[14:41:48.503] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:48.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:59:0) after 1m0s
[14:42:48.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:59:0)
[14:42:48.479] Timer tick, asking Buildbucket for the build status
[14:42:48.527] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:48.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:60:0) after 1m0s
[14:43:48.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:60:0)
[14:43:48.511] Timer tick, asking Buildbucket for the build status
[14:43:48.625] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:48.625] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:61:0) after 1m0s
[14:44:48.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:61:0)
[14:44:48.640] Timer tick, asking Buildbucket for the build status
[14:44:48.680] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:48.680] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:62:0) after 1m0s
[14:45:48.680] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:62:0)
[14:45:48.680] Timer tick, asking Buildbucket for the build status
[14:45:48.763] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:48.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:63:0) after 1m0s
[14:46:48.785] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:63:0)
[14:46:48.785] Timer tick, asking Buildbucket for the build status
[14:46:48.866] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:48.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:64:0) after 1m0s
[14:47:48.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:64:0)
[14:47:48.880] Timer tick, asking Buildbucket for the build status
[14:47:48.934] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:48.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:65:0) after 1m0s
[14:48:48.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:65:0)
[14:48:48.962] Timer tick, asking Buildbucket for the build status
[14:48:48.995] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:48.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:66:0) after 1m0s
[14:49:49.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:66:0)
[14:49:49.017] Timer tick, asking Buildbucket for the build status
[14:49:49.164] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:49.164] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:67:0) after 1m0s
[14:50:49.181] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:67:0)
[14:50:49.181] Timer tick, asking Buildbucket for the build status
[14:50:49.237] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:49.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:68:0) after 1m0s
[14:51:49.257] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:68:0)
[14:51:49.257] Timer tick, asking Buildbucket for the build status
[14:51:49.504] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:49.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:69:0) after 1m0s
[14:52:49.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:69:0)
[14:52:49.524] Timer tick, asking Buildbucket for the build status
[14:52:49.567] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:49.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:70:0) after 1m0s
[14:53:49.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:70:0)
[14:53:49.546] Timer tick, asking Buildbucket for the build status
[14:53:49.601] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:49.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:71:0) after 1m0s
[14:54:49.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:71:0)
[14:54:49.602] Timer tick, asking Buildbucket for the build status
[14:54:49.784] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:49.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:72:0) after 1m0s
[14:55:49.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:72:0)
[14:55:49.795] Timer tick, asking Buildbucket for the build status
[14:55:49.935] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:49.935] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:73:0) after 1m0s
[14:56:49.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:73:0)
[14:56:49.955] Timer tick, asking Buildbucket for the build status
[14:56:50.068] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:50.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:74:0) after 1m0s
[14:57:50.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:74:0)
[14:57:50.093] Timer tick, asking Buildbucket for the build status
[14:57:50.150] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:50.150] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:75:0) after 1m0s
[14:58:50.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:75:0)
[14:58:50.171] Timer tick, asking Buildbucket for the build status
[14:58:50.207] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:50.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:76:0) after 1m0s
[14:59:50.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:76:0)
[14:59:50.180] Timer tick, asking Buildbucket for the build status
[14:59:50.243] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:50.243] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:77:0) after 1m0s
[15:00:50.242] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:77:0)
[15:00:50.242] Timer tick, asking Buildbucket for the build status
[15:00:50.296] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:50.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:78:0) after 1m0s
[15:01:50.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:78:0)
[15:01:50.271] Timer tick, asking Buildbucket for the build status
[15:01:50.439] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:50.439] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:79:0) after 1m0s
[15:02:50.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:79:0)
[15:02:50.457] Timer tick, asking Buildbucket for the build status
[15:02:50.491] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:50.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:80:0) after 1m0s
[15:03:50.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:80:0)
[15:03:50.514] Timer tick, asking Buildbucket for the build status
[15:03:50.581] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:50.581] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:81:0) after 1m0s
[15:04:50.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:81:0)
[15:04:50.604] Timer tick, asking Buildbucket for the build status
[15:04:50.655] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:50.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:82:0) after 1m0s
[15:05:50.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:82:0)
[15:05:50.668] Timer tick, asking Buildbucket for the build status
[15:05:50.731] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:50.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:83:0) after 1m0s
[15:06:50.751] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:83:0)
[15:06:50.751] Timer tick, asking Buildbucket for the build status
[15:06:50.861] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:50.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:84:0) after 1m0s
[15:07:50.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:84:0)
[15:07:50.841] Timer tick, asking Buildbucket for the build status
[15:07:50.987] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:50.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:85:0) after 1m0s
[15:08:50.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:85:0)
[15:08:50.998] Timer tick, asking Buildbucket for the build status
[15:08:51.036] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:51.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:86:0) after 1m0s
[15:09:51.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:86:0)
[15:09:51.046] Timer tick, asking Buildbucket for the build status
[15:09:51.185] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:51.185] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:87:0) after 1m0s
[15:10:51.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:87:0)
[15:10:51.197] Timer tick, asking Buildbucket for the build status
[15:10:51.275] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:51.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:88:0) after 1m0s
[15:11:51.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:88:0)
[15:11:51.311] Timer tick, asking Buildbucket for the build status
[15:11:51.378] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:51.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:89:0) after 1m0s
[15:12:51.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:89:0)
[15:12:51.399] Timer tick, asking Buildbucket for the build status
[15:12:51.435] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:51.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:90:0) after 1m0s
[15:13:51.467] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:90:0)
[15:13:51.467] Timer tick, asking Buildbucket for the build status
[15:13:51.675] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:51.675] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:91:0) after 1m0s
[15:14:51.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:91:0)
[15:14:51.673] Timer tick, asking Buildbucket for the build status
[15:14:51.711] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:51.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:92:0) after 1m0s
[15:15:51.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:92:0)
[15:15:51.737] Timer tick, asking Buildbucket for the build status
[15:15:51.848] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:51.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:93:0) after 1m0s
[15:16:51.823] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:93:0)
[15:16:51.823] Timer tick, asking Buildbucket for the build status
[15:16:52.081] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:52.081] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:94:0) after 1m0s
[15:17:52.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:94:0)
[15:17:52.107] Timer tick, asking Buildbucket for the build status
[15:17:52.150] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:52.150] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:95:0) after 1m0s
[15:18:52.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:95:0)
[15:18:52.137] Timer tick, asking Buildbucket for the build status
[15:18:52.210] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:52.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:96:0) after 1m0s
[15:19:52.229] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:96:0)
[15:19:52.229] Timer tick, asking Buildbucket for the build status
[15:19:52.291] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:52.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:97:0) after 1m0s
[15:20:52.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:97:0)
[15:20:52.268] Timer tick, asking Buildbucket for the build status
[15:20:52.442] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:52.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:98:0) after 1m0s
[15:21:52.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:98:0)
[15:21:52.414] Timer tick, asking Buildbucket for the build status
[15:21:52.468] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:52.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:99:0) after 1m0s
[15:22:52.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:99:0)
[15:22:52.507] Timer tick, asking Buildbucket for the build status
[15:22:52.539] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:52.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:100:0) after 1m0s
[15:23:52.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:100:0)
[15:23:52.526] Timer tick, asking Buildbucket for the build status
[15:23:52.757] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:52.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:101:0) after 1m0s
[15:24:52.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:101:0)
[15:24:52.776] Timer tick, asking Buildbucket for the build status
[15:24:52.819] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:52.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:102:0) after 1m0s
[15:25:52.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:102:0)
[15:25:52.919] Timer tick, asking Buildbucket for the build status
[15:25:53.011] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:53.011] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:103:0) after 1m0s
[15:26:52.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:103:0)
[15:26:52.996] Timer tick, asking Buildbucket for the build status
[15:26:53.033] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:53.033] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:104:0) after 1m0s
[15:27:53.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:104:0)
[15:27:53.029] Timer tick, asking Buildbucket for the build status
[15:27:53.103] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:53.103] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:105:0) after 1m0s
[15:28:53.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:105:0)
[15:28:53.104] Timer tick, asking Buildbucket for the build status
[15:28:53.138] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:53.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:106:0) after 1m0s
[15:29:53.121] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:106:0)
[15:29:53.121] Timer tick, asking Buildbucket for the build status
[15:29:53.174] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:53.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:107:0) after 1m0s
[15:30:53.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:107:0)
[15:30:53.149] Timer tick, asking Buildbucket for the build status
[15:30:53.197] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:53.197] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:108:0) after 1m0s
[15:31:53.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:108:0)
[15:31:53.217] Timer tick, asking Buildbucket for the build status
[15:31:53.317] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:53.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:109:0) after 1m0s
[15:32:53.338] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:109:0)
[15:32:53.338] Timer tick, asking Buildbucket for the build status
[15:32:53.373] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:53.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:110:0) after 1m0s
[15:33:53.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:110:0)
[15:33:53.372] Timer tick, asking Buildbucket for the build status
[15:33:53.601] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:53.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:111:0) after 1m0s
[15:34:53.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:111:0)
[15:34:53.646] Timer tick, asking Buildbucket for the build status
[15:34:53.683] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:53.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:112:0) after 1m0s
[15:35:53.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:112:0)
[15:35:53.696] Timer tick, asking Buildbucket for the build status
[15:35:53.729] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:53.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:113:0) after 1m0s
[15:36:53.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:113:0)
[15:36:53.749] Timer tick, asking Buildbucket for the build status
[15:36:53.794] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:53.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:114:0) after 1m0s
[15:37:53.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:114:0)
[15:37:53.816] Timer tick, asking Buildbucket for the build status
[15:37:53.983] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:53.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:115:0) after 1m0s
[15:38:54.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:115:0)
[15:38:54.005] Timer tick, asking Buildbucket for the build status
[15:38:54.042] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:54.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:116:0) after 1m0s
[15:39:54.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:116:0)
[15:39:54.065] Timer tick, asking Buildbucket for the build status
[15:39:54.098] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:54.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:117:0) after 1m0s
[15:40:54.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:117:0)
[15:40:54.123] Timer tick, asking Buildbucket for the build status
[15:40:54.358] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:54.358] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:118:0) after 1m0s
[15:41:54.380] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:118:0)
[15:41:54.380] Timer tick, asking Buildbucket for the build status
[15:41:54.530] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:54.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:119:0) after 1m0s
[15:42:54.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:119:0)
[15:42:54.534] Timer tick, asking Buildbucket for the build status
[15:42:54.663] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:54.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:120:0) after 1m0s
[15:43:54.642] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:120:0)
[15:43:54.642] Timer tick, asking Buildbucket for the build status
[15:43:54.707] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:54.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:121:0) after 1m0s
[15:44:54.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:121:0)
[15:44:54.697] Timer tick, asking Buildbucket for the build status
[15:44:54.734] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:54.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:122:0) after 1m0s
[15:45:54.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:122:0)
[15:45:54.760] Timer tick, asking Buildbucket for the build status
[15:45:54.867] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:54.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:123:0) after 1m0s
[15:46:54.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:123:0)
[15:46:54.895] Timer tick, asking Buildbucket for the build status
[15:46:54.932] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:54.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:124:0) after 1m0s
[15:47:54.928] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:124:0)
[15:47:54.928] Timer tick, asking Buildbucket for the build status
[15:47:55.036] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:55.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:125:0) after 1m0s
[15:48:55.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:125:0)
[15:48:55.036] Timer tick, asking Buildbucket for the build status
[15:48:55.077] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:55.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:126:0) after 1m0s
[15:49:55.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:126:0)
[15:49:55.125] Timer tick, asking Buildbucket for the build status
[15:49:55.272] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:55.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:127:0) after 1m0s
[15:50:55.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:127:0)
[15:50:55.284] Timer tick, asking Buildbucket for the build status
[15:50:55.377] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:55.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:128:0) after 1m0s
[15:51:55.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:128:0)
[15:51:55.401] Timer tick, asking Buildbucket for the build status
[15:51:55.459] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:55.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:129:0) after 1m0s
[15:52:55.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:129:0)
[15:52:55.460] Timer tick, asking Buildbucket for the build status
[15:52:55.620] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:55.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:130:0) after 1m0s
[15:53:55.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:130:0)
[15:53:55.602] Timer tick, asking Buildbucket for the build status
[15:53:55.681] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:55.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:131:0) after 1m0s
[15:54:55.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:131:0)
[15:54:55.659] Timer tick, asking Buildbucket for the build status
[15:54:55.728] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:55.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:132:0) after 1m0s
[15:55:55.699] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:132:0)
[15:55:55.699] Timer tick, asking Buildbucket for the build status
[15:55:55.875] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:55.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:133:0) after 1m0s
[15:56:55.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:133:0)
[15:56:55.855] Timer tick, asking Buildbucket for the build status
[15:56:55.895] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:55.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:134:0) after 1m0s
[15:57:55.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:134:0)
[15:57:55.876] Timer tick, asking Buildbucket for the build status
[15:57:55.921] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:55.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:135:0) after 1m0s
[15:58:55.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:135:0)
[15:58:55.927] Timer tick, asking Buildbucket for the build status
[15:58:55.961] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:55.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:136:0) after 1m0s
[15:59:55.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:136:0)
[15:59:55.938] Timer tick, asking Buildbucket for the build status
[15:59:55.995] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:55.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:137:0) after 1m0s
[16:00:55.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:137:0)
[16:00:55.996] Timer tick, asking Buildbucket for the build status
[16:00:56.096] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:56.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:138:0) after 1m0s
[16:01:56.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:138:0)
[16:01:56.107] Timer tick, asking Buildbucket for the build status
[16:01:56.234] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:56.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:139:0) after 1m0s
[16:02:56.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:139:0)
[16:02:56.277] Timer tick, asking Buildbucket for the build status
[16:02:56.315] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:56.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:140:0) after 1m0s
[16:03:56.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:140:0)
[16:03:56.319] Timer tick, asking Buildbucket for the build status
[16:03:56.469] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:56.470] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:141:0) after 1m0s
[16:04:56.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:141:0)
[16:04:56.487] Timer tick, asking Buildbucket for the build status
[16:04:56.589] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:56.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:142:0) after 1m0s
[16:05:56.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:142:0)
[16:05:56.595] Timer tick, asking Buildbucket for the build status
[16:05:56.761] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:56.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:143:0) after 1m0s
[16:06:56.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:143:0)
[16:06:56.779] Timer tick, asking Buildbucket for the build status
[16:06:56.857] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:56.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:144:0) after 1m0s
[16:07:56.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:144:0)
[16:07:56.896] Timer tick, asking Buildbucket for the build status
[16:07:57.218] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:57.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:145:0) after 1m0s
[16:08:57.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:145:0)
[16:08:57.204] Timer tick, asking Buildbucket for the build status
[16:08:57.318] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:57.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:146:0) after 1m0s
[16:09:57.338] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:146:0)
[16:09:57.338] Timer tick, asking Buildbucket for the build status
[16:09:57.384] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:57.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:147:0) after 1m0s
[16:10:57.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:147:0)
[16:10:57.416] Timer tick, asking Buildbucket for the build status
[16:10:57.801] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:57.801] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:148:0) after 1m0s
[16:11:57.826] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:148:0)
[16:11:57.826] Timer tick, asking Buildbucket for the build status
[16:11:57.864] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:57.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:149:0) after 1m0s
[16:12:57.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:149:0)
[16:12:57.854] Timer tick, asking Buildbucket for the build status
[16:12:57.892] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:57.892] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:150:0) after 1m0s
[16:13:57.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:150:0)
[16:13:57.870] Timer tick, asking Buildbucket for the build status
[16:13:58.224] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:58.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:151:0) after 1m0s
[16:14:58.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:151:0)
[16:14:58.222] Timer tick, asking Buildbucket for the build status
[16:14:58.313] Build 8919004939379567984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:58.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084435001376350576:152:0) after 1m0s
[16:15:25.433] Received PubSub notification, asking Buildbucket for the build status
[16:15:25.470] Build 8919004939379567984: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:15:25.470] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1552580124588787",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552571083621409",
"id": "8919004939379567984",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"75.0.3733.0\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-32\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Build From Tarball\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"4186-af94c3f\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"32\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"build-from-tarball-0-2172\"], \"pool\": [\"luci.infra.cron\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildername\": \"Build From Tarball\", \"recipe\": \"build_from_tarball\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"version\": \"75.0.3733.0\", \"path_config\": \"generic\", \"bot_id\": \"build-from-tarball-0-2172\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552571136619944",
"status": "COMPLETED",
"status_changed_ts": "1552580125121057",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9084435001376350576",
"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/8919004939379567984/+/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:43954434da9d3810",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1552580125123304",
"url": "https://ci.chromium.org/b/8919004939379567984",
"utcnow_ts": "1552580125462080"
}
}
[16:15:25.470] Invocation finished in 2h30m43.137177126s with status SUCCEEDED