[21:41:03.803] New invocation is queued and will start shortly
[21:41:04.879] Starting the invocation (attempt 1)
[21:41:04.916] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:41:04.916] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:41:04.916] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018088052144098432",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c78650e3d69fa13e8de5bbf2f7db19b7b6e63eca\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9018088052144098432",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/c78650e3d69fa13e8de5bbf2f7db19b7b6e63eca",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c78650e3d69fa13e8de5bbf2f7db19b7b6e63eca",
"gitiles_ref:refs/heads/master"
]
}
[21:41:05.774] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615844465199504",
"id": "8852657990017436512",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c78650e3d69fa13e8de5bbf2f7db19b7b6e63eca\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615844465591421",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/26805",
"builder:Linux Viz",
"buildset:commit/git/c78650e3d69fa13e8de5bbf2f7db19b7b6e63eca",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c78650e3d69fa13e8de5bbf2f7db19b7b6e63eca",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018088052144098432",
"scheduler_job_id:chromium/Linux Viz",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852657990017436512/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1615844465591536",
"url": "https://ci.chromium.org/b/8852657990017436512",
"utcnow_ts": "1615844465763025"
}
}
[21:41:05.774] Task URL: https://ci.chromium.org/b/8852657990017436512
[21:41:05.774] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:2:0) after 1m0s
[21:42:05.902] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:2:0)
[21:42:05.902] Timer tick, asking Buildbucket for the build status
[21:42:05.980] Build 8852657990017436512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:05.980] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:3:0) after 1m0s
[21:43:06.024] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:3:0)
[21:43:06.024] Timer tick, asking Buildbucket for the build status
[21:43:06.089] Build 8852657990017436512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:06.089] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:4:0) after 1m0s
[21:43:12.054] Received PubSub notification, asking Buildbucket for the build status
[21:43:12.125] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:06.154] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:4:0)
[21:44:06.154] Timer tick, asking Buildbucket for the build status
[21:44:06.560] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:06.560] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:6:0) after 1m0s
[21:45:06.965] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:6:0)
[21:45:06.966] Timer tick, asking Buildbucket for the build status
[21:45:07.087] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:07.087] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:7:0) after 1m0s
[21:46:07.113] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:7:0)
[21:46:07.113] Timer tick, asking Buildbucket for the build status
[21:46:07.601] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:07.601] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:8:0) after 1m0s
[21:47:07.639] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:8:0)
[21:47:07.639] Timer tick, asking Buildbucket for the build status
[21:47:07.701] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:07.701] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:9:0) after 1m0s
[21:48:07.750] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:9:0)
[21:48:07.750] Timer tick, asking Buildbucket for the build status
[21:48:07.850] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:07.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:10:0) after 1m0s
[21:49:07.921] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:10:0)
[21:49:07.921] Timer tick, asking Buildbucket for the build status
[21:49:08.003] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:08.003] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:11:0) after 1m0s
[21:50:08.019] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:11:0)
[21:50:08.019] Timer tick, asking Buildbucket for the build status
[21:50:08.636] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:08.636] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:12:0) after 1m0s
[21:51:08.686] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:12:0)
[21:51:08.686] Timer tick, asking Buildbucket for the build status
[21:51:08.815] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:08.815] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:13:0) after 1m0s
[21:52:08.850] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:13:0)
[21:52:08.850] Timer tick, asking Buildbucket for the build status
[21:52:09.100] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:09.100] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:14:0) after 1m0s
[21:53:09.084] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:14:0)
[21:53:09.084] Timer tick, asking Buildbucket for the build status
[21:53:10.178] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:10.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:15:0) after 1m0s
[21:54:10.210] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:15:0)
[21:54:10.210] Timer tick, asking Buildbucket for the build status
[21:54:10.293] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:10.293] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:16:0) after 1m0s
[21:55:10.373] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:16:0)
[21:55:10.373] Timer tick, asking Buildbucket for the build status
[21:55:10.626] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:10.626] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:17:0) after 1m0s
[21:56:10.671] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:17:0)
[21:56:10.671] Timer tick, asking Buildbucket for the build status
[21:56:10.803] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:10.803] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:18:0) after 1m0s
[21:57:10.807] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:18:0)
[21:57:10.807] Timer tick, asking Buildbucket for the build status
[21:57:10.943] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:10.943] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:19:0) after 1m0s
[21:58:11.008] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:19:0)
[21:58:11.008] Timer tick, asking Buildbucket for the build status
[21:58:11.235] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:11.235] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:20:0) after 1m0s
[21:59:11.297] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:20:0)
[21:59:11.297] Timer tick, asking Buildbucket for the build status
[21:59:11.380] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:11.380] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:21:0) after 1m0s
[22:00:11.475] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:21:0)
[22:00:11.475] Timer tick, asking Buildbucket for the build status
[22:00:11.574] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:11.574] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:22:0) after 1m0s
[22:01:11.846] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:22:0)
[22:01:11.846] Timer tick, asking Buildbucket for the build status
[22:01:11.964] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:11.964] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:23:0) after 1m0s
[22:02:12.081] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:23:0)
[22:02:12.081] Timer tick, asking Buildbucket for the build status
[22:02:12.249] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:12.249] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:24:0) after 1m0s
[22:03:12.305] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:24:0)
[22:03:12.305] Timer tick, asking Buildbucket for the build status
[22:03:12.426] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:12.427] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:25:0) after 1m0s
[22:04:12.505] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:25:0)
[22:04:12.505] Timer tick, asking Buildbucket for the build status
[22:04:12.576] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:12.576] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:26:0) after 1m0s
[22:05:12.587] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:26:0)
[22:05:12.587] Timer tick, asking Buildbucket for the build status
[22:05:12.650] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:12.650] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:27:0) after 1m0s
[22:06:12.620] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:27:0)
[22:06:12.620] Timer tick, asking Buildbucket for the build status
[22:06:13.815] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:13.815] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:28:0) after 1m0s
[22:07:13.881] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:28:0)
[22:07:13.881] Timer tick, asking Buildbucket for the build status
[22:07:14.155] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:14.155] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:29:0) after 1m0s
[22:08:14.283] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:29:0)
[22:08:14.283] Timer tick, asking Buildbucket for the build status
[22:08:14.693] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:14.693] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:30:0) after 1m0s
[22:09:14.799] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:30:0)
[22:09:14.799] Timer tick, asking Buildbucket for the build status
[22:09:14.934] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:14.934] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:31:0) after 1m0s
[22:10:14.960] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:31:0)
[22:10:14.960] Timer tick, asking Buildbucket for the build status
[22:10:15.377] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:15.377] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:32:0) after 1m0s
[22:11:15.424] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:32:0)
[22:11:15.424] Timer tick, asking Buildbucket for the build status
[22:11:15.828] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:15.828] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:33:0) after 1m0s
[22:12:15.828] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:33:0)
[22:12:15.828] Timer tick, asking Buildbucket for the build status
[22:12:15.944] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:15.944] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:34:0) after 1m0s
[22:13:16.148] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:34:0)
[22:13:16.148] Timer tick, asking Buildbucket for the build status
[22:13:16.227] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:16.227] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:35:0) after 1m0s
[22:14:16.310] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:35:0)
[22:14:16.310] Timer tick, asking Buildbucket for the build status
[22:14:16.381] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:16.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:36:0) after 1m0s
[22:15:16.414] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:36:0)
[22:15:16.414] Timer tick, asking Buildbucket for the build status
[22:15:16.607] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:16.607] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:37:0) after 1m0s
[22:16:16.641] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:37:0)
[22:16:16.641] Timer tick, asking Buildbucket for the build status
[22:16:17.153] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:17.153] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:38:0) after 1m0s
[22:17:17.201] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:38:0)
[22:17:17.201] Timer tick, asking Buildbucket for the build status
[22:17:17.293] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:17.293] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:39:0) after 1m0s
[22:18:17.407] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:39:0)
[22:18:17.407] Timer tick, asking Buildbucket for the build status
[22:18:18.116] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:18.116] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:40:0) after 1m0s
[22:19:18.411] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:40:0)
[22:19:18.411] Timer tick, asking Buildbucket for the build status
[22:19:18.490] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:18.490] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:41:0) after 1m0s
[22:20:18.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:41:0)
[22:20:18.593] Timer tick, asking Buildbucket for the build status
[22:20:19.292] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:19.292] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:42:0) after 1m0s
[22:21:19.350] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:42:0)
[22:21:19.350] Timer tick, asking Buildbucket for the build status
[22:21:19.492] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:19.492] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:43:0) after 1m0s
[22:22:19.782] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:43:0)
[22:22:19.782] Timer tick, asking Buildbucket for the build status
[22:22:20.036] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:20.036] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:44:0) after 1m0s
[22:23:20.033] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:44:0)
[22:23:20.033] Timer tick, asking Buildbucket for the build status
[22:23:20.215] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:20.215] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:45:0) after 1m0s
[22:24:20.241] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:45:0)
[22:24:20.241] Timer tick, asking Buildbucket for the build status
[22:24:20.322] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:20.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:46:0) after 1m0s
[22:25:20.342] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:46:0)
[22:25:20.342] Timer tick, asking Buildbucket for the build status
[22:25:20.438] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:20.438] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:47:0) after 1m0s
[22:26:20.526] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:47:0)
[22:26:20.526] Timer tick, asking Buildbucket for the build status
[22:26:20.659] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:20.659] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:48:0) after 1m0s
[22:27:20.773] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:48:0)
[22:27:20.773] Timer tick, asking Buildbucket for the build status
[22:27:20.858] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:20.858] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:49:0) after 1m0s
[22:28:20.864] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:49:0)
[22:28:20.864] Timer tick, asking Buildbucket for the build status
[22:28:20.969] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:20.969] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:50:0) after 1m0s
[22:29:21.033] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:50:0)
[22:29:21.033] Timer tick, asking Buildbucket for the build status
[22:29:21.135] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:21.135] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:51:0) after 1m0s
[22:30:21.718] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:51:0)
[22:30:21.718] Timer tick, asking Buildbucket for the build status
[22:30:21.824] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:21.824] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:52:0) after 1m0s
[22:31:21.965] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:52:0)
[22:31:21.965] Timer tick, asking Buildbucket for the build status
[22:31:22.291] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:22.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:53:0) after 1m0s
[22:32:22.369] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:53:0)
[22:32:22.369] Timer tick, asking Buildbucket for the build status
[22:32:22.714] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:22.714] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:54:0) after 1m0s
[22:33:22.735] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:54:0)
[22:33:22.735] Timer tick, asking Buildbucket for the build status
[22:33:23.062] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:23.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:55:0) after 1m0s
[22:34:23.132] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:55:0)
[22:34:23.132] Timer tick, asking Buildbucket for the build status
[22:34:23.459] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:23.459] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:56:0) after 1m0s
[22:35:23.550] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:56:0)
[22:35:23.550] Timer tick, asking Buildbucket for the build status
[22:35:23.634] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:23.634] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:57:0) after 1m0s
[22:36:23.644] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:57:0)
[22:36:23.644] Timer tick, asking Buildbucket for the build status
[22:36:23.985] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:23.985] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:58:0) after 1m0s
[22:37:24.033] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:58:0)
[22:37:24.033] Timer tick, asking Buildbucket for the build status
[22:37:24.153] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:24.153] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:59:0) after 1m0s
[22:38:24.337] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:59:0)
[22:38:24.337] Timer tick, asking Buildbucket for the build status
[22:38:25.058] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:25.058] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:60:0) after 1m0s
[22:39:25.324] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:60:0)
[22:39:25.324] Timer tick, asking Buildbucket for the build status
[22:39:25.616] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:25.616] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:61:0) after 1m0s
[22:40:25.774] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:61:0)
[22:40:25.774] Timer tick, asking Buildbucket for the build status
[22:40:26.298] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:26.298] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:62:0) after 1m0s
[22:41:27.204] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:62:0)
[22:41:27.204] Timer tick, asking Buildbucket for the build status
[22:41:27.317] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:27.317] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:63:0) after 1m0s
[22:42:27.703] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:63:0)
[22:42:27.703] Timer tick, asking Buildbucket for the build status
[22:42:27.828] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:27.828] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:64:0) after 1m0s
[22:43:27.877] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:64:0)
[22:43:27.877] Timer tick, asking Buildbucket for the build status
[22:43:27.967] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:27.967] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:65:0) after 1m0s
[22:44:28.042] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:65:0)
[22:44:28.042] Timer tick, asking Buildbucket for the build status
[22:44:30.582] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:30.582] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:66:0) after 1m0s
[22:45:30.683] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:66:0)
[22:45:30.683] Timer tick, asking Buildbucket for the build status
[22:45:31.080] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:31.080] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:67:0) after 1m0s
[22:46:31.141] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:67:0)
[22:46:31.141] Timer tick, asking Buildbucket for the build status
[22:46:31.260] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:31.261] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:68:0) after 1m0s
[22:47:31.283] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:68:0)
[22:47:31.283] Timer tick, asking Buildbucket for the build status
[22:47:31.405] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:31.405] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:69:0) after 1m0s
[22:48:31.776] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:69:0)
[22:48:31.776] Timer tick, asking Buildbucket for the build status
[22:48:32.036] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:32.036] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:70:0) after 1m0s
[22:49:32.050] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:70:0)
[22:49:32.050] Timer tick, asking Buildbucket for the build status
[22:49:32.287] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:32.287] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:71:0) after 1m0s
[22:50:32.341] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:71:0)
[22:50:32.341] Timer tick, asking Buildbucket for the build status
[22:50:32.482] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:32.482] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:72:0) after 1m0s
[22:51:32.731] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:72:0)
[22:51:32.731] Timer tick, asking Buildbucket for the build status
[22:51:33.132] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:33.132] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:73:0) after 1m0s
[22:52:33.955] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:73:0)
[22:52:33.955] Timer tick, asking Buildbucket for the build status
[22:52:34.079] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:34.079] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:74:0) after 1m0s
[22:53:34.078] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:74:0)
[22:53:34.078] Timer tick, asking Buildbucket for the build status
[22:53:34.349] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:34.350] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:75:0) after 1m0s
[22:54:34.400] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:75:0)
[22:54:34.400] Timer tick, asking Buildbucket for the build status
[22:54:34.510] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:34.510] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:76:0) after 1m0s
[22:55:34.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:76:0)
[22:55:34.593] Timer tick, asking Buildbucket for the build status
[22:55:34.675] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:34.675] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:77:0) after 1m0s
[22:56:34.738] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:77:0)
[22:56:34.738] Timer tick, asking Buildbucket for the build status
[22:56:34.848] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:34.848] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:78:0) after 1m0s
[22:57:34.923] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:78:0)
[22:57:34.923] Timer tick, asking Buildbucket for the build status
[22:57:35.014] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:35.014] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:79:0) after 1m0s
[22:58:35.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:79:0)
[22:58:35.093] Timer tick, asking Buildbucket for the build status
[22:58:35.189] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:35.189] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:80:0) after 1m0s
[22:59:35.626] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:80:0)
[22:59:35.626] Timer tick, asking Buildbucket for the build status
[22:59:35.718] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:35.718] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:81:0) after 1m0s
[23:00:38.734] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:81:0)
[23:00:38.734] Timer tick, asking Buildbucket for the build status
[23:00:39.417] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:39.417] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:82:0) after 1m0s
[23:01:39.545] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:82:0)
[23:01:39.545] Timer tick, asking Buildbucket for the build status
[23:01:39.677] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:39.677] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:83:0) after 1m0s
[23:02:39.729] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:83:0)
[23:02:39.729] Timer tick, asking Buildbucket for the build status
[23:02:39.797] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:39.797] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:84:0) after 1m0s
[23:03:39.836] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:84:0)
[23:03:39.836] Timer tick, asking Buildbucket for the build status
[23:03:40.143] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:40.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:85:0) after 1m0s
[23:04:40.181] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:85:0)
[23:04:40.181] Timer tick, asking Buildbucket for the build status
[23:04:43.948] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:43.948] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:86:0) after 1m0s
[23:05:43.982] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:86:0)
[23:05:43.982] Timer tick, asking Buildbucket for the build status
[23:05:44.124] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:44.124] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:87:0) after 1m0s
[23:06:44.179] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:87:0)
[23:06:44.179] Timer tick, asking Buildbucket for the build status
[23:06:44.352] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:44.352] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:88:0) after 1m0s
[23:07:44.960] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:88:0)
[23:07:44.960] Timer tick, asking Buildbucket for the build status
[23:07:46.125] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:46.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:89:0) after 1m0s
[23:08:47.420] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:89:0)
[23:08:47.420] Timer tick, asking Buildbucket for the build status
[23:08:47.866] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:47.866] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:90:0) after 1m0s
[23:09:47.896] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:90:0)
[23:09:47.896] Timer tick, asking Buildbucket for the build status
[23:09:48.042] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:48.042] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:91:0) after 1m0s
[23:10:48.359] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:91:0)
[23:10:48.359] Timer tick, asking Buildbucket for the build status
[23:10:48.485] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:48.485] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:92:0) after 1m0s
[23:11:48.557] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:92:0)
[23:11:48.557] Timer tick, asking Buildbucket for the build status
[23:11:48.716] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:48.716] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:93:0) after 1m0s
[23:12:49.186] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:93:0)
[23:12:49.186] Timer tick, asking Buildbucket for the build status
[23:12:49.259] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:49.259] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:94:0) after 1m0s
[23:13:49.445] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:94:0)
[23:13:49.445] Timer tick, asking Buildbucket for the build status
[23:13:49.674] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:49.674] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:95:0) after 1m0s
[23:14:49.762] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:95:0)
[23:14:49.762] Timer tick, asking Buildbucket for the build status
[23:14:49.854] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:49.854] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:96:0) after 1m0s
[23:15:49.904] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:96:0)
[23:15:49.904] Timer tick, asking Buildbucket for the build status
[23:15:50.075] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:50.075] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:97:0) after 1m0s
[23:16:50.121] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:97:0)
[23:16:50.121] Timer tick, asking Buildbucket for the build status
[23:16:50.430] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:50.430] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:98:0) after 1m0s
[23:17:50.514] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:98:0)
[23:17:50.514] Timer tick, asking Buildbucket for the build status
[23:17:50.636] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:50.636] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:99:0) after 1m0s
[23:18:50.710] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:99:0)
[23:18:50.710] Timer tick, asking Buildbucket for the build status
[23:18:51.046] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:51.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:100:0) after 1m0s
[23:19:51.102] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:100:0)
[23:19:51.102] Timer tick, asking Buildbucket for the build status
[23:19:51.184] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:51.184] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:101:0) after 1m0s
[23:20:51.208] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:101:0)
[23:20:51.208] Timer tick, asking Buildbucket for the build status
[23:20:53.995] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:53.995] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:102:0) after 1m0s
[23:21:54.054] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:102:0)
[23:21:54.054] Timer tick, asking Buildbucket for the build status
[23:21:54.881] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:54.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:103:0) after 1m0s
[23:22:54.921] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:103:0)
[23:22:54.921] Timer tick, asking Buildbucket for the build status
[23:22:55.008] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:55.008] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:104:0) after 1m0s
[23:23:55.098] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:104:0)
[23:23:55.098] Timer tick, asking Buildbucket for the build status
[23:23:56.334] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:56.334] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:105:0) after 1m0s
[23:24:56.532] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:105:0)
[23:24:56.532] Timer tick, asking Buildbucket for the build status
[23:24:57.239] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:57.239] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:106:0) after 1m0s
[23:25:57.347] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:106:0)
[23:25:57.347] Timer tick, asking Buildbucket for the build status
[23:25:58.412] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:58.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:107:0) after 1m0s
[23:26:58.681] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:107:0)
[23:26:58.681] Timer tick, asking Buildbucket for the build status
[23:26:58.796] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:58.796] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:108:0) after 1m0s
[23:27:58.790] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:108:0)
[23:27:58.790] Timer tick, asking Buildbucket for the build status
[23:27:59.356] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:59.356] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:109:0) after 1m0s
[23:28:59.733] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:109:0)
[23:28:59.733] Timer tick, asking Buildbucket for the build status
[23:28:59.862] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:59.863] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:110:0) after 1m0s
[23:30:00.210] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:110:0)
[23:30:00.210] Timer tick, asking Buildbucket for the build status
[23:30:00.385] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:00.385] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:111:0) after 1m0s
[23:31:00.718] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:111:0)
[23:31:00.718] Timer tick, asking Buildbucket for the build status
[23:31:00.814] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:00.814] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:112:0) after 1m0s
[23:32:00.890] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:112:0)
[23:32:00.890] Timer tick, asking Buildbucket for the build status
[23:32:01.194] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:01.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:113:0) after 1m0s
[23:33:01.698] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:113:0)
[23:33:01.698] Timer tick, asking Buildbucket for the build status
[23:33:01.830] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:01.830] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:114:0) after 1m0s
[23:34:01.870] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:114:0)
[23:34:01.870] Timer tick, asking Buildbucket for the build status
[23:34:02.142] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:02.142] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:115:0) after 1m0s
[23:35:02.165] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:115:0)
[23:35:02.165] Timer tick, asking Buildbucket for the build status
[23:35:02.589] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:02.589] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:116:0) after 1m0s
[23:36:02.645] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:116:0)
[23:36:02.645] Timer tick, asking Buildbucket for the build status
[23:36:02.745] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:02.745] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:117:0) after 1m0s
[23:37:02.765] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:117:0)
[23:37:02.765] Timer tick, asking Buildbucket for the build status
[23:37:02.876] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:02.876] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:118:0) after 1m0s
[23:38:02.899] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:118:0)
[23:38:02.899] Timer tick, asking Buildbucket for the build status
[23:38:03.002] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:03.002] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:119:0) after 1m0s
[23:39:03.043] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:119:0)
[23:39:03.043] Timer tick, asking Buildbucket for the build status
[23:39:03.403] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:03.403] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:120:0) after 1m0s
[23:40:03.379] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:120:0)
[23:40:03.379] Timer tick, asking Buildbucket for the build status
[23:40:03.449] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:03.449] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:121:0) after 1m0s
[23:41:03.472] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:121:0)
[23:41:03.472] Timer tick, asking Buildbucket for the build status
[23:41:03.714] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:03.714] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:122:0) after 1m0s
[23:42:03.829] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:122:0)
[23:42:03.829] Timer tick, asking Buildbucket for the build status
[23:42:03.938] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:03.938] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:123:0) after 1m0s
[23:43:03.998] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:123:0)
[23:43:03.998] Timer tick, asking Buildbucket for the build status
[23:43:04.091] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:04.091] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:124:0) after 1m0s
[23:44:04.159] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:124:0)
[23:44:04.159] Timer tick, asking Buildbucket for the build status
[23:44:04.250] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:04.250] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:125:0) after 1m0s
[23:45:04.282] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:125:0)
[23:45:04.282] Timer tick, asking Buildbucket for the build status
[23:45:04.402] Build 8852657990017436512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:04.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018088052144098432:126:0) after 1m0s
[23:45:18.376] Received PubSub notification, asking Buildbucket for the build status
[23:45:18.538] Build 8852657990017436512: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:45:18.538] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615851918054634",
"created_by": "project:chromium",
"created_ts": "1615844465199504",
"failure_reason": "BUILD_FAILURE",
"id": "8852657990017436512",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c78650e3d69fa13e8de5bbf2f7db19b7b6e63eca\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"5 Test Suite(s) failed.\\n\\n**non_skia_renderer_browser_tests** failed.\\n\\n**skia_renderer_content_browsertests** failed.\\n\\n**vulkan_swiftshader_blink_web_tests** failed.\\n\\n**vulkan_swiftshader_content_browsertests** failed.\\n\\n**non_skia_renderer_swiftshader_blink_web_tests** failed.\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"c78650e3d69fa13e8de5bbf2f7db19b7b6e63eca\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-31-mr04\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 26805, \"got_angle_revision\": \"a11d65a172f885042cf4fdab5bfd124d174f5190\", \"got_dawn_revision\": \"8c40cad1c92d7e85154bbae4cb92539e2200d139\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"c78650e3d69fa13e8de5bbf2f7db19b7b6e63eca\", \"got_revision_cp\": \"refs/heads/master@{#862972}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"69b79eec6533f113920a5dd863ee2dea7d0c64e7\", \"got_v8_revision\": \"d7ff8abb545ad8160e189a7e8352c13c7b0e30b8\", \"got_v8_revision_cp\": \"refs/heads/9.1.126@{#1}\", \"got_webrtc_revision\": \"6097b0fac0946a29d59a9266ea656c39b3fd7336\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33456}\", \"is_cached\": true, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-viz-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c78650e3d69fa13e8de5bbf2f7db19b7b6e63eca\", \"swarm_hashes_refs/heads/master(at){#862972}_without_patch\": {\"blink_web_tests\": \"102dbf08bf5d813fe402d0c3411f7c03d370f875\", \"browser_tests\": \"dd834721e75d458a2e159e5a504a9c4dc446d5d8\", \"content_browsertests\": \"a7743fc4cb90a3d31e9d749b850dfaf3af458214\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3ee79eab7726d87d4f9bf92f4b58b9506b0a7a0d17f466f0c3d67bd50a4697d1_v2\", \"builder_4a782824ba39c0c6a32b2128ebb0687e83cb58dc0cbed64e2911b02f7893cfe6_v2\", \"builder_5e40e9df3c25d6ec04b042625e64587de25b7cc9257c9b922c4342550ccccbb8_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-31-mr04\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"5 Test Suite(s) failed.\\n\\n**non_skia_renderer_browser_tests** failed.\\n\\n**skia_renderer_content_browsertests** failed.\\n\\n**vulkan_swiftshader_blink_web_tests** failed.\\n\\n**vulkan_swiftshader_content_browsertests** failed.\\n\\n**non_skia_renderer_swiftshader_blink_web_tests** failed.\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615844591348313",
"status": "COMPLETED",
"status_changed_ts": "1615844591840867",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/26805",
"builder:Linux Viz",
"buildset:commit/git/c78650e3d69fa13e8de5bbf2f7db19b7b6e63eca",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c78650e3d69fa13e8de5bbf2f7db19b7b6e63eca",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018088052144098432",
"scheduler_job_id:chromium/Linux Viz",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852657990017436512/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:5250a7652a1b3610",
"user_agent:luci-scheduler"
],
"updated_ts": "1615851918055456",
"url": "https://ci.chromium.org/b/8852657990017436512",
"utcnow_ts": "1615851918528941"
}
}
[23:45:18.538] Invocation finished in 2h4m14.750153053s with status FAILED