[10:45:22.311] New invocation is queued and will start shortly
[10:45:22.311] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[10:45:23.429] Starting the invocation (attempt 1)
[10:45:23.484] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:45:23.484] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:45:23.484] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9084265090163537792",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"75.0.3735.0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9084265090163537792",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[10:45:24.944] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "project:infra",
"created_ts": "1552733123770089",
"id": "8918835027967737312",
"lease_expiration_ts": "1552851924632678",
"lease_key": "2029717321",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"75.0.3735.0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552733124634903",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9084265090163537792",
"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/8918835027967737312/+/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:439eecbedb1a6810",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1552733124635039",
"url": "https://ci.chromium.org/b/8918835027967737312",
"utcnow_ts": "1552733124931895"
}
}
[10:45:24.944] Task URL: https://ci.chromium.org/b/8918835027967737312
[10:45:24.944] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:2:0) after 1m0s
[10:46:16.768] Received PubSub notification, asking Buildbucket for the build status
[10:46:16.890] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:24.964] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:2:0)
[10:46:24.964] Timer tick, asking Buildbucket for the build status
[10:46:25.022] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:25.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:4:0) after 1m0s
[10:47:25.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:4:0)
[10:47:25.037] Timer tick, asking Buildbucket for the build status
[10:47:25.117] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:25.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:5:0) after 1m0s
[10:48:25.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:5:0)
[10:48:25.134] Timer tick, asking Buildbucket for the build status
[10:48:25.222] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:25.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:6:0) after 1m0s
[10:49:25.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:6:0)
[10:49:25.243] Timer tick, asking Buildbucket for the build status
[10:49:25.327] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:25.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:7:0) after 1m0s
[10:50:25.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:7:0)
[10:50:25.346] Timer tick, asking Buildbucket for the build status
[10:50:25.403] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:25.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:8:0) after 1m0s
[10:51:25.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:8:0)
[10:51:25.425] Timer tick, asking Buildbucket for the build status
[10:51:25.493] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:25.493] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:9:0) after 1m0s
[10:52:25.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:9:0)
[10:52:25.485] Timer tick, asking Buildbucket for the build status
[10:52:25.568] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:25.568] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:10:0) after 1m0s
[10:53:25.630] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:10:0)
[10:53:25.630] Timer tick, asking Buildbucket for the build status
[10:53:25.754] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:25.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:11:0) after 1m0s
[10:54:25.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:11:0)
[10:54:25.772] Timer tick, asking Buildbucket for the build status
[10:54:25.840] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:25.840] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:12:0) after 1m0s
[10:55:25.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:12:0)
[10:55:25.861] Timer tick, asking Buildbucket for the build status
[10:55:25.927] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:25.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:13:0) after 1m0s
[10:56:25.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:13:0)
[10:56:25.946] Timer tick, asking Buildbucket for the build status
[10:56:26.044] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:26.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:14:0) after 1m0s
[10:57:26.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:14:0)
[10:57:26.066] Timer tick, asking Buildbucket for the build status
[10:57:26.151] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:26.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:15:0) after 1m0s
[10:58:26.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:15:0)
[10:58:26.171] Timer tick, asking Buildbucket for the build status
[10:58:26.285] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:26.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:16:0) after 1m0s
[10:59:26.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:16:0)
[10:59:26.291] Timer tick, asking Buildbucket for the build status
[10:59:26.361] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:26.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:17:0) after 1m0s
[11:00:26.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:17:0)
[11:00:26.384] Timer tick, asking Buildbucket for the build status
[11:00:26.456] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:26.456] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:18:0) after 1m0s
[11:01:26.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:18:0)
[11:01:26.477] Timer tick, asking Buildbucket for the build status
[11:01:26.521] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:26.521] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:19:0) after 1m0s
[11:02:26.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:19:0)
[11:02:26.543] Timer tick, asking Buildbucket for the build status
[11:02:26.606] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:26.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:20:0) after 1m0s
[11:03:26.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:20:0)
[11:03:26.601] Timer tick, asking Buildbucket for the build status
[11:03:26.661] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:26.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:21:0) after 1m0s
[11:04:26.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:21:0)
[11:04:26.734] Timer tick, asking Buildbucket for the build status
[11:04:26.803] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:26.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:22:0) after 1m0s
[11:05:26.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:22:0)
[11:05:26.824] Timer tick, asking Buildbucket for the build status
[11:05:26.884] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:26.884] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:23:0) after 1m0s
[11:06:26.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:23:0)
[11:06:26.911] Timer tick, asking Buildbucket for the build status
[11:06:26.989] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:26.989] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:24:0) after 1m0s
[11:07:27.073] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:24:0)
[11:07:27.073] Timer tick, asking Buildbucket for the build status
[11:07:27.161] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:27.161] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:25:0) after 1m0s
[11:08:27.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:25:0)
[11:08:27.183] Timer tick, asking Buildbucket for the build status
[11:08:27.366] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:27.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:26:0) after 1m0s
[11:09:27.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:26:0)
[11:09:27.383] Timer tick, asking Buildbucket for the build status
[11:09:27.456] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:27.456] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:27:0) after 1m0s
[11:10:27.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:27:0)
[11:10:27.475] Timer tick, asking Buildbucket for the build status
[11:10:27.581] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:27.581] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:28:0) after 1m0s
[11:11:27.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:28:0)
[11:11:27.601] Timer tick, asking Buildbucket for the build status
[11:11:27.673] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:27.673] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:29:0) after 1m0s
[11:12:27.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:29:0)
[11:12:27.692] Timer tick, asking Buildbucket for the build status
[11:12:27.790] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:27.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:30:0) after 1m0s
[11:13:27.808] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:30:0)
[11:13:27.808] Timer tick, asking Buildbucket for the build status
[11:13:27.878] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:27.878] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:31:0) after 1m0s
[11:14:27.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:31:0)
[11:14:27.972] Timer tick, asking Buildbucket for the build status
[11:14:28.100] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:28.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:32:0) after 1m0s
[11:15:28.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:32:0)
[11:15:28.134] Timer tick, asking Buildbucket for the build status
[11:15:28.271] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:28.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:33:0) after 1m0s
[11:16:28.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:33:0)
[11:16:28.266] Timer tick, asking Buildbucket for the build status
[11:16:28.349] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:28.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:34:0) after 1m0s
[11:17:28.368] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:34:0)
[11:17:28.368] Timer tick, asking Buildbucket for the build status
[11:17:28.510] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:28.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:35:0) after 1m0s
[11:18:28.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:35:0)
[11:18:28.528] Timer tick, asking Buildbucket for the build status
[11:18:28.591] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:28.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:36:0) after 1m0s
[11:19:28.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:36:0)
[11:19:28.567] Timer tick, asking Buildbucket for the build status
[11:19:28.691] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:28.691] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:37:0) after 1m0s
[11:20:28.699] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:37:0)
[11:20:28.699] Timer tick, asking Buildbucket for the build status
[11:20:28.857] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:28.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:38:0) after 1m0s
[11:21:28.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:38:0)
[11:21:28.874] Timer tick, asking Buildbucket for the build status
[11:21:29.024] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:29.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:39:0) after 1m0s
[11:22:29.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:39:0)
[11:22:29.044] Timer tick, asking Buildbucket for the build status
[11:22:29.223] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:29.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:40:0) after 1m0s
[11:23:29.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:40:0)
[11:23:29.306] Timer tick, asking Buildbucket for the build status
[11:23:29.457] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:29.457] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:41:0) after 1m0s
[11:24:29.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:41:0)
[11:24:29.473] Timer tick, asking Buildbucket for the build status
[11:24:29.592] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:29.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:42:0) after 1m0s
[11:25:29.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:42:0)
[11:25:29.628] Timer tick, asking Buildbucket for the build status
[11:25:29.688] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:29.688] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:43:0) after 1m0s
[11:26:29.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:43:0)
[11:26:29.686] Timer tick, asking Buildbucket for the build status
[11:26:29.772] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:29.772] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:44:0) after 1m0s
[11:27:29.879] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:44:0)
[11:27:29.879] Timer tick, asking Buildbucket for the build status
[11:27:29.999] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:29.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:45:0) after 1m0s
[11:28:30.012] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:45:0)
[11:28:30.012] Timer tick, asking Buildbucket for the build status
[11:28:30.217] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:30.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:46:0) after 1m0s
[11:29:30.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:46:0)
[11:29:30.222] Timer tick, asking Buildbucket for the build status
[11:29:30.381] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:30.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:47:0) after 1m0s
[11:30:30.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:47:0)
[11:30:30.399] Timer tick, asking Buildbucket for the build status
[11:30:30.609] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:30.609] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:48:0) after 1m0s
[11:31:30.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:48:0)
[11:31:30.580] Timer tick, asking Buildbucket for the build status
[11:31:30.726] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:30.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:49:0) after 1m0s
[11:32:30.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:49:0)
[11:32:30.745] Timer tick, asking Buildbucket for the build status
[11:32:30.826] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:30.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:50:0) after 1m0s
[11:33:30.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:50:0)
[11:33:30.855] Timer tick, asking Buildbucket for the build status
[11:33:30.997] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:30.997] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:51:0) after 1m0s
[11:34:31.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:51:0)
[11:34:31.001] Timer tick, asking Buildbucket for the build status
[11:34:31.073] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:31.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:52:0) after 1m0s
[11:35:31.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:52:0)
[11:35:31.096] Timer tick, asking Buildbucket for the build status
[11:35:31.292] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:31.292] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:53:0) after 1m0s
[11:36:31.313] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:53:0)
[11:36:31.313] Timer tick, asking Buildbucket for the build status
[11:36:31.662] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:31.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:54:0) after 1m0s
[11:37:31.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:54:0)
[11:37:31.632] Timer tick, asking Buildbucket for the build status
[11:37:31.701] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:31.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:55:0) after 1m0s
[11:38:31.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:55:0)
[11:38:31.709] Timer tick, asking Buildbucket for the build status
[11:38:31.872] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:31.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:56:0) after 1m0s
[11:39:31.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:56:0)
[11:39:31.884] Timer tick, asking Buildbucket for the build status
[11:39:31.949] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:31.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:57:0) after 1m0s
[11:40:31.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:57:0)
[11:40:31.967] Timer tick, asking Buildbucket for the build status
[11:40:32.031] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:32.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:58:0) after 1m0s
[11:41:32.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:58:0)
[11:41:32.029] Timer tick, asking Buildbucket for the build status
[11:41:32.101] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:32.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:59:0) after 1m0s
[11:42:32.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:59:0)
[11:42:32.106] Timer tick, asking Buildbucket for the build status
[11:42:32.195] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:32.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:60:0) after 1m0s
[11:43:32.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:60:0)
[11:43:32.175] Timer tick, asking Buildbucket for the build status
[11:43:32.295] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:32.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:61:0) after 1m0s
[11:44:32.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:61:0)
[11:44:32.299] Timer tick, asking Buildbucket for the build status
[11:44:32.443] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:32.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:62:0) after 1m0s
[11:45:32.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:62:0)
[11:45:32.421] Timer tick, asking Buildbucket for the build status
[11:45:32.481] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:32.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:63:0) after 1m0s
[11:46:32.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:63:0)
[11:46:32.461] Timer tick, asking Buildbucket for the build status
[11:46:32.551] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:32.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:64:0) after 1m0s
[11:47:32.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:64:0)
[11:47:32.554] Timer tick, asking Buildbucket for the build status
[11:47:32.619] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:32.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:65:0) after 1m0s
[11:48:32.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:65:0)
[11:48:32.638] Timer tick, asking Buildbucket for the build status
[11:48:32.780] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:32.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:66:0) after 1m0s
[11:49:32.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:66:0)
[11:49:32.814] Timer tick, asking Buildbucket for the build status
[11:49:32.908] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:32.908] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:67:0) after 1m0s
[11:50:32.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:67:0)
[11:50:32.890] Timer tick, asking Buildbucket for the build status
[11:50:32.958] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:32.958] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:68:0) after 1m0s
[11:51:32.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:68:0)
[11:51:32.978] Timer tick, asking Buildbucket for the build status
[11:51:33.041] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:33.041] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:69:0) after 1m0s
[11:52:33.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:69:0)
[11:52:33.047] Timer tick, asking Buildbucket for the build status
[11:52:33.116] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:33.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:70:0) after 1m0s
[11:53:33.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:70:0)
[11:53:33.146] Timer tick, asking Buildbucket for the build status
[11:53:33.291] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:33.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:71:0) after 1m0s
[11:54:33.290] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:71:0)
[11:54:33.290] Timer tick, asking Buildbucket for the build status
[11:54:33.554] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:33.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:72:0) after 1m0s
[11:55:33.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:72:0)
[11:55:33.574] Timer tick, asking Buildbucket for the build status
[11:55:33.633] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:33.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:73:0) after 1m0s
[11:56:33.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:73:0)
[11:56:33.639] Timer tick, asking Buildbucket for the build status
[11:56:33.710] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:33.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:74:0) after 1m0s
[11:57:33.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:74:0)
[11:57:33.683] Timer tick, asking Buildbucket for the build status
[11:57:33.767] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:33.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:75:0) after 1m0s
[11:58:33.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:75:0)
[11:58:33.784] Timer tick, asking Buildbucket for the build status
[11:58:33.901] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:33.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:76:0) after 1m0s
[11:59:33.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:76:0)
[11:59:33.943] Timer tick, asking Buildbucket for the build status
[11:59:34.006] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:34.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:77:0) after 1m0s
[12:00:34.026] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:77:0)
[12:00:34.026] Timer tick, asking Buildbucket for the build status
[12:00:34.091] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:34.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:78:0) after 1m0s
[12:01:34.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:78:0)
[12:01:34.150] Timer tick, asking Buildbucket for the build status
[12:01:34.336] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:34.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:79:0) after 1m0s
[12:02:34.357] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:79:0)
[12:02:34.357] Timer tick, asking Buildbucket for the build status
[12:02:34.475] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:34.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:80:0) after 1m0s
[12:03:34.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:80:0)
[12:03:34.482] Timer tick, asking Buildbucket for the build status
[12:03:34.543] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:34.543] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:81:0) after 1m0s
[12:04:34.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:81:0)
[12:04:34.554] Timer tick, asking Buildbucket for the build status
[12:04:34.655] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:34.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:82:0) after 1m0s
[12:05:34.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:82:0)
[12:05:34.691] Timer tick, asking Buildbucket for the build status
[12:05:35.171] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:35.171] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:83:0) after 1m0s
[12:06:35.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:83:0)
[12:06:35.208] Timer tick, asking Buildbucket for the build status
[12:06:35.371] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:35.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:84:0) after 1m0s
[12:07:35.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:84:0)
[12:07:35.358] Timer tick, asking Buildbucket for the build status
[12:07:35.569] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:35.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:85:0) after 1m0s
[12:08:35.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:85:0)
[12:08:35.540] Timer tick, asking Buildbucket for the build status
[12:08:35.605] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:35.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:86:0) after 1m0s
[12:09:35.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:86:0)
[12:09:35.626] Timer tick, asking Buildbucket for the build status
[12:09:35.973] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:35.973] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:87:0) after 1m0s
[12:10:35.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:87:0)
[12:10:35.969] Timer tick, asking Buildbucket for the build status
[12:10:36.025] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:36.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:88:0) after 1m0s
[12:11:36.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:88:0)
[12:11:36.055] Timer tick, asking Buildbucket for the build status
[12:11:36.122] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:36.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:89:0) after 1m0s
[12:12:36.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:89:0)
[12:12:36.144] Timer tick, asking Buildbucket for the build status
[12:12:36.210] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:36.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:90:0) after 1m0s
[12:13:36.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:90:0)
[12:13:36.228] Timer tick, asking Buildbucket for the build status
[12:13:36.407] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:36.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:91:0) after 1m0s
[12:14:36.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:91:0)
[12:14:36.431] Timer tick, asking Buildbucket for the build status
[12:14:36.563] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:36.563] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:92:0) after 1m0s
[12:15:36.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:92:0)
[12:15:36.581] Timer tick, asking Buildbucket for the build status
[12:15:36.690] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:36.690] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:93:0) after 1m0s
[12:16:36.718] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:93:0)
[12:16:36.718] Timer tick, asking Buildbucket for the build status
[12:16:36.786] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:36.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:94:0) after 1m0s
[12:17:36.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:94:0)
[12:17:36.786] Timer tick, asking Buildbucket for the build status
[12:17:36.852] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:36.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:95:0) after 1m0s
[12:18:36.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:95:0)
[12:18:36.875] Timer tick, asking Buildbucket for the build status
[12:18:36.938] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:36.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:96:0) after 1m0s
[12:19:36.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:96:0)
[12:19:36.959] Timer tick, asking Buildbucket for the build status
[12:19:37.021] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:37.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:97:0) after 1m0s
[12:20:37.041] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:97:0)
[12:20:37.041] Timer tick, asking Buildbucket for the build status
[12:20:37.113] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:37.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:98:0) after 1m0s
[12:21:37.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:98:0)
[12:21:37.152] Timer tick, asking Buildbucket for the build status
[12:21:37.213] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:37.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:99:0) after 1m0s
[12:22:37.200] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:99:0)
[12:22:37.200] Timer tick, asking Buildbucket for the build status
[12:22:37.261] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:37.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:100:0) after 1m0s
[12:23:37.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:100:0)
[12:23:37.287] Timer tick, asking Buildbucket for the build status
[12:23:37.354] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:37.354] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:101:0) after 1m0s
[12:24:37.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:101:0)
[12:24:37.378] Timer tick, asking Buildbucket for the build status
[12:24:37.442] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:37.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:102:0) after 1m0s
[12:25:37.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:102:0)
[12:25:37.461] Timer tick, asking Buildbucket for the build status
[12:25:37.525] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:37.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:103:0) after 1m0s
[12:26:37.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:103:0)
[12:26:37.524] Timer tick, asking Buildbucket for the build status
[12:26:37.594] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:37.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:104:0) after 1m0s
[12:27:37.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:104:0)
[12:27:37.590] Timer tick, asking Buildbucket for the build status
[12:27:37.888] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:37.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:105:0) after 1m0s
[12:28:37.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:105:0)
[12:28:37.901] Timer tick, asking Buildbucket for the build status
[12:28:37.997] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:37.997] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:106:0) after 1m0s
[12:29:38.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:106:0)
[12:29:38.015] Timer tick, asking Buildbucket for the build status
[12:29:38.153] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:38.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:107:0) after 1m0s
[12:30:38.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:107:0)
[12:30:38.131] Timer tick, asking Buildbucket for the build status
[12:30:38.268] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:38.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:108:0) after 1m0s
[12:31:38.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:108:0)
[12:31:38.308] Timer tick, asking Buildbucket for the build status
[12:31:38.391] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:38.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:109:0) after 1m0s
[12:32:38.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:109:0)
[12:32:38.405] Timer tick, asking Buildbucket for the build status
[12:32:38.465] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:38.465] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:110:0) after 1m0s
[12:33:38.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:110:0)
[12:33:38.464] Timer tick, asking Buildbucket for the build status
[12:33:38.527] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:38.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:111:0) after 1m0s
[12:34:38.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:111:0)
[12:34:38.552] Timer tick, asking Buildbucket for the build status
[12:34:38.612] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:38.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:112:0) after 1m0s
[12:35:38.634] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:112:0)
[12:35:38.634] Timer tick, asking Buildbucket for the build status
[12:35:38.866] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:38.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:113:0) after 1m0s
[12:36:38.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:113:0)
[12:36:38.852] Timer tick, asking Buildbucket for the build status
[12:36:38.917] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:38.917] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:114:0) after 1m0s
[12:37:38.941] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:114:0)
[12:37:38.941] Timer tick, asking Buildbucket for the build status
[12:37:39.011] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:39.011] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:115:0) after 1m0s
[12:38:39.032] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:115:0)
[12:38:39.032] Timer tick, asking Buildbucket for the build status
[12:38:39.094] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:39.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:116:0) after 1m0s
[12:39:39.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:116:0)
[12:39:39.085] Timer tick, asking Buildbucket for the build status
[12:39:39.247] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:39.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:117:0) after 1m0s
[12:40:39.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:117:0)
[12:40:39.268] Timer tick, asking Buildbucket for the build status
[12:40:39.355] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:39.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:118:0) after 1m0s
[12:41:39.393] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:118:0)
[12:41:39.393] Timer tick, asking Buildbucket for the build status
[12:41:39.665] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:39.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:119:0) after 1m0s
[12:42:39.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:119:0)
[12:42:39.685] Timer tick, asking Buildbucket for the build status
[12:42:39.753] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:39.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:120:0) after 1m0s
[12:43:39.736] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:120:0)
[12:43:39.736] Timer tick, asking Buildbucket for the build status
[12:43:39.809] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:39.809] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:121:0) after 1m0s
[12:44:39.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:121:0)
[12:44:39.863] Timer tick, asking Buildbucket for the build status
[12:44:39.956] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:39.956] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:122:0) after 1m0s
[12:45:40.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:122:0)
[12:45:40.001] Timer tick, asking Buildbucket for the build status
[12:45:40.094] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:40.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:123:0) after 1m0s
[12:46:40.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:123:0)
[12:46:40.134] Timer tick, asking Buildbucket for the build status
[12:46:40.296] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:40.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:124:0) after 1m0s
[12:47:40.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:124:0)
[12:47:40.325] Timer tick, asking Buildbucket for the build status
[12:47:40.577] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:40.577] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:125:0) after 1m0s
[12:48:40.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:125:0)
[12:48:40.558] Timer tick, asking Buildbucket for the build status
[12:48:40.855] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:40.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:126:0) after 1m0s
[12:49:40.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:126:0)
[12:49:40.875] Timer tick, asking Buildbucket for the build status
[12:49:40.938] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:40.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:127:0) after 1m0s
[12:50:40.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:127:0)
[12:50:40.959] Timer tick, asking Buildbucket for the build status
[12:50:41.192] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:41.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:128:0) after 1m0s
[12:51:41.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:128:0)
[12:51:41.215] Timer tick, asking Buildbucket for the build status
[12:51:41.320] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:41.320] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:129:0) after 1m0s
[12:52:41.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:129:0)
[12:52:41.342] Timer tick, asking Buildbucket for the build status
[12:52:41.576] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:41.576] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:130:0) after 1m0s
[12:53:41.583] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:130:0)
[12:53:41.583] Timer tick, asking Buildbucket for the build status
[12:53:41.651] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:41.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:131:0) after 1m0s
[12:54:41.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:131:0)
[12:54:41.670] Timer tick, asking Buildbucket for the build status
[12:54:41.811] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:41.811] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:132:0) after 1m0s
[12:55:41.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:132:0)
[12:55:41.804] Timer tick, asking Buildbucket for the build status
[12:55:41.862] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:41.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:133:0) after 1m0s
[12:56:41.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:133:0)
[12:56:41.886] Timer tick, asking Buildbucket for the build status
[12:56:41.968] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:41.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:134:0) after 1m0s
[12:57:41.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:134:0)
[12:57:41.991] Timer tick, asking Buildbucket for the build status
[12:57:42.057] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:42.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:135:0) after 1m0s
[12:58:42.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:135:0)
[12:58:42.052] Timer tick, asking Buildbucket for the build status
[12:58:42.127] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:42.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:136:0) after 1m0s
[12:59:42.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:136:0)
[12:59:42.152] Timer tick, asking Buildbucket for the build status
[12:59:42.213] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:42.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:137:0) after 1m0s
[13:00:42.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:137:0)
[13:00:42.238] Timer tick, asking Buildbucket for the build status
[13:00:42.506] Build 8918835027967737312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:42.506] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084265090163537792:138:0) after 1m0s
[13:01:06.547] Received PubSub notification, asking Buildbucket for the build status
[13:01:06.685] Build 8918835027967737312: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:01:06.685] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1552741262041943",
"created_by": "project:infra",
"created_ts": "1552733123770089",
"id": "8918835027967737312",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"75.0.3735.0\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-32\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Build From Tarball\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"4201-b04f7f8\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"32\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"build-from-tarball-0-szb4\"], \"pool\": [\"luci.infra.cron\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildername\": \"Build From Tarball\", \"recipe\": \"build_from_tarball\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"version\": \"75.0.3735.0\", \"path_config\": \"generic\", \"bot_id\": \"build-from-tarball-0-szb4\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552733176161100",
"status": "COMPLETED",
"status_changed_ts": "1552741266277305",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9084265090163537792",
"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/8918835027967737312/+/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:439eecbedb1a6810",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1552741266281269",
"url": "https://ci.chromium.org/b/8918835027967737312",
"utcnow_ts": "1552741266671723"
}
}
[13:01:06.685] Invocation finished in 2h15m44.391914505s with status SUCCEEDED