Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Slow auth server can hang client connections in async recording mode #4695

Closed
klizhentas opened this issue Nov 4, 2020 · 13 comments · Fixed by #4813
Closed

Slow auth server can hang client connections in async recording mode #4695

klizhentas opened this issue Nov 4, 2020 · 13 comments · Fixed by #4813
Assignees

Comments

@klizhentas
Copy link
Contributor

Description

Slow or unresponsive auth service can hang client connections in async recording mode.

What happens

Teleport async recording mode sends all events to disk, and uploads them to the server later.
It uploads some events synchronously to the audit log so they show up in the global event log right away.
However if the auth server is slow, the fanout blocks the session.

@webvictim
Copy link
Contributor

Is this resolved by restarting the auth server, or the node? I think I may have seen this issue on a node running 4.4.2 yesterday - new sessions would hang and not start with no obvious errors in the log, but this was resolved after restarting the node.

@klizhentas
Copy link
Contributor Author

@webvictim node

@webvictim
Copy link
Contributor

Just had this happen again. Here's debugging info in case it's relevant.

Version on auth/proxy:

$ teleport version
Teleport Enterprise v5.0.0-beta.9 git:v5.0.0-beta.9-0-g6c3b0771b go1.14.4

Version on node:

# /usr/local/bin/teleport version
Teleport v4.4.2 git:v4.4.2-0-gecb165f89 go1.14.4

Auth/proxy logs:

Nov 04 15:35:21 artemis teleport[23175]: DEBU [PROXY]     conn(192.168.64.1:57482->192.168.64.254:3023, user=gus) auth attempt fingerprint:ssh-rsa-cert-v01@openssh.com SHA256:oFiJbvCYGqmZkSJ6r7TLWnbOTNaQRSJVYyC/eifkyVU local:192.168.64.254:3023 remote:192.168.64.1:57482 user:gus srv/authhandlers.go:158
Nov 04 15:35:21 artemis teleport[23175]: DEBU [PROXY]     conn(192.168.64.1:57482->192.168.64.254:3023, user=gus) auth attempt with key ssh-rsa-cert-v01@openssh.com SHA256:oFiJbvCYGqmZkSJ6r7TLWnbOTNaQRSJVYyC/eifkyVU, &ssh.Certificate{Nonce:[]uint8{0xc3, 0xea, 0xfb, 0x50, 0x98, 0x1b, 0x39, 0x57, 0x8, 0x83, 0xe4, 0x
35, 0x49, 0x26, 0x7, 0xd0, 0xde, 0x7, 0x80, 0xe2, 0x74, 0xca, 0x45, 0x19, 0xd9, 0x48, 0x3b, 0xdc, 0xa, 0xb6, 0xa6, 0x29}, Key:(*ssh.rsaPublicKey)(0x9fbb2a8), Serial:0x0, CertType:0x1, KeyId:"username", ValidPrincipals:[]string{"gus", "username", "test"}, ValidAfter:0x5fa2f005, ValidBefore:0x5fa39901, Permissions:s
sh.Permissions{CriticalOptions:map[string]string{}, Extensions:map[string]string{"permit-agent-forwarding":"", "permit-port-forwarding":"", "permit-pty":"", "teleport-roles":"{\"version\":\"v1\",\"roles\":[\"admin\"]}", "teleport-route-to-cluster":"teleport.domain.com", "teleport-traits":"{\"kubernetes_groups\":[\
"system:masters\"],\"kubernetes_users\":null,\"logins\":[\"username\"]}"}}, Reserved:[]uint8{}, SignatureKey:(*ssh.rsaPublicKey)(0x9fbb2c8), Signature:(*ssh.Signature)(0x5dd1d60)} fingerprint:ssh-rsa-cert-v01@openssh.com SHA256:oFiJbvCYGqmZkSJ6r7TLWnbOTNaQRSJVYyC/eifkyVU local:192.168.64.254:3023 remote:192.168.64
.1:57482 user:gus srv/authhandlers.go:158
Nov 04 15:35:21 artemis teleport[23175]: DEBU [PROXY]     Successfully authenticated fingerprint:ssh-rsa-cert-v01@openssh.com SHA256:oFiJbvCYGqmZkSJ6r7TLWnbOTNaQRSJVYyC/eifkyVU local:192.168.64.254:3023 remote:192.168.64.1:57482 user:gus srv/authhandlers.go:214
Nov 04 15:35:21 artemis teleport[23175]: DEBU [SSH:PROXY] Incoming connection 192.168.64.1:57482 -> 192.168.64.254:3023 vesion: SSH-2.0-OpenSSH_8.1. sshutils/server.go:438
Nov 04 15:35:21 artemis teleport[23175]: DEBU [PROXY]     Handling request env, want reply false. id:1178 local:192.168.64.254:3023 login:gus remote:192.168.64.1:57482 teleportUser:username regular/sshserver.go:1239
Nov 04 15:35:21 artemis teleport[23175]: DEBU [KEEPALIVE] Starting keep-alive loop with with interval 5m0s and max count 3. srv/keepalive.go:67
Nov 04 15:35:21 artemis teleport[23175]: DEBU [PROXY]     Handling request env, want reply false. id:1178 local:192.168.64.254:3023 login:gus remote:192.168.64.1:57482 teleportUser:username regular/sshserver.go:1239
Nov 04 15:35:21 artemis teleport[23175]: DEBU [PROXY]     Handling request subsystem, want reply true. id:1178 local:192.168.64.254:3023 login:gus remote:192.168.64.1:57482 teleportUser:username regular/sshserver.go:1239
Nov 04 15:35:21 artemis teleport[23175]: DEBU [NODE]      parse_proxy_subsys("proxy:zeus:3022") regular/proxy.go:82
Nov 04 15:35:21 artemis teleport[23175]: DEBU [NODE]      Proxy subsystem: routing user "username" to cluster "teleport.domain.com" based on the route to cluster extension. regular/proxy.go:171
Nov 04 15:35:21 artemis teleport[23175]: DEBU [NODE]      newProxySubsys({default zeus 3022 teleport.domain.com}). regular/proxy.go:184
Nov 04 15:35:21 artemis teleport[23175]: DEBU [PROXY]     Subsystem request: proxySubsys(cluster=default/teleport.domain.com, host=zeus, port=3022). id:1178 local:192.168.64.254:3023 login:gus remote:192.168.64.1:57482 teleportUser:username regular/sshserver.go:1353
Nov 04 15:35:21 artemis teleport[23175]: DEBU [SUBSYSTEM] Starting subsystem trace.fields:map[dst:192.168.64.254:3023 src:192.168.64.1:57482] regular/proxy.go:213
Nov 04 15:35:21 artemis teleport[23175]: DEBU [SUBSYSTEM] proxy connecting to host=zeus port=3022, exact port=true trace.fields:map[dst:192.168.64.254:3023 src:192.168.64.1:57482] regular/proxy.go:331
Nov 04 15:35:21 artemis teleport[23175]: DEBU [PROXY:SER] Dialing from: "192.168.64.1:57482" to: "6c3161e1-828d-4573-90e2-0e511a452830.teleport.domain.com". trace.fields:map[cluster:teleport.domain.com] reversetunnel/localsite.go:183
Nov 04 15:35:21 artemis teleport[23175]: DEBU [PROXY:SER] Tunnel dialing to 6c3161e1-828d-4573-90e2-0e511a452830.teleport.domain.com. trace.fields:map[cluster:teleport.domain.com] reversetunnel/localsite.go:265
Nov 04 15:35:21 artemis teleport[23175]: DEBU [PROXY:SER] Connecting to 192.168.64.1:59442 through tunnel. trace.fields:map[cluster:teleport.domain.com] reversetunnel/localsite.go:433

The node is running at INFO level so I don't have any logs, but here's a kill -USR1:

Nov 04 15:30:48 zeus teleport[3547317]: INFO [PROC:1]    Got signal "user defined signal 1", logging diagostic info to stderr. service/signals.go:100
Nov 04 15:30:48 zeus teleport[3547317]: Runtime stats
Nov 04 15:30:48 zeus teleport[3547317]: goroutines: 97
Nov 04 15:30:48 zeus teleport[3547317]: OS threads: 25
Nov 04 15:30:48 zeus teleport[3547317]: GOMAXPROCS: 8
Nov 04 15:30:48 zeus teleport[3547317]: num CPU: 8
Nov 04 15:30:48 zeus teleport[3547317]: Memory stats
Nov 04 15:30:48 zeus teleport[3547317]: alloc: 10998792
Nov 04 15:30:48 zeus teleport[3547317]: total-alloc: 8540753752
Nov 04 15:30:48 zeus teleport[3547317]: sys: 74727680
Nov 04 15:30:48 zeus teleport[3547317]: lookups: 0
Nov 04 15:30:48 zeus teleport[3547317]: mallocs: 80830425
Nov 04 15:30:48 zeus teleport[3547317]: frees: 80763553
Nov 04 15:30:48 zeus teleport[3547317]: heap-alloc: 10998792
Nov 04 15:30:48 zeus teleport[3547317]: heap-sys: 64815104
Nov 04 15:30:48 zeus teleport[3547317]: heap-idle: 49020928
Nov 04 15:30:48 zeus teleport[3547317]: heap-in-use: 15794176
Nov 04 15:30:48 zeus teleport[3547317]: heap-released: 42819584
Nov 04 15:30:48 zeus teleport[3547317]: heap-objects: 66872
Nov 04 15:30:48 zeus teleport[3547317]: stack-in-use: 2293760
Nov 04 15:30:48 zeus teleport[3547317]: stack-sys: 2293760
Nov 04 15:30:48 zeus teleport[3547317]: stack-mspan-inuse: 249424
Nov 04 15:30:48 zeus teleport[3547317]: stack-mspan-sys: 376832
Nov 04 15:30:48 zeus teleport[3547317]: stack-mcache-inuse: 13888
Nov 04 15:30:48 zeus teleport[3547317]: stack-mcache-sys: 16384
Nov 04 15:30:48 zeus teleport[3547317]: other-sys: 1680415
Nov 04 15:30:48 zeus teleport[3547317]: gc-sys: 3811592
Nov 04 15:30:48 zeus teleport[3547317]: next-gc: when heap-alloc >= 20699520
Nov 04 15:30:48 zeus teleport[3547317]: last-gc: 2020-11-04 15:30:33.402725901 -0400 AST
Nov 04 15:30:48 zeus teleport[3547317]: gc-pause-total: 179.308571ms
Nov 04 15:30:48 zeus teleport[3547317]: gc-pause: 66729
Nov 04 15:30:48 zeus teleport[3547317]: num-gc: 1437
Nov 04 15:30:48 zeus teleport[3547317]: enable-gc: true
Nov 04 15:30:48 zeus teleport[3547317]: debug-gc: false
Nov 04 15:30:48 zeus teleport[3547317]: Goroutines
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 1 [running, locked to thread]:
Nov 04 15:30:48 zeus teleport[3547317]: runtime/pprof.writeGoroutineStacks(0x2bc9760, 0xc000010020, 0xb, 0xc00053a150)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/pprof/pprof.go:665 +0x9d
Nov 04 15:30:48 zeus teleport[3547317]: runtime/pprof.writeGoroutine(0x2bc9760, 0xc000010020, 0x2, 0x1000, 0xb)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/pprof/pprof.go:654 +0x44
Nov 04 15:30:48 zeus teleport[3547317]: runtime/pprof.(*Profile).WriteTo(0x41fcea0, 0x2bc9760, 0xc000010020, 0x2, 0xc000bf6410, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/pprof/pprof.go:329 +0x3da
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.goroutineDump(0x2bc9760, 0xc000010020)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/info.go:45 +0x61
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.writeDebugInfo(0x2bc9760, 0xc000010020)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/info.go:41 +0x123
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).WaitForSignals(0xc0004982d0, 0x2c2b640, 0xc000052040, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/signals.go:100 +0x91f
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.waitAndReload(0x2c2b640, 0xc000052040, 0xc00012f360, 0x11, 0xc0002e87c8, 0x4, 0xc0001301c0, 0x40, 0xc0004f5aa0, 0x1, ...)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:429 +0x79
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.Run(0x2c2b640, 0xc000052040, 0xc00012f360, 0x11, 0xc0002e87c8, 0x4, 0xc0001301c0, 0x40, 0xc0004f5aa0, 0x1, ...)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:416 +0x168
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/tool/teleport/common.OnStart(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/tool/teleport/common/teleport.go:188
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/tool/teleport/common.Run(0xc00004e0d0, 0x3, 0x3, 0x0, 0x0, 0x0, 0xc0000a6058)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/tool/teleport/common/teleport.go:165 +0x2c19
Nov 04 15:30:48 zeus teleport[3547317]: main.main()
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/tool/teleport/main.go:26 +0x69
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 41 [chan receive]:
Nov 04 15:30:48 zeus teleport[3547317]: k8s.io/klog/v2.(*loggingT).flushDaemon(0x42a3c80)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/k8s.io/klog/v2/klog.go:1131 +0x8b
Nov 04 15:30:48 zeus teleport[3547317]: created by k8s.io/klog/v2.init.0
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/k8s.io/klog/v2/klog.go:416 +0xd6
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 126 [sync.Cond.Wait, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: runtime.goparkunlock(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/proc.go:310
Nov 04 15:30:48 zeus teleport[3547317]: sync.runtime_notifyListWait(0xc0006e4790, 0xc000000000)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 04 15:30:48 zeus teleport[3547317]: sync.(*Cond).Wait(0xc0006e4780)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*mux).Wait(0xc000408cb0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:110 +0x84
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.NewClient.func1(0xc0001328c0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:62 +0x33
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.NewClient
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:61 +0xe5
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 55 [select]:
Nov 04 15:30:48 zeus teleport[3547317]: go.opencensus.io/stats/view.(*worker).start(0xc0004e8080)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/go.opencensus.io/stats/view/worker.go:276 +0x100
Nov 04 15:30:48 zeus teleport[3547317]: created by go.opencensus.io/stats/view.init.0
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/go.opencensus.io/stats/view/worker.go:34 +0x68
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 60 [select]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).fanOut(0xc0002332c0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:400 +0x17d
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/service.NewSupervisor
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:164 +0x25e
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 61 [select, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: database/sql.(*DB).connectionOpener(0xc000233380, 0x2c2b600, 0xc0005b7700)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/database/sql/sql.go:1052 +0xe8
Nov 04 15:30:48 zeus teleport[3547317]: created by database/sql.OpenDB
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/database/sql/sql.go:722 +0x15d
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 62 [select, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: database/sql.(*DB).connectionResetter(0xc000233380, 0x2c2b600, 0xc0005b7700)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/database/sql/sql.go:1065 +0xfb
Nov 04 15:30:48 zeus teleport[3547317]: created by database/sql.OpenDB
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/database/sql/sql.go:723 +0x193
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 138 [select]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/backend/lite.(*LiteBackend).runPeriodicOperations(0xc0004fb040)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/backend/lite/periodic.go:36 +0x129
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/backend/lite.NewWithConfig
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/backend/lite/lite.go:179 +0x84c
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 163 [select, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: database/sql.(*DB).connectionResetter(0xc000900480, 0x2c2b600, 0xc000506980)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/database/sql/sql.go:1065 +0xfb
Nov 04 15:30:48 zeus teleport[3547317]: created by database/sql.OpenDB
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/database/sql/sql.go:723 +0x193
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 141 [select, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/sshutils.(*Server).Wait(0xc00025ba40, 0x2c2b640, 0xc000052040)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/sshutils/server.go:293 +0xde
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).Wait(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:283
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).initSSH.func1(0x0, 0xffffffffffffffff)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:1689 +0x1196
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalService).Serve(0xc0004f7780, 0x26da04c, 0x14)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:450 +0x2a
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve.func1(0xc0002332c0, 0x2c2bd40, 0xc0004f7780)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:242 +0x30b
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:237 +0x6e
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 142 [chan receive, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).onExit.func1(0x0, 0xffffffffffffffff)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:1322 +0xd1
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalService).Serve(0xc0004f77e0, 0x26da04c, 0x14)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:450 +0x2a
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve.func1(0xc0002332c0, 0x2c2bd40, 0xc0004f77e0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:242 +0x30b
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:237 +0x6e
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 143 [select, 4 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).syncRotationStateCycle(0xc0004982d0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/connect.go:484 +0x393
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).periodicSyncRotationState(0xc0004982d0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/connect.go:436 +0x249
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalService).Serve(0xc0004f7800, 0x26da04c, 0x14)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:450 +0x2a
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve.func1(0xc0002332c0, 0x2c2bd40, 0xc0004f7800)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:242 +0x30b
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:237 +0x6e
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 144 [syscall]:
Nov 04 15:30:48 zeus teleport[3547317]: os/signal.signal_recv(0x2bed0c0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/sigqueue.go:147 +0x9c
Nov 04 15:30:48 zeus teleport[3547317]: os/signal.loop()
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/os/signal/signal_unix.go:23 +0x22
Nov 04 15:30:48 zeus teleport[3547317]: created by os/signal.Notify.func1
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/os/signal/signal.go:127 +0x44
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 125 [chan receive, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*Client).handleChannelOpens(0xc0001328c0, 0xc000437020)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:147 +0x52
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.NewClient
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:60 +0xc3
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 111 [chan receive, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).onExit.func1(0x0, 0xffffffffffffffff)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:1322 +0xd1
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalService).Serve(0xc000117580, 0x26da04c, 0x14)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:450 +0x2a
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve.func1(0xc0002332c0, 0x2c2bd40, 0xc000117580)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:242 +0x30b
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:237 +0x6e
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 162 [select, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: database/sql.(*DB).connectionOpener(0xc000900480, 0x2c2b600, 0xc000506980)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/database/sql/sql.go:1052 +0xe8
Nov 04 15:30:48 zeus teleport[3547317]: created by database/sql.OpenDB
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/database/sql/sql.go:722 +0x15d
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 124 [chan receive, 4 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*Client).handleGlobalRequests(0xc0001328c0, 0xc0004370e0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:138 +0x6d
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.NewClient
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:59 +0x97
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 782030 [sync.Cond.Wait, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: runtime.goparkunlock(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/proc.go:310
Nov 04 15:30:48 zeus teleport[3547317]: sync.runtime_notifyListWait(0xc00091e250, 0xe)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 04 15:30:48 zeus teleport[3547317]: sync.(*Cond).Wait(0xc00091e240)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*buffer).Read(0xc00103fe80, 0xc000c03000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/buffer.go:94 +0x1de
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*channel).ReadExtended(0xc000562180, 0xc000c03000, 0x1000, 0x1000, 0x0, 0x44, 0xc0007f78a8, 0x4673e9)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:351 +0x1e5
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*channel).Read(0xc000562180, 0xc000c03000, 0x1000, 0x1000, 0x4a1190, 0xc0007f78e0, 0x384)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:528 +0x5b
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/utils.(*TimeoutConn).Read(0xc000bf5290, 0xc000c03000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/utils/timeout.go:58 +0xa1
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/utils.(*TrackingConn).Read(0xc0001d3540, 0xc000c03000, 0x1000, 0x1000, 0x44, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/utils/conn.go:130 +0x55
Nov 04 15:30:48 zeus teleport[3547317]: io.(*multiReader).Read(0xc0001d3700, 0xc000c03000, 0x1000, 0x1000, 0x44, 0x79280f, 0xc0007f7aa8)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/multi.go:26 +0xac
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/sshutils.(*connectionWrapper).Read(0xc000bf52c0, 0xc000c03000, 0x1000, 0x1000, 0xc0007f7c20, 0xc0007f7d40, 0x20)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/sshutils/server.go:640 +0x6c6
Nov 04 15:30:48 zeus teleport[3547317]: bufio.(*Reader).Read(0xc000807e60, 0xc0001b1800, 0x4, 0x684, 0xeae117a2ff68e605, 0x20, 0xc0007f7c20)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/bufio/bufio.go:226 +0x24f
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadAtLeast(0x2bc38e0, 0xc000807e60, 0xc0001b1800, 0x4, 0x684, 0x4, 0x20, 0xc0007f7d10, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:310 +0x87
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadFull(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:329
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*chacha20Poly1305Cipher).readCipherPacket(0xc0015081e0, 0xc000000016, 0x2bc38e0, 0xc000807e60, 0xc0001b1805, 0x27, 0x67f, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/cipher.go:675 +0x21b
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*connectionState).readPacket(0xc000a04360, 0xc000807e60, 0xc0007f7f30, 0x415903, 0xc001508000, 0xc0010016e0, 0xc0007f7fb0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:130 +0x68
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*transport).readPacket(0xc000a04360, 0xc000906de0, 0x27, 0x27, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:114 +0x37
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readOnePacket(0xc000689e40, 0xc0007f7f00, 0xc000906de0, 0x27, 0x27, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:374 +0x50
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readLoop(0xc000689e40)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:197 +0x4d
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newServerTransport
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:142 +0xe3
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 123 [chan receive]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readPacket(0xc000470160, 0xc000f9a2c2, 0x9, 0x9, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:187 +0x4e
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*mux).onePacket(0xc000408cb0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:215 +0x37
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*mux).loop(0xc000408cb0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:190 +0x35
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newMux
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:128 +0x153
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 121 [IO wait]:
Nov 04 15:30:48 zeus teleport[3547317]: internal/poll.runtime_pollWait(0x7f8029aa2e68, 0x72, 0xffffffffffffffff)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/netpoll.go:203 +0x55
Nov 04 15:30:48 zeus teleport[3547317]: internal/poll.(*pollDesc).wait(0xc000868198, 0x72, 0x1000, 0x1000, 0xffffffffffffffff)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/internal/poll/fd_poll_runtime.go:87 +0x45
Nov 04 15:30:48 zeus teleport[3547317]: internal/poll.(*pollDesc).waitRead(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/internal/poll/fd_poll_runtime.go:92
Nov 04 15:30:48 zeus teleport[3547317]: internal/poll.(*FD).Read(0xc000868180, 0xc0005f2000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/internal/poll/fd_unix.go:169 +0x19b
Nov 04 15:30:48 zeus teleport[3547317]: net.(*netFD).Read(0xc000868180, 0xc0005f2000, 0x1000, 0x1000, 0xc000853cd0, 0x10, 0x10)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/net/fd_unix.go:202 +0x4f
Nov 04 15:30:48 zeus teleport[3547317]: net.(*conn).Read(0xc0006ea080, 0xc0005f2000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/net/net.go:184 +0x8e
Nov 04 15:30:48 zeus teleport[3547317]: bufio.(*Reader).Read(0xc000436e40, 0xc00084a2e0, 0x4, 0x4, 0x1000000d5865ec1, 0xc000454600, 0xc000454610)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/bufio/bufio.go:226 +0x24f
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadAtLeast(0x2bc38e0, 0xc000436e40, 0xc00084a2e0, 0x4, 0x4, 0x4, 0x10000000000000c, 0xc000454600, 0x9)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:310 +0x87
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadFull(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:329
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*gcmCipher).readCipherPacket(0xc00084a2d0, 0xc000004dfd, 0x2bc38e0, 0xc000436e40, 0xc000454601, 0x9, 0x58f, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/cipher.go:373 +0x83
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*connectionState).readPacket(0xc000802120, 0xc000436e40, 0xc000853f30, 0x415903, 0xc000436f60, 0xc000436c60, 0xc000853fb0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:130 +0x68
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*transport).readPacket(0xc000802120, 0xc000f9a2c2, 0x9, 0x9, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:114 +0x37
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readOnePacket(0xc000470160, 0xc000853f00, 0xc000f9a2c2, 0x9, 0x9, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:374 +0x50
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readLoop(0xc000470160)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:197 +0x4d
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newClientTransport
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:134 +0x181
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 122 [select, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).kexLoop(0xc000470160)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:268 +0xfa
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newClientTransport
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:135 +0x1a3
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 127 [select]:
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc/internal/transport.(*http2Client).keepalive(0xc00025b180)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/http2_client.go:1331 +0x193
Nov 04 15:30:48 zeus teleport[3547317]: created by google.golang.org/grpc/internal/transport.newHTTP2Client
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/http2_client.go:295 +0x16cd
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 175 [select]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/backend/lite.(*LiteBackend).runPeriodicOperations(0xc000441ee0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/backend/lite/periodic.go:36 +0x129
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/backend/lite.NewWithConfig
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/backend/lite/lite.go:179 +0x84c
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 176 [select, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.(*ccBalancerWrapper).watcher(0xc0005070c0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/balancer_conn_wrappers.go:69 +0xc2
Nov 04 15:30:48 zeus teleport[3547317]: created by google.golang.org/grpc.newCCBalancerWrapper
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/balancer_conn_wrappers.go:60 +0x16d
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 177 [chan receive, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.(*addrConn).resetTransport(0xc00028f080)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/clientconn.go:1179 +0x6ea
Nov 04 15:30:48 zeus teleport[3547317]: created by google.golang.org/grpc.(*addrConn).connect
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/clientconn.go:815 +0x128
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 128 [sync.Cond.Wait]:
Nov 04 15:30:48 zeus teleport[3547317]: runtime.goparkunlock(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/proc.go:310
Nov 04 15:30:48 zeus teleport[3547317]: sync.runtime_notifyListWait(0xc0006e4810, 0xc000001ebd)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 04 15:30:48 zeus teleport[3547317]: sync.(*Cond).Wait(0xc0006e4800)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*buffer).Read(0xc0004393a0, 0xc00033b500, 0x96a, 0x96a, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/buffer.go:94 +0x1de
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*channel).ReadExtended(0xc0004d06c0, 0xc00033b500, 0x96a, 0x96a, 0x0, 0x96a, 0x203000, 0x1f)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:351 +0x1e5
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*channel).Read(0xc0004d06c0, 0xc00033b500, 0x96a, 0x96a, 0xc00033b505, 0x0, 0x965)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:528 +0x5b
Nov 04 15:30:48 zeus teleport[3547317]: crypto/tls.(*atLeastReader).Read(0xc0004a6b20, 0xc00033b500, 0x96a, 0x96a, 0x2f, 0x965, 0xc00045da90)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/crypto/tls/conn.go:760 +0x60
Nov 04 15:30:48 zeus teleport[3547317]: bytes.(*Buffer).ReadFrom(0xc0008485d8, 0x2bc3b20, 0xc0004a6b20, 0x41aa45, 0x237eee0, 0x2559aa0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/bytes/buffer.go:204 +0xb1
Nov 04 15:30:48 zeus teleport[3547317]: crypto/tls.(*Conn).readFromUntil(0xc000848380, 0x7f8029057388, 0xc0004fc720, 0x5, 0xc0004fc720, 0x1e)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/crypto/tls/conn.go:782 +0xec
Nov 04 15:30:48 zeus teleport[3547317]: crypto/tls.(*Conn).readRecordOrCCS(0xc000848380, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/crypto/tls/conn.go:589 +0x115
Nov 04 15:30:48 zeus teleport[3547317]: crypto/tls.(*Conn).readRecord(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/crypto/tls/conn.go:557
Nov 04 15:30:48 zeus teleport[3547317]: crypto/tls.(*Conn).Read(0xc000848380, 0xc00098a000, 0x8000, 0x8000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/crypto/tls/conn.go:1233 +0x15b
Nov 04 15:30:48 zeus teleport[3547317]: bufio.(*Reader).Read(0xc0006e6b40, 0xc0004c81f8, 0x9, 0x9, 0x20, 0x18, 0x2421e80)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/bufio/bufio.go:226 +0x24f
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadAtLeast(0x2bc38e0, 0xc0006e6b40, 0xc0004c81f8, 0x9, 0x9, 0x9, 0x100000000a808c5, 0x0, 0x9)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:310 +0x87
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadFull(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:329
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/net/http2.readFrameHeader(0xc0004c81f8, 0x9, 0x9, 0x2bc38e0, 0xc0006e6b40, 0x0, 0xc000000000, 0x41d368, 0xc000132970)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/net/http2/frame.go:237 +0x87
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/net/http2.(*Framer).ReadFrame(0xc0004c81c0, 0xc0013a0340, 0x42a3700, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/net/http2/frame.go:492 +0xa1
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc/internal/transport.(*http2Client).reader(0xc00025b180)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/http2_client.go:1265 +0x16f
Nov 04 15:30:48 zeus teleport[3547317]: created by google.golang.org/grpc/internal/transport.newHTTP2Client
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/http2_client.go:300 +0xd23
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 129 [select]:
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc/internal/transport.(*controlBuffer).get(0xc000132960, 0x1, 0x0, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/controlbuf.go:395 +0x122
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc/internal/transport.(*loopyWriter).run(0xc0006e6c00, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/controlbuf.go:513 +0x1cd
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc/internal/transport.newHTTP2Client.func3(0xc00025b180)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/http2_client.go:346 +0x7b
Nov 04 15:30:48 zeus teleport[3547317]: created by google.golang.org/grpc/internal/transport.newHTTP2Client
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/http2_client.go:344 +0xedb
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 740017 [select, 153 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).kexLoop(0xc00038a160)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:268 +0xfa
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newServerTransport
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:143 +0x105
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 227 [select, 38 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc/internal/transport.(*recvBufferReader).readClient(0xc000207b80, 0xc000117ff0, 0x5, 0x5, 0x23bcb00, 0xc00078ebc8, 0x10d)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:186 +0xd3
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc/internal/transport.(*recvBufferReader).Read(0xc000207b80, 0xc000117ff0, 0x5, 0x5, 0xc00028dd40, 0x27cc8c8, 0xc000b9d990)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:166 +0x18a
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc/internal/transport.(*transportReader).Read(0xc0005d2960, 0xc000117ff0, 0x5, 0x5, 0xc000b9da4c, 0xc000b9d9b8, 0xd7a59c)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:479 +0x55
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadAtLeast(0x2bc8220, 0xc0005d2960, 0xc000117ff0, 0x5, 0x5, 0x5, 0x4bc57c, 0xc0005d28d0, 0xc0004357a0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:310 +0x87
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadFull(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:329
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc/internal/transport.(*Stream).Read(0xc0001c6800, 0xc000117ff0, 0x5, 0x5, 0xc0015a0000, 0x2378060, 0x2638740)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:463 +0xcd
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.(*parser).recvMsg(0xc000117fe0, 0x400000, 0xc001064500, 0xc0010fe240, 0x112, 0x112, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/rpc_util.go:508 +0x63
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.recvAndDecompress(0xc000117fe0, 0xc0001c6800, 0x0, 0x0, 0x400000, 0x0, 0x0, 0x0, 0xc0010fe240, 0x112, ...)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/rpc_util.go:639 +0x4d
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.recv(0xc000117fe0, 0x7f8029057d00, 0x42d0cb0, 0xc0001c6800, 0x0, 0x0, 0x2638740, 0xc001386240, 0x400000, 0x0, ...)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/rpc_util.go:707 +0x9b
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.(*csAttempt).recvMsg(0xc0001bcc00, 0x2638740, 0xc001386240, 0x0, 0xc000b9dd38, 0x474870)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:885 +0xed
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.(*clientStream).RecvMsg.func1(0xc0001bcc00, 0x203000, 0x203000)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:736 +0x46
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.(*clientStream).withRetry(0xc00043cd80, 0xc000b9de48, 0xc000b9de18, 0x0, 0x20)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:590 +0x361
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.(*clientStream).RecvMsg(0xc00043cd80, 0x2638740, 0xc001386240, 0xc000b9df0c, 0x2)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:735 +0x103
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/auth/proto.(*authServiceWatchEventsClient).Recv(0xc0006f53d0, 0xc000b9df08, 0x2, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/auth/proto/authservice.pb.go:2198 +0x62
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/auth.(*streamWatcher).receiveEvents(0xc0003deea0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/auth/clt.go:874 +0x4e
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/auth.(*Client).NewWatcher
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/auth/clt.go:842 +0x365
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 226 [select, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.newClientStream.func5(0xc000031c00, 0xc00043cd80, 0x2c2b6c0, 0xc0005d28d0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:319 +0xd7
Nov 04 15:30:48 zeus teleport[3547317]: created by google.golang.org/grpc.newClientStream
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:318 +0xbbf
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 398 [select, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc/internal/transport.(*Stream).waitOnHeader(0xc0004e6a00)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:318 +0xcc
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc/internal/transport.(*Stream).RecvCompress(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:333
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.(*csAttempt).recvMsg(0xc0004e9b80, 0x2439960, 0xc0009e2720, 0x0, 0xc0006ab74c, 0x10)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:871 +0x755
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.(*clientStream).RecvMsg.func1(0xc0004e9b80, 0x1, 0x8)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:736 +0x46
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.(*clientStream).withRetry(0xc0004feea0, 0xc000a76f28, 0xc000a76ef8, 0xc0006ab750, 0x41caf6)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:594 +0x9c
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.(*clientStream).RecvMsg(0xc0004feea0, 0x2439960, 0xc0009e2720, 0x1, 0xc0009e2720)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:735 +0x103
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/auth.(*streamKeepAliver).recv(0xc0005eacc0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/auth/clt.go:799 +0x5e
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/auth.(*Client).NewKeepAliver
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/auth/clt.go:754 +0x1e0
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 219 [chan receive, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*Client).handleChannelOpens(0xc0008b4460, 0xc0003de540)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:147 +0x52
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.NewClient
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:60 +0xc3
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 200 [select, 38 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/cache.(*Cache).fetchAndWatch(0xc00080d4a0, 0x2c2b600, 0xc000506d80, 0x2c36cc0, 0xc0003decc0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/cache/cache.go:476 +0x68e
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/cache.(*Cache).update(0xc00080d4a0, 0x2c2b600, 0xc000506d80)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/cache/cache.go:329 +0xf6
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/cache.New
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/cache/cache.go:298 +0x6db
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 161 [select, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).kexLoop(0xc000722160)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:268 +0xfa
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newClientTransport
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:135 +0x1a3
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 160 [IO wait]:
Nov 04 15:30:48 zeus teleport[3547317]: internal/poll.runtime_pollWait(0x7f8029aa2688, 0x72, 0xffffffffffffffff)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/netpoll.go:203 +0x55
Nov 04 15:30:48 zeus teleport[3547317]: internal/poll.(*pollDesc).wait(0xc0007dc218, 0x72, 0x1000, 0x1000, 0xffffffffffffffff)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/internal/poll/fd_poll_runtime.go:87 +0x45
Nov 04 15:30:48 zeus teleport[3547317]: internal/poll.(*pollDesc).waitRead(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/internal/poll/fd_poll_runtime.go:92
Nov 04 15:30:48 zeus teleport[3547317]: internal/poll.(*FD).Read(0xc0007dc200, 0xc000959000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/internal/poll/fd_unix.go:169 +0x19b
Nov 04 15:30:48 zeus teleport[3547317]: net.(*netFD).Read(0xc0007dc200, 0xc000959000, 0x1000, 0x1000, 0xc00084ecd0, 0x10, 0x10)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/net/fd_unix.go:202 +0x4f
Nov 04 15:30:48 zeus teleport[3547317]: net.(*conn).Read(0xc000708130, 0xc000959000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/net/net.go:184 +0x8e
Nov 04 15:30:48 zeus teleport[3547317]: bufio.(*Reader).Read(0xc0006e6240, 0xc000920600, 0x4, 0x4, 0x10000009d97e101, 0xc00151ee00, 0xc00151ee10)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/bufio/bufio.go:226 +0x24f
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadAtLeast(0x2bc38e0, 0xc0006e6240, 0xc000920600, 0x4, 0x4, 0x4, 0x10000000000000c, 0x0, 0x9)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:310 +0x87
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadFull(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:329
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*gcmCipher).readCipherPacket(0xc0009205f0, 0xc00001cd72, 0x2bc38e0, 0xc0006e6240, 0xc00151ee01, 0x9, 0x6af, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/cipher.go:373 +0x83
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*connectionState).readPacket(0xc0003e4240, 0xc0006e6240, 0xc00084ef30, 0x415903, 0xc0006e63c0, 0xc000f6b2c0, 0xc00084efb0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:130 +0x68
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*transport).readPacket(0xc0003e4240, 0xc000d8c590, 0x9, 0x9, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:114 +0x37
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readOnePacket(0xc000722160, 0xc00084ef00, 0xc000d8c590, 0x9, 0x9, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:374 +0x50
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readLoop(0xc000722160)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:197 +0x4d
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newClientTransport
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:134 +0x181
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 396 [select, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.newClientStream.func5(0xc000031c00, 0xc0004feea0, 0x2c2b6c0, 0xc0009e2660)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:319 +0xd7
Nov 04 15:30:48 zeus teleport[3547317]: created by google.golang.org/grpc.newClientStream
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:318 +0xbbf
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 207 [select]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/events.(*Uploader).Serve(0xc0005576b0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/events/uploader.go:156 +0x11c
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).initUploaderService.func1(0x0, 0xffffffffffffffff)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:1801 +0x37
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalService).Serve(0xc000a474a0, 0x26da04c, 0x14)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:450 +0x2a
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve.func1(0xc0002332c0, 0x2c2bd40, 0xc000a474a0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:242 +0x30b
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:237 +0x6e
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 208 [chan receive, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).onExit.func1(0x0, 0xffffffffffffffff)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:1322 +0xd1
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalService).Serve(0xc000a47500, 0x26da04c, 0x14)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:450 +0x2a
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve.func1(0xc0002332c0, 0x2c2bd40, 0xc000a47500)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:242 +0x30b
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:237 +0x6e
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 209 [select]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/events/filesessions.(*Uploader).Serve(0xc00059e820, 0x120, 0x110)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/events/filesessions/fileasync.go:181 +0x1e9
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).initUploaderService.func3(0x0, 0xffffffffffffffff)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:1826 +0x48
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalService).Serve(0xc000a47560, 0x26da04c, 0x14)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:450 +0x2a
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve.func1(0xc0002332c0, 0x2c2bd40, 0xc000a47560)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:242 +0x30b
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:237 +0x6e
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 242 [chan receive, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).onExit.func1(0x0, 0xffffffffffffffff)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:1322 +0xd1
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalService).Serve(0xc000a475c0, 0x26da04c, 0x14)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:450 +0x2a
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve.func1(0xc0002332c0, 0x2c2bd40, 0xc000a475c0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:242 +0x30b
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:237 +0x6e
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 243 [select, 54 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).periodicUpdateLabel(0xc0009f1440, 0xc0002e8958, 0x4, 0x2c46080, 0xc000a52820)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:759 +0x181
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).updateLabels
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:733 +0xfc
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 244 [select, 54 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).periodicUpdateLabel(0xc0009f1440, 0xc0002e89a0, 0xd, 0x2c46080, 0xc000a52870)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:759 +0x181
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).updateLabels
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:733 +0xfc
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 245 [select, 54 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).periodicUpdateLabel(0xc0009f1440, 0xc0002e8a20, 0xd, 0x2c46080, 0xc000a528c0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:759 +0x181
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).updateLabels
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:733 +0xfc
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 246 [select, 4 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).periodicUpdateLabel(0xc0009f1440, 0xc00012fa00, 0x12, 0x2c46080, 0xc000a52910)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:759 +0x181
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).updateLabels
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:733 +0xfc
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 247 [select, 4 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).periodicUpdateLabel(0xc0009f1440, 0xc00012fb00, 0x12, 0x2c46080, 0xc000a52960)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:759 +0x181
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).updateLabels
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:733 +0xfc
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 248 [select, 54 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).periodicUpdateLabel(0xc0009f1440, 0xc0002e88c8, 0x8, 0x2c46080, 0xc000a529b0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:759 +0x181
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).updateLabels
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:733 +0xfc
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 249 [select, 54 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).periodicUpdateLabel(0xc0009f1440, 0xc0002e8910, 0x10, 0x2c46080, 0xc000a52a00)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:759 +0x181
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).updateLabels
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:733 +0xfc
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 250 [select]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*Heartbeat).Run(0xc0003e5c20, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/heartbeat.go:251 +0x1fe
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).Start
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:257 +0x137
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 251 [select, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/reversetunnel/track.(*Tracker).run(0xc0005e8a80, 0x2c2b600, 0xc0005e89c0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/track/tracker.go:93 +0x122
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/reversetunnel/track.New
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/track/tracker.go:85 +0x139
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 252 [select, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/utils/workpool.(*group).run(0xc0008079e0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/utils/workpool/workpool.go:216 +0x18e
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/utils/workpool.(*Pool).start
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/utils/workpool/workpool.go:106 +0x187
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 253 [select]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/reversetunnel.(*AgentPool).pollAndSyncAgents(0xc0005bc000)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agentpool.go:240 +0x13f
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/reversetunnel.(*AgentPool).Start
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agentpool.go:165 +0xfd
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 254 [select, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/reversetunnel.(*AgentPool).processSeekEvents(0xc0005bc000)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agentpool.go:183 +0x14f
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/reversetunnel.(*AgentPool).Start
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agentpool.go:166 +0x11f
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 290 [select, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/reversetunnel.(*Agent).processRequests(0xc00025bc00, 0xc0008b4460, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:400 +0x35d
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/reversetunnel.(*Agent).run.func1()
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:355 +0x121
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/reversetunnel/track.(*Tracker).WithProxy(0xc0005e8a80, 0xc0007fbf38, 0xc0008079e0, 0x1, 0xc000a33780, 0xc0003de780, 0x6, 0x6, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/track/tracker.go:187 +0x1d8
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/reversetunnel.(*Agent).run(0xc00025bc00)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:364 +0x399
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/reversetunnel.(*Agent).Start
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:199 +0x3f
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 280 [select, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.newClientStream.func5(0xc000031c00, 0xc0008e2480, 0x2c2b6c0, 0xc00034be30)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:319 +0xd7
Nov 04 15:30:48 zeus teleport[3547317]: created by google.golang.org/grpc.newClientStream
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:318 +0xbbf
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 782115 [select, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.StartKeepAliveLoop(0xc000f7be50, 0x1, 0x1, 0x45d964b800, 0x3, 0x2c2b600, 0xc0004b48c0, 0xc000f7bcc0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/keepalive.go:71 +0x335
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).handleSessionRequests
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1218 +0x5a5
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 397 [select]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/auth.(*streamKeepAliver).forwardKeepAlives(0xc0005eacc0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/auth/clt.go:773 +0xea
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/auth.(*Client).NewKeepAliver
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/auth/clt.go:753 +0x1be
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 740061 [select]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*session).heartbeat(0xc0009220b0, 0xc0009f0900)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:1163 +0x275
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv.(*session).startInteractive
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:777 +0xcb4
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 281 [select, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc/internal/transport.(*recvBufferReader).readClient(0xc000050820, 0xc000439350, 0x5, 0x5, 0x456dfc, 0xc0009103b8, 0x1)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:186 +0xd3
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc/internal/transport.(*recvBufferReader).Read(0xc000050820, 0xc000439350, 0x5, 0x5, 0xc00028d4e0, 0x27cc8c8, 0xc0007d4990)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:166 +0x18a
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc/internal/transport.(*transportReader).Read(0xc00034bf20, 0xc000439350, 0x5, 0x5, 0xc0007d4a4c, 0xc0007d49b8, 0xd7a59c)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:479 +0x55
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadAtLeast(0x2bc8220, 0xc00034bf20, 0xc000439350, 0x5, 0x5, 0x5, 0x4bc57c, 0xc00034be30, 0xc000910420)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:310 +0x87
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadFull(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:329
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc/internal/transport.(*Stream).Read(0xc0008fc100, 0xc000439350, 0x5, 0x5, 0xc00003c000, 0x2378060, 0x2638740)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:463 +0xcd
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.(*parser).recvMsg(0xc000439340, 0x400000, 0xc0005b6800, 0x0, 0x0, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/rpc_util.go:508 +0x63
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.recvAndDecompress(0xc000439340, 0xc0008fc100, 0x0, 0x0, 0x400000, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/rpc_util.go:639 +0x4d
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.recv(0xc000439340, 0x7f8029057d00, 0x42d0cb0, 0xc0008fc100, 0x0, 0x0, 0x2638740, 0xc0005b6880, 0x400000, 0x0, ...)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/rpc_util.go:707 +0x9b
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.(*csAttempt).recvMsg(0xc0009ac900, 0x2638740, 0xc0005b6880, 0x0, 0xc0007d4d38, 0x474870)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:885 +0xed
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.(*clientStream).RecvMsg.func1(0xc0009ac900, 0x203000, 0x203000)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:736 +0x46
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.(*clientStream).withRetry(0xc0008e2480, 0xc0007d4e48, 0xc0007d4e18, 0x1, 0x20)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:590 +0x361
Nov 04 15:30:48 zeus teleport[3547317]: google.golang.org/grpc.(*clientStream).RecvMsg(0xc0008e2480, 0x2638740, 0xc0005b6880, 0xc0007d4f0c, 0x2)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:735 +0x103
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/auth/proto.(*authServiceWatchEventsClient).Recv(0xc0006f5d10, 0xc0007d4f08, 0x2, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/auth/proto/authservice.pb.go:2198 +0x62
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/auth.(*streamWatcher).receiveEvents(0xc000376480)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/auth/clt.go:874 +0x4e
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/auth.(*Client).NewWatcher
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/auth/clt.go:842 +0x365
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 79 [select, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/reversetunnel.(*Agent).handleDiscovery(0xc00025bc00, 0x2c3fa20, 0xc000562300, 0xc000502240)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:472 +0x15b
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/reversetunnel.(*Agent).processRequests
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:455 +0xa0d
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 777374 [select, 20 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).kexLoop(0xc000d2a2c0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:268 +0xfa
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newClientTransport
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:135 +0x1a3
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 221 [select, 4 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/reversetunnel.(*Agent).handleGlobalRequests(0xc00025bc00, 0x2c2b600, 0xc0005e8c40, 0xc0003de6c0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:282 +0xe2
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/reversetunnel.(*Agent).connect
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:272 +0x58c
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 220 [sync.Cond.Wait, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: runtime.goparkunlock(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/proc.go:310
Nov 04 15:30:48 zeus teleport[3547317]: sync.runtime_notifyListWait(0xc0006e43d0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 04 15:30:48 zeus teleport[3547317]: sync.(*Cond).Wait(0xc0006e43c0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*mux).Wait(0xc0001e2000, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:110 +0x84
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.NewClient.func1(0xc0008b4460)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:62 +0x33
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.NewClient
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:61 +0xe5
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 217 [chan receive]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readPacket(0xc000722160, 0xc000d8c590, 0x9, 0x9, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:187 +0x4e
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*mux).onePacket(0xc0001e2000, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:215 +0x37
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*mux).loop(0xc0001e2000)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:190 +0x35
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newMux
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:128 +0x153
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 740016 [sync.Cond.Wait, 4 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: runtime.goparkunlock(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/proc.go:310
Nov 04 15:30:48 zeus teleport[3547317]: sync.runtime_notifyListWait(0xc0008c0a50, 0x54)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 04 15:30:48 zeus teleport[3547317]: sync.(*Cond).Wait(0xc0008c0a40)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*buffer).Read(0xc000e47d00, 0xc000c94000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/buffer.go:94 +0x1de
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*channel).ReadExtended(0xc0009000c0, 0xc000c94000, 0x1000, 0x1000, 0x0, 0x1c, 0xc0007fd8a8, 0x4673e9)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:351 +0x1e5
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*channel).Read(0xc0009000c0, 0xc000c94000, 0x1000, 0x1000, 0x4a1190, 0xc0007fd8e0, 0x384)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:528 +0x5b
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/utils.(*TimeoutConn).Read(0xc0009e3350, 0xc000c94000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/utils/timeout.go:58 +0xa1
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/utils.(*TrackingConn).Read(0xc0016309a0, 0xc000c94000, 0x1000, 0x1000, 0x1c, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/utils/conn.go:130 +0x55
Nov 04 15:30:48 zeus teleport[3547317]: io.(*multiReader).Read(0xc001630a40, 0xc000c94000, 0x1000, 0x1000, 0x1c, 0x79280f, 0xc0007fdaa8)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/multi.go:26 +0xac
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/sshutils.(*connectionWrapper).Read(0xc0009e3380, 0xc000c94000, 0x1000, 0x1000, 0xc0007fdc20, 0xc0007fdd40, 0x20)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/sshutils/server.go:640 +0x6c6
Nov 04 15:30:48 zeus teleport[3547317]: bufio.(*Reader).Read(0xc0010a25a0, 0xc00151e700, 0x4, 0x684, 0x462d3a1faf205554, 0x20, 0xc0007fdc20)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/bufio/bufio.go:226 +0x24f
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadAtLeast(0x2bc38e0, 0xc0010a25a0, 0xc00151e700, 0x4, 0x684, 0x4, 0x20, 0xc0007fdd10, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:310 +0x87
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadFull(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:329
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*chacha20Poly1305Cipher).readCipherPacket(0xc0010a2960, 0xc000000058, 0x2bc38e0, 0xc0010a25a0, 0xc00151e705, 0x1, 0x67f, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/cipher.go:675 +0x21b
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*connectionState).readPacket(0xc0004bc000, 0xc0010a25a0, 0xc0007fdf30, 0x415903, 0xc0010a26c0, 0xc001508540, 0xc0007fdfb0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:130 +0x68
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*transport).readPacket(0xc0004bc000, 0xc00114eaa8, 0x1, 0x1, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:114 +0x37
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readOnePacket(0xc00038a160, 0xc0007fdf00, 0xc00114eaa8, 0x1, 0x1, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:374 +0x50
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readLoop(0xc00038a160)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:197 +0x4d
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newServerTransport
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:142 +0xe3
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 740063 [syscall, 153 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: syscall.Syscall6(0xf7, 0x1, 0x3f2b0, 0xc0009f54f8, 0x1000004, 0x0, 0x0, 0x60, 0x25f87a0, 0x7f802be41fff)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/syscall/asm_linux_amd64.s:41 +0x5
Nov 04 15:30:48 zeus teleport[3547317]: os.(*Process).blockUntilWaitable(0xc0009beba0, 0xc000330480, 0x3, 0xc0009f55e0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/os/wait_waitid.go:31 +0x98
Nov 04 15:30:48 zeus teleport[3547317]: os.(*Process).wait(0xc0009beba0, 0x2, 0x2, 0xc0009f5764)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/os/exec_unix.go:22 +0x39
Nov 04 15:30:48 zeus teleport[3547317]: os.(*Process).Wait(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/os/exec.go:125
Nov 04 15:30:48 zeus teleport[3547317]: os/exec.(*Cmd).Wait(0xc00094a420, 0x0, 0xc000502a08)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/os/exec/exec.go:507 +0x60
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*terminal).Wait(0xc000252620, 0xc00073bab8, 0x0, 0xc00073ba08)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/term.go:204 +0x33
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*session).startInteractive.func2(0xc0009220b0, 0xc0009f0900, 0xc000f24150, 0xc0009cfd80)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:804 +0x4c
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv.(*session).startInteractive
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:803 +0xd7b
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 740057 [select]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/events.(*AuditWriter).processEvents(0xc000820900)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/events/auditwriter.go:241 +0x223
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/events.NewAuditWriter
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/events/auditwriter.go:57 +0x41d
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 740048 [select, 153 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).handleSessionRequests(0xc0009f1440, 0x2c2b600, 0xc0005b62c0, 0xc0002520e0, 0xc0017bbf60, 0x9, 0xc000ea8138, 0x3, 0xc00048b800, 0x74e, ...)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1245 +0x723
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleNewChan.func1(0xc0009f1440, 0x2c2b600, 0xc0005b62c0, 0xc0002520e0, 0xc0008565a0, 0x2c3fa20, 0xc0004d0b40, 0xc000e81560, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1038 +0xf9
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleNewChan
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1037 +0xc8c
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 782031 [select, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).kexLoop(0xc000689e40)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:268 +0xfa
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newServerTransport
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:143 +0x105
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 782363 [semacquire, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: sync.runtime_SemacquireMutex(0xc000a49234, 0xc0019f9c00, 0x1)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/sema.go:71 +0x47
Nov 04 15:30:48 zeus teleport[3547317]: sync.(*Mutex).lockSlow(0xc000a49230)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/sync/mutex.go:138 +0xfc
Nov 04 15:30:48 zeus teleport[3547317]: sync.(*Mutex).Lock(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/sync/mutex.go:81
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*SessionRegistry).addSession(0xc000a49230, 0xc000f85080)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:97 +0xd9
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*SessionRegistry).OpenSession(0xc000a49230, 0x2c41100, 0xc00139e580, 0xc00123c340, 0xc0009f0fc0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:204 +0x268
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*TermHandlers).HandleShell(0xc0006eb3a0, 0x2c41100, 0xc00139e580, 0xc00123c340, 0xc0009f0fc0, 0x2, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/termhandlers.go:113 +0xc3
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).dispatch(0xc0009f1440, 0x2c41100, 0xc00139e580, 0xc00123c340, 0xc0009f0fc0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1318 +0x67e
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).handleSessionRequests(0xc0009f1440, 0x2c2b600, 0xc00169b340, 0xc000254690, 0xc000d8d930, 0x9, 0xc0010da718, 0x3, 0xc0011da800, 0x74e, ...)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1257 +0x791
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleNewChan.func1(0xc0009f1440, 0x2c2b600, 0xc00169b340, 0xc000254690, 0xc0002e5170, 0x2c3fa20, 0xc000a4ecc0, 0xc0003076e0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1038 +0xf9
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleNewChan
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1037 +0xc8c
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 543 [select, 153 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/events.(*AuditWriter).EmitAuditEvent(0xc000820700, 0x2c2b600, 0xc0004b5c40, 0x2c5a940, 0xc000418c40, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/events/auditwriter.go:194 +0x18c
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*SessionRegistry).emitSessionLeaveEvent(0xc000a49230, 0xc000900fc0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:263 +0x1fe
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*SessionRegistry).leaveSession(0xc000a49230, 0xc000900fc0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:292 +0x85
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*party).Close.func1()
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:1383 +0xc5
Nov 04 15:30:48 zeus teleport[3547317]: sync.(*Once).doSlow(0xc000901070, 0xc000c579f0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/sync/once.go:66 +0xec
Nov 04 15:30:48 zeus teleport[3547317]: sync.(*Once).Do(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/sync/once.go:57
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*party).Close(0xc000900fc0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:1381 +0x82
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.closeAll(0xc001549740, 0x6, 0x6, 0x6, 0xc0000501e0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/ctx.go:767 +0xb6
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*ServerContext).Close(0xc0004b2900, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/ctx.go:583 +0xc9
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).handleSessionRequests(0xc0009f1440, 0x2c2b600, 0xc00043eec0, 0xc0002d8af0, 0xc0003ba9f0, 0x9, 0xc000211520, 0x3, 0xc00151b800, 0x74e, ...)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1255 +0x9d1
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleNewChan.func1(0xc0009f1440, 0x2c2b600, 0xc00043eec0, 0xc0002d8af0, 0xc000857170, 0x2c3fa20, 0xc000a4e240, 0xc000530a20, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1038 +0xf9
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleNewChan
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1037 +0xc8c
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 552 [select, 1260 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/events.(*ProtoStream).Close(0xc000a8c120, 0x2c2b600, 0xc0004b5c40, 0xc000ba15d8, 0xc000ba14e8)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/events/stream.go:415 +0x106
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/events.(*TeeStream).Close(0xc000a36b20, 0x2c2b600, 0xc0004b5c40, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/events/emitter.go:449 +0x48
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/events.(*CheckingStream).Close(0xc00043bdd0, 0x2c2b600, 0xc0004b5c40, 0xc000ba14d8, 0xe22b3d)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/events/emitter.go:357 +0x47
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/events.(*AuditWriter).recoverStream(0xc000820700, 0x2743378, 0x37)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/events/auditwriter.go:277 +0x64
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/events.(*AuditWriter).processEvents(0xc000820700)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/events/auditwriter.go:260 +0x819
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/events.NewAuditWriter
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/events/auditwriter.go:57 +0x41d
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 556 [select]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*session).heartbeat(0xc0009a2fd0, 0xc0004b2900)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:1163 +0x275
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv.(*session).startInteractive
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:777 +0xcb4
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 557 [select, 1260 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/events.(*AuditWriter).EmitAuditEvent(0xc000820700, 0x2c2b600, 0xc0004b5c40, 0x2c5aa40, 0xc000eeb110, 0xed733d0ee, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/events/auditwriter.go:194 +0x18c
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/events.(*AuditWriter).Write(0xc000820700, 0xc000a9e000, 0x1b, 0x8000, 0xffffffffffffffff, 0xc00091fd80, 0x27cc8e0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/events/auditwriter.go:173 +0x2a0
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*multiWriter).Write(0xc00091fd80, 0xc000a9e000, 0x1b, 0x8000, 0x1b, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:1302 +0x10a
Nov 04 15:30:48 zeus teleport[3547317]: io.copyBuffer(0x2bc51e0, 0xc00091fd80, 0x2bc9720, 0xc0004150c0, 0xc000a9e000, 0x8000, 0x8000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:407 +0x1fb
Nov 04 15:30:48 zeus teleport[3547317]: io.Copy(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:364
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*session).startInteractive.func1(0xc0009a2fd0, 0xc00072e1c0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:789 +0xdf
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv.(*session).startInteractive
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:786 +0xd2f
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 558 [syscall, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: syscall.Syscall6(0xf7, 0x1, 0x362630, 0xc000a85cf8, 0x1000004, 0x0, 0x0, 0x2330ba0, 0xc0003bc4b0, 0xc0008bcf00)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/syscall/asm_linux_amd64.s:41 +0x5
Nov 04 15:30:48 zeus teleport[3547317]: os.(*Process).blockUntilWaitable(0xc000223e60, 0x41caf6, 0xc0008b6318, 0x8)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/os/wait_waitid.go:31 +0x98
Nov 04 15:30:48 zeus teleport[3547317]: os.(*Process).wait(0xc000223e60, 0x10, 0xc000a85ec0, 0x41caf6)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/os/exec_unix.go:22 +0x39
Nov 04 15:30:48 zeus teleport[3547317]: os.(*Process).Wait(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/os/exec.go:125
Nov 04 15:30:48 zeus teleport[3547317]: os/exec.(*Cmd).Wait(0xc000792b00, 0xc0003bbef6, 0x26be60f)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/os/exec/exec.go:507 +0x60
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*terminal).Wait(0xc0002d9110, 0x5, 0xc0008dc178, 0x2257ea0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/term.go:204 +0x33
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*session).startInteractive.func2(0xc0009a2fd0, 0xc0004b2900, 0xc00072e1c0, 0xc00023b700)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:804 +0x4c
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv.(*session).startInteractive
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:803 +0xd7b
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 559 [chan receive, 2812 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*session).startInteractive.func3(0xc0009a2fd0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:841 +0x3c
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv.(*session).startInteractive
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:840 +0xda0
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 782324 [select, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/sshutils.(*Server).HandleConnection(0xc00025ba40, 0x2c4b0c0, 0xc000dde630)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/sshutils/server.go:482 +0x837
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleConnection(0xc0009f1440, 0x2c4b0c0, 0xc000dde630)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:289 +0x43
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/reversetunnel.(*transport).start(0xc000a4ea80)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/transport.go:255 +0x60e
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/reversetunnel.(*Agent).processRequests
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:443 +0x752
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 782032 [chan receive, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readPacket(0xc000689e40, 0xc000906de0, 0x27, 0x27, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:187 +0x4e
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*mux).onePacket(0xc0002767e0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:215 +0x37
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*mux).loop(0xc0002767e0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:190 +0x35
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newMux
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:128 +0x153
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 777376 [chan receive, 4 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*Client).handleGlobalRequests(0xc001040320, 0xc000789c20)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:138 +0x6d
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.NewClient
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:59 +0x97
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 777375 [chan receive]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readPacket(0xc000d2a2c0, 0xc0016701e0, 0xed, 0xed, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:187 +0x4e
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*mux).onePacket(0xc000127650, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:215 +0x37
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*mux).loop(0xc000127650)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:190 +0x35
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newMux
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:128 +0x153
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 740064 [chan receive, 153 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*session).startInteractive.func3(0xc0009220b0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:841 +0x3c
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv.(*session).startInteractive
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:840 +0xda0
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 782368 [select, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.StartKeepAliveLoop(0xc0013a25a0, 0x1, 0x1, 0x45d964b800, 0x3, 0x2c2b600, 0xc00169b340, 0xc0013a2450)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/keepalive.go:71 +0x335
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).handleSessionRequests
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1218 +0x5a5
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 740053 [select, 4 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.StartKeepAliveLoop(0xc0006f59b0, 0x1, 0x1, 0x45d964b800, 0x3, 0x2c2b600, 0xc0005b62c0, 0xc0006f5830)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/keepalive.go:71 +0x335
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).handleSessionRequests
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1218 +0x5a5
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 740015 [select, 4 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/sshutils.(*Server).HandleConnection(0xc00025ba40, 0x2c4b0c0, 0xc0009e3320)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/sshutils/server.go:482 +0x837
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleConnection(0xc0009f1440, 0x2c4b0c0, 0xc0009e3320)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:289 +0x43
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/reversetunnel.(*transport).start(0xc000a4e000)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/transport.go:255 +0x60e
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/reversetunnel.(*Agent).processRequests
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:443 +0x752
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 740034 [chan receive, 4 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readPacket(0xc00038a160, 0xc00114eaa8, 0x1, 0x1, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:187 +0x4e
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*mux).onePacket(0xc000252070, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:215 +0x37
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*mux).loop(0xc000252070)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:190 +0x35
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newMux
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:128 +0x153
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 782269 [chan receive, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readPacket(0xc000cd4b00, 0xc0017ba1a0, 0xf, 0xf, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:187 +0x4e
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*mux).onePacket(0xc000254620, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:215 +0x37
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*mux).loop(0xc000254620)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:190 +0x35
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newMux
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:128 +0x153
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 740056 [select]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/events.(*sliceWriter).receiveAndUpload(0xc000e77f20)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/events/stream.go:473 +0x20c
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/events.NewProtoStream
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/events/stream.go:299 +0x3d9
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 782029 [select, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/sshutils.(*Server).HandleConnection(0xc00025ba40, 0x2c4b0c0, 0xc000bf5260)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/sshutils/server.go:482 +0x837
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleConnection(0xc0009f1440, 0x2c4b0c0, 0xc000bf5260)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:289 +0x43
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/reversetunnel.(*transport).start(0xc000232600)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/transport.go:255 +0x60e
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/reversetunnel.(*Agent).processRequests
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:443 +0x752
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 777373 [IO wait]:
Nov 04 15:30:48 zeus teleport[3547317]: internal/poll.runtime_pollWait(0x7f8029065c78, 0x72, 0xffffffffffffffff)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/netpoll.go:203 +0x55
Nov 04 15:30:48 zeus teleport[3547317]: internal/poll.(*pollDesc).wait(0xc000a45798, 0x72, 0x1000, 0x1000, 0xffffffffffffffff)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/internal/poll/fd_poll_runtime.go:87 +0x45
Nov 04 15:30:48 zeus teleport[3547317]: internal/poll.(*pollDesc).waitRead(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/internal/poll/fd_poll_runtime.go:92
Nov 04 15:30:48 zeus teleport[3547317]: internal/poll.(*FD).Read(0xc000a45780, 0xc0001b3000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/internal/poll/fd_unix.go:169 +0x19b
Nov 04 15:30:48 zeus teleport[3547317]: net.(*netFD).Read(0xc000a45780, 0xc0001b3000, 0x1000, 0x1000, 0xc000082cd0, 0x10, 0x10)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/net/fd_unix.go:202 +0x4f
Nov 04 15:30:48 zeus teleport[3547317]: net.(*conn).Read(0xc00078ed78, 0xc0001b3000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/net/net.go:184 +0x8e
Nov 04 15:30:48 zeus teleport[3547317]: bufio.(*Reader).Read(0xc0007897a0, 0xc000c2f5a0, 0x4, 0x4, 0x1000000633483fb, 0xc000337000, 0xc000337100)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/bufio/bufio.go:226 +0x24f
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadAtLeast(0x2bc38e0, 0xc0007897a0, 0xc000c2f5a0, 0x4, 0x4, 0x4, 0x10000000000000c, 0x0, 0xed)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:310 +0x87
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadFull(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:329
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*gcmCipher).readCipherPacket(0xc000c2f590, 0xc0000008bc, 0x2bc38e0, 0xc0007897a0, 0xc000337001, 0xed, 0xa8f, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/cipher.go:373 +0x83
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*connectionState).readPacket(0xc000828a20, 0xc0007897a0, 0xc000082f30, 0x415903, 0xc0007898c0, 0xc000ffa240, 0xc000082fb0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:130 +0x68
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*transport).readPacket(0xc000828a20, 0xc0016701e0, 0xed, 0xed, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:114 +0x37
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readOnePacket(0xc000d2a2c0, 0xc000082f00, 0xc0016701e0, 0xed, 0xed, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:374 +0x50
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readLoop(0xc000d2a2c0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:197 +0x4d
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newClientTransport
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:134 +0x181
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 782110 [semacquire, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: sync.runtime_SemacquireMutex(0xc000a49234, 0xc000a0c400, 0x1)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/sema.go:71 +0x47
Nov 04 15:30:48 zeus teleport[3547317]: sync.(*Mutex).lockSlow(0xc000a49230)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/sync/mutex.go:138 +0xfc
Nov 04 15:30:48 zeus teleport[3547317]: sync.(*Mutex).Lock(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/sync/mutex.go:81
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*SessionRegistry).addSession(0xc000a49230, 0xc0009a3760)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:97 +0xd9
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*SessionRegistry).OpenSession(0xc000a49230, 0x2c41100, 0xc000c1dbe0, 0xc00091e800, 0xc0004b38c0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:204 +0x268
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*TermHandlers).HandleShell(0xc0006eb3a0, 0x2c41100, 0xc000c1dbe0, 0xc00091e800, 0xc0004b38c0, 0x2, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/termhandlers.go:113 +0xc3
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).dispatch(0xc0009f1440, 0x2c41100, 0xc000c1dbe0, 0xc00091e800, 0xc0004b38c0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1318 +0x67e
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).handleSessionRequests(0xc0009f1440, 0x2c2b600, 0xc0004b48c0, 0xc000276850, 0xc000211c60, 0x9, 0xc001644ed8, 0x3, 0xc000bf8000, 0x74e, ...)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1257 +0x791
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleNewChan.func1(0xc0009f1440, 0x2c2b600, 0xc0004b48c0, 0xc000276850, 0xc000857dd0, 0x2c3fa20, 0xc000a4e780, 0xc000e91b00, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1038 +0xf9
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleNewChan
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1037 +0xc8c
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 777396 [select]:
Nov 04 15:30:48 zeus teleport[3547317]: net/http.(*persistConn).writeLoop(0xc000828900)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/net/http/transport.go:2277 +0x11c
Nov 04 15:30:48 zeus teleport[3547317]: created by net/http.(*Transport).dialConn
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/net/http/transport.go:1648 +0xc7b
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 740060 [sync.Cond.Wait, 74 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: runtime.goparkunlock(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/proc.go:310
Nov 04 15:30:48 zeus teleport[3547317]: sync.runtime_notifyListWait(0xc000220d90, 0x8)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 04 15:30:48 zeus teleport[3547317]: sync.(*Cond).Wait(0xc000220d80)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*buffer).Read(0xc00102c7c0, 0xc0007a2000, 0x8000, 0x8000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/buffer.go:94 +0x1de
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*channel).ReadExtended(0xc0004d0b40, 0xc0007a2000, 0x8000, 0x8000, 0xc000000000, 0x4aa072, 0xc000e77b00, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:351 +0x1e5
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*channel).Read(0xc0004d0b40, 0xc0007a2000, 0x8000, 0x8000, 0x4673e9, 0x2d649703ebe189, 0x3b40d1f8)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:528 +0x5b
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.trackingChannel.Read(0x2c3fa20, 0xc0004d0b40, 0x2be3400, 0xc0009f0900, 0xc0007a2000, 0x8000, 0x8000, 0x4, 0xc0001ff5c0, 0x27cc8c8)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/monitor.go:226 +0x5c
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*party).Read(0xc0001ff5c0, 0xc0007a2000, 0x8000, 0x8000, 0x4, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:1369 +0x60
Nov 04 15:30:48 zeus teleport[3547317]: io.copyBuffer(0x2bc9760, 0xc00022d460, 0x2bc5220, 0xc0001ff5c0, 0xc0007a2000, 0x8000, 0x8000, 0xc0009ceb80, 0xc000fb07b8, 0xadd3a4)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:405 +0x122
Nov 04 15:30:48 zeus teleport[3547317]: io.Copy(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:364
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*session).addParty.func2(0xc0009220b0, 0xc0001ff5c0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:1227 +0xd7
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv.(*session).addParty
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:1225 +0x52b
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 782268 [select, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).kexLoop(0xc000cd4b00)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:268 +0xfa
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newServerTransport
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:143 +0x105
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 782267 [sync.Cond.Wait, 2 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: runtime.goparkunlock(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/proc.go:310
Nov 04 15:30:48 zeus teleport[3547317]: sync.runtime_notifyListWait(0xc000d86d10, 0xb)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 04 15:30:48 zeus teleport[3547317]: sync.(*Cond).Wait(0xc000d86d00)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*buffer).Read(0xc000e472c0, 0xc000aff000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/buffer.go:94 +0x1de
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*channel).ReadExtended(0xc000a4e9c0, 0xc000aff000, 0x1000, 0x1000, 0x0, 0x34c, 0xc0011458a8, 0x4673e9)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:351 +0x1e5
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*channel).Read(0xc000a4e9c0, 0xc000aff000, 0x1000, 0x1000, 0x4a1190, 0xc0011458e0, 0x384)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:528 +0x5b
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/utils.(*TimeoutConn).Read(0xc000dde660, 0xc000aff000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/utils/timeout.go:58 +0xa1
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/utils.(*TrackingConn).Read(0xc000e473c0, 0xc000aff000, 0x1000, 0x1000, 0x34c, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/utils/conn.go:130 +0x55
Nov 04 15:30:48 zeus teleport[3547317]: io.(*multiReader).Read(0xc001c71e00, 0xc000aff000, 0x1000, 0x1000, 0x34c, 0x79280f, 0xc001145aa8)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/multi.go:26 +0xac
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/sshutils.(*connectionWrapper).Read(0xc000dde690, 0xc000aff000, 0x1000, 0x1000, 0xc001145c20, 0xc001145d40, 0x20)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/sshutils/server.go:640 +0x6c6
Nov 04 15:30:48 zeus teleport[3547317]: bufio.(*Reader).Read(0xc000f05440, 0xc0011f0e00, 0x4, 0x684, 0xc35ca542c52b946b, 0x20, 0xc001145c20)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/bufio/bufio.go:226 +0x24f
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadAtLeast(0x2bc38e0, 0xc000f05440, 0xc0011f0e00, 0x4, 0x684, 0x4, 0x20, 0xc001145d10, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:310 +0x87
Nov 04 15:30:48 zeus teleport[3547317]: io.ReadFull(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:329
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*chacha20Poly1305Cipher).readCipherPacket(0xc000f057a0, 0xc000000011, 0x2bc38e0, 0xc000f05440, 0xc0011f0e05, 0xf, 0x67f, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/cipher.go:675 +0x21b
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*connectionState).readPacket(0xc000ce3680, 0xc000f05440, 0xc001145f30, 0x4158a2, 0xc000f05618, 0xc0011866a8, 0xc001145fb0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:130 +0x68
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*transport).readPacket(0xc000ce3680, 0xc0017ba1a0, 0xf, 0xf, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:114 +0x37
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readOnePacket(0xc000cd4b00, 0xc001145f00, 0xc0017ba1a0, 0xf, 0xf, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:374 +0x50
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*handshakeTransport).readLoop(0xc000cd4b00)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:197 +0x4d
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.newServerTransport
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:142 +0xe3
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 777394 [sync.Cond.Wait, 20 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: runtime.goparkunlock(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/proc.go:310
Nov 04 15:30:48 zeus teleport[3547317]: sync.runtime_notifyListWait(0xc0008c1590, 0xc000000000)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 04 15:30:48 zeus teleport[3547317]: sync.(*Cond).Wait(0xc0008c1580)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*mux).Wait(0xc000127650, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:110 +0x84
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.NewClient.func1(0xc001040320)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:62 +0x33
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.NewClient
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:61 +0xe5
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 777395 [sync.Cond.Wait]:
Nov 04 15:30:48 zeus teleport[3547317]: runtime.goparkunlock(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/proc.go:310
Nov 04 15:30:48 zeus teleport[3547317]: sync.runtime_notifyListWait(0xc0008c1610, 0xc000000457)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 04 15:30:48 zeus teleport[3547317]: sync.(*Cond).Wait(0xc0008c1600)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*buffer).Read(0xc001a3ddc0, 0xc0001d0800, 0x15d3, 0x15d3, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/buffer.go:94 +0x1de
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*channel).ReadExtended(0xc000232840, 0xc0001d0800, 0x15d3, 0x15d3, 0x0, 0x15d3, 0x203000, 0xcf)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:351 +0x1e5
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*channel).Read(0xc000232840, 0xc0001d0800, 0x15d3, 0x15d3, 0x12, 0xf44, 0x150a)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:528 +0x5b
Nov 04 15:30:48 zeus teleport[3547317]: crypto/tls.(*atLeastReader).Read(0xc0004a7120, 0xc0001d0800, 0x15d3, 0x15d3, 0x0, 0xc000ec1a40, 0xc000b9f918)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/crypto/tls/conn.go:760 +0x60
Nov 04 15:30:48 zeus teleport[3547317]: bytes.(*Buffer).ReadFrom(0xc0010f4958, 0x2bc3b20, 0xc0004a7120, 0x41aa45, 0x237eee0, 0x2559aa0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/bytes/buffer.go:204 +0xb1
Nov 04 15:30:48 zeus teleport[3547317]: crypto/tls.(*Conn).readFromUntil(0xc0010f4700, 0x7f8029057388, 0xc000bf5c20, 0x5, 0xc000bf5c20, 0xc0006ea2e0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/crypto/tls/conn.go:782 +0xec
Nov 04 15:30:48 zeus teleport[3547317]: crypto/tls.(*Conn).readRecordOrCCS(0xc0010f4700, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/crypto/tls/conn.go:589 +0x115
Nov 04 15:30:48 zeus teleport[3547317]: crypto/tls.(*Conn).readRecord(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/crypto/tls/conn.go:557
Nov 04 15:30:48 zeus teleport[3547317]: crypto/tls.(*Conn).Read(0xc0010f4700, 0xc000db4000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/crypto/tls/conn.go:1233 +0x15b
Nov 04 15:30:48 zeus teleport[3547317]: net/http.(*persistConn).Read(0xc000828900, 0xc000db4000, 0x1000, 0x1000, 0xc000d157a0, 0xc000b9fc70, 0x4153e5)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/net/http/transport.go:1825 +0x75
Nov 04 15:30:48 zeus teleport[3547317]: bufio.(*Reader).fill(0xc000ec1a40)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/bufio/bufio.go:100 +0x103
Nov 04 15:30:48 zeus teleport[3547317]: bufio.(*Reader).Peek(0xc000ec1a40, 0x1, 0x0, 0x0, 0x1, 0xc0008d7800, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/bufio/bufio.go:138 +0x4f
Nov 04 15:30:48 zeus teleport[3547317]: net/http.(*persistConn).readLoop(0xc000828900)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/net/http/transport.go:1978 +0x1a8
Nov 04 15:30:48 zeus teleport[3547317]: created by net/http.(*Transport).dialConn
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/net/http/transport.go:1647 +0xc56
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 740062 [syscall]:
Nov 04 15:30:48 zeus teleport[3547317]: syscall.Syscall(0x0, 0x10, 0xc000926000, 0x8000, 0x4aa225, 0x7f802801b7b0, 0x72)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/syscall/asm_linux_amd64.s:18 +0x5
Nov 04 15:30:48 zeus teleport[3547317]: syscall.read(0x10, 0xc000926000, 0x8000, 0x8000, 0x0, 0x1b, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/syscall/zsyscall_linux_amd64.go:686 +0x5a
Nov 04 15:30:48 zeus teleport[3547317]: syscall.Read(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/syscall/syscall_unix.go:189
Nov 04 15:30:48 zeus teleport[3547317]: internal/poll.(*FD).Read(0xc000e77b00, 0xc000926000, 0x8000, 0x8000, 0x0, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/internal/poll/fd_unix.go:165 +0x133
Nov 04 15:30:48 zeus teleport[3547317]: os.(*File).read(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/os/file_unix.go:263
Nov 04 15:30:48 zeus teleport[3547317]: os.(*File).Read(0xc00022d460, 0xc000926000, 0x8000, 0x8000, 0x1b, 0x0, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/os/file.go:116 +0x71
Nov 04 15:30:48 zeus teleport[3547317]: io.copyBuffer(0x2bc51e0, 0xc00169a440, 0x2bc9720, 0xc00022d460, 0xc000926000, 0x8000, 0x8000, 0x0, 0x1, 0x0)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:405 +0x122
Nov 04 15:30:48 zeus teleport[3547317]: io.Copy(...)
Nov 04 15:30:48 zeus teleport[3547317]:         /opt/go/src/io/io.go:364
Nov 04 15:30:48 zeus teleport[3547317]: github.com/gravitational/teleport/lib/srv.(*session).startInteractive.func1(0xc0009220b0, 0xc000f24150)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:789 +0xdf
Nov 04 15:30:48 zeus teleport[3547317]: created by github.com/gravitational/teleport/lib/srv.(*session).startInteractive
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:786 +0xd2f
Nov 04 15:30:48 zeus teleport[3547317]: goroutine 777377 [chan receive, 20 minutes]:
Nov 04 15:30:48 zeus teleport[3547317]: golang.org/x/crypto/ssh.(*Client).handleChannelOpens(0xc001040320, 0xc000789b60)
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:147 +0x52
Nov 04 15:30:48 zeus teleport[3547317]: created by golang.org/x/crypto/ssh.NewClient
Nov 04 15:30:48 zeus teleport[3547317]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:60 +0xc3

@klizhentas
Copy link
Contributor Author

@webvictim do you use the iot mode? I wonder if the broken SSH tunnel info never gets propagated to the new transport

@klizhentas
Copy link
Contributor Author

klizhentas commented Nov 5, 2020

@webvictim can you also set env vars on the node that hangs

GRPC_GO_LOG_VERBOSITY_LEVEL=99 GRPC_GO_LOG_SEVERITY_LEVEL=info

You should see a lot of GRPC state logs

@klizhentas
Copy link
Contributor Author

grpc/grpc-go#2653

@klizhentas
Copy link
Contributor Author

grpc/grpc-go#2636

@klizhentas
Copy link
Contributor Author

@klizhentas
Copy link
Contributor Author

Hanging connections: grpc/grpc-go#3980

@klizhentas
Copy link
Contributor Author

Check keepalives server vs client:

grpc/grpc-go#3837 (comment)

@webvictim
Copy link
Contributor

@klizhentas Yes, I use IoT mode. I’ll set the variables and see if I can get extra debugging info.

@webvictim
Copy link
Contributor

@klizhentas I set those variables in Teleport's environment (via systemd) and restarted, but I can't see anything different in my log output. What should I be looking for?

klizhentas added a commit that referenced this issue Nov 6, 2020
This commit fixes #4695.

Teleport in async recording mode sends all events to disk,
and uploads them to the server later.

It uploads some events synchronously to the audit log so
they show up in the global event log right away.

However if the auth server is slow, the fanout blocks the session.

This commit makes the fanout of some events to be fast,
but nonblocking and never fail so sessions will not hang
unless the disk writes hang.

It also adds ability to debug GRPC connection state
when running in debug mode.

To start sending GRPC connection state logs,
set environment variables:

GRPC_GO_LOG_SEVERITY_LEVEL=info GRPC_GO_LOG_VERBOSITY_LEVEL=99 teleport start -d
@klizhentas
Copy link
Contributor Author

@webvictim the variables were ignores, see my PR, I fixed them in debug buld

klizhentas added a commit that referenced this issue Nov 9, 2020
This commit fixes #4695.

Teleport in async recording mode sends all events to disk,
and uploads them to the server later.

It uploads some events synchronously to the audit log so
they show up in the global event log right away.

However if the auth server is slow, the fanout blocks the session.

This commit makes the fanout of some events to be fast,
but nonblocking and never fail so sessions will not hang
unless the disk writes hang.

It adds a backoff period and timeout after which some
events will be lost, but session will continue without locking.

It also adds ability to debug GRPC connection state
when running in debug mode.

To start sending GRPC connection state logs,
set environment variables:

GRPC_GO_LOG_SEVERITY_LEVEL=info GRPC_GO_LOG_VERBOSITY_LEVEL=99 teleport start -d
klizhentas added a commit that referenced this issue Nov 12, 2020
This commit fixes #4695.

Teleport in async recording mode sends all events to disk,
and uploads them to the server later.

It uploads some events synchronously to the audit log so
they show up in the global event log right away.

However if the auth server is slow, the fanout blocks the session.

This commit makes the fanout of some events to be fast,
but nonblocking and never fail so sessions will not hang
unless the disk writes hang.

It adds a backoff period and timeout after which some
events will be lost, but session will continue without locking.
klizhentas added a commit that referenced this issue Nov 12, 2020
This commit fixes #4695.

Teleport in async recording mode sends all events to disk,
and uploads them to the server later.

It uploads some events synchronously to the audit log so
they show up in the global event log right away.

However if the auth server is slow, the fanout blocks the session.

This commit makes the fanout of some events to be fast,
but nonblocking and never fail so sessions will not hang
unless the disk writes hang.

It adds a backoff period and timeout after which some
events will be lost, but session will continue without locking.
klizhentas added a commit that referenced this issue Nov 14, 2020
This commit fixes #4695.

Teleport in async recording mode sends all events to disk,
and uploads them to the server later.

It uploads some events synchronously to the audit log so
they show up in the global event log right away.

However if the auth server is slow, the fanout blocks the session.

This commit makes the fanout of some events to be fast,
but nonblocking and never fail so sessions will not hang
unless the disk writes hang.

It adds a backoff period and timeout after which some
events will be lost, but session will continue without locking.
russjones pushed a commit that referenced this issue Nov 14, 2020
This commit fixes #4695.

Teleport in async recording mode sends all events to disk,
and uploads them to the server later.

It uploads some events synchronously to the audit log so
they show up in the global event log right away.

However if the auth server is slow, the fanout blocks the session.

This commit makes the fanout of some events to be fast,
but nonblocking and never fail so sessions will not hang
unless the disk writes hang.

It adds a backoff period and timeout after which some
events will be lost, but session will continue without locking.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

Successfully merging a pull request may close this issue.

2 participants