[08:57:54.113] New invocation is queued and will start shortly
[08:57:54.113] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[08:57:55.240] Starting the invocation (attempt 1)
[08:57:55.283] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:57:55.283] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:57:55.283] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9085721403051062576",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"74.0.3719.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:9085721403051062576",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[08:57:56.759] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551344275425135",
"id": "8920291341009809792",
"lease_expiration_ts": "1551463076491011",
"lease_key": "1036306023",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3719.4\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551344276492304",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9085721403051062576",
"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/8920291341009809792/+/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:434c249a58bf9410",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1551344276492434",
"url": "https://ci.chromium.org/b/8920291341009809792",
"utcnow_ts": "1551344276727746"
}
}
[08:57:56.759] Task URL: https://ci.chromium.org/b/8920291341009809792
[08:57:56.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:2:0) after 1m0s
[08:58:10.609] Received PubSub notification, asking Buildbucket for the build status
[08:58:10.650] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:56.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:2:0)
[08:58:56.770] Timer tick, asking Buildbucket for the build status
[08:58:56.942] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:56.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:4:0) after 1m0s
[08:59:57.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:4:0)
[08:59:57.001] Timer tick, asking Buildbucket for the build status
[08:59:57.265] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:57.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:5:0) after 1m0s
[09:00:57.347] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:5:0)
[09:00:57.347] Timer tick, asking Buildbucket for the build status
[09:00:57.588] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:57.588] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:6:0) after 1m0s
[09:01:57.612] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:6:0)
[09:01:57.612] Timer tick, asking Buildbucket for the build status
[09:01:57.824] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:57.824] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:7:0) after 1m0s
[09:02:57.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:7:0)
[09:02:57.843] Timer tick, asking Buildbucket for the build status
[09:02:57.988] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:57.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:8:0) after 1m0s
[09:03:58.010] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:8:0)
[09:03:58.010] Timer tick, asking Buildbucket for the build status
[09:03:58.073] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:58.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:9:0) after 1m0s
[09:04:58.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:9:0)
[09:04:58.093] Timer tick, asking Buildbucket for the build status
[09:04:58.154] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:58.154] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:10:0) after 1m0s
[09:05:58.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:10:0)
[09:05:58.156] Timer tick, asking Buildbucket for the build status
[09:05:58.686] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:58.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:11:0) after 1m0s
[09:06:58.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:11:0)
[09:06:58.703] Timer tick, asking Buildbucket for the build status
[09:06:58.904] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:58.904] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:12:0) after 1m0s
[09:07:58.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:12:0)
[09:07:58.917] Timer tick, asking Buildbucket for the build status
[09:07:58.964] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:58.964] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:13:0) after 1m0s
[09:08:58.983] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:13:0)
[09:08:58.983] Timer tick, asking Buildbucket for the build status
[09:08:59.049] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:59.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:14:0) after 1m0s
[09:09:59.073] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:14:0)
[09:09:59.073] Timer tick, asking Buildbucket for the build status
[09:09:59.132] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:59.132] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:15:0) after 1m0s
[09:10:59.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:15:0)
[09:10:59.145] Timer tick, asking Buildbucket for the build status
[09:10:59.213] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:59.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:16:0) after 1m0s
[09:11:59.239] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:16:0)
[09:11:59.239] Timer tick, asking Buildbucket for the build status
[09:11:59.321] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:59.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:17:0) after 1m0s
[09:12:59.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:17:0)
[09:12:59.301] Timer tick, asking Buildbucket for the build status
[09:12:59.399] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:59.399] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:18:0) after 1m0s
[09:13:59.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:18:0)
[09:13:59.428] Timer tick, asking Buildbucket for the build status
[09:13:59.565] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:59.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:19:0) after 1m0s
[09:14:59.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:19:0)
[09:14:59.582] Timer tick, asking Buildbucket for the build status
[09:14:59.634] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:59.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:20:0) after 1m0s
[09:15:59.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:20:0)
[09:15:59.655] Timer tick, asking Buildbucket for the build status
[09:15:59.773] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:59.773] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:21:0) after 1m0s
[09:16:59.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:21:0)
[09:16:59.761] Timer tick, asking Buildbucket for the build status
[09:16:59.927] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:59.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:22:0) after 1m0s
[09:17:59.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:22:0)
[09:17:59.950] Timer tick, asking Buildbucket for the build status
[09:18:00.210] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:00.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:23:0) after 1m0s
[09:19:00.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:23:0)
[09:19:00.225] Timer tick, asking Buildbucket for the build status
[09:19:00.689] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:00.690] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:24:0) after 1m0s
[09:20:00.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:24:0)
[09:20:00.694] Timer tick, asking Buildbucket for the build status
[09:20:00.770] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:00.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:25:0) after 1m0s
[09:21:00.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:25:0)
[09:21:00.765] Timer tick, asking Buildbucket for the build status
[09:21:01.014] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:01.014] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:26:0) after 1m0s
[09:22:01.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:26:0)
[09:22:01.002] Timer tick, asking Buildbucket for the build status
[09:22:01.105] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:01.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:27:0) after 1m0s
[09:23:01.126] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:27:0)
[09:23:01.126] Timer tick, asking Buildbucket for the build status
[09:23:01.273] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:01.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:28:0) after 1m0s
[09:24:01.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:28:0)
[09:24:01.274] Timer tick, asking Buildbucket for the build status
[09:24:01.474] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:01.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:29:0) after 1m0s
[09:25:01.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:29:0)
[09:25:01.493] Timer tick, asking Buildbucket for the build status
[09:25:02.227] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:02.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:30:0) after 1m0s
[09:26:02.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:30:0)
[09:26:02.246] Timer tick, asking Buildbucket for the build status
[09:26:02.301] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:02.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:31:0) after 1m0s
[09:27:02.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:31:0)
[09:27:02.324] Timer tick, asking Buildbucket for the build status
[09:27:02.429] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:02.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:32:0) after 1m0s
[09:28:02.450] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:32:0)
[09:28:02.450] Timer tick, asking Buildbucket for the build status
[09:28:02.595] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:02.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:33:0) after 1m0s
[09:29:02.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:33:0)
[09:29:02.567] Timer tick, asking Buildbucket for the build status
[09:29:04.837] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:04.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:34:0) after 1m0s
[09:30:04.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:34:0)
[09:30:04.855] Timer tick, asking Buildbucket for the build status
[09:30:06.135] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:06.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:35:0) after 1m0s
[09:31:06.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:35:0)
[09:31:06.133] Timer tick, asking Buildbucket for the build status
[09:31:06.656] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:06.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:36:0) after 1m0s
[09:32:06.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:36:0)
[09:32:06.673] Timer tick, asking Buildbucket for the build status
[09:32:07.598] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:07.598] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:37:0) after 1m0s
[09:33:07.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:37:0)
[09:33:07.592] Timer tick, asking Buildbucket for the build status
[09:33:07.755] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:07.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:38:0) after 1m0s
[09:34:07.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:38:0)
[09:34:07.737] Timer tick, asking Buildbucket for the build status
[09:34:08.183] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:08.183] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:39:0) after 1m0s
[09:35:08.207] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:39:0)
[09:35:08.207] Timer tick, asking Buildbucket for the build status
[09:35:08.310] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:08.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:40:0) after 1m0s
[09:36:08.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:40:0)
[09:36:08.329] Timer tick, asking Buildbucket for the build status
[09:36:08.409] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:08.409] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:41:0) after 1m0s
[09:37:08.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:41:0)
[09:37:08.458] Timer tick, asking Buildbucket for the build status
[09:37:08.512] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:08.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:42:0) after 1m0s
[09:38:08.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:42:0)
[09:38:08.482] Timer tick, asking Buildbucket for the build status
[09:38:08.579] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:08.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:43:0) after 1m0s
[09:39:08.605] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:43:0)
[09:39:08.605] Timer tick, asking Buildbucket for the build status
[09:39:09.038] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:09.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:44:0) after 1m0s
[09:40:09.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:44:0)
[09:40:09.059] Timer tick, asking Buildbucket for the build status
[09:40:09.200] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:09.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:45:0) after 1m0s
[09:41:09.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:45:0)
[09:41:09.184] Timer tick, asking Buildbucket for the build status
[09:41:09.264] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:09.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:46:0) after 1m0s
[09:42:09.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:46:0)
[09:42:09.304] Timer tick, asking Buildbucket for the build status
[09:42:09.776] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:09.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:47:0) after 1m0s
[09:43:09.806] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:47:0)
[09:43:09.806] Timer tick, asking Buildbucket for the build status
[09:43:09.869] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:09.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:48:0) after 1m0s
[09:44:09.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:48:0)
[09:44:09.888] Timer tick, asking Buildbucket for the build status
[09:44:10.060] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:10.060] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:49:0) after 1m0s
[09:45:10.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:49:0)
[09:45:10.079] Timer tick, asking Buildbucket for the build status
[09:45:10.119] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:10.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:50:0) after 1m0s
[09:46:10.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:50:0)
[09:46:10.146] Timer tick, asking Buildbucket for the build status
[09:46:10.239] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:10.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:51:0) after 1m0s
[09:47:10.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:51:0)
[09:47:10.284] Timer tick, asking Buildbucket for the build status
[09:47:10.336] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:10.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:52:0) after 1m0s
[09:48:10.360] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:52:0)
[09:48:10.360] Timer tick, asking Buildbucket for the build status
[09:48:10.431] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:10.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:53:0) after 1m0s
[09:49:10.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:53:0)
[09:49:10.458] Timer tick, asking Buildbucket for the build status
[09:49:10.511] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:10.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:54:0) after 1m0s
[09:50:10.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:54:0)
[09:50:10.502] Timer tick, asking Buildbucket for the build status
[09:50:10.579] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:10.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:55:0) after 1m0s
[09:51:10.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:55:0)
[09:51:10.562] Timer tick, asking Buildbucket for the build status
[09:51:10.810] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:10.810] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:56:0) after 1m0s
[09:52:10.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:56:0)
[09:52:10.803] Timer tick, asking Buildbucket for the build status
[09:52:10.939] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:10.939] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:57:0) after 1m0s
[09:53:10.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:57:0)
[09:53:10.923] Timer tick, asking Buildbucket for the build status
[09:53:10.974] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:10.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:58:0) after 1m0s
[09:54:10.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:58:0)
[09:54:10.991] Timer tick, asking Buildbucket for the build status
[09:54:11.241] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:11.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:59:0) after 1m0s
[09:55:11.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:59:0)
[09:55:11.265] Timer tick, asking Buildbucket for the build status
[09:55:11.576] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:11.576] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:60:0) after 1m0s
[09:56:11.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:60:0)
[09:56:11.600] Timer tick, asking Buildbucket for the build status
[09:56:11.652] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:11.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:61:0) after 1m0s
[09:57:11.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:61:0)
[09:57:11.682] Timer tick, asking Buildbucket for the build status
[09:57:11.840] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:11.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:62:0) after 1m0s
[09:58:11.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:62:0)
[09:58:11.862] Timer tick, asking Buildbucket for the build status
[09:58:11.954] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:11.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:63:0) after 1m0s
[09:59:11.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:63:0)
[09:59:11.999] Timer tick, asking Buildbucket for the build status
[09:59:12.062] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:12.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:64:0) after 1m0s
[10:00:12.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:64:0)
[10:00:12.059] Timer tick, asking Buildbucket for the build status
[10:00:12.173] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:12.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:65:0) after 1m0s
[10:01:12.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:65:0)
[10:01:12.243] Timer tick, asking Buildbucket for the build status
[10:01:12.366] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:12.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:66:0) after 1m0s
[10:02:12.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:66:0)
[10:02:12.356] Timer tick, asking Buildbucket for the build status
[10:02:12.676] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:12.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:67:0) after 1m0s
[10:03:12.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:67:0)
[10:03:12.673] Timer tick, asking Buildbucket for the build status
[10:03:13.842] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:13.842] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:68:0) after 1m0s
[10:04:13.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:68:0)
[10:04:13.860] Timer tick, asking Buildbucket for the build status
[10:04:14.268] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:14.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:69:0) after 1m0s
[10:05:14.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:69:0)
[10:05:14.305] Timer tick, asking Buildbucket for the build status
[10:05:14.400] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:14.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:70:0) after 1m0s
[10:06:14.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:70:0)
[10:06:14.397] Timer tick, asking Buildbucket for the build status
[10:06:14.451] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:14.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:71:0) after 1m0s
[10:07:14.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:71:0)
[10:07:14.472] Timer tick, asking Buildbucket for the build status
[10:07:14.567] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:14.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:72:0) after 1m0s
[10:08:14.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:72:0)
[10:08:14.570] Timer tick, asking Buildbucket for the build status
[10:08:14.632] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:14.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:73:0) after 1m0s
[10:09:14.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:73:0)
[10:09:14.662] Timer tick, asking Buildbucket for the build status
[10:09:14.939] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:14.939] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:74:0) after 1m0s
[10:10:14.958] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:74:0)
[10:10:14.958] Timer tick, asking Buildbucket for the build status
[10:10:14.993] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:14.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:75:0) after 1m0s
[10:11:15.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:75:0)
[10:11:15.015] Timer tick, asking Buildbucket for the build status
[10:11:15.198] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:15.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:76:0) after 1m0s
[10:12:15.181] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:76:0)
[10:12:15.181] Timer tick, asking Buildbucket for the build status
[10:12:15.263] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:15.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:77:0) after 1m0s
[10:13:15.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:77:0)
[10:13:15.283] Timer tick, asking Buildbucket for the build status
[10:13:15.330] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:15.330] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:78:0) after 1m0s
[10:14:15.349] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:78:0)
[10:14:15.349] Timer tick, asking Buildbucket for the build status
[10:14:15.387] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:15.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:79:0) after 1m0s
[10:15:15.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:79:0)
[10:15:15.371] Timer tick, asking Buildbucket for the build status
[10:15:15.495] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:15.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:80:0) after 1m0s
[10:16:15.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:80:0)
[10:16:15.561] Timer tick, asking Buildbucket for the build status
[10:16:15.722] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:15.723] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:81:0) after 1m0s
[10:17:15.732] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:81:0)
[10:17:15.732] Timer tick, asking Buildbucket for the build status
[10:17:15.795] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:15.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:82:0) after 1m0s
[10:18:15.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:82:0)
[10:18:15.790] Timer tick, asking Buildbucket for the build status
[10:18:16.008] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:16.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:83:0) after 1m0s
[10:19:16.019] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:83:0)
[10:19:16.019] Timer tick, asking Buildbucket for the build status
[10:19:16.214] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:16.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:84:0) after 1m0s
[10:20:16.236] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:84:0)
[10:20:16.236] Timer tick, asking Buildbucket for the build status
[10:20:16.711] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:16.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:85:0) after 1m0s
[10:21:16.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:85:0)
[10:21:16.730] Timer tick, asking Buildbucket for the build status
[10:21:16.790] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:16.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:86:0) after 1m0s
[10:22:16.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:86:0)
[10:22:16.775] Timer tick, asking Buildbucket for the build status
[10:22:16.829] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:16.829] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:87:0) after 1m0s
[10:23:16.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:87:0)
[10:23:16.834] Timer tick, asking Buildbucket for the build status
[10:23:17.027] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:17.027] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:88:0) after 1m0s
[10:24:17.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:88:0)
[10:24:17.049] Timer tick, asking Buildbucket for the build status
[10:24:17.177] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:17.177] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:89:0) after 1m0s
[10:25:17.173] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:89:0)
[10:25:17.173] Timer tick, asking Buildbucket for the build status
[10:25:17.266] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:17.266] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:90:0) after 1m0s
[10:26:17.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:90:0)
[10:26:17.287] Timer tick, asking Buildbucket for the build status
[10:26:17.416] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:17.416] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:91:0) after 1m0s
[10:27:17.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:91:0)
[10:27:17.421] Timer tick, asking Buildbucket for the build status
[10:27:17.462] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:17.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:92:0) after 1m0s
[10:28:17.453] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:92:0)
[10:28:17.453] Timer tick, asking Buildbucket for the build status
[10:28:17.530] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:17.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:93:0) after 1m0s
[10:29:17.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:93:0)
[10:29:17.511] Timer tick, asking Buildbucket for the build status
[10:29:17.559] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:17.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:94:0) after 1m0s
[10:30:17.608] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:94:0)
[10:30:17.608] Timer tick, asking Buildbucket for the build status
[10:30:17.681] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:17.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:95:0) after 1m0s
[10:31:17.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:95:0)
[10:31:17.708] Timer tick, asking Buildbucket for the build status
[10:31:17.784] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:17.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:96:0) after 1m0s
[10:32:17.808] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:96:0)
[10:32:17.808] Timer tick, asking Buildbucket for the build status
[10:32:17.871] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:17.871] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:97:0) after 1m0s
[10:33:17.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:97:0)
[10:33:17.896] Timer tick, asking Buildbucket for the build status
[10:33:17.940] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:17.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:98:0) after 1m0s
[10:34:17.930] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:98:0)
[10:34:17.930] Timer tick, asking Buildbucket for the build status
[10:34:18.140] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:18.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:99:0) after 1m0s
[10:35:18.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:99:0)
[10:35:18.174] Timer tick, asking Buildbucket for the build status
[10:35:18.233] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:18.233] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:100:0) after 1m0s
[10:36:18.267] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:100:0)
[10:36:18.267] Timer tick, asking Buildbucket for the build status
[10:36:18.372] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:18.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:101:0) after 1m0s
[10:37:18.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:101:0)
[10:37:18.387] Timer tick, asking Buildbucket for the build status
[10:37:18.434] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:18.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:102:0) after 1m0s
[10:38:18.420] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:102:0)
[10:38:18.420] Timer tick, asking Buildbucket for the build status
[10:38:18.467] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:18.467] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:103:0) after 1m0s
[10:39:18.495] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:103:0)
[10:39:18.495] Timer tick, asking Buildbucket for the build status
[10:39:18.789] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:18.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:104:0) after 1m0s
[10:40:18.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:104:0)
[10:40:18.817] Timer tick, asking Buildbucket for the build status
[10:40:19.221] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:19.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:105:0) after 1m0s
[10:41:19.209] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:105:0)
[10:41:19.209] Timer tick, asking Buildbucket for the build status
[10:41:19.248] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:19.248] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:106:0) after 1m0s
[10:42:19.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:106:0)
[10:42:19.261] Timer tick, asking Buildbucket for the build status
[10:42:19.325] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:19.325] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:107:0) after 1m0s
[10:43:19.315] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:107:0)
[10:43:19.315] Timer tick, asking Buildbucket for the build status
[10:43:19.358] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:19.358] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:108:0) after 1m0s
[10:44:19.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:108:0)
[10:44:19.405] Timer tick, asking Buildbucket for the build status
[10:44:19.478] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:19.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:109:0) after 1m0s
[10:45:19.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:109:0)
[10:45:19.506] Timer tick, asking Buildbucket for the build status
[10:45:19.562] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:19.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:110:0) after 1m0s
[10:46:19.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:110:0)
[10:46:19.596] Timer tick, asking Buildbucket for the build status
[10:46:19.691] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:19.691] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:111:0) after 1m0s
[10:47:19.725] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:111:0)
[10:47:19.725] Timer tick, asking Buildbucket for the build status
[10:47:19.775] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:19.775] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:112:0) after 1m0s
[10:48:19.796] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:112:0)
[10:48:19.796] Timer tick, asking Buildbucket for the build status
[10:48:19.865] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:19.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:113:0) after 1m0s
[10:49:19.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:113:0)
[10:49:19.860] Timer tick, asking Buildbucket for the build status
[10:49:19.986] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:19.986] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:114:0) after 1m0s
[10:50:20.026] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:114:0)
[10:50:20.026] Timer tick, asking Buildbucket for the build status
[10:50:20.091] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:20.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:115:0) after 1m0s
[10:51:20.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:115:0)
[10:51:20.109] Timer tick, asking Buildbucket for the build status
[10:51:20.179] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:20.179] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:116:0) after 1m0s
[10:52:20.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:116:0)
[10:52:20.174] Timer tick, asking Buildbucket for the build status
[10:52:20.237] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:20.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:117:0) after 1m0s
[10:53:20.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:117:0)
[10:53:20.247] Timer tick, asking Buildbucket for the build status
[10:53:20.341] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:20.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:118:0) after 1m0s
[10:54:20.368] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:118:0)
[10:54:20.368] Timer tick, asking Buildbucket for the build status
[10:54:20.546] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:20.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:119:0) after 1m0s
[10:55:20.539] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:119:0)
[10:55:20.539] Timer tick, asking Buildbucket for the build status
[10:55:20.667] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:20.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:120:0) after 1m0s
[10:56:20.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:120:0)
[10:56:20.692] Timer tick, asking Buildbucket for the build status
[10:56:20.889] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:20.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:121:0) after 1m0s
[10:57:20.873] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:121:0)
[10:57:20.873] Timer tick, asking Buildbucket for the build status
[10:57:20.988] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:20.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:122:0) after 1m0s
[10:58:21.043] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:122:0)
[10:58:21.043] Timer tick, asking Buildbucket for the build status
[10:58:21.136] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:21.136] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:123:0) after 1m0s
[10:59:21.161] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:123:0)
[10:59:21.161] Timer tick, asking Buildbucket for the build status
[10:59:21.215] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:21.215] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:124:0) after 1m0s
[11:00:21.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:124:0)
[11:00:21.245] Timer tick, asking Buildbucket for the build status
[11:00:21.458] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:21.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:125:0) after 1m0s
[11:01:21.441] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:125:0)
[11:01:21.441] Timer tick, asking Buildbucket for the build status
[11:01:21.494] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:21.494] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:126:0) after 1m0s
[11:02:21.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:126:0)
[11:02:21.522] Timer tick, asking Buildbucket for the build status
[11:02:21.613] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:21.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:127:0) after 1m0s
[11:03:21.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:127:0)
[11:03:21.596] Timer tick, asking Buildbucket for the build status
[11:03:21.652] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:21.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:128:0) after 1m0s
[11:04:21.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:128:0)
[11:04:21.673] Timer tick, asking Buildbucket for the build status
[11:04:22.411] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:22.411] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:129:0) after 1m0s
[11:05:22.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:129:0)
[11:05:22.439] Timer tick, asking Buildbucket for the build status
[11:05:22.774] Build 8920291341009809792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:22.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085721403051062576:130:0) after 1m0s
[11:05:39.654] Received PubSub notification, asking Buildbucket for the build status
[11:05:39.723] Build 8920291341009809792: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:05:39.723] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1551351938681617",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551344275425135",
"id": "8920291341009809792",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3719.4\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-32\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Build From Tarball\"], \"kvm\": [\"1\"], \"id\": [\"gce-trusty-32-0258d129-us-east1-b-1m4q\"], \"server_version\": [\"4129-0985e08\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"32\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"pool\": [\"luci.infra.cron\"], \"caches\": [\"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildername\": \"Build From Tarball\", \"recipe\": \"build_from_tarball\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1551344275425135, \"tags\": [\"builder:Build From Tarball\", \"parent_buildername:publish_tarball\", \"scheduler_invocation_id:9085721403051062576\", \"scheduler_job_id:infra/Build From Tarball\", \"user_agent:luci-scheduler\", \"user_agent:recipe\"], \"bucket\": \"luci.infra.cron\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"infra\", \"id\": \"8920291341009809792\"}}, \"version\": \"74.0.3719.4\", \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Build From Tarball\", \"key\": \"builder\"}, {\"value\": \"publish_tarball\", \"key\": \"parent_buildername\"}, {\"value\": \"9085721403051062576\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"infra/Build From Tarball\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}, {\"value\": \"recipe\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"infra\", \"builder\": \"Build From Tarball\", \"bucket\": \"cron\"}, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"ca0db2d762966d4b32d49d86567d9d7697438caf\"}, \"recipe\": {\"name\": \"build_from_tarball\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/infra/infra\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8920291341009809792\", \"input\": {}, \"createTime\": \"2019-02-28T08:57:55.425135Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-32-0258d129-us-east1-b-1m4q\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551344289832847",
"status": "COMPLETED",
"status_changed_ts": "1551351939402523",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9085721403051062576",
"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/8920291341009809792/+/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:434c249a58bf9410",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1551351939403973",
"url": "https://ci.chromium.org/b/8920291341009809792",
"utcnow_ts": "1551351939711613"
}
}
[11:05:39.723] Invocation finished in 2h7m45.627697783s with status SUCCEEDED