[18:52:56.523] New invocation is queued and will start shortly
[18:52:56.523] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[18:52:57.631] Starting the invocation (attempt 1)
[18:52:57.676] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:52:57.676] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:52:57.676] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9043737570919783232",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"85.0.4164.4\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"1b18d1c4-9451-420d-ab9d-0cc38fca1a12\",\"title\":\"publish_tarball/0\"}]},\"version\":\"85.0.4164.4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9043737570919783232",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[18:52:58.439] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:infra",
"created_ts": "1591383177806586",
"id": "8878307508907092816",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4164.4\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"1b18d1c4-9451-420d-ab9d-0cc38fca1a12\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4164.4\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1591383178079101",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9043737570919783232",
"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/8878307508907092816/+/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": "1591383178079732",
"url": "https://ci.chromium.org/b/8878307508907092816",
"utcnow_ts": "1591383178423739"
}
}
[18:52:58.439] Task URL: https://ci.chromium.org/b/8878307508907092816
[18:52:58.439] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:2:0) after 1m0s
[18:53:48.476] Received PubSub notification, asking Buildbucket for the build status
[18:53:48.708] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:58.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:2:0)
[18:53:58.499] Timer tick, asking Buildbucket for the build status
[18:53:58.604] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:58.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:4:0) after 1m0s
[18:54:59.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:4:0)
[18:54:59.008] Timer tick, asking Buildbucket for the build status
[18:54:59.709] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:59.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:5:0) after 1m0s
[18:55:59.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:5:0)
[18:55:59.772] Timer tick, asking Buildbucket for the build status
[18:56:00.205] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:00.205] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:6:0) after 1m0s
[18:57:00.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:6:0)
[18:57:00.747] Timer tick, asking Buildbucket for the build status
[18:57:01.122] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:01.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:7:0) after 1m0s
[18:58:01.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:7:0)
[18:58:01.978] Timer tick, asking Buildbucket for the build status
[18:58:02.353] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:02.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:8:0) after 1m0s
[18:59:02.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:8:0)
[18:59:02.424] Timer tick, asking Buildbucket for the build status
[18:59:02.558] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:02.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:9:0) after 1m0s
[19:00:03.680] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:9:0)
[19:00:03.680] Timer tick, asking Buildbucket for the build status
[19:00:03.848] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:03.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:10:0) after 1m0s
[19:01:03.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:10:0)
[19:01:03.868] Timer tick, asking Buildbucket for the build status
[19:01:04.968] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:04.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:11:0) after 1m0s
[19:02:05.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:11:0)
[19:02:05.111] Timer tick, asking Buildbucket for the build status
[19:02:05.744] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:05.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:12:0) after 1m0s
[19:03:05.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:12:0)
[19:03:05.793] Timer tick, asking Buildbucket for the build status
[19:03:06.228] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:06.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:13:0) after 1m0s
[19:04:06.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:13:0)
[19:04:06.251] Timer tick, asking Buildbucket for the build status
[19:04:06.550] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:06.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:14:0) after 1m0s
[19:05:06.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:14:0)
[19:05:06.619] Timer tick, asking Buildbucket for the build status
[19:05:06.764] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:06.764] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:15:0) after 1m0s
[19:06:06.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:15:0)
[19:06:06.789] Timer tick, asking Buildbucket for the build status
[19:06:08.889] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:08.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:16:0) after 1m0s
[19:07:08.940] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:16:0)
[19:07:08.940] Timer tick, asking Buildbucket for the build status
[19:07:09.384] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:09.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:17:0) after 1m0s
[19:08:09.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:17:0)
[19:08:09.409] Timer tick, asking Buildbucket for the build status
[19:08:09.608] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:09.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:18:0) after 1m0s
[19:09:09.630] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:18:0)
[19:09:09.630] Timer tick, asking Buildbucket for the build status
[19:09:09.824] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:09.824] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:19:0) after 1m0s
[19:10:09.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:19:0)
[19:10:09.865] Timer tick, asking Buildbucket for the build status
[19:10:10.005] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:10.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:20:0) after 1m0s
[19:11:10.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:20:0)
[19:11:10.111] Timer tick, asking Buildbucket for the build status
[19:11:10.591] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:10.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:21:0) after 1m0s
[19:12:10.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:21:0)
[19:12:10.631] Timer tick, asking Buildbucket for the build status
[19:12:12.667] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:12.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:22:0) after 1m0s
[19:13:12.787] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:22:0)
[19:13:12.787] Timer tick, asking Buildbucket for the build status
[19:13:13.021] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:13.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:23:0) after 1m0s
[19:14:13.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:23:0)
[19:14:13.125] Timer tick, asking Buildbucket for the build status
[19:14:13.638] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:13.638] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:24:0) after 1m0s
[19:15:13.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:24:0)
[19:15:13.830] Timer tick, asking Buildbucket for the build status
[19:15:14.124] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:14.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:25:0) after 1m0s
[19:16:14.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:25:0)
[19:16:14.109] Timer tick, asking Buildbucket for the build status
[19:16:14.304] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:14.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:26:0) after 1m0s
[19:17:14.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:26:0)
[19:17:14.455] Timer tick, asking Buildbucket for the build status
[19:17:14.601] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:14.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:27:0) after 1m0s
[19:18:14.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:27:0)
[19:18:14.790] Timer tick, asking Buildbucket for the build status
[19:18:14.922] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:14.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:28:0) after 1m0s
[19:19:15.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:28:0)
[19:19:15.215] Timer tick, asking Buildbucket for the build status
[19:19:15.533] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:15.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:29:0) after 1m0s
[19:20:15.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:29:0)
[19:20:15.888] Timer tick, asking Buildbucket for the build status
[19:20:16.051] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:16.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:30:0) after 1m0s
[19:21:16.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:30:0)
[19:21:16.402] Timer tick, asking Buildbucket for the build status
[19:21:20.567] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:20.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:31:0) after 1m0s
[19:22:20.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:31:0)
[19:22:20.682] Timer tick, asking Buildbucket for the build status
[19:22:23.924] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:23.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:32:0) after 1m0s
[19:23:24.019] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:32:0)
[19:23:24.019] Timer tick, asking Buildbucket for the build status
[19:23:31.581] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:31.581] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:33:0) after 1m0s
[19:24:31.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:33:0)
[19:24:31.604] Timer tick, asking Buildbucket for the build status
[19:24:32.197] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:32.197] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:34:0) after 1m0s
[19:25:32.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:34:0)
[19:25:32.185] Timer tick, asking Buildbucket for the build status
[19:25:32.532] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:32.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:35:0) after 1m0s
[19:26:32.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:35:0)
[19:26:32.552] Timer tick, asking Buildbucket for the build status
[19:26:32.783] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:32.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:36:0) after 1m0s
[19:27:32.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:36:0)
[19:27:32.890] Timer tick, asking Buildbucket for the build status
[19:27:33.230] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:33.230] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:37:0) after 1m0s
[19:28:33.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:37:0)
[19:28:33.215] Timer tick, asking Buildbucket for the build status
[19:28:33.916] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:33.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:38:0) after 1m0s
[19:29:33.930] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:38:0)
[19:29:33.930] Timer tick, asking Buildbucket for the build status
[19:29:34.305] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:34.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:39:0) after 1m0s
[19:30:34.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:39:0)
[19:30:34.345] Timer tick, asking Buildbucket for the build status
[19:30:34.496] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:34.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:40:0) after 1m0s
[19:31:34.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:40:0)
[19:31:34.670] Timer tick, asking Buildbucket for the build status
[19:31:35.426] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:35.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:41:0) after 1m0s
[19:32:35.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:41:0)
[19:32:35.522] Timer tick, asking Buildbucket for the build status
[19:32:35.952] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:35.952] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:42:0) after 1m0s
[19:33:36.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:42:0)
[19:33:36.074] Timer tick, asking Buildbucket for the build status
[19:33:36.732] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:36.732] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:43:0) after 1m0s
[19:34:36.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:43:0)
[19:34:36.910] Timer tick, asking Buildbucket for the build status
[19:34:37.351] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:37.351] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:44:0) after 1m0s
[19:35:37.380] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:44:0)
[19:35:37.380] Timer tick, asking Buildbucket for the build status
[19:35:37.565] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:37.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:45:0) after 1m0s
[19:36:37.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:45:0)
[19:36:37.602] Timer tick, asking Buildbucket for the build status
[19:36:37.790] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:37.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:46:0) after 1m0s
[19:37:37.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:46:0)
[19:37:37.944] Timer tick, asking Buildbucket for the build status
[19:37:38.275] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:38.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:47:0) after 1m0s
[19:38:38.332] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:47:0)
[19:38:38.332] Timer tick, asking Buildbucket for the build status
[19:38:38.751] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:38.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:48:0) after 1m0s
[19:39:38.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:48:0)
[19:39:38.815] Timer tick, asking Buildbucket for the build status
[19:39:39.126] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:39.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:49:0) after 1m0s
[19:40:39.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:49:0)
[19:40:39.196] Timer tick, asking Buildbucket for the build status
[19:40:39.456] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:39.456] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:50:0) after 1m0s
[19:41:39.488] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:50:0)
[19:41:39.488] Timer tick, asking Buildbucket for the build status
[19:41:39.862] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:39.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:51:0) after 1m0s
[19:42:39.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:51:0)
[19:42:39.886] Timer tick, asking Buildbucket for the build status
[19:42:41.844] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:41.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:52:0) after 1m0s
[19:43:41.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:52:0)
[19:43:41.998] Timer tick, asking Buildbucket for the build status
[19:43:42.772] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:42.772] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:53:0) after 1m0s
[19:44:42.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:53:0)
[19:44:42.804] Timer tick, asking Buildbucket for the build status
[19:44:42.945] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:42.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:54:0) after 1m0s
[19:45:43.083] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:54:0)
[19:45:43.084] Timer tick, asking Buildbucket for the build status
[19:45:43.252] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:43.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:55:0) after 1m0s
[19:46:43.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:55:0)
[19:46:43.304] Timer tick, asking Buildbucket for the build status
[19:46:43.555] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:43.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:56:0) after 1m0s
[19:47:43.715] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:56:0)
[19:47:43.715] Timer tick, asking Buildbucket for the build status
[19:47:44.049] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:44.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:57:0) after 1m0s
[19:48:44.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:57:0)
[19:48:44.179] Timer tick, asking Buildbucket for the build status
[19:48:45.301] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:45.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:58:0) after 1m0s
[19:49:45.637] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:58:0)
[19:49:45.637] Timer tick, asking Buildbucket for the build status
[19:49:45.828] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:45.829] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:59:0) after 1m0s
[19:50:46.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:59:0)
[19:50:46.168] Timer tick, asking Buildbucket for the build status
[19:50:46.738] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:46.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:60:0) after 1m0s
[19:51:46.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:60:0)
[19:51:46.855] Timer tick, asking Buildbucket for the build status
[19:51:46.972] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:46.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:61:0) after 1m0s
[19:52:47.054] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:61:0)
[19:52:47.054] Timer tick, asking Buildbucket for the build status
[19:52:47.565] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:47.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:62:0) after 1m0s
[19:53:47.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:62:0)
[19:53:47.614] Timer tick, asking Buildbucket for the build status
[19:53:48.009] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:48.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:63:0) after 1m0s
[19:54:48.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:63:0)
[19:54:48.178] Timer tick, asking Buildbucket for the build status
[19:54:48.334] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:48.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:64:0) after 1m0s
[19:55:48.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:64:0)
[19:55:48.392] Timer tick, asking Buildbucket for the build status
[19:55:48.571] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:48.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:65:0) after 1m0s
[19:56:48.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:65:0)
[19:56:48.695] Timer tick, asking Buildbucket for the build status
[19:56:48.837] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:48.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:66:0) after 1m0s
[19:57:48.975] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:66:0)
[19:57:48.975] Timer tick, asking Buildbucket for the build status
[19:57:49.102] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:49.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:67:0) after 1m0s
[19:58:49.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:67:0)
[19:58:49.086] Timer tick, asking Buildbucket for the build status
[19:58:49.263] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:49.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:68:0) after 1m0s
[19:59:49.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:68:0)
[19:59:49.375] Timer tick, asking Buildbucket for the build status
[19:59:49.514] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:49.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:69:0) after 1m0s
[20:00:49.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:69:0)
[20:00:49.786] Timer tick, asking Buildbucket for the build status
[20:00:50.678] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:50.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:70:0) after 1m0s
[20:01:50.746] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:70:0)
[20:01:50.746] Timer tick, asking Buildbucket for the build status
[20:01:51.174] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:51.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:71:0) after 1m0s
[20:02:51.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:71:0)
[20:02:51.252] Timer tick, asking Buildbucket for the build status
[20:02:52.092] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:52.092] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:72:0) after 1m0s
[20:03:52.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:72:0)
[20:03:52.167] Timer tick, asking Buildbucket for the build status
[20:03:52.415] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:52.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:73:0) after 1m0s
[20:04:52.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:73:0)
[20:04:52.805] Timer tick, asking Buildbucket for the build status
[20:04:53.295] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:53.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:74:0) after 1m0s
[20:05:54.024] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:74:0)
[20:05:54.024] Timer tick, asking Buildbucket for the build status
[20:05:54.425] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:54.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:75:0) after 1m0s
[20:06:54.751] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:75:0)
[20:06:54.751] Timer tick, asking Buildbucket for the build status
[20:06:54.978] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:54.978] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:76:0) after 1m0s
[20:07:55.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:76:0)
[20:07:55.546] Timer tick, asking Buildbucket for the build status
[20:07:55.738] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:55.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:77:0) after 1m0s
[20:08:55.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:77:0)
[20:08:55.760] Timer tick, asking Buildbucket for the build status
[20:08:55.879] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:55.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:78:0) after 1m0s
[20:09:55.930] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:78:0)
[20:09:55.930] Timer tick, asking Buildbucket for the build status
[20:09:56.158] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:56.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:79:0) after 1m0s
[20:10:56.190] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:79:0)
[20:10:56.190] Timer tick, asking Buildbucket for the build status
[20:10:56.791] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:56.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:80:0) after 1m0s
[20:11:56.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:80:0)
[20:11:56.872] Timer tick, asking Buildbucket for the build status
[20:11:57.268] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:57.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:81:0) after 1m0s
[20:12:57.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:81:0)
[20:12:57.319] Timer tick, asking Buildbucket for the build status
[20:12:57.466] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:57.466] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:82:0) after 1m0s
[20:13:57.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:82:0)
[20:13:57.475] Timer tick, asking Buildbucket for the build status
[20:13:58.046] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:58.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:83:0) after 1m0s
[20:14:58.075] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:83:0)
[20:14:58.075] Timer tick, asking Buildbucket for the build status
[20:14:58.496] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:58.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:84:0) after 1m0s
[20:15:58.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:84:0)
[20:15:58.551] Timer tick, asking Buildbucket for the build status
[20:15:58.718] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:58.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:85:0) after 1m0s
[20:16:58.839] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:85:0)
[20:16:58.839] Timer tick, asking Buildbucket for the build status
[20:16:59.254] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:59.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:86:0) after 1m0s
[20:17:59.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:86:0)
[20:17:59.293] Timer tick, asking Buildbucket for the build status
[20:17:59.469] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:59.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:87:0) after 1m0s
[20:18:59.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:87:0)
[20:18:59.516] Timer tick, asking Buildbucket for the build status
[20:18:59.679] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:59.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:88:0) after 1m0s
[20:19:59.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:88:0)
[20:19:59.710] Timer tick, asking Buildbucket for the build status
[20:19:59.891] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:59.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:89:0) after 1m0s
[20:20:59.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:89:0)
[20:20:59.919] Timer tick, asking Buildbucket for the build status
[20:21:00.423] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:00.423] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:90:0) after 1m0s
[20:22:00.480] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:90:0)
[20:22:00.480] Timer tick, asking Buildbucket for the build status
[20:22:02.190] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:02.190] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:91:0) after 1m0s
[20:23:03.338] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:91:0)
[20:23:03.338] Timer tick, asking Buildbucket for the build status
[20:23:03.522] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:03.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:92:0) after 1m0s
[20:24:03.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:92:0)
[20:24:03.669] Timer tick, asking Buildbucket for the build status
[20:24:03.804] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:03.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:93:0) after 1m0s
[20:25:04.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:93:0)
[20:25:04.252] Timer tick, asking Buildbucket for the build status
[20:25:04.513] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:04.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:94:0) after 1m0s
[20:26:05.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:94:0)
[20:26:05.107] Timer tick, asking Buildbucket for the build status
[20:26:05.302] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:05.302] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:95:0) after 1m0s
[20:27:05.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:95:0)
[20:27:05.364] Timer tick, asking Buildbucket for the build status
[20:27:05.523] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:05.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:96:0) after 1m0s
[20:28:05.661] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:96:0)
[20:28:05.661] Timer tick, asking Buildbucket for the build status
[20:28:05.804] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:05.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:97:0) after 1m0s
[20:29:05.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:97:0)
[20:29:05.937] Timer tick, asking Buildbucket for the build status
[20:29:06.159] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:06.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:98:0) after 1m0s
[20:30:06.236] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:98:0)
[20:30:06.236] Timer tick, asking Buildbucket for the build status
[20:30:06.388] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:06.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:99:0) after 1m0s
[20:31:06.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:99:0)
[20:31:06.421] Timer tick, asking Buildbucket for the build status
[20:31:06.579] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:06.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:100:0) after 1m0s
[20:32:06.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:100:0)
[20:32:06.597] Timer tick, asking Buildbucket for the build status
[20:32:06.773] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:06.773] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:101:0) after 1m0s
[20:33:06.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:101:0)
[20:33:06.834] Timer tick, asking Buildbucket for the build status
[20:33:06.973] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:06.973] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:102:0) after 1m0s
[20:34:06.970] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:102:0)
[20:34:06.970] Timer tick, asking Buildbucket for the build status
[20:34:07.128] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:07.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:103:0) after 1m0s
[20:35:07.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:103:0)
[20:35:07.144] Timer tick, asking Buildbucket for the build status
[20:35:07.529] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:07.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:104:0) after 1m0s
[20:36:07.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:104:0)
[20:36:07.563] Timer tick, asking Buildbucket for the build status
[20:36:07.863] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:07.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:105:0) after 1m0s
[20:37:07.873] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:105:0)
[20:37:07.873] Timer tick, asking Buildbucket for the build status
[20:37:08.053] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:08.053] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:106:0) after 1m0s
[20:38:08.206] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:106:0)
[20:38:08.206] Timer tick, asking Buildbucket for the build status
[20:38:08.379] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:08.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:107:0) after 1m0s
[20:39:08.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:107:0)
[20:39:08.513] Timer tick, asking Buildbucket for the build status
[20:39:08.700] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:08.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:108:0) after 1m0s
[20:40:08.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:108:0)
[20:40:08.861] Timer tick, asking Buildbucket for the build status
[20:40:09.532] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:09.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:109:0) after 1m0s
[20:41:09.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:109:0)
[20:41:09.717] Timer tick, asking Buildbucket for the build status
[20:41:09.839] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:09.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:110:0) after 1m0s
[20:42:09.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:110:0)
[20:42:09.954] Timer tick, asking Buildbucket for the build status
[20:42:10.072] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:10.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:111:0) after 1m0s
[20:43:10.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:111:0)
[20:43:10.097] Timer tick, asking Buildbucket for the build status
[20:43:10.721] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:10.721] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:112:0) after 1m0s
[20:44:10.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:112:0)
[20:44:10.799] Timer tick, asking Buildbucket for the build status
[20:44:11.064] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:11.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:113:0) after 1m0s
[20:45:11.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:113:0)
[20:45:11.085] Timer tick, asking Buildbucket for the build status
[20:45:11.264] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:11.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:114:0) after 1m0s
[20:46:11.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:114:0)
[20:46:11.262] Timer tick, asking Buildbucket for the build status
[20:46:11.618] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:11.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:115:0) after 1m0s
[20:47:11.702] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:115:0)
[20:47:11.702] Timer tick, asking Buildbucket for the build status
[20:47:11.864] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:11.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:116:0) after 1m0s
[20:48:11.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:116:0)
[20:48:11.954] Timer tick, asking Buildbucket for the build status
[20:48:12.360] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:12.360] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:117:0) after 1m0s
[20:49:12.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:117:0)
[20:49:12.365] Timer tick, asking Buildbucket for the build status
[20:49:12.923] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:12.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:118:0) after 1m0s
[20:50:12.931] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:118:0)
[20:50:12.931] Timer tick, asking Buildbucket for the build status
[20:50:13.733] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:13.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:119:0) after 1m0s
[20:51:13.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:119:0)
[20:51:13.760] Timer tick, asking Buildbucket for the build status
[20:51:14.662] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:14.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:120:0) after 1m0s
[20:52:14.707] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:120:0)
[20:52:14.707] Timer tick, asking Buildbucket for the build status
[20:52:15.117] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:15.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:121:0) after 1m0s
[20:53:15.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:121:0)
[20:53:15.183] Timer tick, asking Buildbucket for the build status
[20:53:15.598] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:15.598] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:122:0) after 1m0s
[20:54:15.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:122:0)
[20:54:15.654] Timer tick, asking Buildbucket for the build status
[20:54:15.847] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:15.847] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:123:0) after 1m0s
[20:55:15.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:123:0)
[20:55:15.914] Timer tick, asking Buildbucket for the build status
[20:55:16.480] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:16.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:124:0) after 1m0s
[20:56:16.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:124:0)
[20:56:16.686] Timer tick, asking Buildbucket for the build status
[20:56:16.880] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:16.880] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:125:0) after 1m0s
[20:57:16.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:125:0)
[20:57:16.945] Timer tick, asking Buildbucket for the build status
[20:57:17.227] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:17.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:126:0) after 1m0s
[20:58:17.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:126:0)
[20:58:17.361] Timer tick, asking Buildbucket for the build status
[20:58:17.664] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:17.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:127:0) after 1m0s
[20:59:17.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:127:0)
[20:59:17.770] Timer tick, asking Buildbucket for the build status
[20:59:17.924] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:17.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:128:0) after 1m0s
[21:00:18.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:128:0)
[21:00:18.031] Timer tick, asking Buildbucket for the build status
[21:00:18.195] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:18.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:129:0) after 1m0s
[21:01:18.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:129:0)
[21:01:18.256] Timer tick, asking Buildbucket for the build status
[21:01:18.615] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:18.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:130:0) after 1m0s
[21:02:18.630] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:130:0)
[21:02:18.630] Timer tick, asking Buildbucket for the build status
[21:02:20.288] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:20.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:131:0) after 1m0s
[21:03:20.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:131:0)
[21:03:20.356] Timer tick, asking Buildbucket for the build status
[21:03:20.888] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:20.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:132:0) after 1m0s
[21:04:20.922] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:132:0)
[21:04:20.922] Timer tick, asking Buildbucket for the build status
[21:04:21.297] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:21.297] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:133:0) after 1m0s
[21:05:21.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:133:0)
[21:05:21.359] Timer tick, asking Buildbucket for the build status
[21:05:22.343] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:22.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:134:0) after 1m0s
[21:06:22.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:134:0)
[21:06:22.491] Timer tick, asking Buildbucket for the build status
[21:06:22.611] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:22.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:135:0) after 1m0s
[21:07:22.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:135:0)
[21:07:22.735] Timer tick, asking Buildbucket for the build status
[21:07:23.285] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:23.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:136:0) after 1m0s
[21:08:23.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:136:0)
[21:08:23.281] Timer tick, asking Buildbucket for the build status
[21:08:24.535] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:24.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:137:0) after 1m0s
[21:09:24.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:137:0)
[21:09:24.741] Timer tick, asking Buildbucket for the build status
[21:09:24.979] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:24.979] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:138:0) after 1m0s
[21:10:25.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:138:0)
[21:10:25.062] Timer tick, asking Buildbucket for the build status
[21:10:25.312] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:25.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:139:0) after 1m0s
[21:11:25.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:139:0)
[21:11:25.331] Timer tick, asking Buildbucket for the build status
[21:11:25.592] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:25.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:140:0) after 1m0s
[21:12:25.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:140:0)
[21:12:25.578] Timer tick, asking Buildbucket for the build status
[21:12:25.830] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:25.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:141:0) after 1m0s
[21:13:25.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:141:0)
[21:13:25.936] Timer tick, asking Buildbucket for the build status
[21:13:26.128] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:26.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:142:0) after 1m0s
[21:14:26.315] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:142:0)
[21:14:26.315] Timer tick, asking Buildbucket for the build status
[21:14:26.560] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:26.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:143:0) after 1m0s
[21:15:26.755] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:143:0)
[21:15:26.755] Timer tick, asking Buildbucket for the build status
[21:15:27.071] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:27.071] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:144:0) after 1m0s
[21:16:27.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:144:0)
[21:16:27.274] Timer tick, asking Buildbucket for the build status
[21:16:27.411] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:27.411] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:145:0) after 1m0s
[21:17:27.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:145:0)
[21:17:27.555] Timer tick, asking Buildbucket for the build status
[21:17:27.680] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:27.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:146:0) after 1m0s
[21:18:27.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:146:0)
[21:18:27.890] Timer tick, asking Buildbucket for the build status
[21:18:28.421] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:28.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:147:0) after 1m0s
[21:19:28.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:147:0)
[21:19:28.558] Timer tick, asking Buildbucket for the build status
[21:19:28.785] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:28.785] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:148:0) after 1m0s
[21:20:28.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:148:0)
[21:20:28.863] Timer tick, asking Buildbucket for the build status
[21:20:29.053] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:29.053] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:149:0) after 1m0s
[21:21:29.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:149:0)
[21:21:29.162] Timer tick, asking Buildbucket for the build status
[21:21:29.597] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:29.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:150:0) after 1m0s
[21:22:29.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:150:0)
[21:22:29.657] Timer tick, asking Buildbucket for the build status
[21:22:30.042] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:30.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:151:0) after 1m0s
[21:23:30.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:151:0)
[21:23:30.141] Timer tick, asking Buildbucket for the build status
[21:23:30.276] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:30.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:152:0) after 1m0s
[21:24:30.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:152:0)
[21:24:30.364] Timer tick, asking Buildbucket for the build status
[21:24:30.798] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:30.798] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:153:0) after 1m0s
[21:25:30.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:153:0)
[21:25:30.892] Timer tick, asking Buildbucket for the build status
[21:25:31.052] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:31.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:154:0) after 1m0s
[21:26:31.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:154:0)
[21:26:31.179] Timer tick, asking Buildbucket for the build status
[21:26:31.755] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:31.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:155:0) after 1m0s
[21:27:31.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:155:0)
[21:27:31.805] Timer tick, asking Buildbucket for the build status
[21:27:32.162] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:32.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:156:0) after 1m0s
[21:28:32.236] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:156:0)
[21:28:32.236] Timer tick, asking Buildbucket for the build status
[21:28:32.379] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:32.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:157:0) after 1m0s
[21:29:32.454] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:157:0)
[21:29:32.454] Timer tick, asking Buildbucket for the build status
[21:29:33.073] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:33.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:158:0) after 1m0s
[21:30:33.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:158:0)
[21:30:33.111] Timer tick, asking Buildbucket for the build status
[21:30:33.310] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:33.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:159:0) after 1m0s
[21:31:33.462] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:159:0)
[21:31:33.462] Timer tick, asking Buildbucket for the build status
[21:31:33.632] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:33.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:160:0) after 1m0s
[21:32:33.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:160:0)
[21:32:33.659] Timer tick, asking Buildbucket for the build status
[21:32:33.801] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:33.801] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:161:0) after 1m0s
[21:33:33.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:161:0)
[21:33:33.831] Timer tick, asking Buildbucket for the build status
[21:33:34.069] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:34.069] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:162:0) after 1m0s
[21:34:34.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:162:0)
[21:34:34.097] Timer tick, asking Buildbucket for the build status
[21:34:34.228] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:34.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:163:0) after 1m0s
[21:35:34.323] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:163:0)
[21:35:34.323] Timer tick, asking Buildbucket for the build status
[21:35:35.083] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:35.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:164:0) after 1m0s
[21:36:35.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:164:0)
[21:36:35.155] Timer tick, asking Buildbucket for the build status
[21:36:35.291] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:35.292] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:165:0) after 1m0s
[21:37:35.531] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:165:0)
[21:37:35.531] Timer tick, asking Buildbucket for the build status
[21:37:35.679] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:35.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:166:0) after 1m0s
[21:38:35.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:166:0)
[21:38:35.901] Timer tick, asking Buildbucket for the build status
[21:38:36.222] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:36.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:167:0) after 1m0s
[21:39:36.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:167:0)
[21:39:36.248] Timer tick, asking Buildbucket for the build status
[21:39:36.386] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:36.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:168:0) after 1m0s
[21:40:36.441] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:168:0)
[21:40:36.441] Timer tick, asking Buildbucket for the build status
[21:40:36.998] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:36.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:169:0) after 1m0s
[21:41:37.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:169:0)
[21:41:37.134] Timer tick, asking Buildbucket for the build status
[21:41:37.765] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:37.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:170:0) after 1m0s
[21:42:37.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:170:0)
[21:42:37.763] Timer tick, asking Buildbucket for the build status
[21:42:37.927] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:37.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:171:0) after 1m0s
[21:43:37.989] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:171:0)
[21:43:37.990] Timer tick, asking Buildbucket for the build status
[21:43:38.227] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:38.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:172:0) after 1m0s
[21:44:38.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:172:0)
[21:44:38.252] Timer tick, asking Buildbucket for the build status
[21:44:38.498] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:38.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:173:0) after 1m0s
[21:45:38.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:173:0)
[21:45:38.653] Timer tick, asking Buildbucket for the build status
[21:45:38.791] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:38.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:174:0) after 1m0s
[21:46:38.771] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:174:0)
[21:46:38.771] Timer tick, asking Buildbucket for the build status
[21:46:39.144] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:39.144] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:175:0) after 1m0s
[21:47:39.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:175:0)
[21:47:39.174] Timer tick, asking Buildbucket for the build status
[21:47:40.114] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:40.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:176:0) after 1m0s
[21:48:40.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:176:0)
[21:48:40.247] Timer tick, asking Buildbucket for the build status
[21:48:40.488] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:40.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:177:0) after 1m0s
[21:49:40.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:177:0)
[21:49:40.514] Timer tick, asking Buildbucket for the build status
[21:49:40.767] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:40.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:178:0) after 1m0s
[21:50:40.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:178:0)
[21:50:40.901] Timer tick, asking Buildbucket for the build status
[21:50:41.452] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:41.452] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:179:0) after 1m0s
[21:51:41.531] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:179:0)
[21:51:41.531] Timer tick, asking Buildbucket for the build status
[21:51:41.961] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:41.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:180:0) after 1m0s
[21:52:42.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:180:0)
[21:52:42.016] Timer tick, asking Buildbucket for the build status
[21:52:42.201] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:42.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:181:0) after 1m0s
[21:53:42.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:181:0)
[21:53:42.305] Timer tick, asking Buildbucket for the build status
[21:53:42.522] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:42.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:182:0) after 1m0s
[21:54:42.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:182:0)
[21:54:42.552] Timer tick, asking Buildbucket for the build status
[21:54:43.192] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:43.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:183:0) after 1m0s
[21:55:43.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:183:0)
[21:55:43.266] Timer tick, asking Buildbucket for the build status
[21:55:43.418] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:43.418] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:184:0) after 1m0s
[21:56:43.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:184:0)
[21:56:43.487] Timer tick, asking Buildbucket for the build status
[21:56:43.763] Build 8878307508907092816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:43.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043737570919783232:185:0) after 1m0s
[21:57:31.839] Received PubSub notification, asking Buildbucket for the build status
[21:57:31.993] Build 8878307508907092816: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:57:31.994] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1591394250983669",
"created_by": "project:infra",
"created_ts": "1591383177806586",
"id": "8878307508907092816",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4164.4\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"1b18d1c4-9451-420d-ab9d-0cc38fca1a12\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4164.4\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-dd41\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5168-d212e19\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1591383226152191",
"status": "COMPLETED",
"status_changed_ts": "1591394251519978",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9043737570919783232",
"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/8878307508907092816/+/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:4c9ea5c800390a10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1591394251535585",
"url": "https://ci.chromium.org/b/8878307508907092816",
"utcnow_ts": "1591394251985962"
}
}
[21:57:31.994] Invocation finished in 3h4m35.490857504s with status SUCCEEDED