[10:34:16.222] New invocation is queued and will start shortly
[10:34:16.222] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[10:34:17.338] Starting the invocation (attempt 1)
[10:34:17.385] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:34:17.385] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:34:17.385] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9085805937035602320",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"74.0.3718.3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9085805937035602320",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[10:34:18.753] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551263657476119",
"id": "8920375875056938352",
"lease_expiration_ts": "1551382458510979",
"lease_key": "381943769",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3718.3\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551263658511598",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9085805937035602320",
"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/8920375875056938352/+/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:43475677b121e910",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1551263658511675",
"url": "https://ci.chromium.org/b/8920375875056938352",
"utcnow_ts": "1551263658744120"
}
}
[10:34:18.753] Task URL: https://ci.chromium.org/b/8920375875056938352
[10:34:18.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:2:0) after 1m0s
[10:35:08.722] Received PubSub notification, asking Buildbucket for the build status
[10:35:08.767] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:18.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:2:0)
[10:35:18.744] Timer tick, asking Buildbucket for the build status
[10:35:18.847] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:18.847] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:4:0) after 1m0s
[10:36:18.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:4:0)
[10:36:18.865] Timer tick, asking Buildbucket for the build status
[10:36:18.954] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:18.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:5:0) after 1m0s
[10:37:18.951] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:5:0)
[10:37:18.951] Timer tick, asking Buildbucket for the build status
[10:37:19.021] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:19.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:6:0) after 1m0s
[10:38:18.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:6:0)
[10:38:18.995] Timer tick, asking Buildbucket for the build status
[10:38:19.076] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:19.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:7:0) after 1m0s
[10:39:19.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:7:0)
[10:39:19.088] Timer tick, asking Buildbucket for the build status
[10:39:19.155] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:19.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:8:0) after 1m0s
[10:40:19.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:8:0)
[10:40:19.156] Timer tick, asking Buildbucket for the build status
[10:40:19.209] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:19.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:9:0) after 1m0s
[10:41:19.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:9:0)
[10:41:19.228] Timer tick, asking Buildbucket for the build status
[10:41:19.275] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:19.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:10:0) after 1m0s
[10:42:19.300] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:10:0)
[10:42:19.300] Timer tick, asking Buildbucket for the build status
[10:42:19.508] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:19.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:11:0) after 1m0s
[10:43:19.500] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:11:0)
[10:43:19.500] Timer tick, asking Buildbucket for the build status
[10:43:19.558] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:19.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:12:0) after 1m0s
[10:44:19.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:12:0)
[10:44:19.574] Timer tick, asking Buildbucket for the build status
[10:44:19.800] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:19.800] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:13:0) after 1m0s
[10:45:19.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:13:0)
[10:45:19.821] Timer tick, asking Buildbucket for the build status
[10:45:19.888] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:19.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:14:0) after 1m0s
[10:46:19.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:14:0)
[10:46:19.907] Timer tick, asking Buildbucket for the build status
[10:46:19.971] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:19.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:15:0) after 1m0s
[10:47:19.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:15:0)
[10:47:19.990] Timer tick, asking Buildbucket for the build status
[10:47:20.200] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:20.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:16:0) after 1m0s
[10:48:20.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:16:0)
[10:48:20.188] Timer tick, asking Buildbucket for the build status
[10:48:20.230] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:20.230] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:17:0) after 1m0s
[10:49:20.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:17:0)
[10:49:20.249] Timer tick, asking Buildbucket for the build status
[10:49:20.310] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:20.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:18:0) after 1m0s
[10:50:20.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:18:0)
[10:50:20.282] Timer tick, asking Buildbucket for the build status
[10:50:20.578] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:20.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:19:0) after 1m0s
[10:51:20.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:19:0)
[10:51:20.598] Timer tick, asking Buildbucket for the build status
[10:51:20.659] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:20.659] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:20:0) after 1m0s
[10:52:20.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:20:0)
[10:52:20.674] Timer tick, asking Buildbucket for the build status
[10:52:20.723] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:20.723] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:21:0) after 1m0s
[10:53:20.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:21:0)
[10:53:20.775] Timer tick, asking Buildbucket for the build status
[10:53:20.951] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:20.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:22:0) after 1m0s
[10:54:20.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:22:0)
[10:54:20.973] Timer tick, asking Buildbucket for the build status
[10:54:21.078] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:21.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:23:0) after 1m0s
[10:55:21.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:23:0)
[10:55:21.108] Timer tick, asking Buildbucket for the build status
[10:55:21.162] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:21.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:24:0) after 1m0s
[10:56:21.181] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:24:0)
[10:56:21.181] Timer tick, asking Buildbucket for the build status
[10:56:21.300] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:21.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:25:0) after 1m0s
[10:57:21.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:25:0)
[10:57:21.335] Timer tick, asking Buildbucket for the build status
[10:57:21.417] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:21.417] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:26:0) after 1m0s
[10:58:21.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:26:0)
[10:58:21.439] Timer tick, asking Buildbucket for the build status
[10:58:21.486] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:21.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:27:0) after 1m0s
[10:59:21.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:27:0)
[10:59:21.573] Timer tick, asking Buildbucket for the build status
[10:59:21.672] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:21.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:28:0) after 1m0s
[11:00:21.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:28:0)
[11:00:21.695] Timer tick, asking Buildbucket for the build status
[11:00:21.765] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:21.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:29:0) after 1m0s
[11:01:21.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:29:0)
[11:01:21.760] Timer tick, asking Buildbucket for the build status
[11:01:21.821] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:21.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:30:0) after 1m0s
[11:02:21.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:30:0)
[11:02:21.838] Timer tick, asking Buildbucket for the build status
[11:02:21.882] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:21.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:31:0) after 1m0s
[11:03:21.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:31:0)
[11:03:21.901] Timer tick, asking Buildbucket for the build status
[11:03:21.965] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:21.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:32:0) after 1m0s
[11:04:21.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:32:0)
[11:04:21.982] Timer tick, asking Buildbucket for the build status
[11:04:22.028] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:22.028] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:33:0) after 1m0s
[11:05:22.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:33:0)
[11:05:22.048] Timer tick, asking Buildbucket for the build status
[11:05:22.120] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:22.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:34:0) after 1m0s
[11:06:22.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:34:0)
[11:06:22.141] Timer tick, asking Buildbucket for the build status
[11:06:22.178] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:22.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:35:0) after 1m0s
[11:07:22.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:35:0)
[11:07:22.196] Timer tick, asking Buildbucket for the build status
[11:07:22.238] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:22.238] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:36:0) after 1m0s
[11:08:22.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:36:0)
[11:08:22.262] Timer tick, asking Buildbucket for the build status
[11:08:22.379] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:22.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:37:0) after 1m0s
[11:09:22.403] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:37:0)
[11:09:22.403] Timer tick, asking Buildbucket for the build status
[11:09:22.525] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:22.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:38:0) after 1m0s
[11:10:22.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:38:0)
[11:10:22.502] Timer tick, asking Buildbucket for the build status
[11:10:22.541] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:22.541] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:39:0) after 1m0s
[11:11:22.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:39:0)
[11:11:22.524] Timer tick, asking Buildbucket for the build status
[11:11:22.604] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:22.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:40:0) after 1m0s
[11:12:22.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:40:0)
[11:12:22.611] Timer tick, asking Buildbucket for the build status
[11:12:22.787] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:22.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:41:0) after 1m0s
[11:13:22.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:41:0)
[11:13:22.812] Timer tick, asking Buildbucket for the build status
[11:13:22.961] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:22.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:42:0) after 1m0s
[11:14:22.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:42:0)
[11:14:22.969] Timer tick, asking Buildbucket for the build status
[11:14:23.193] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:23.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:43:0) after 1m0s
[11:15:23.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:43:0)
[11:15:23.171] Timer tick, asking Buildbucket for the build status
[11:15:23.232] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:23.232] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:44:0) after 1m0s
[11:16:23.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:44:0)
[11:16:23.256] Timer tick, asking Buildbucket for the build status
[11:16:23.322] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:23.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:45:0) after 1m0s
[11:17:23.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:45:0)
[11:17:23.331] Timer tick, asking Buildbucket for the build status
[11:17:23.414] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:23.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:46:0) after 1m0s
[11:18:23.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:46:0)
[11:18:23.432] Timer tick, asking Buildbucket for the build status
[11:18:23.529] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:23.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:47:0) after 1m0s
[11:19:23.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:47:0)
[11:19:23.519] Timer tick, asking Buildbucket for the build status
[11:19:23.759] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:23.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:48:0) after 1m0s
[11:20:23.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:48:0)
[11:20:23.738] Timer tick, asking Buildbucket for the build status
[11:20:23.784] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:23.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:49:0) after 1m0s
[11:21:23.759] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:49:0)
[11:21:23.759] Timer tick, asking Buildbucket for the build status
[11:21:23.831] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:23.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:50:0) after 1m0s
[11:22:23.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:50:0)
[11:22:23.915] Timer tick, asking Buildbucket for the build status
[11:22:23.951] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:23.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:51:0) after 1m0s
[11:23:23.968] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:51:0)
[11:23:23.968] Timer tick, asking Buildbucket for the build status
[11:23:24.033] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:24.033] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:52:0) after 1m0s
[11:24:24.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:52:0)
[11:24:24.065] Timer tick, asking Buildbucket for the build status
[11:24:24.104] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:24.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:53:0) after 1m0s
[11:25:24.090] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:53:0)
[11:25:24.090] Timer tick, asking Buildbucket for the build status
[11:25:24.191] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:24.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:54:0) after 1m0s
[11:26:24.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:54:0)
[11:26:24.163] Timer tick, asking Buildbucket for the build status
[11:26:24.293] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:24.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:55:0) after 1m0s
[11:27:24.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:55:0)
[11:27:24.302] Timer tick, asking Buildbucket for the build status
[11:27:24.365] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:24.365] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:56:0) after 1m0s
[11:28:24.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:56:0)
[11:28:24.383] Timer tick, asking Buildbucket for the build status
[11:28:24.447] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:24.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:57:0) after 1m0s
[11:29:24.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:57:0)
[11:29:24.468] Timer tick, asking Buildbucket for the build status
[11:29:24.535] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:24.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:58:0) after 1m0s
[11:30:24.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:58:0)
[11:30:24.581] Timer tick, asking Buildbucket for the build status
[11:30:24.713] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:24.713] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:59:0) after 1m0s
[11:31:24.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:59:0)
[11:31:24.737] Timer tick, asking Buildbucket for the build status
[11:31:25.076] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:25.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:60:0) after 1m0s
[11:32:25.078] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:60:0)
[11:32:25.078] Timer tick, asking Buildbucket for the build status
[11:32:25.489] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:25.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:61:0) after 1m0s
[11:33:25.476] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:61:0)
[11:33:25.476] Timer tick, asking Buildbucket for the build status
[11:33:25.575] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:25.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:62:0) after 1m0s
[11:34:25.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:62:0)
[11:34:25.593] Timer tick, asking Buildbucket for the build status
[11:34:25.657] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:25.657] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:63:0) after 1m0s
[11:35:25.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:63:0)
[11:35:25.662] Timer tick, asking Buildbucket for the build status
[11:35:25.784] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:25.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:64:0) after 1m0s
[11:36:25.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:64:0)
[11:36:25.777] Timer tick, asking Buildbucket for the build status
[11:36:25.831] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:25.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:65:0) after 1m0s
[11:37:25.856] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:65:0)
[11:37:25.856] Timer tick, asking Buildbucket for the build status
[11:37:26.091] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:26.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:66:0) after 1m0s
[11:38:26.101] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:66:0)
[11:38:26.101] Timer tick, asking Buildbucket for the build status
[11:38:26.278] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:26.278] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:67:0) after 1m0s
[11:39:26.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:67:0)
[11:39:26.256] Timer tick, asking Buildbucket for the build status
[11:39:26.401] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:26.401] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:68:0) after 1m0s
[11:40:26.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:68:0)
[11:40:26.447] Timer tick, asking Buildbucket for the build status
[11:40:26.487] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:26.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:69:0) after 1m0s
[11:41:26.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:69:0)
[11:41:26.482] Timer tick, asking Buildbucket for the build status
[11:41:26.603] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:26.603] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:70:0) after 1m0s
[11:42:26.606] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:70:0)
[11:42:26.606] Timer tick, asking Buildbucket for the build status
[11:42:26.701] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:26.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:71:0) after 1m0s
[11:43:26.732] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:71:0)
[11:43:26.732] Timer tick, asking Buildbucket for the build status
[11:43:26.867] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:26.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:72:0) after 1m0s
[11:44:26.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:72:0)
[11:44:26.893] Timer tick, asking Buildbucket for the build status
[11:44:26.972] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:26.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:73:0) after 1m0s
[11:45:26.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:73:0)
[11:45:26.987] Timer tick, asking Buildbucket for the build status
[11:45:27.045] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:27.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:74:0) after 1m0s
[11:46:27.025] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:74:0)
[11:46:27.025] Timer tick, asking Buildbucket for the build status
[11:46:27.066] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:27.066] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:75:0) after 1m0s
[11:47:27.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:75:0)
[11:47:27.052] Timer tick, asking Buildbucket for the build status
[11:47:27.173] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:27.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:76:0) after 1m0s
[11:48:27.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:76:0)
[11:48:27.170] Timer tick, asking Buildbucket for the build status
[11:48:27.221] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:27.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:77:0) after 1m0s
[11:49:27.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:77:0)
[11:49:27.216] Timer tick, asking Buildbucket for the build status
[11:49:27.258] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:27.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:78:0) after 1m0s
[11:50:27.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:78:0)
[11:50:27.246] Timer tick, asking Buildbucket for the build status
[11:50:27.294] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:27.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:79:0) after 1m0s
[11:51:27.321] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:79:0)
[11:51:27.321] Timer tick, asking Buildbucket for the build status
[11:51:27.490] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:27.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:80:0) after 1m0s
[11:52:27.508] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:80:0)
[11:52:27.508] Timer tick, asking Buildbucket for the build status
[11:52:27.554] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:27.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:81:0) after 1m0s
[11:53:27.576] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:81:0)
[11:53:27.576] Timer tick, asking Buildbucket for the build status
[11:53:27.649] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:27.649] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:82:0) after 1m0s
[11:54:27.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:82:0)
[11:54:27.639] Timer tick, asking Buildbucket for the build status
[11:54:27.699] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:27.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:83:0) after 1m0s
[11:55:27.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:83:0)
[11:55:27.729] Timer tick, asking Buildbucket for the build status
[11:55:27.779] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:27.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:84:0) after 1m0s
[11:56:27.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:84:0)
[11:56:27.790] Timer tick, asking Buildbucket for the build status
[11:56:27.893] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:27.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:85:0) after 1m0s
[11:57:27.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:85:0)
[11:57:27.915] Timer tick, asking Buildbucket for the build status
[11:57:28.186] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:28.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:86:0) after 1m0s
[11:58:28.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:86:0)
[11:58:28.213] Timer tick, asking Buildbucket for the build status
[11:58:28.258] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:28.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:87:0) after 1m0s
[11:59:28.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:87:0)
[11:59:28.252] Timer tick, asking Buildbucket for the build status
[11:59:28.592] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:28.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:88:0) after 1m0s
[12:00:28.615] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:88:0)
[12:00:28.615] Timer tick, asking Buildbucket for the build status
[12:00:28.666] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:28.666] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:89:0) after 1m0s
[12:01:28.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:89:0)
[12:01:28.668] Timer tick, asking Buildbucket for the build status
[12:01:28.736] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:28.736] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:90:0) after 1m0s
[12:02:28.762] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:90:0)
[12:02:28.762] Timer tick, asking Buildbucket for the build status
[12:02:28.824] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:28.824] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:91:0) after 1m0s
[12:03:28.839] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:91:0)
[12:03:28.839] Timer tick, asking Buildbucket for the build status
[12:03:29.101] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:29.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:92:0) after 1m0s
[12:04:29.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:92:0)
[12:04:29.153] Timer tick, asking Buildbucket for the build status
[12:04:29.201] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:29.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:93:0) after 1m0s
[12:05:29.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:93:0)
[12:05:29.203] Timer tick, asking Buildbucket for the build status
[12:05:29.467] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:29.467] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:94:0) after 1m0s
[12:06:29.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:94:0)
[12:06:29.492] Timer tick, asking Buildbucket for the build status
[12:06:29.582] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:29.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:95:0) after 1m0s
[12:07:29.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:95:0)
[12:07:29.600] Timer tick, asking Buildbucket for the build status
[12:07:29.667] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:29.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:96:0) after 1m0s
[12:08:29.690] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:96:0)
[12:08:29.690] Timer tick, asking Buildbucket for the build status
[12:08:29.796] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:29.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:97:0) after 1m0s
[12:09:29.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:97:0)
[12:09:29.831] Timer tick, asking Buildbucket for the build status
[12:09:29.905] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:29.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:98:0) after 1m0s
[12:10:29.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:98:0)
[12:10:29.925] Timer tick, asking Buildbucket for the build status
[12:10:29.967] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:29.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:99:0) after 1m0s
[12:11:29.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:99:0)
[12:11:29.990] Timer tick, asking Buildbucket for the build status
[12:11:30.054] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:30.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:100:0) after 1m0s
[12:12:30.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:100:0)
[12:12:30.118] Timer tick, asking Buildbucket for the build status
[12:12:30.345] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:30.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:101:0) after 1m0s
[12:13:30.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:101:0)
[12:13:30.379] Timer tick, asking Buildbucket for the build status
[12:13:30.765] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:30.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:102:0) after 1m0s
[12:14:30.808] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:102:0)
[12:14:30.808] Timer tick, asking Buildbucket for the build status
[12:14:30.851] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:30.851] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:103:0) after 1m0s
[12:15:30.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:103:0)
[12:15:30.909] Timer tick, asking Buildbucket for the build status
[12:15:30.994] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:30.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:104:0) after 1m0s
[12:16:30.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:104:0)
[12:16:30.979] Timer tick, asking Buildbucket for the build status
[12:16:31.097] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:31.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:105:0) after 1m0s
[12:17:31.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:105:0)
[12:17:31.098] Timer tick, asking Buildbucket for the build status
[12:17:31.142] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:31.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:106:0) after 1m0s
[12:18:31.166] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:106:0)
[12:18:31.166] Timer tick, asking Buildbucket for the build status
[12:18:31.210] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:31.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:107:0) after 1m0s
[12:19:31.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:107:0)
[12:19:31.232] Timer tick, asking Buildbucket for the build status
[12:19:31.338] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:31.338] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:108:0) after 1m0s
[12:20:31.377] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:108:0)
[12:20:31.377] Timer tick, asking Buildbucket for the build status
[12:20:31.414] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:31.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:109:0) after 1m0s
[12:21:31.395] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:109:0)
[12:21:31.395] Timer tick, asking Buildbucket for the build status
[12:21:31.449] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:31.449] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:110:0) after 1m0s
[12:22:31.470] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:110:0)
[12:22:31.470] Timer tick, asking Buildbucket for the build status
[12:22:31.582] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:31.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:111:0) after 1m0s
[12:23:31.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:111:0)
[12:23:31.578] Timer tick, asking Buildbucket for the build status
[12:23:31.645] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:31.645] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:112:0) after 1m0s
[12:24:31.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:112:0)
[12:24:31.660] Timer tick, asking Buildbucket for the build status
[12:24:31.782] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:31.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:113:0) after 1m0s
[12:25:31.806] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:113:0)
[12:25:31.806] Timer tick, asking Buildbucket for the build status
[12:25:31.859] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:31.859] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:114:0) after 1m0s
[12:26:31.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:114:0)
[12:26:31.874] Timer tick, asking Buildbucket for the build status
[12:26:31.927] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:31.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:115:0) after 1m0s
[12:27:32.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:115:0)
[12:27:32.007] Timer tick, asking Buildbucket for the build status
[12:27:32.061] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:32.061] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:116:0) after 1m0s
[12:28:32.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:116:0)
[12:28:32.092] Timer tick, asking Buildbucket for the build status
[12:28:32.275] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:32.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:117:0) after 1m0s
[12:29:32.317] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:117:0)
[12:29:32.317] Timer tick, asking Buildbucket for the build status
[12:29:32.379] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:32.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:118:0) after 1m0s
[12:30:32.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:118:0)
[12:30:32.389] Timer tick, asking Buildbucket for the build status
[12:30:32.777] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:32.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:119:0) after 1m0s
[12:31:32.785] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:119:0)
[12:31:32.785] Timer tick, asking Buildbucket for the build status
[12:31:32.850] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:32.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:120:0) after 1m0s
[12:32:32.829] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:120:0)
[12:32:32.829] Timer tick, asking Buildbucket for the build status
[12:32:32.869] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:32.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:121:0) after 1m0s
[12:33:32.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:121:0)
[12:33:32.908] Timer tick, asking Buildbucket for the build status
[12:33:33.085] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:33.085] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:122:0) after 1m0s
[12:34:33.080] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:122:0)
[12:34:33.080] Timer tick, asking Buildbucket for the build status
[12:34:33.126] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:33.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:123:0) after 1m0s
[12:35:33.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:123:0)
[12:35:33.148] Timer tick, asking Buildbucket for the build status
[12:35:33.207] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:33.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:124:0) after 1m0s
[12:36:33.205] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:124:0)
[12:36:33.205] Timer tick, asking Buildbucket for the build status
[12:36:33.335] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:33.335] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:125:0) after 1m0s
[12:37:33.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:125:0)
[12:37:33.367] Timer tick, asking Buildbucket for the build status
[12:37:33.516] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:33.516] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:126:0) after 1m0s
[12:38:33.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:126:0)
[12:38:33.540] Timer tick, asking Buildbucket for the build status
[12:38:33.773] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:33.773] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:127:0) after 1m0s
[12:39:33.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:127:0)
[12:39:33.779] Timer tick, asking Buildbucket for the build status
[12:39:33.897] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:33.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:128:0) after 1m0s
[12:40:33.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:128:0)
[12:40:33.882] Timer tick, asking Buildbucket for the build status
[12:40:33.941] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:33.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:129:0) after 1m0s
[12:41:33.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:129:0)
[12:41:33.967] Timer tick, asking Buildbucket for the build status
[12:41:34.473] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:34.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:130:0) after 1m0s
[12:42:34.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:130:0)
[12:42:34.473] Timer tick, asking Buildbucket for the build status
[12:42:34.520] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:34.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:131:0) after 1m0s
[12:43:34.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:131:0)
[12:43:34.561] Timer tick, asking Buildbucket for the build status
[12:43:34.634] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:34.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:132:0) after 1m0s
[12:44:34.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:132:0)
[12:44:34.657] Timer tick, asking Buildbucket for the build status
[12:44:34.976] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:34.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:133:0) after 1m0s
[12:45:35.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:133:0)
[12:45:35.001] Timer tick, asking Buildbucket for the build status
[12:45:35.174] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:35.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:134:0) after 1m0s
[12:46:35.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:134:0)
[12:46:35.180] Timer tick, asking Buildbucket for the build status
[12:46:35.225] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:35.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:135:0) after 1m0s
[12:47:35.257] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:135:0)
[12:47:35.257] Timer tick, asking Buildbucket for the build status
[12:47:35.529] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:35.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:136:0) after 1m0s
[12:48:35.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:136:0)
[12:48:35.521] Timer tick, asking Buildbucket for the build status
[12:48:35.652] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:35.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:137:0) after 1m0s
[12:49:35.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:137:0)
[12:49:35.679] Timer tick, asking Buildbucket for the build status
[12:49:35.954] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:35.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:138:0) after 1m0s
[12:50:35.974] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:138:0)
[12:50:35.974] Timer tick, asking Buildbucket for the build status
[12:50:36.116] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:36.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:139:0) after 1m0s
[12:51:36.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:139:0)
[12:51:36.137] Timer tick, asking Buildbucket for the build status
[12:51:36.263] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:36.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:140:0) after 1m0s
[12:52:36.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:140:0)
[12:52:36.291] Timer tick, asking Buildbucket for the build status
[12:52:36.347] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:36.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:141:0) after 1m0s
[12:53:36.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:141:0)
[12:53:36.367] Timer tick, asking Buildbucket for the build status
[12:53:36.576] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:36.577] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:142:0) after 1m0s
[12:54:36.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:142:0)
[12:54:36.601] Timer tick, asking Buildbucket for the build status
[12:54:36.858] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:36.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:143:0) after 1m0s
[12:55:36.877] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:143:0)
[12:55:36.877] Timer tick, asking Buildbucket for the build status
[12:55:36.930] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:36.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:144:0) after 1m0s
[12:56:36.966] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:144:0)
[12:56:36.966] Timer tick, asking Buildbucket for the build status
[12:56:37.026] Build 8920375875056938352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:37.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085805937035602320:145:0) after 1m0s
[12:57:27.223] Received PubSub notification, asking Buildbucket for the build status
[12:57:27.383] Build 8920375875056938352: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:57:27.383] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1551272246480554",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551263657476119",
"id": "8920375875056938352",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3718.3\"}}",
"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\"], \"id\": [\"gce-trusty-32-0258d129-us-west1-c-v556\"], \"server_version\": [\"4129-0985e08\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"32\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"pool\": [\"luci.infra.cron\"], \"caches\": [\"vpython\"]}}, \"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\": 1551263657476119, \"tags\": [\"builder:Build From Tarball\", \"parent_buildername:publish_tarball\", \"scheduler_invocation_id:9085805937035602320\", \"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\": \"8920375875056938352\"}}, \"version\": \"74.0.3718.3\", \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Build From Tarball\", \"key\": \"builder\"}, {\"value\": \"publish_tarball\", \"key\": \"parent_buildername\"}, {\"value\": \"9085805937035602320\", \"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\": \"dde284651e8a272e8dedbe01fc6531dba95bccb4\"}, \"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\": \"8920375875056938352\", \"input\": {}, \"createTime\": \"2019-02-27T10:34:17.476119Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-32-0258d129-us-west1-c-v556\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551263708065388",
"status": "COMPLETED",
"status_changed_ts": "1551272246969298",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9085805937035602320",
"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/8920375875056938352/+/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:43475677b121e910",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1551272246970373",
"url": "https://ci.chromium.org/b/8920375875056938352",
"utcnow_ts": "1551272247370528"
}
}
[12:57:27.383] Invocation finished in 2h23m11.178877777s with status SUCCEEDED