[19:40:24.668] New invocation is queued and will start shortly
[19:40:24.668] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[19:40:25.864] Starting the invocation (attempt 1)
[19:40:25.898] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:40:25.898] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:40:25.898] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9083959637500529744",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"74.0.3729.22\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9083959637500529744",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[19:40:26.917] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "project:infra",
"created_ts": "1553024426081669",
"id": "8918529575356197248",
"lease_expiration_ts": "1553143226799529",
"lease_key": "460411681",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3729.22\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553024426801306",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9083959637500529744",
"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/8918529575356197248/+/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:43b049aa063f1c10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1553024426801395",
"url": "https://ci.chromium.org/b/8918529575356197248",
"utcnow_ts": "1553024426909691"
}
}
[19:40:26.917] Task URL: https://ci.chromium.org/b/8918529575356197248
[19:40:26.917] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:2:0) after 1m0s
[19:41:26.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:2:0)
[19:41:26.924] Timer tick, asking Buildbucket for the build status
[19:41:26.989] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:26.989] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:3:0) after 1m0s
[19:41:28.424] Received PubSub notification, asking Buildbucket for the build status
[19:41:28.508] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:27.024] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:3:0)
[19:42:27.024] Timer tick, asking Buildbucket for the build status
[19:42:27.205] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:27.205] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:5:0) after 1m0s
[19:43:27.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:5:0)
[19:43:27.238] Timer tick, asking Buildbucket for the build status
[19:43:27.390] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:27.390] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:6:0) after 1m0s
[19:44:27.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:6:0)
[19:44:27.408] Timer tick, asking Buildbucket for the build status
[19:44:27.491] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:27.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:7:0) after 1m0s
[19:45:27.503] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:7:0)
[19:45:27.503] Timer tick, asking Buildbucket for the build status
[19:45:27.553] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:27.553] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:8:0) after 1m0s
[19:46:27.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:8:0)
[19:46:27.568] Timer tick, asking Buildbucket for the build status
[19:46:27.653] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:27.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:9:0) after 1m0s
[19:47:27.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:9:0)
[19:47:27.653] Timer tick, asking Buildbucket for the build status
[19:47:27.805] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:27.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:10:0) after 1m0s
[19:48:27.822] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:10:0)
[19:48:27.822] Timer tick, asking Buildbucket for the build status
[19:48:27.909] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:27.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:11:0) after 1m0s
[19:49:27.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:11:0)
[19:49:27.945] Timer tick, asking Buildbucket for the build status
[19:49:28.013] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:28.013] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:12:0) after 1m0s
[19:50:27.983] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:12:0)
[19:50:27.983] Timer tick, asking Buildbucket for the build status
[19:50:28.036] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:28.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:13:0) after 1m0s
[19:51:28.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:13:0)
[19:51:28.008] Timer tick, asking Buildbucket for the build status
[19:51:28.297] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:28.297] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:14:0) after 1m0s
[19:52:28.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:14:0)
[19:52:28.294] Timer tick, asking Buildbucket for the build status
[19:52:28.470] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:28.470] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:15:0) after 1m0s
[19:53:28.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:15:0)
[19:53:28.486] Timer tick, asking Buildbucket for the build status
[19:53:28.616] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:28.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:16:0) after 1m0s
[19:54:28.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:16:0)
[19:54:28.627] Timer tick, asking Buildbucket for the build status
[19:54:28.813] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:28.813] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:17:0) after 1m0s
[19:55:28.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:17:0)
[19:55:28.832] Timer tick, asking Buildbucket for the build status
[19:55:28.912] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:28.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:18:0) after 1m0s
[19:56:28.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:18:0)
[19:56:28.935] Timer tick, asking Buildbucket for the build status
[19:56:28.989] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:28.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:19:0) after 1m0s
[19:57:28.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:19:0)
[19:57:28.986] Timer tick, asking Buildbucket for the build status
[19:57:29.055] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:29.055] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:20:0) after 1m0s
[19:58:29.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:20:0)
[19:58:29.064] Timer tick, asking Buildbucket for the build status
[19:58:29.104] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:29.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:21:0) after 1m0s
[19:59:29.126] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:21:0)
[19:59:29.127] Timer tick, asking Buildbucket for the build status
[19:59:29.288] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:29.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:22:0) after 1m0s
[20:00:29.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:22:0)
[20:00:29.277] Timer tick, asking Buildbucket for the build status
[20:00:29.369] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:29.369] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:23:0) after 1m0s
[20:01:29.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:23:0)
[20:01:29.365] Timer tick, asking Buildbucket for the build status
[20:01:29.494] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:29.494] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:24:0) after 1m0s
[20:02:29.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:24:0)
[20:02:29.493] Timer tick, asking Buildbucket for the build status
[20:02:29.607] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:29.607] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:25:0) after 1m0s
[20:03:29.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:25:0)
[20:03:29.624] Timer tick, asking Buildbucket for the build status
[20:03:29.695] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:29.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:26:0) after 1m0s
[20:04:29.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:26:0)
[20:04:29.687] Timer tick, asking Buildbucket for the build status
[20:04:29.788] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:29.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:27:0) after 1m0s
[20:05:29.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:27:0)
[20:05:29.770] Timer tick, asking Buildbucket for the build status
[20:05:29.955] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:29.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:28:0) after 1m0s
[20:06:29.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:28:0)
[20:06:29.924] Timer tick, asking Buildbucket for the build status
[20:06:29.987] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:29.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:29:0) after 1m0s
[20:07:30.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:29:0)
[20:07:30.004] Timer tick, asking Buildbucket for the build status
[20:07:30.064] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:30.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:30:0) after 1m0s
[20:08:30.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:30:0)
[20:08:30.069] Timer tick, asking Buildbucket for the build status
[20:08:30.202] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:30.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:31:0) after 1m0s
[20:09:30.220] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:31:0)
[20:09:30.220] Timer tick, asking Buildbucket for the build status
[20:09:30.387] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:30.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:32:0) after 1m0s
[20:10:30.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:32:0)
[20:10:30.370] Timer tick, asking Buildbucket for the build status
[20:10:30.417] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:30.417] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:33:0) after 1m0s
[20:11:30.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:33:0)
[20:11:30.438] Timer tick, asking Buildbucket for the build status
[20:11:30.528] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:30.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:34:0) after 1m0s
[20:12:30.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:34:0)
[20:12:30.535] Timer tick, asking Buildbucket for the build status
[20:12:30.761] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:30.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:35:0) after 1m0s
[20:13:30.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:35:0)
[20:13:30.809] Timer tick, asking Buildbucket for the build status
[20:13:30.890] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:30.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:36:0) after 1m0s
[20:14:30.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:36:0)
[20:14:30.905] Timer tick, asking Buildbucket for the build status
[20:14:30.957] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:30.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:37:0) after 1m0s
[20:15:30.971] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:37:0)
[20:15:30.971] Timer tick, asking Buildbucket for the build status
[20:15:31.020] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:31.020] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:38:0) after 1m0s
[20:16:31.041] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:38:0)
[20:16:31.041] Timer tick, asking Buildbucket for the build status
[20:16:31.080] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:31.080] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:39:0) after 1m0s
[20:17:31.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:39:0)
[20:17:31.086] Timer tick, asking Buildbucket for the build status
[20:17:31.158] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:31.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:40:0) after 1m0s
[20:18:31.157] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:40:0)
[20:18:31.157] Timer tick, asking Buildbucket for the build status
[20:18:31.225] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:31.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:41:0) after 1m0s
[20:19:31.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:41:0)
[20:19:31.245] Timer tick, asking Buildbucket for the build status
[20:19:31.286] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:31.286] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:42:0) after 1m0s
[20:20:31.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:42:0)
[20:20:31.262] Timer tick, asking Buildbucket for the build status
[20:20:31.323] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:31.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:43:0) after 1m0s
[20:21:31.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:43:0)
[20:21:31.348] Timer tick, asking Buildbucket for the build status
[20:21:31.389] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:31.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:44:0) after 1m0s
[20:22:31.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:44:0)
[20:22:31.401] Timer tick, asking Buildbucket for the build status
[20:22:31.456] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:31.456] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:45:0) after 1m0s
[20:23:31.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:45:0)
[20:23:31.475] Timer tick, asking Buildbucket for the build status
[20:23:31.520] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:31.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:46:0) after 1m0s
[20:24:31.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:46:0)
[20:24:31.559] Timer tick, asking Buildbucket for the build status
[20:24:31.686] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:31.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:47:0) after 1m0s
[20:25:31.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:47:0)
[20:25:31.708] Timer tick, asking Buildbucket for the build status
[20:25:31.775] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:31.775] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:48:0) after 1m0s
[20:26:31.748] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:48:0)
[20:26:31.748] Timer tick, asking Buildbucket for the build status
[20:26:31.802] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:31.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:49:0) after 1m0s
[20:27:31.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:49:0)
[20:27:31.830] Timer tick, asking Buildbucket for the build status
[20:27:31.877] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:31.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:50:0) after 1m0s
[20:28:31.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:50:0)
[20:28:31.887] Timer tick, asking Buildbucket for the build status
[20:28:31.926] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:31.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:51:0) after 1m0s
[20:29:31.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:51:0)
[20:29:31.949] Timer tick, asking Buildbucket for the build status
[20:29:32.003] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:32.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:52:0) after 1m0s
[20:30:31.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:52:0)
[20:30:31.991] Timer tick, asking Buildbucket for the build status
[20:30:32.045] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:32.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:53:0) after 1m0s
[20:31:32.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:53:0)
[20:31:32.066] Timer tick, asking Buildbucket for the build status
[20:31:32.141] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:32.141] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:54:0) after 1m0s
[20:32:32.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:54:0)
[20:32:32.149] Timer tick, asking Buildbucket for the build status
[20:32:32.193] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:32.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:55:0) after 1m0s
[20:33:32.186] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:55:0)
[20:33:32.186] Timer tick, asking Buildbucket for the build status
[20:33:32.394] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:32.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:56:0) after 1m0s
[20:34:32.411] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:56:0)
[20:34:32.411] Timer tick, asking Buildbucket for the build status
[20:34:32.455] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:32.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:57:0) after 1m0s
[20:35:32.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:57:0)
[20:35:32.469] Timer tick, asking Buildbucket for the build status
[20:35:32.768] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:32.768] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:58:0) after 1m0s
[20:36:32.748] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:58:0)
[20:36:32.748] Timer tick, asking Buildbucket for the build status
[20:36:32.821] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:32.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:59:0) after 1m0s
[20:37:32.798] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:59:0)
[20:37:32.798] Timer tick, asking Buildbucket for the build status
[20:37:32.855] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:32.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:60:0) after 1m0s
[20:38:32.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:60:0)
[20:38:32.886] Timer tick, asking Buildbucket for the build status
[20:38:32.960] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:32.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:61:0) after 1m0s
[20:39:32.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:61:0)
[20:39:32.985] Timer tick, asking Buildbucket for the build status
[20:39:33.026] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:33.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:62:0) after 1m0s
[20:40:33.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:62:0)
[20:40:33.018] Timer tick, asking Buildbucket for the build status
[20:40:33.151] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:33.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:63:0) after 1m0s
[20:41:33.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:63:0)
[20:41:33.180] Timer tick, asking Buildbucket for the build status
[20:41:33.229] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:33.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:64:0) after 1m0s
[20:42:33.231] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:64:0)
[20:42:33.231] Timer tick, asking Buildbucket for the build status
[20:42:33.330] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:33.330] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:65:0) after 1m0s
[20:43:33.352] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:65:0)
[20:43:33.352] Timer tick, asking Buildbucket for the build status
[20:43:33.400] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:33.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:66:0) after 1m0s
[20:44:33.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:66:0)
[20:44:33.442] Timer tick, asking Buildbucket for the build status
[20:44:33.513] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:33.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:67:0) after 1m0s
[20:45:33.534] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:67:0)
[20:45:33.534] Timer tick, asking Buildbucket for the build status
[20:45:33.583] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:33.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:68:0) after 1m0s
[20:46:33.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:68:0)
[20:46:33.607] Timer tick, asking Buildbucket for the build status
[20:46:33.651] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:33.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:69:0) after 1m0s
[20:47:33.681] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:69:0)
[20:47:33.681] Timer tick, asking Buildbucket for the build status
[20:47:33.774] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:33.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:70:0) after 1m0s
[20:48:33.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:70:0)
[20:48:33.815] Timer tick, asking Buildbucket for the build status
[20:48:33.852] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:33.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:71:0) after 1m0s
[20:49:33.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:71:0)
[20:49:33.884] Timer tick, asking Buildbucket for the build status
[20:49:33.938] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:33.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:72:0) after 1m0s
[20:50:33.958] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:72:0)
[20:50:33.958] Timer tick, asking Buildbucket for the build status
[20:50:34.063] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:34.063] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:73:0) after 1m0s
[20:51:34.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:73:0)
[20:51:34.096] Timer tick, asking Buildbucket for the build status
[20:51:34.157] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:34.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:74:0) after 1m0s
[20:52:34.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:74:0)
[20:52:34.136] Timer tick, asking Buildbucket for the build status
[20:52:34.186] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:34.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:75:0) after 1m0s
[20:53:34.300] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:75:0)
[20:53:34.300] Timer tick, asking Buildbucket for the build status
[20:53:34.455] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:34.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:76:0) after 1m0s
[20:54:34.505] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:76:0)
[20:54:34.506] Timer tick, asking Buildbucket for the build status
[20:54:34.712] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:34.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:77:0) after 1m0s
[20:55:34.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:77:0)
[20:55:34.710] Timer tick, asking Buildbucket for the build status
[20:55:34.794] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:34.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:78:0) after 1m0s
[20:56:34.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:78:0)
[20:56:34.815] Timer tick, asking Buildbucket for the build status
[20:56:34.929] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:34.929] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:79:0) after 1m0s
[20:57:34.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:79:0)
[20:57:34.929] Timer tick, asking Buildbucket for the build status
[20:57:34.975] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:34.975] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:80:0) after 1m0s
[20:58:34.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:80:0)
[20:58:34.986] Timer tick, asking Buildbucket for the build status
[20:58:35.251] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:35.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:81:0) after 1m0s
[20:59:35.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:81:0)
[20:59:35.283] Timer tick, asking Buildbucket for the build status
[20:59:35.322] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:35.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:82:0) after 1m0s
[21:00:35.298] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:82:0)
[21:00:35.298] Timer tick, asking Buildbucket for the build status
[21:00:35.340] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:35.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:83:0) after 1m0s
[21:01:35.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:83:0)
[21:01:35.330] Timer tick, asking Buildbucket for the build status
[21:01:35.580] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:35.580] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:84:0) after 1m0s
[21:02:35.577] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:84:0)
[21:02:35.577] Timer tick, asking Buildbucket for the build status
[21:02:35.982] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:35.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:85:0) after 1m0s
[21:03:36.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:85:0)
[21:03:36.007] Timer tick, asking Buildbucket for the build status
[21:03:36.050] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:36.050] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:86:0) after 1m0s
[21:04:36.075] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:86:0)
[21:04:36.075] Timer tick, asking Buildbucket for the build status
[21:04:36.116] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:36.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:87:0) after 1m0s
[21:05:36.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:87:0)
[21:05:36.128] Timer tick, asking Buildbucket for the build status
[21:05:36.192] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:36.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:88:0) after 1m0s
[21:06:36.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:88:0)
[21:06:36.219] Timer tick, asking Buildbucket for the build status
[21:06:36.278] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:36.278] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:89:0) after 1m0s
[21:07:36.340] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:89:0)
[21:07:36.340] Timer tick, asking Buildbucket for the build status
[21:07:36.469] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:36.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:90:0) after 1m0s
[21:08:36.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:90:0)
[21:08:36.494] Timer tick, asking Buildbucket for the build status
[21:08:36.533] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:36.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:91:0) after 1m0s
[21:09:36.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:91:0)
[21:09:36.543] Timer tick, asking Buildbucket for the build status
[21:09:36.696] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:36.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:92:0) after 1m0s
[21:10:36.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:92:0)
[21:10:36.740] Timer tick, asking Buildbucket for the build status
[21:10:36.801] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:36.801] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:93:0) after 1m0s
[21:11:36.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:93:0)
[21:11:36.792] Timer tick, asking Buildbucket for the build status
[21:11:36.834] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:36.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:94:0) after 1m0s
[21:12:36.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:94:0)
[21:12:36.867] Timer tick, asking Buildbucket for the build status
[21:12:36.906] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:36.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:95:0) after 1m0s
[21:13:36.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:95:0)
[21:13:36.902] Timer tick, asking Buildbucket for the build status
[21:13:36.958] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:36.958] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:96:0) after 1m0s
[21:14:36.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:96:0)
[21:14:36.976] Timer tick, asking Buildbucket for the build status
[21:14:37.085] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:37.085] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:97:0) after 1m0s
[21:15:37.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:97:0)
[21:15:37.119] Timer tick, asking Buildbucket for the build status
[21:15:37.163] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:37.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:98:0) after 1m0s
[21:16:37.186] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:98:0)
[21:16:37.186] Timer tick, asking Buildbucket for the build status
[21:16:37.393] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:37.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:99:0) after 1m0s
[21:17:37.420] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:99:0)
[21:17:37.420] Timer tick, asking Buildbucket for the build status
[21:17:37.474] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:37.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:100:0) after 1m0s
[21:18:37.471] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:100:0)
[21:18:37.471] Timer tick, asking Buildbucket for the build status
[21:18:37.512] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:37.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:101:0) after 1m0s
[21:19:37.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:101:0)
[21:19:37.532] Timer tick, asking Buildbucket for the build status
[21:19:37.579] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:37.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:102:0) after 1m0s
[21:20:37.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:102:0)
[21:20:37.600] Timer tick, asking Buildbucket for the build status
[21:20:37.693] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:37.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:103:0) after 1m0s
[21:21:37.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:103:0)
[21:21:37.682] Timer tick, asking Buildbucket for the build status
[21:21:37.727] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:37.727] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:104:0) after 1m0s
[21:22:37.751] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:104:0)
[21:22:37.751] Timer tick, asking Buildbucket for the build status
[21:22:37.791] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:37.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:105:0) after 1m0s
[21:23:37.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:105:0)
[21:23:37.811] Timer tick, asking Buildbucket for the build status
[21:23:37.855] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:37.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:106:0) after 1m0s
[21:24:37.849] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:106:0)
[21:24:37.849] Timer tick, asking Buildbucket for the build status
[21:24:37.895] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:37.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:107:0) after 1m0s
[21:25:37.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:107:0)
[21:25:37.888] Timer tick, asking Buildbucket for the build status
[21:25:38.004] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:38.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:108:0) after 1m0s
[21:26:37.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:108:0)
[21:26:37.976] Timer tick, asking Buildbucket for the build status
[21:26:38.021] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:38.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:109:0) after 1m0s
[21:27:38.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:109:0)
[21:27:38.045] Timer tick, asking Buildbucket for the build status
[21:27:38.163] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:38.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:110:0) after 1m0s
[21:28:38.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:110:0)
[21:28:38.183] Timer tick, asking Buildbucket for the build status
[21:28:38.224] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:38.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:111:0) after 1m0s
[21:29:38.244] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:111:0)
[21:29:38.244] Timer tick, asking Buildbucket for the build status
[21:29:38.289] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:38.289] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:112:0) after 1m0s
[21:30:38.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:112:0)
[21:30:38.299] Timer tick, asking Buildbucket for the build status
[21:30:38.353] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:38.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:113:0) after 1m0s
[21:31:38.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:113:0)
[21:31:38.376] Timer tick, asking Buildbucket for the build status
[21:31:38.429] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:38.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:114:0) after 1m0s
[21:32:38.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:114:0)
[21:32:38.426] Timer tick, asking Buildbucket for the build status
[21:32:38.469] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:38.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:115:0) after 1m0s
[21:33:38.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:115:0)
[21:33:38.489] Timer tick, asking Buildbucket for the build status
[21:33:38.687] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:38.687] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:116:0) after 1m0s
[21:34:38.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:116:0)
[21:34:38.708] Timer tick, asking Buildbucket for the build status
[21:34:38.763] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:38.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:117:0) after 1m0s
[21:35:38.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:117:0)
[21:35:38.784] Timer tick, asking Buildbucket for the build status
[21:35:38.825] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:38.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:118:0) after 1m0s
[21:36:38.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:118:0)
[21:36:38.833] Timer tick, asking Buildbucket for the build status
[21:36:39.126] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:39.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:119:0) after 1m0s
[21:37:39.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:119:0)
[21:37:39.147] Timer tick, asking Buildbucket for the build status
[21:37:39.203] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:39.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:120:0) after 1m0s
[21:38:39.205] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:120:0)
[21:38:39.205] Timer tick, asking Buildbucket for the build status
[21:38:39.251] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:39.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:121:0) after 1m0s
[21:39:39.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:121:0)
[21:39:39.276] Timer tick, asking Buildbucket for the build status
[21:39:39.329] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:39.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:122:0) after 1m0s
[21:40:39.340] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:122:0)
[21:40:39.340] Timer tick, asking Buildbucket for the build status
[21:40:40.021] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:40.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:123:0) after 1m0s
[21:41:40.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:123:0)
[21:41:40.038] Timer tick, asking Buildbucket for the build status
[21:41:40.119] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:40.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:124:0) after 1m0s
[21:42:40.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:124:0)
[21:42:40.144] Timer tick, asking Buildbucket for the build status
[21:42:40.197] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:40.197] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:125:0) after 1m0s
[21:43:40.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:125:0)
[21:43:40.170] Timer tick, asking Buildbucket for the build status
[21:43:40.276] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:40.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:126:0) after 1m0s
[21:44:40.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:126:0)
[21:44:40.286] Timer tick, asking Buildbucket for the build status
[21:44:40.388] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:40.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:127:0) after 1m0s
[21:45:40.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:127:0)
[21:45:40.372] Timer tick, asking Buildbucket for the build status
[21:45:40.436] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:40.436] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:128:0) after 1m0s
[21:46:40.470] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:128:0)
[21:46:40.470] Timer tick, asking Buildbucket for the build status
[21:46:40.512] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:40.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:129:0) after 1m0s
[21:47:40.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:129:0)
[21:47:40.523] Timer tick, asking Buildbucket for the build status
[21:47:40.565] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:40.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:130:0) after 1m0s
[21:48:40.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:130:0)
[21:48:40.554] Timer tick, asking Buildbucket for the build status
[21:48:40.615] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:40.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:131:0) after 1m0s
[21:49:40.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:131:0)
[21:49:40.640] Timer tick, asking Buildbucket for the build status
[21:49:40.762] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:40.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:132:0) after 1m0s
[21:50:40.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:132:0)
[21:50:40.802] Timer tick, asking Buildbucket for the build status
[21:50:40.849] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:40.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:133:0) after 1m0s
[21:51:40.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:133:0)
[21:51:40.869] Timer tick, asking Buildbucket for the build status
[21:51:41.006] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:41.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:134:0) after 1m0s
[21:52:41.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:134:0)
[21:52:41.077] Timer tick, asking Buildbucket for the build status
[21:52:41.121] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:41.121] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:135:0) after 1m0s
[21:53:41.160] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:135:0)
[21:53:41.160] Timer tick, asking Buildbucket for the build status
[21:53:41.259] Build 8918529575356197248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:41.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083959637500529744:136:0) after 1m0s
[21:54:01.354] Received PubSub notification, asking Buildbucket for the build status
[21:54:01.400] Build 8918529575356197248: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:54:01.400] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1553032440242677",
"created_by": "project:infra",
"created_ts": "1553024426081669",
"id": "8918529575356197248",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3729.22\"}}",
"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\": [\"build-from-tarball-0-72pp\"], \"server_version\": [\"4209-02e057f\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"32\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"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}, \"version\": \"74.0.3729.22\", \"path_config\": \"generic\", \"bot_id\": \"build-from-tarball-0-72pp\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553024485216386",
"status": "COMPLETED",
"status_changed_ts": "1553032440912556",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9083959637500529744",
"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/8918529575356197248/+/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:43b049aa063f1c10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1553032440958619",
"url": "https://ci.chromium.org/b/8918529575356197248",
"utcnow_ts": "1553032441392947"
}
}
[21:54:01.400] Invocation finished in 2h13m36.748577615s with status SUCCEEDED