[12:10:42.091] New invocation is queued and will start shortly
[12:10:42.091] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[12:10:43.172] Starting the invocation (attempt 1)
[12:10:43.215] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:10:43.215] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:10:43.215] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9085346885281903056",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"74.0.3724.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:9085346885281903056",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[12:10:45.294] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551701443325283",
"id": "8919916823322055392",
"lease_expiration_ts": "1551820244047189",
"lease_key": "1892694662",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3724.0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551701444047913",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9085346885281903056",
"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/8919916823322055392/+/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:43616e8cd87e3b10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1551701444048018",
"url": "https://ci.chromium.org/b/8919916823322055392",
"utcnow_ts": "1551701445148546"
}
}
[12:10:45.294] Task URL: https://ci.chromium.org/b/8919916823322055392
[12:10:45.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:2:0) after 1m0s
[12:10:56.087] Received PubSub notification, asking Buildbucket for the build status
[12:10:56.129] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:45.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:2:0)
[12:11:45.312] Timer tick, asking Buildbucket for the build status
[12:11:45.407] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:45.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:4:0) after 1m0s
[12:12:45.412] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:4:0)
[12:12:45.412] Timer tick, asking Buildbucket for the build status
[12:12:45.513] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:45.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:5:0) after 1m0s
[12:13:45.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:5:0)
[12:13:45.487] Timer tick, asking Buildbucket for the build status
[12:13:45.821] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:45.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:6:0) after 1m0s
[12:14:45.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:6:0)
[12:14:45.803] Timer tick, asking Buildbucket for the build status
[12:14:46.009] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:46.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:7:0) after 1m0s
[12:15:45.980] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:7:0)
[12:15:45.980] Timer tick, asking Buildbucket for the build status
[12:15:46.025] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:46.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:8:0) after 1m0s
[12:16:46.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:8:0)
[12:16:46.074] Timer tick, asking Buildbucket for the build status
[12:16:46.157] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:46.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:9:0) after 1m0s
[12:17:46.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:9:0)
[12:17:46.176] Timer tick, asking Buildbucket for the build status
[12:17:46.237] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:46.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:10:0) after 1m0s
[12:18:46.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:10:0)
[12:18:46.255] Timer tick, asking Buildbucket for the build status
[12:18:46.357] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:46.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:11:0) after 1m0s
[12:19:46.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:11:0)
[12:19:46.433] Timer tick, asking Buildbucket for the build status
[12:19:46.583] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:46.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:12:0) after 1m0s
[12:20:46.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:12:0)
[12:20:46.558] Timer tick, asking Buildbucket for the build status
[12:20:46.692] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:46.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:13:0) after 1m0s
[12:21:46.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:13:0)
[12:21:46.671] Timer tick, asking Buildbucket for the build status
[12:21:46.735] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:46.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:14:0) after 1m0s
[12:22:46.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:14:0)
[12:22:46.708] Timer tick, asking Buildbucket for the build status
[12:22:46.784] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:46.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:15:0) after 1m0s
[12:23:46.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:15:0)
[12:23:46.789] Timer tick, asking Buildbucket for the build status
[12:23:46.845] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:46.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:16:0) after 1m0s
[12:24:46.873] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:16:0)
[12:24:46.873] Timer tick, asking Buildbucket for the build status
[12:24:46.915] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:46.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:17:0) after 1m0s
[12:25:46.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:17:0)
[12:25:46.895] Timer tick, asking Buildbucket for the build status
[12:25:47.077] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:47.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:18:0) after 1m0s
[12:26:47.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:18:0)
[12:26:47.085] Timer tick, asking Buildbucket for the build status
[12:26:47.200] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:47.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:19:0) after 1m0s
[12:27:47.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:19:0)
[12:27:47.210] Timer tick, asking Buildbucket for the build status
[12:27:47.274] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:47.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:20:0) after 1m0s
[12:28:47.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:20:0)
[12:28:47.246] Timer tick, asking Buildbucket for the build status
[12:28:47.296] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:47.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:21:0) after 1m0s
[12:29:47.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:21:0)
[12:29:47.287] Timer tick, asking Buildbucket for the build status
[12:29:47.488] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:47.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:22:0) after 1m0s
[12:30:47.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:22:0)
[12:30:47.497] Timer tick, asking Buildbucket for the build status
[12:30:47.589] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:47.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:23:0) after 1m0s
[12:31:47.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:23:0)
[12:31:47.607] Timer tick, asking Buildbucket for the build status
[12:31:47.670] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:47.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:24:0) after 1m0s
[12:32:47.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:24:0)
[12:32:47.688] Timer tick, asking Buildbucket for the build status
[12:32:47.825] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:47.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:25:0) after 1m0s
[12:33:47.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:25:0)
[12:33:47.843] Timer tick, asking Buildbucket for the build status
[12:33:47.900] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:47.900] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:26:0) after 1m0s
[12:34:47.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:26:0)
[12:34:47.918] Timer tick, asking Buildbucket for the build status
[12:34:48.306] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:48.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:27:0) after 1m0s
[12:35:48.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:27:0)
[12:35:48.397] Timer tick, asking Buildbucket for the build status
[12:35:48.470] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:48.470] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:28:0) after 1m0s
[12:36:48.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:28:0)
[12:36:48.510] Timer tick, asking Buildbucket for the build status
[12:36:48.549] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:48.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:29:0) after 1m0s
[12:37:48.566] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:29:0)
[12:37:48.566] Timer tick, asking Buildbucket for the build status
[12:37:48.646] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:48.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:30:0) after 1m0s
[12:38:48.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:30:0)
[12:38:48.666] Timer tick, asking Buildbucket for the build status
[12:38:48.709] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:48.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:31:0) after 1m0s
[12:39:48.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:31:0)
[12:39:48.733] Timer tick, asking Buildbucket for the build status
[12:39:49.014] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:49.014] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:32:0) after 1m0s
[12:40:49.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:32:0)
[12:40:49.031] Timer tick, asking Buildbucket for the build status
[12:40:49.087] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:49.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:33:0) after 1m0s
[12:41:49.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:33:0)
[12:41:49.088] Timer tick, asking Buildbucket for the build status
[12:41:49.209] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:49.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:34:0) after 1m0s
[12:42:49.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:34:0)
[12:42:49.232] Timer tick, asking Buildbucket for the build status
[12:42:49.359] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:49.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:35:0) after 1m0s
[12:43:49.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:35:0)
[12:43:49.364] Timer tick, asking Buildbucket for the build status
[12:43:49.437] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:49.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:36:0) after 1m0s
[12:44:49.474] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:36:0)
[12:44:49.474] Timer tick, asking Buildbucket for the build status
[12:44:49.591] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:49.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:37:0) after 1m0s
[12:45:49.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:37:0)
[12:45:49.562] Timer tick, asking Buildbucket for the build status
[12:45:49.667] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:49.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:38:0) after 1m0s
[12:46:49.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:38:0)
[12:46:49.687] Timer tick, asking Buildbucket for the build status
[12:46:49.812] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:49.812] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:39:0) after 1m0s
[12:47:49.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:39:0)
[12:47:49.790] Timer tick, asking Buildbucket for the build status
[12:47:49.889] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:49.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:40:0) after 1m0s
[12:48:49.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:40:0)
[12:48:49.909] Timer tick, asking Buildbucket for the build status
[12:48:49.957] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:49.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:41:0) after 1m0s
[12:49:49.977] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:41:0)
[12:49:49.977] Timer tick, asking Buildbucket for the build status
[12:49:50.166] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:50.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:42:0) after 1m0s
[12:50:50.200] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:42:0)
[12:50:50.200] Timer tick, asking Buildbucket for the build status
[12:50:50.290] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:50.290] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:43:0) after 1m0s
[12:51:50.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:43:0)
[12:51:50.310] Timer tick, asking Buildbucket for the build status
[12:51:50.403] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:50.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:44:0) after 1m0s
[12:52:50.407] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:44:0)
[12:52:50.407] Timer tick, asking Buildbucket for the build status
[12:52:50.468] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:50.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:45:0) after 1m0s
[12:53:50.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:45:0)
[12:53:50.481] Timer tick, asking Buildbucket for the build status
[12:53:50.568] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:50.568] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:46:0) after 1m0s
[12:54:50.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:46:0)
[12:54:50.538] Timer tick, asking Buildbucket for the build status
[12:54:50.578] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:50.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:47:0) after 1m0s
[12:55:50.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:47:0)
[12:55:50.569] Timer tick, asking Buildbucket for the build status
[12:55:50.622] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:50.622] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:48:0) after 1m0s
[12:56:50.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:48:0)
[12:56:50.640] Timer tick, asking Buildbucket for the build status
[12:56:50.679] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:50.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:49:0) after 1m0s
[12:57:50.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:49:0)
[12:57:50.694] Timer tick, asking Buildbucket for the build status
[12:57:50.734] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:50.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:50:0) after 1m0s
[12:58:50.755] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:50:0)
[12:58:50.755] Timer tick, asking Buildbucket for the build status
[12:58:50.936] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:50.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:51:0) after 1m0s
[12:59:50.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:51:0)
[12:59:50.923] Timer tick, asking Buildbucket for the build status
[12:59:51.007] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:51.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:52:0) after 1m0s
[13:00:51.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:52:0)
[13:00:51.033] Timer tick, asking Buildbucket for the build status
[13:00:51.082] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:51.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:53:0) after 1m0s
[13:01:51.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:53:0)
[13:01:51.088] Timer tick, asking Buildbucket for the build status
[13:01:51.167] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:51.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:54:0) after 1m0s
[13:02:51.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:54:0)
[13:02:51.179] Timer tick, asking Buildbucket for the build status
[13:02:51.277] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:51.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:55:0) after 1m0s
[13:03:51.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:55:0)
[13:03:51.371] Timer tick, asking Buildbucket for the build status
[13:03:51.477] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:51.477] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:56:0) after 1m0s
[13:04:51.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:56:0)
[13:04:51.515] Timer tick, asking Buildbucket for the build status
[13:04:51.765] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:51.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:57:0) after 1m0s
[13:05:51.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:57:0)
[13:05:51.790] Timer tick, asking Buildbucket for the build status
[13:05:51.833] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:51.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:58:0) after 1m0s
[13:06:51.829] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:58:0)
[13:06:51.829] Timer tick, asking Buildbucket for the build status
[13:06:51.921] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:51.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:59:0) after 1m0s
[13:07:51.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:59:0)
[13:07:51.963] Timer tick, asking Buildbucket for the build status
[13:07:52.012] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:52.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:60:0) after 1m0s
[13:08:52.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:60:0)
[13:08:52.038] Timer tick, asking Buildbucket for the build status
[13:08:52.109] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:52.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:61:0) after 1m0s
[13:09:52.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:61:0)
[13:09:52.112] Timer tick, asking Buildbucket for the build status
[13:09:52.307] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:52.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:62:0) after 1m0s
[13:10:52.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:62:0)
[13:10:52.327] Timer tick, asking Buildbucket for the build status
[13:10:52.372] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:52.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:63:0) after 1m0s
[13:11:52.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:63:0)
[13:11:52.394] Timer tick, asking Buildbucket for the build status
[13:11:52.456] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:52.457] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:64:0) after 1m0s
[13:12:52.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:64:0)
[13:12:52.487] Timer tick, asking Buildbucket for the build status
[13:12:52.534] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:52.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:65:0) after 1m0s
[13:13:52.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:65:0)
[13:13:52.559] Timer tick, asking Buildbucket for the build status
[13:13:52.604] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:52.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:66:0) after 1m0s
[13:14:52.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:66:0)
[13:14:52.594] Timer tick, asking Buildbucket for the build status
[13:14:52.639] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:52.639] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:67:0) after 1m0s
[13:15:52.658] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:67:0)
[13:15:52.658] Timer tick, asking Buildbucket for the build status
[13:15:52.701] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:52.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:68:0) after 1m0s
[13:16:52.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:68:0)
[13:16:52.710] Timer tick, asking Buildbucket for the build status
[13:16:52.761] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:52.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:69:0) after 1m0s
[13:17:52.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:69:0)
[13:17:52.781] Timer tick, asking Buildbucket for the build status
[13:17:52.820] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:52.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:70:0) after 1m0s
[13:18:52.839] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:70:0)
[13:18:52.839] Timer tick, asking Buildbucket for the build status
[13:18:52.891] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:52.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:71:0) after 1m0s
[13:19:52.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:71:0)
[13:19:52.917] Timer tick, asking Buildbucket for the build status
[13:19:53.067] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:53.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:72:0) after 1m0s
[13:20:53.089] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:72:0)
[13:20:53.089] Timer tick, asking Buildbucket for the build status
[13:20:53.168] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:53.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:73:0) after 1m0s
[13:21:53.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:73:0)
[13:21:53.219] Timer tick, asking Buildbucket for the build status
[13:21:53.274] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:53.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:74:0) after 1m0s
[13:22:53.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:74:0)
[13:22:53.325] Timer tick, asking Buildbucket for the build status
[13:22:53.412] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:53.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:75:0) after 1m0s
[13:23:53.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:75:0)
[13:23:53.402] Timer tick, asking Buildbucket for the build status
[13:23:53.491] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:53.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:76:0) after 1m0s
[13:24:53.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:76:0)
[13:24:53.468] Timer tick, asking Buildbucket for the build status
[13:24:53.607] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:53.607] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:77:0) after 1m0s
[13:25:53.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:77:0)
[13:25:53.604] Timer tick, asking Buildbucket for the build status
[13:25:53.756] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:53.756] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:78:0) after 1m0s
[13:26:53.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:78:0)
[13:26:53.794] Timer tick, asking Buildbucket for the build status
[13:26:54.099] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:54.099] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:79:0) after 1m0s
[13:27:54.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:79:0)
[13:27:54.105] Timer tick, asking Buildbucket for the build status
[13:27:54.167] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:54.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:80:0) after 1m0s
[13:28:54.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:80:0)
[13:28:54.152] Timer tick, asking Buildbucket for the build status
[13:28:54.197] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:54.197] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:81:0) after 1m0s
[13:29:54.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:81:0)
[13:29:54.199] Timer tick, asking Buildbucket for the build status
[13:29:54.418] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:54.418] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:82:0) after 1m0s
[13:30:54.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:82:0)
[13:30:54.443] Timer tick, asking Buildbucket for the build status
[13:30:54.533] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:54.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:83:0) after 1m0s
[13:31:54.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:83:0)
[13:31:54.533] Timer tick, asking Buildbucket for the build status
[13:31:54.604] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:54.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:84:0) after 1m0s
[13:32:54.612] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:84:0)
[13:32:54.612] Timer tick, asking Buildbucket for the build status
[13:32:54.660] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:54.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:85:0) after 1m0s
[13:33:54.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:85:0)
[13:33:54.673] Timer tick, asking Buildbucket for the build status
[13:33:54.769] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:54.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:86:0) after 1m0s
[13:34:54.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:86:0)
[13:34:54.789] Timer tick, asking Buildbucket for the build status
[13:34:54.901] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:54.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:87:0) after 1m0s
[13:35:54.912] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:87:0)
[13:35:54.912] Timer tick, asking Buildbucket for the build status
[13:35:55.104] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:55.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:88:0) after 1m0s
[13:36:55.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:88:0)
[13:36:55.105] Timer tick, asking Buildbucket for the build status
[13:36:55.143] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:55.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:89:0) after 1m0s
[13:37:55.117] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:89:0)
[13:37:55.117] Timer tick, asking Buildbucket for the build status
[13:37:55.286] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:55.286] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:90:0) after 1m0s
[13:38:55.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:90:0)
[13:38:55.302] Timer tick, asking Buildbucket for the build status
[13:38:55.357] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:55.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:91:0) after 1m0s
[13:39:55.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:91:0)
[13:39:55.381] Timer tick, asking Buildbucket for the build status
[13:39:55.487] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:55.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:92:0) after 1m0s
[13:40:55.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:92:0)
[13:40:55.511] Timer tick, asking Buildbucket for the build status
[13:40:55.590] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:55.590] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:93:0) after 1m0s
[13:41:55.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:93:0)
[13:41:55.610] Timer tick, asking Buildbucket for the build status
[13:41:55.652] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:55.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:94:0) after 1m0s
[13:42:55.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:94:0)
[13:42:55.674] Timer tick, asking Buildbucket for the build status
[13:42:55.719] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:55.719] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:95:0) after 1m0s
[13:43:55.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:95:0)
[13:43:55.749] Timer tick, asking Buildbucket for the build status
[13:43:55.827] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:55.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:96:0) after 1m0s
[13:44:55.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:96:0)
[13:44:55.848] Timer tick, asking Buildbucket for the build status
[13:44:55.994] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:55.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:97:0) after 1m0s
[13:45:55.983] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:97:0)
[13:45:55.983] Timer tick, asking Buildbucket for the build status
[13:45:56.111] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:56.111] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:98:0) after 1m0s
[13:46:56.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:98:0)
[13:46:56.163] Timer tick, asking Buildbucket for the build status
[13:46:56.230] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:56.230] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:99:0) after 1m0s
[13:47:56.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:99:0)
[13:47:56.255] Timer tick, asking Buildbucket for the build status
[13:47:56.341] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:56.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:100:0) after 1m0s
[13:48:56.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:100:0)
[13:48:56.369] Timer tick, asking Buildbucket for the build status
[13:48:56.511] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:56.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:101:0) after 1m0s
[13:49:56.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:101:0)
[13:49:56.542] Timer tick, asking Buildbucket for the build status
[13:49:56.579] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:56.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:102:0) after 1m0s
[13:50:56.613] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:102:0)
[13:50:56.613] Timer tick, asking Buildbucket for the build status
[13:50:56.756] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:56.756] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:103:0) after 1m0s
[13:51:56.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:103:0)
[13:51:56.766] Timer tick, asking Buildbucket for the build status
[13:51:57.210] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:57.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:104:0) after 1m0s
[13:52:57.230] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:104:0)
[13:52:57.230] Timer tick, asking Buildbucket for the build status
[13:52:57.329] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:57.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:105:0) after 1m0s
[13:53:57.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:105:0)
[13:53:57.301] Timer tick, asking Buildbucket for the build status
[13:53:57.338] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:57.338] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:106:0) after 1m0s
[13:54:57.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:106:0)
[13:54:57.361] Timer tick, asking Buildbucket for the build status
[13:54:57.405] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:57.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:107:0) after 1m0s
[13:55:57.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:107:0)
[13:55:57.436] Timer tick, asking Buildbucket for the build status
[13:55:57.644] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:57.644] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:108:0) after 1m0s
[13:56:57.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:108:0)
[13:56:57.645] Timer tick, asking Buildbucket for the build status
[13:56:57.992] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:57.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:109:0) after 1m0s
[13:57:57.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:109:0)
[13:57:57.979] Timer tick, asking Buildbucket for the build status
[13:57:58.022] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:58.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:110:0) after 1m0s
[13:58:58.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:110:0)
[13:58:58.042] Timer tick, asking Buildbucket for the build status
[13:58:58.180] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:58.180] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:111:0) after 1m0s
[13:59:58.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:111:0)
[13:59:58.185] Timer tick, asking Buildbucket for the build status
[13:59:58.517] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:58.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:112:0) after 1m0s
[14:00:58.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:112:0)
[14:00:58.529] Timer tick, asking Buildbucket for the build status
[14:00:58.582] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:58.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:113:0) after 1m0s
[14:01:58.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:113:0)
[14:01:58.558] Timer tick, asking Buildbucket for the build status
[14:01:58.631] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:58.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:114:0) after 1m0s
[14:02:58.652] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:114:0)
[14:02:58.652] Timer tick, asking Buildbucket for the build status
[14:02:58.783] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:58.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:115:0) after 1m0s
[14:03:58.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:115:0)
[14:03:58.814] Timer tick, asking Buildbucket for the build status
[14:03:59.015] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:59.015] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:116:0) after 1m0s
[14:04:59.006] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:116:0)
[14:04:59.006] Timer tick, asking Buildbucket for the build status
[14:04:59.072] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:59.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:117:0) after 1m0s
[14:05:59.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:117:0)
[14:05:59.096] Timer tick, asking Buildbucket for the build status
[14:05:59.254] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:59.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:118:0) after 1m0s
[14:06:59.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:118:0)
[14:06:59.286] Timer tick, asking Buildbucket for the build status
[14:06:59.409] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:59.409] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:119:0) after 1m0s
[14:07:59.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:119:0)
[14:07:59.400] Timer tick, asking Buildbucket for the build status
[14:07:59.525] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:59.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:120:0) after 1m0s
[14:08:59.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:120:0)
[14:08:59.546] Timer tick, asking Buildbucket for the build status
[14:08:59.622] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:59.622] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:121:0) after 1m0s
[14:09:59.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:121:0)
[14:09:59.645] Timer tick, asking Buildbucket for the build status
[14:09:59.880] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:59.881] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:122:0) after 1m0s
[14:10:59.889] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:122:0)
[14:10:59.889] Timer tick, asking Buildbucket for the build status
[14:10:59.932] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:59.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:123:0) after 1m0s
[14:11:59.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:123:0)
[14:11:59.915] Timer tick, asking Buildbucket for the build status
[14:12:00.014] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:00.014] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:124:0) after 1m0s
[14:13:00.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:124:0)
[14:13:00.045] Timer tick, asking Buildbucket for the build status
[14:13:00.186] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:00.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:125:0) after 1m0s
[14:14:00.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:125:0)
[14:14:00.192] Timer tick, asking Buildbucket for the build status
[14:14:00.312] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:00.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:126:0) after 1m0s
[14:15:00.352] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:126:0)
[14:15:00.352] Timer tick, asking Buildbucket for the build status
[14:15:00.415] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:00.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:127:0) after 1m0s
[14:16:00.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:127:0)
[14:16:00.430] Timer tick, asking Buildbucket for the build status
[14:16:00.527] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:00.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:128:0) after 1m0s
[14:17:00.530] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:128:0)
[14:17:00.530] Timer tick, asking Buildbucket for the build status
[14:17:00.668] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:00.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:129:0) after 1m0s
[14:18:00.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:129:0)
[14:18:00.689] Timer tick, asking Buildbucket for the build status
[14:18:00.733] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:00.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:130:0) after 1m0s
[14:19:00.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:130:0)
[14:19:00.800] Timer tick, asking Buildbucket for the build status
[14:19:00.908] Build 8919916823322055392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:00.908] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085346885281903056:131:0) after 1m0s
[14:19:59.754] Received PubSub notification, asking Buildbucket for the build status
[14:19:59.815] Build 8919916823322055392: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:19:59.815] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1551709198763368",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551701443325283",
"id": "8919916823322055392",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3724.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\": [\"4143-92e279d\"], \"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\": [\"gce-trusty-32-0258d129-us-east1-b-j1hv\"], \"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}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1551701443325283, \"tags\": [\"builder:Build From Tarball\", \"parent_buildername:publish_tarball\", \"scheduler_invocation_id:9085346885281903056\", \"scheduler_job_id:infra/Build From Tarball\", \"user_agent:luci-scheduler\", \"user_agent:recipe\"], \"bucket\": \"luci.infra.cron\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"infra\", \"id\": \"8919916823322055392\"}}, \"version\": \"74.0.3724.0\", \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Build From Tarball\", \"key\": \"builder\"}, {\"value\": \"publish_tarball\", \"key\": \"parent_buildername\"}, {\"value\": \"9085346885281903056\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"infra/Build From Tarball\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}, {\"value\": \"recipe\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"infra\", \"builder\": \"Build From Tarball\", \"bucket\": \"cron\"}, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"ca0db2d762966d4b32d49d86567d9d7697438caf\"}, \"recipe\": {\"name\": \"build_from_tarball\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/infra/infra\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8919916823322055392\", \"input\": {}, \"createTime\": \"2019-03-04T12:10:43.325283Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-32-0258d129-us-east1-b-j1hv\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551701455382993",
"status": "COMPLETED",
"status_changed_ts": "1551709199362826",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9085346885281903056",
"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/8919916823322055392/+/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:43616e8cd87e3b10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1551709199364220",
"url": "https://ci.chromium.org/b/8919916823322055392",
"utcnow_ts": "1551709199800109"
}
}
[14:19:59.815] Invocation finished in 2h9m17.741758983s with status SUCCEEDED