[02:13:52.276] New invocation is queued and will start shortly
[02:13:52.276] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[02:13:53.355] Starting the invocation (attempt 1)
[02:13:53.405] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:13:53.406] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:13:53.406] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9083210107399031504",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"74.0.3729.39\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9083210107399031504",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[02:13:54.151] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "project:infra",
"created_ts": "1553739233561676",
"id": "8917780045387628992",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3729.39\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553739233884593",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9083210107399031504",
"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/8917780045387628992/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1553739233884726",
"url": "https://ci.chromium.org/b/8917780045387628992",
"utcnow_ts": "1553739234140929"
}
}
[02:13:54.152] Task URL: https://ci.chromium.org/b/8917780045387628992
[02:13:54.152] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:2:0) after 1m0s
[02:14:33.074] Received PubSub notification, asking Buildbucket for the build status
[02:14:33.178] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:54.202] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:2:0)
[02:14:54.202] Timer tick, asking Buildbucket for the build status
[02:14:54.279] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:54.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:4:0) after 1m0s
[02:15:54.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:4:0)
[02:15:54.286] Timer tick, asking Buildbucket for the build status
[02:15:54.395] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:54.395] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:5:0) after 1m0s
[02:16:54.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:5:0)
[02:16:54.414] Timer tick, asking Buildbucket for the build status
[02:16:54.510] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:54.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:6:0) after 1m0s
[02:17:54.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:6:0)
[02:17:54.528] Timer tick, asking Buildbucket for the build status
[02:17:54.892] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:54.892] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:7:0) after 1m0s
[02:18:54.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:7:0)
[02:18:54.896] Timer tick, asking Buildbucket for the build status
[02:18:54.946] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:54.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:8:0) after 1m0s
[02:19:54.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:8:0)
[02:19:54.965] Timer tick, asking Buildbucket for the build status
[02:19:55.169] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:55.169] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:9:0) after 1m0s
[02:20:55.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:9:0)
[02:20:55.214] Timer tick, asking Buildbucket for the build status
[02:20:55.251] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:55.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:10:0) after 1m0s
[02:21:55.269] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:10:0)
[02:21:55.269] Timer tick, asking Buildbucket for the build status
[02:21:55.324] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:55.324] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:11:0) after 1m0s
[02:22:55.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:11:0)
[02:22:55.344] Timer tick, asking Buildbucket for the build status
[02:22:55.587] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:55.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:12:0) after 1m0s
[02:23:55.606] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:12:0)
[02:23:55.606] Timer tick, asking Buildbucket for the build status
[02:23:55.643] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:55.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:13:0) after 1m0s
[02:24:55.663] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:13:0)
[02:24:55.663] Timer tick, asking Buildbucket for the build status
[02:24:55.790] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:55.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:14:0) after 1m0s
[02:25:55.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:14:0)
[02:25:55.851] Timer tick, asking Buildbucket for the build status
[02:25:55.894] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:55.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:15:0) after 1m0s
[02:26:55.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:15:0)
[02:26:55.901] Timer tick, asking Buildbucket for the build status
[02:26:55.938] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:55.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:16:0) after 1m0s
[02:27:55.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:16:0)
[02:27:55.957] Timer tick, asking Buildbucket for the build status
[02:27:56.086] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:56.086] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:17:0) after 1m0s
[02:28:56.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:17:0)
[02:28:56.082] Timer tick, asking Buildbucket for the build status
[02:28:56.145] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:56.145] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:18:0) after 1m0s
[02:29:56.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:18:0)
[02:29:56.167] Timer tick, asking Buildbucket for the build status
[02:29:56.301] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:56.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:19:0) after 1m0s
[02:30:56.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:19:0)
[02:30:56.348] Timer tick, asking Buildbucket for the build status
[02:30:56.401] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:56.401] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:20:0) after 1m0s
[02:31:56.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:20:0)
[02:31:56.417] Timer tick, asking Buildbucket for the build status
[02:31:56.465] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:56.465] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:21:0) after 1m0s
[02:32:56.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:21:0)
[02:32:56.487] Timer tick, asking Buildbucket for the build status
[02:32:56.551] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:56.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:22:0) after 1m0s
[02:33:56.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:22:0)
[02:33:56.560] Timer tick, asking Buildbucket for the build status
[02:33:56.597] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:56.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:23:0) after 1m0s
[02:34:56.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:23:0)
[02:34:56.589] Timer tick, asking Buildbucket for the build status
[02:34:56.843] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:56.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:24:0) after 1m0s
[02:35:56.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:24:0)
[02:35:56.825] Timer tick, asking Buildbucket for the build status
[02:35:56.902] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:56.902] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:25:0) after 1m0s
[02:36:56.933] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:25:0)
[02:36:56.933] Timer tick, asking Buildbucket for the build status
[02:36:57.032] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:57.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:26:0) after 1m0s
[02:37:57.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:26:0)
[02:37:57.055] Timer tick, asking Buildbucket for the build status
[02:37:57.128] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:57.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:27:0) after 1m0s
[02:38:57.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:27:0)
[02:38:57.151] Timer tick, asking Buildbucket for the build status
[02:38:57.192] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:57.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:28:0) after 1m0s
[02:39:57.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:28:0)
[02:39:57.208] Timer tick, asking Buildbucket for the build status
[02:39:57.453] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:57.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:29:0) after 1m0s
[02:40:57.470] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:29:0)
[02:40:57.470] Timer tick, asking Buildbucket for the build status
[02:40:57.574] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:57.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:30:0) after 1m0s
[02:41:57.572] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:30:0)
[02:41:57.572] Timer tick, asking Buildbucket for the build status
[02:41:57.651] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:57.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:31:0) after 1m0s
[02:42:57.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:31:0)
[02:42:57.671] Timer tick, asking Buildbucket for the build status
[02:42:57.716] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:57.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:32:0) after 1m0s
[02:43:57.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:32:0)
[02:43:57.734] Timer tick, asking Buildbucket for the build status
[02:43:57.811] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:57.811] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:33:0) after 1m0s
[02:44:57.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:33:0)
[02:44:57.794] Timer tick, asking Buildbucket for the build status
[02:44:57.838] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:57.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:34:0) after 1m0s
[02:45:57.864] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:34:0)
[02:45:57.864] Timer tick, asking Buildbucket for the build status
[02:45:58.024] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:58.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:35:0) after 1m0s
[02:46:58.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:35:0)
[02:46:58.049] Timer tick, asking Buildbucket for the build status
[02:46:58.086] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:58.086] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:36:0) after 1m0s
[02:47:58.099] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:36:0)
[02:47:58.099] Timer tick, asking Buildbucket for the build status
[02:47:58.156] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:58.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:37:0) after 1m0s
[02:48:58.159] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:37:0)
[02:48:58.159] Timer tick, asking Buildbucket for the build status
[02:48:58.258] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:58.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:38:0) after 1m0s
[02:49:58.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:38:0)
[02:49:58.259] Timer tick, asking Buildbucket for the build status
[02:49:58.374] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:58.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:39:0) after 1m0s
[02:50:58.393] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:39:0)
[02:50:58.393] Timer tick, asking Buildbucket for the build status
[02:50:58.438] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:58.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:40:0) after 1m0s
[02:51:58.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:40:0)
[02:51:58.502] Timer tick, asking Buildbucket for the build status
[02:51:58.693] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:58.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:41:0) after 1m0s
[02:52:58.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:41:0)
[02:52:58.710] Timer tick, asking Buildbucket for the build status
[02:52:58.844] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:58.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:42:0) after 1m0s
[02:53:58.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:42:0)
[02:53:58.867] Timer tick, asking Buildbucket for the build status
[02:53:58.931] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:58.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:43:0) after 1m0s
[02:54:58.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:43:0)
[02:54:58.953] Timer tick, asking Buildbucket for the build status
[02:54:59.019] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:59.019] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:44:0) after 1m0s
[02:55:59.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:44:0)
[02:55:59.040] Timer tick, asking Buildbucket for the build status
[02:55:59.152] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:59.152] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:45:0) after 1m0s
[02:56:59.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:45:0)
[02:56:59.172] Timer tick, asking Buildbucket for the build status
[02:56:59.213] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:59.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:46:0) after 1m0s
[02:57:59.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:46:0)
[02:57:59.191] Timer tick, asking Buildbucket for the build status
[02:57:59.265] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:59.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:47:0) after 1m0s
[02:58:59.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:47:0)
[02:58:59.291] Timer tick, asking Buildbucket for the build status
[02:58:59.348] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:59.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:48:0) after 1m0s
[02:59:59.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:48:0)
[02:59:59.367] Timer tick, asking Buildbucket for the build status
[02:59:59.405] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:59.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:49:0) after 1m0s
[03:00:59.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:49:0)
[03:00:59.481] Timer tick, asking Buildbucket for the build status
[03:00:59.554] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:59.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:50:0) after 1m0s
[03:01:59.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:50:0)
[03:01:59.593] Timer tick, asking Buildbucket for the build status
[03:01:59.636] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:59.636] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:51:0) after 1m0s
[03:02:59.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:51:0)
[03:02:59.655] Timer tick, asking Buildbucket for the build status
[03:02:59.696] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:59.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:52:0) after 1m0s
[03:03:59.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:52:0)
[03:03:59.689] Timer tick, asking Buildbucket for the build status
[03:03:59.783] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:59.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:53:0) after 1m0s
[03:04:59.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:53:0)
[03:04:59.793] Timer tick, asking Buildbucket for the build status
[03:04:59.854] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:59.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:54:0) after 1m0s
[03:05:59.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:54:0)
[03:05:59.834] Timer tick, asking Buildbucket for the build status
[03:05:59.877] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:59.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:55:0) after 1m0s
[03:06:59.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:55:0)
[03:06:59.858] Timer tick, asking Buildbucket for the build status
[03:06:59.910] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:59.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:56:0) after 1m0s
[03:07:59.933] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:56:0)
[03:07:59.933] Timer tick, asking Buildbucket for the build status
[03:07:59.987] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:59.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:57:0) after 1m0s
[03:09:00.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:57:0)
[03:09:00.012] Timer tick, asking Buildbucket for the build status
[03:09:00.081] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:00.081] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:58:0) after 1m0s
[03:10:00.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:58:0)
[03:10:00.086] Timer tick, asking Buildbucket for the build status
[03:10:00.174] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:00.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:59:0) after 1m0s
[03:11:00.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:59:0)
[03:11:00.194] Timer tick, asking Buildbucket for the build status
[03:11:00.292] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:00.292] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:60:0) after 1m0s
[03:12:00.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:60:0)
[03:12:00.319] Timer tick, asking Buildbucket for the build status
[03:12:00.417] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:00.417] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:61:0) after 1m0s
[03:13:00.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:61:0)
[03:13:00.439] Timer tick, asking Buildbucket for the build status
[03:13:00.502] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:00.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:62:0) after 1m0s
[03:14:00.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:62:0)
[03:14:00.523] Timer tick, asking Buildbucket for the build status
[03:14:00.582] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:00.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:63:0) after 1m0s
[03:15:00.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:63:0)
[03:15:00.569] Timer tick, asking Buildbucket for the build status
[03:15:00.634] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:00.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:64:0) after 1m0s
[03:16:00.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:64:0)
[03:16:00.640] Timer tick, asking Buildbucket for the build status
[03:16:00.775] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:00.775] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:65:0) after 1m0s
[03:17:00.796] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:65:0)
[03:17:00.796] Timer tick, asking Buildbucket for the build status
[03:17:00.969] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:00.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:66:0) after 1m0s
[03:18:00.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:66:0)
[03:18:00.992] Timer tick, asking Buildbucket for the build status
[03:18:01.069] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:01.069] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:67:0) after 1m0s
[03:19:01.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:67:0)
[03:19:01.093] Timer tick, asking Buildbucket for the build status
[03:19:01.166] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:01.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:68:0) after 1m0s
[03:20:01.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:68:0)
[03:20:01.185] Timer tick, asking Buildbucket for the build status
[03:20:01.310] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:01.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:69:0) after 1m0s
[03:21:01.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:69:0)
[03:21:01.329] Timer tick, asking Buildbucket for the build status
[03:21:01.384] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:01.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:70:0) after 1m0s
[03:22:01.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:70:0)
[03:22:01.363] Timer tick, asking Buildbucket for the build status
[03:22:01.509] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:01.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:71:0) after 1m0s
[03:23:01.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:71:0)
[03:23:01.533] Timer tick, asking Buildbucket for the build status
[03:23:01.580] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:01.580] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:72:0) after 1m0s
[03:24:01.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:72:0)
[03:24:01.603] Timer tick, asking Buildbucket for the build status
[03:24:01.664] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:01.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:73:0) after 1m0s
[03:25:01.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:73:0)
[03:25:01.683] Timer tick, asking Buildbucket for the build status
[03:25:01.805] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:01.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:74:0) after 1m0s
[03:26:01.826] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:74:0)
[03:26:01.826] Timer tick, asking Buildbucket for the build status
[03:26:01.868] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:01.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:75:0) after 1m0s
[03:27:01.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:75:0)
[03:27:01.888] Timer tick, asking Buildbucket for the build status
[03:27:01.982] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:01.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:76:0) after 1m0s
[03:28:02.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:76:0)
[03:28:02.004] Timer tick, asking Buildbucket for the build status
[03:28:02.096] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:02.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:77:0) after 1m0s
[03:29:02.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:77:0)
[03:29:02.125] Timer tick, asking Buildbucket for the build status
[03:29:02.279] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:02.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:78:0) after 1m0s
[03:30:02.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:78:0)
[03:30:02.301] Timer tick, asking Buildbucket for the build status
[03:30:02.355] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:02.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:79:0) after 1m0s
[03:31:02.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:79:0)
[03:31:02.376] Timer tick, asking Buildbucket for the build status
[03:31:02.438] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:02.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:80:0) after 1m0s
[03:32:02.462] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:80:0)
[03:32:02.462] Timer tick, asking Buildbucket for the build status
[03:32:02.528] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:02.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:81:0) after 1m0s
[03:33:02.566] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:81:0)
[03:33:02.566] Timer tick, asking Buildbucket for the build status
[03:33:02.769] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:02.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:82:0) after 1m0s
[03:34:02.819] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:82:0)
[03:34:02.820] Timer tick, asking Buildbucket for the build status
[03:34:03.047] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:03.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:83:0) after 1m0s
[03:35:03.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:83:0)
[03:35:03.105] Timer tick, asking Buildbucket for the build status
[03:35:03.175] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:03.175] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:84:0) after 1m0s
[03:36:03.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:84:0)
[03:36:03.171] Timer tick, asking Buildbucket for the build status
[03:36:03.226] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:03.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:85:0) after 1m0s
[03:37:03.269] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:85:0)
[03:37:03.269] Timer tick, asking Buildbucket for the build status
[03:37:03.481] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:03.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:86:0) after 1m0s
[03:38:03.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:86:0)
[03:38:03.502] Timer tick, asking Buildbucket for the build status
[03:38:03.537] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:03.537] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:87:0) after 1m0s
[03:39:03.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:87:0)
[03:39:03.579] Timer tick, asking Buildbucket for the build status
[03:39:03.670] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:03.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:88:0) after 1m0s
[03:40:03.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:88:0)
[03:40:03.693] Timer tick, asking Buildbucket for the build status
[03:40:03.861] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:03.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:89:0) after 1m0s
[03:41:03.894] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:89:0)
[03:41:03.894] Timer tick, asking Buildbucket for the build status
[03:41:03.942] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:03.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:90:0) after 1m0s
[03:42:04.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:90:0)
[03:42:04.059] Timer tick, asking Buildbucket for the build status
[03:42:04.121] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:04.121] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:91:0) after 1m0s
[03:43:04.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:91:0)
[03:43:04.141] Timer tick, asking Buildbucket for the build status
[03:43:04.183] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:04.183] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:92:0) after 1m0s
[03:44:04.206] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:92:0)
[03:44:04.206] Timer tick, asking Buildbucket for the build status
[03:44:04.263] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:04.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:93:0) after 1m0s
[03:45:04.285] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:93:0)
[03:45:04.285] Timer tick, asking Buildbucket for the build status
[03:45:04.337] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:04.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:94:0) after 1m0s
[03:46:04.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:94:0)
[03:46:04.426] Timer tick, asking Buildbucket for the build status
[03:46:04.478] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:04.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:95:0) after 1m0s
[03:47:04.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:95:0)
[03:47:04.447] Timer tick, asking Buildbucket for the build status
[03:47:04.503] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:04.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:96:0) after 1m0s
[03:48:04.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:96:0)
[03:48:04.511] Timer tick, asking Buildbucket for the build status
[03:48:04.581] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:04.581] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:97:0) after 1m0s
[03:49:04.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:97:0)
[03:49:04.565] Timer tick, asking Buildbucket for the build status
[03:49:04.665] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:04.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:98:0) after 1m0s
[03:50:04.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:98:0)
[03:50:04.640] Timer tick, asking Buildbucket for the build status
[03:50:04.688] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:04.688] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:99:0) after 1m0s
[03:51:04.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:99:0)
[03:51:04.710] Timer tick, asking Buildbucket for the build status
[03:51:04.750] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:04.750] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:100:0) after 1m0s
[03:52:04.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:100:0)
[03:52:04.781] Timer tick, asking Buildbucket for the build status
[03:52:04.826] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:04.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:101:0) after 1m0s
[03:53:04.850] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:101:0)
[03:53:04.850] Timer tick, asking Buildbucket for the build status
[03:53:04.990] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:04.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:102:0) after 1m0s
[03:54:05.006] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:102:0)
[03:54:05.007] Timer tick, asking Buildbucket for the build status
[03:54:05.065] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:05.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:103:0) after 1m0s
[03:55:05.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:103:0)
[03:55:05.086] Timer tick, asking Buildbucket for the build status
[03:55:05.124] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:05.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:104:0) after 1m0s
[03:56:05.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:104:0)
[03:56:05.145] Timer tick, asking Buildbucket for the build status
[03:56:05.219] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:05.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:105:0) after 1m0s
[03:57:05.206] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:105:0)
[03:57:05.206] Timer tick, asking Buildbucket for the build status
[03:57:05.253] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:05.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:106:0) after 1m0s
[03:58:05.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:106:0)
[03:58:05.276] Timer tick, asking Buildbucket for the build status
[03:58:05.319] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:05.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:107:0) after 1m0s
[03:59:05.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:107:0)
[03:59:05.361] Timer tick, asking Buildbucket for the build status
[03:59:05.418] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:05.418] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:108:0) after 1m0s
[04:00:05.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:108:0)
[04:00:05.397] Timer tick, asking Buildbucket for the build status
[04:00:05.505] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:05.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:109:0) after 1m0s
[04:01:05.527] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:109:0)
[04:01:05.527] Timer tick, asking Buildbucket for the build status
[04:01:05.580] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:05.580] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:110:0) after 1m0s
[04:02:05.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:110:0)
[04:02:05.578] Timer tick, asking Buildbucket for the build status
[04:02:05.623] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:05.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:111:0) after 1m0s
[04:03:05.649] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:111:0)
[04:03:05.649] Timer tick, asking Buildbucket for the build status
[04:03:05.709] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:05.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:112:0) after 1m0s
[04:04:05.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:112:0)
[04:04:05.689] Timer tick, asking Buildbucket for the build status
[04:04:05.736] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:05.736] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:113:0) after 1m0s
[04:05:05.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:113:0)
[04:05:05.749] Timer tick, asking Buildbucket for the build status
[04:05:05.903] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:05.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:114:0) after 1m0s
[04:06:05.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:114:0)
[04:06:05.906] Timer tick, asking Buildbucket for the build status
[04:06:05.981] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:05.981] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:115:0) after 1m0s
[04:07:06.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:115:0)
[04:07:06.013] Timer tick, asking Buildbucket for the build status
[04:07:06.077] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:06.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:116:0) after 1m0s
[04:08:06.100] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:116:0)
[04:08:06.100] Timer tick, asking Buildbucket for the build status
[04:08:06.188] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:06.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:117:0) after 1m0s
[04:09:06.200] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:117:0)
[04:09:06.200] Timer tick, asking Buildbucket for the build status
[04:09:06.292] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:06.292] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:118:0) after 1m0s
[04:10:06.303] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:118:0)
[04:10:06.303] Timer tick, asking Buildbucket for the build status
[04:10:06.496] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:06.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:119:0) after 1m0s
[04:11:06.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:119:0)
[04:11:06.535] Timer tick, asking Buildbucket for the build status
[04:11:06.582] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:06.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:120:0) after 1m0s
[04:12:06.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:120:0)
[04:12:06.607] Timer tick, asking Buildbucket for the build status
[04:12:06.668] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:06.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:121:0) after 1m0s
[04:13:06.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:121:0)
[04:13:06.654] Timer tick, asking Buildbucket for the build status
[04:13:06.703] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:06.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:122:0) after 1m0s
[04:14:06.721] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:122:0)
[04:14:06.721] Timer tick, asking Buildbucket for the build status
[04:14:06.791] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:06.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:123:0) after 1m0s
[04:15:06.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:123:0)
[04:15:06.779] Timer tick, asking Buildbucket for the build status
[04:15:06.888] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:06.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:124:0) after 1m0s
[04:16:06.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:124:0)
[04:16:06.896] Timer tick, asking Buildbucket for the build status
[04:16:07.004] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:07.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:125:0) after 1m0s
[04:17:07.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:125:0)
[04:17:07.004] Timer tick, asking Buildbucket for the build status
[04:17:07.056] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:07.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:126:0) after 1m0s
[04:18:07.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:126:0)
[04:18:07.086] Timer tick, asking Buildbucket for the build status
[04:18:07.551] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:07.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:127:0) after 1m0s
[04:19:07.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:127:0)
[04:19:07.589] Timer tick, asking Buildbucket for the build status
[04:19:07.650] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:07.650] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:128:0) after 1m0s
[04:20:07.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:128:0)
[04:20:07.673] Timer tick, asking Buildbucket for the build status
[04:20:07.771] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:07.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:129:0) after 1m0s
[04:21:07.746] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:129:0)
[04:21:07.746] Timer tick, asking Buildbucket for the build status
[04:21:07.816] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:07.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:130:0) after 1m0s
[04:22:07.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:130:0)
[04:22:07.848] Timer tick, asking Buildbucket for the build status
[04:22:07.893] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:07.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:131:0) after 1m0s
[04:23:07.913] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:131:0)
[04:23:07.913] Timer tick, asking Buildbucket for the build status
[04:23:08.162] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:08.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:132:0) after 1m0s
[04:24:08.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:132:0)
[04:24:08.185] Timer tick, asking Buildbucket for the build status
[04:24:08.224] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:08.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:133:0) after 1m0s
[04:25:08.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:133:0)
[04:25:08.245] Timer tick, asking Buildbucket for the build status
[04:25:08.342] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:08.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:134:0) after 1m0s
[04:26:08.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:134:0)
[04:26:08.363] Timer tick, asking Buildbucket for the build status
[04:26:08.504] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:08.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:135:0) after 1m0s
[04:27:08.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:135:0)
[04:27:08.526] Timer tick, asking Buildbucket for the build status
[04:27:08.656] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:08.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:136:0) after 1m0s
[04:28:08.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:136:0)
[04:28:08.638] Timer tick, asking Buildbucket for the build status
[04:28:08.685] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:08.685] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:137:0) after 1m0s
[04:29:08.725] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:137:0)
[04:29:08.725] Timer tick, asking Buildbucket for the build status
[04:29:08.807] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:08.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:138:0) after 1m0s
[04:30:08.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:138:0)
[04:30:08.795] Timer tick, asking Buildbucket for the build status
[04:30:08.834] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:08.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:139:0) after 1m0s
[04:31:08.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:139:0)
[04:31:08.816] Timer tick, asking Buildbucket for the build status
[04:31:08.884] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:08.884] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:140:0) after 1m0s
[04:32:08.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:140:0)
[04:32:08.911] Timer tick, asking Buildbucket for the build status
[04:32:09.035] Build 8917780045387628992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:09.035] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083210107399031504:141:0) after 1m0s
[04:32:25.330] Received PubSub notification, asking Buildbucket for the build status
[04:32:25.406] Build 8917780045387628992: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:32:25.406] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1553747544497652",
"created_by": "project:infra",
"created_ts": "1553739233561676",
"id": "8917780045387628992",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3729.39\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"build-from-tarball-0-f7cu\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"74.0.3729.39\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Build From Tarball\"], \"caches\": [\"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"build-from-tarball-0-f7cu\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4224-5f5030f\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553739271928626",
"status": "COMPLETED",
"status_changed_ts": "1553747545059487",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9083210107399031504",
"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/8917780045387628992/+/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:43dae4c39acb5a10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1553747545066135",
"url": "https://ci.chromium.org/b/8917780045387628992",
"utcnow_ts": "1553747545397992"
}
}
[04:32:25.406] Invocation finished in 2h18m33.14704909s with status SUCCEEDED