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

Fan out events in async mode for async recordings. #4696

Merged
merged 1 commit into from
Nov 12, 2020

Conversation

klizhentas
Copy link
Contributor

@klizhentas klizhentas commented Nov 4, 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

lib/events/api.go Outdated Show resolved Hide resolved
lib/events/emitter.go Show resolved Hide resolved
lib/events/emitter.go Outdated Show resolved Hide resolved
lib/events/emitter.go Outdated Show resolved Hide resolved
lib/events/emitter.go Outdated Show resolved Hide resolved
lib/events/emitter.go Outdated Show resolved Hide resolved
@klizhentas
Copy link
Contributor Author

@webvictim can you try this commit on your hanging server please? Please also set the environment variables to show connection state:

GRPC_GO_LOG_SEVERITY_LEVEL=info GRPC_GO_LOG_VERBOSITY_LEVEL=99 teleport start -d

lib/events/emitter_test.go Show resolved Hide resolved
lib/service/override.go Outdated Show resolved Hide resolved
lib/service/service.go Outdated Show resolved Hide resolved
lib/events/emitter.go Show resolved Hide resolved
lib/events/emitter_test.go Outdated Show resolved Hide resolved
lib/events/emitter_test.go Outdated Show resolved Hide resolved
lib/events/emitter_test.go Outdated Show resolved Hide resolved
lib/events/emitter_test.go Outdated Show resolved Hide resolved
lib/service/service.go Show resolved Hide resolved
lib/service/service.go Outdated Show resolved Hide resolved
lib/srv/sess.go Outdated Show resolved Hide resolved
@klizhentas
Copy link
Contributor Author

klizhentas commented Nov 9, 2020

@awly @a-palchikov @fspmarshall @russjones hey folks, please take another look. I have added some defensive logic to make sure SSH connections never hang due to audit at the expense of loosing a portion of the events.

I've been struggling to find a balance between security and audits, but decided to get on the side use not being waken up with S1s over hanging sessions. To compensate this, I propose #4755 in 5.1

@klizhentas
Copy link
Contributor Author

@webvictim can you give this branch (fresh bulid) another try? It should never hang your server

lib/events/auditwriter.go Show resolved Hide resolved
lib/events/auditwriter.go Outdated Show resolved Hide resolved
lib/events/auditwriter.go Outdated Show resolved Hide resolved
lib/events/auditwriter.go Show resolved Hide resolved
lib/events/auditwriter.go Show resolved Hide resolved
lib/events/stream_test.go Outdated Show resolved Hide resolved
lib/events/stream_test.go Outdated Show resolved Hide resolved
lib/service/service.go Outdated Show resolved Hide resolved
lib/service/override.go Outdated Show resolved Hide resolved
lib/service/service.go Outdated Show resolved Hide resolved
@webvictim
Copy link
Contributor

@klizhentas I have just deployed your second update - I'll let you know. The previous branch that you asked me to try before seemed stable for a while, but then when I tried to log in again today after a couple of days idle it turned out it was hung again.

Here's the USR1 output from before I redeployed:

Nov 09 13:15:38 zeus teleport[853588]: INFO [PROC:1]    Got signal "user defined signal 1", logging diagostic info to stderr. service/signals.go:100
Nov 09 13:15:38 zeus teleport[853588]: Runtime stats
Nov 09 13:15:38 zeus teleport[853588]: goroutines: 92
Nov 09 13:15:38 zeus teleport[853588]: OS threads: 25
Nov 09 13:15:38 zeus teleport[853588]: GOMAXPROCS: 8
Nov 09 13:15:38 zeus teleport[853588]: num CPU: 8
Nov 09 13:15:38 zeus teleport[853588]: Memory stats
Nov 09 13:15:38 zeus teleport[853588]: alloc: 13886848
Nov 09 13:15:38 zeus teleport[853588]: total-alloc: 14942936128
Nov 09 13:15:38 zeus teleport[853588]: sys: 74727680
Nov 09 13:15:38 zeus teleport[853588]: lookups: 0
Nov 09 13:15:38 zeus teleport[853588]: mallocs: 128871025
Nov 09 13:15:38 zeus teleport[853588]: frees: 128762301
Nov 09 13:15:38 zeus teleport[853588]: heap-alloc: 13886848
Nov 09 13:15:38 zeus teleport[853588]: heap-sys: 64847872
Nov 09 13:15:38 zeus teleport[853588]: heap-idle: 48619520
Nov 09 13:15:38 zeus teleport[853588]: heap-in-use: 16228352
Nov 09 13:15:38 zeus teleport[853588]: heap-released: 47087616
Nov 09 13:15:38 zeus teleport[853588]: heap-objects: 108724
Nov 09 13:15:38 zeus teleport[853588]: stack-in-use: 2260992
Nov 09 13:15:38 zeus teleport[853588]: stack-sys: 2260992
Nov 09 13:15:38 zeus teleport[853588]: stack-mspan-inuse: 266288
Nov 09 13:15:38 zeus teleport[853588]: stack-mspan-sys: 393216
Nov 09 13:15:38 zeus teleport[853588]: stack-mcache-inuse: 13888
Nov 09 13:15:38 zeus teleport[853588]: stack-mcache-sys: 16384
Nov 09 13:15:38 zeus teleport[853588]: other-sys: 1633847
Nov 09 13:15:38 zeus teleport[853588]: gc-sys: 3811592
Nov 09 13:15:38 zeus teleport[853588]: next-gc: when heap-alloc >= 15682912
Nov 09 13:15:38 zeus teleport[853588]: last-gc: 2020-11-09 13:14:00.929223099 -0400 AST
Nov 09 13:15:38 zeus teleport[853588]: gc-pause-total: 207.600536ms
Nov 09 13:15:38 zeus teleport[853588]: gc-pause: 73418
Nov 09 13:15:38 zeus teleport[853588]: num-gc: 2353
Nov 09 13:15:38 zeus teleport[853588]: enable-gc: true
Nov 09 13:15:38 zeus teleport[853588]: debug-gc: false
Nov 09 13:15:38 zeus teleport[853588]: Goroutines
Nov 09 13:15:38 zeus teleport[853588]: goroutine 1 [running, locked to thread]:
Nov 09 13:15:38 zeus teleport[853588]: runtime/pprof.writeGoroutineStacks(0x2bcae00, 0xc000010020, 0xb, 0xc000ce6150)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/pprof/pprof.go:665 +0x9d
Nov 09 13:15:38 zeus teleport[853588]: runtime/pprof.writeGoroutine(0x2bcae00, 0xc000010020, 0x2, 0xc00, 0xb)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/pprof/pprof.go:654 +0x44
Nov 09 13:15:38 zeus teleport[853588]: runtime/pprof.(*Profile).WriteTo(0x41feea0, 0x2bcae00, 0xc000010020, 0x2, 0xc000cf8000, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/pprof/pprof.go:329 +0x3da
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.goroutineDump(0x2bcae00, 0xc000010020)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/info.go:45 +0x61
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.writeDebugInfo(0x2bcae00, 0xc000010020)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/info.go:41 +0x123
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).WaitForSignals(0xc0008de1e0, 0x2c2cd60, 0xc000052040, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/signals.go:100 +0x91f
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.waitAndReload(0x2c2cd60, 0xc000052040, 0xc0003308a0, 0x11, 0xc00021c0f0, 0x4, 0xc000054240, 0x40, 0xc0008dba40, 0x1, ...)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:434 +0x79
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.Run(0x2c2cd60, 0xc000052040, 0xc0003308a0, 0x11, 0xc00021c0f0, 0x4, 0xc000054240, 0x40, 0xc0008dba40, 0x1, ...)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:421 +0x176
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/tool/teleport/common.OnStart(...)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/tool/teleport/common/teleport.go:188
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/tool/teleport/common.Run(0xc00004e0d0, 0x3, 0x3, 0x0, 0x0, 0x0, 0xc0000a8058)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/tool/teleport/common/teleport.go:165 +0x2c19
Nov 09 13:15:38 zeus teleport[853588]: main.main()
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/tool/teleport/main.go:26 +0x69
Nov 09 13:15:38 zeus teleport[853588]: goroutine 41 [chan receive]:
Nov 09 13:15:38 zeus teleport[853588]: k8s.io/klog/v2.(*loggingT).flushDaemon(0x42a5ca0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/k8s.io/klog/v2/klog.go:1131 +0x8b
Nov 09 13:15:38 zeus teleport[853588]: created by k8s.io/klog/v2.init.0
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/k8s.io/klog/v2/klog.go:416 +0xd6
Nov 09 13:15:38 zeus teleport[853588]: goroutine 31 [select, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: database/sql.(*DB).connectionOpener(0xc000247ec0, 0x2c2cd20, 0xc00023fa40)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/database/sql/sql.go:1052 +0xe8
Nov 09 13:15:38 zeus teleport[853588]: created by database/sql.OpenDB
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/database/sql/sql.go:722 +0x15d
Nov 09 13:15:38 zeus teleport[853588]: goroutine 98 [select]:
Nov 09 13:15:38 zeus teleport[853588]: go.opencensus.io/stats/view.(*worker).start(0xc00011e180)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/go.opencensus.io/stats/view/worker.go:276 +0x100
Nov 09 13:15:38 zeus teleport[853588]: created by go.opencensus.io/stats/view.init.0
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/go.opencensus.io/stats/view/worker.go:34 +0x68
Nov 09 13:15:38 zeus teleport[853588]: goroutine 30 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).fanOut(0xc000247e00)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:400 +0x17d
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/service.NewSupervisor
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:164 +0x25e
Nov 09 13:15:38 zeus teleport[853588]: goroutine 32 [select, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: database/sql.(*DB).connectionResetter(0xc000247ec0, 0x2c2cd20, 0xc00023fa40)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/database/sql/sql.go:1065 +0xfb
Nov 09 13:15:38 zeus teleport[853588]: created by database/sql.OpenDB
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/database/sql/sql.go:723 +0x193
Nov 09 13:15:38 zeus teleport[853588]: goroutine 294 [select, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).kexLoop(0xc0000e0f20)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:268 +0xfa
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.newClientTransport
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:135 +0x1a3
Nov 09 13:15:38 zeus teleport[853588]: goroutine 198 [sync.Cond.Wait, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: runtime.goparkunlock(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/proc.go:310
Nov 09 13:15:38 zeus teleport[853588]: sync.runtime_notifyListWait(0xc0005cdc90, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 09 13:15:38 zeus teleport[853588]: sync.(*Cond).Wait(0xc0005cdc80)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*mux).Wait(0xc0002c01c0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:110 +0x84
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.NewClient.func1(0xc000226a00)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:62 +0x33
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.NewClient
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:61 +0xe5
Nov 09 13:15:38 zeus teleport[853588]: goroutine 110 [select, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: database/sql.(*DB).connectionOpener(0xc000450480, 0x2c2cd20, 0xc0004355c0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/database/sql/sql.go:1052 +0xe8
Nov 09 13:15:38 zeus teleport[853588]: created by database/sql.OpenDB
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/database/sql/sql.go:722 +0x15d
Nov 09 13:15:38 zeus teleport[853588]: goroutine 191 [chan receive, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.(*addrConn).resetTransport(0xc0000e4840)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/clientconn.go:1179 +0x6ea
Nov 09 13:15:38 zeus teleport[853588]: created by google.golang.org/grpc.(*addrConn).connect
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/clientconn.go:815 +0x128
Nov 09 13:15:38 zeus teleport[853588]: goroutine 197 [chan receive, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*Client).handleChannelOpens(0xc000226a00, 0xc000221620)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:147 +0x52
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.NewClient
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:60 +0xc3
Nov 09 13:15:38 zeus teleport[853588]: goroutine 190 [select, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.(*ccBalancerWrapper).watcher(0xc0006315c0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/balancer_conn_wrappers.go:69 +0xc2
Nov 09 13:15:38 zeus teleport[853588]: created by google.golang.org/grpc.newCCBalancerWrapper
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/balancer_conn_wrappers.go:60 +0x16d
Nov 09 13:15:38 zeus teleport[853588]: goroutine 140 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/backend/lite.(*LiteBackend).runPeriodicOperations(0xc0008dd110)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/backend/lite/periodic.go:36 +0x129
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/backend/lite.NewWithConfig
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/backend/lite/lite.go:179 +0x84c
Nov 09 13:15:38 zeus teleport[853588]: goroutine 36651 [chan receive, 4377 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*session).startInteractive.func3(0xc000c5af20)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:841 +0x3c
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv.(*session).startInteractive
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:840 +0xda0
Nov 09 13:15:38 zeus teleport[853588]: goroutine 111 [select, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: database/sql.(*DB).connectionResetter(0xc000450480, 0x2c2cd20, 0xc0004355c0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/database/sql/sql.go:1065 +0xfb
Nov 09 13:15:38 zeus teleport[853588]: created by database/sql.OpenDB
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/database/sql/sql.go:723 +0x193
Nov 09 13:15:38 zeus teleport[853588]: goroutine 143 [select, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/sshutils.(*Server).Wait(0xc0008c2700, 0x2c2cd60, 0xc000052040)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/sshutils/server.go:293 +0xde
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).Wait(...)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:283
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).initSSH.func1(0x2258f20, 0xc000574020)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:1704 +0x11fb
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalService).Serve(0xc0001e1e60, 0x26db3cc, 0x14)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:450 +0x2a
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve.func1(0xc000247e00, 0x2c2d460, 0xc0001e1e60)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:242 +0x30b
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:237 +0x6e
Nov 09 13:15:38 zeus teleport[853588]: goroutine 144 [chan receive, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).onExit.func1(0x2258f20, 0xc0001fea50)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:1327 +0xd1
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalService).Serve(0xc0001e1f00, 0x26db3cc, 0x14)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:450 +0x2a
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve.func1(0xc000247e00, 0x2c2d460, 0xc0001e1f00)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:242 +0x30b
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:237 +0x6e
Nov 09 13:15:38 zeus teleport[853588]: goroutine 145 [select, 7 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).syncRotationStateCycle(0xc0008de1e0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/connect.go:484 +0x393
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).periodicSyncRotationState(0xc0008de1e0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/connect.go:436 +0x249
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalService).Serve(0xc0001e1f60, 0x26db3cc, 0x14)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:450 +0x2a
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve.func1(0xc000247e00, 0x2c2d460, 0xc0001e1f60)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:242 +0x30b
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:237 +0x6e
Nov 09 13:15:38 zeus teleport[853588]: goroutine 146 [syscall]:
Nov 09 13:15:38 zeus teleport[853588]: os/signal.signal_recv(0x2bee7a0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/sigqueue.go:147 +0x9c
Nov 09 13:15:38 zeus teleport[853588]: os/signal.loop()
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/os/signal/signal_unix.go:23 +0x22
Nov 09 13:15:38 zeus teleport[853588]: created by os/signal.Notify.func1
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/os/signal/signal.go:127 +0x44
Nov 09 13:15:38 zeus teleport[853588]: goroutine 1253411 [select]:
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).kexLoop(0xc0008cb600)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:268 +0xfa
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.newServerTransport
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:143 +0x105
Nov 09 13:15:38 zeus teleport[853588]: goroutine 107 [chan receive, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).onExit.func1(0x2258f20, 0xc00063e520)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:1327 +0xd1
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalService).Serve(0xc0001e1640, 0x26db3cc, 0x14)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:450 +0x2a
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve.func1(0xc000247e00, 0x2c2d460, 0xc0001e1640)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:242 +0x30b
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:237 +0x6e
Nov 09 13:15:38 zeus teleport[853588]: goroutine 157 [select, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).kexLoop(0xc0007ea000)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:268 +0xfa
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.newClientTransport
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:135 +0x1a3
Nov 09 13:15:38 zeus teleport[853588]: goroutine 195 [chan receive]:
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).readPacket(0xc0007ea000, 0xc000537a20, 0x9, 0x9, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:187 +0x4e
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*mux).onePacket(0xc0002c01c0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:215 +0x37
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*mux).loop(0xc0002c01c0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:190 +0x35
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.newMux
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:128 +0x153
Nov 09 13:15:38 zeus teleport[853588]: goroutine 196 [chan receive, 3 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*Client).handleGlobalRequests(0xc000226a00, 0xc0002216e0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:138 +0x6d
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.NewClient
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:59 +0x97
Nov 09 13:15:38 zeus teleport[853588]: goroutine 156 [IO wait]:
Nov 09 13:15:38 zeus teleport[853588]: internal/poll.runtime_pollWait(0x7fce18309bc8, 0x72, 0xffffffffffffffff)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/netpoll.go:203 +0x55
Nov 09 13:15:38 zeus teleport[853588]: internal/poll.(*pollDesc).wait(0xc00011e618, 0x72, 0x1000, 0x1000, 0xffffffffffffffff)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/internal/poll/fd_poll_runtime.go:87 +0x45
Nov 09 13:15:38 zeus teleport[853588]: internal/poll.(*pollDesc).waitRead(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/internal/poll/fd_poll_runtime.go:92
Nov 09 13:15:38 zeus teleport[853588]: internal/poll.(*FD).Read(0xc00011e600, 0xc00092e000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/internal/poll/fd_unix.go:169 +0x19b
Nov 09 13:15:38 zeus teleport[853588]: net.(*netFD).Read(0xc00011e600, 0xc00092e000, 0x1000, 0x1000, 0xc000087cd0, 0x10, 0x10)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/net/fd_unix.go:202 +0x4f
Nov 09 13:15:38 zeus teleport[853588]: net.(*conn).Read(0xc000205d38, 0xc00092e000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/net/net.go:184 +0x8e
Nov 09 13:15:38 zeus teleport[853588]: bufio.(*Reader).Read(0xc000489da0, 0xc000226920, 0x4, 0x4, 0x10000001c313a9b, 0xc000932000, 0xc000932010)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/bufio/bufio.go:226 +0x24f
Nov 09 13:15:38 zeus teleport[853588]: io.ReadAtLeast(0x2bc4f60, 0xc000489da0, 0xc000226920, 0x4, 0x4, 0x4, 0x10000000000000c, 0x0, 0x9)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:310 +0x87
Nov 09 13:15:38 zeus teleport[853588]: io.ReadFull(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:329
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*gcmCipher).readCipherPacket(0xc000226910, 0xc000006992, 0x2bc4f60, 0xc000489da0, 0xc000932001, 0x9, 0x58f, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/cipher.go:373 +0x83
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*connectionState).readPacket(0xc000738fc0, 0xc000489da0, 0xc000087f30, 0x415903, 0xc000489ec0, 0xc000b67680, 0xc000087fb0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:130 +0x68
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*transport).readPacket(0xc000738fc0, 0xc000537a20, 0x9, 0x9, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:114 +0x37
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).readOnePacket(0xc0007ea000, 0xc000087f00, 0xc000537a20, 0x9, 0x9, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:374 +0x50
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).readLoop(0xc0007ea000)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:197 +0x4d
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.newClientTransport
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:134 +0x181
Nov 09 13:15:38 zeus teleport[853588]: goroutine 187 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/backend/lite.(*LiteBackend).runPeriodicOperations(0xc0004fe1a0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/backend/lite/periodic.go:36 +0x129
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/backend/lite.NewWithConfig
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/backend/lite/lite.go:179 +0x84c
Nov 09 13:15:38 zeus teleport[853588]: goroutine 159 [select]:
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc/internal/transport.(*http2Client).keepalive(0xc0008c2000)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/http2_client.go:1331 +0x193
Nov 09 13:15:38 zeus teleport[853588]: created by google.golang.org/grpc/internal/transport.newHTTP2Client
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/http2_client.go:295 +0x16cd
Nov 09 13:15:38 zeus teleport[853588]: goroutine 160 [sync.Cond.Wait]:
Nov 09 13:15:38 zeus teleport[853588]: runtime.goparkunlock(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/proc.go:310
Nov 09 13:15:38 zeus teleport[853588]: sync.runtime_notifyListWait(0xc0005cdd10, 0xc0000029f2)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 09 13:15:38 zeus teleport[853588]: sync.(*Cond).Wait(0xc0005cdd00)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*buffer).Read(0xc0004bf5a0, 0xc000178000, 0x96a, 0x96a, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/buffer.go:94 +0x1de
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*channel).ReadExtended(0xc000247440, 0xc000178000, 0x96a, 0x96a, 0x0, 0x96a, 0x203000, 0x1f)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:351 +0x1e5
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*channel).Read(0xc000247440, 0xc000178000, 0x96a, 0x96a, 0xc000178005, 0x0, 0x965)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:528 +0x5b
Nov 09 13:15:38 zeus teleport[853588]: crypto/tls.(*atLeastReader).Read(0xc0004be9e0, 0xc000178000, 0x96a, 0x96a, 0x2f, 0x965, 0xc000177a90)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/crypto/tls/conn.go:760 +0x60
Nov 09 13:15:38 zeus teleport[853588]: bytes.(*Buffer).ReadFrom(0xc0004c05d8, 0x2bc51a0, 0xc0004be9e0, 0x41aa45, 0x237ffc0, 0x255ada0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/bytes/buffer.go:204 +0xb1
Nov 09 13:15:38 zeus teleport[853588]: crypto/tls.(*Conn).readFromUntil(0xc0004c0380, 0x7fce1834d790, 0xc0008da240, 0x5, 0xc0008da240, 0x1e)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/crypto/tls/conn.go:782 +0xec
Nov 09 13:15:38 zeus teleport[853588]: crypto/tls.(*Conn).readRecordOrCCS(0xc0004c0380, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/crypto/tls/conn.go:589 +0x115
Nov 09 13:15:38 zeus teleport[853588]: crypto/tls.(*Conn).readRecord(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/crypto/tls/conn.go:557
Nov 09 13:15:38 zeus teleport[853588]: crypto/tls.(*Conn).Read(0xc0004c0380, 0xc00093c000, 0x8000, 0x8000, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/crypto/tls/conn.go:1233 +0x15b
Nov 09 13:15:38 zeus teleport[853588]: bufio.(*Reader).Read(0xc000448f00, 0xc000244118, 0x9, 0x9, 0x20, 0x18, 0x2422fe0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/bufio/bufio.go:226 +0x24f
Nov 09 13:15:38 zeus teleport[853588]: io.ReadAtLeast(0x2bc4f60, 0xc000448f00, 0xc000244118, 0x9, 0x9, 0x9, 0x100000000a808c5, 0x0, 0x9)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:310 +0x87
Nov 09 13:15:38 zeus teleport[853588]: io.ReadFull(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:329
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/net/http2.readFrameHeader(0xc000244118, 0x9, 0x9, 0x2bc4f60, 0xc000448f00, 0x0, 0xc000000000, 0x41d368, 0xc00012a100)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/net/http2/frame.go:237 +0x87
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/net/http2.(*Framer).ReadFrame(0xc0002440e0, 0xc0011218e0, 0x42a5720, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/net/http2/frame.go:492 +0xa1
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc/internal/transport.(*http2Client).reader(0xc0008c2000)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/http2_client.go:1265 +0x16f
Nov 09 13:15:38 zeus teleport[853588]: created by google.golang.org/grpc/internal/transport.newHTTP2Client
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/http2_client.go:300 +0xd23
Nov 09 13:15:38 zeus teleport[853588]: goroutine 161 [select]:
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc/internal/transport.(*controlBuffer).get(0xc00012a0f0, 0x1, 0x0, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/controlbuf.go:395 +0x122
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc/internal/transport.(*loopyWriter).run(0xc000448fc0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/controlbuf.go:513 +0x1cd
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc/internal/transport.newHTTP2Client.func3(0xc0008c2000)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/http2_client.go:346 +0x7b
Nov 09 13:15:38 zeus teleport[853588]: created by google.golang.org/grpc/internal/transport.newHTTP2Client
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/http2_client.go:344 +0xedb
Nov 09 13:15:38 zeus teleport[853588]: goroutine 293 [IO wait]:
Nov 09 13:15:38 zeus teleport[853588]: internal/poll.runtime_pollWait(0x7fce18309768, 0x72, 0xffffffffffffffff)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/netpoll.go:203 +0x55
Nov 09 13:15:38 zeus teleport[853588]: internal/poll.(*pollDesc).wait(0xc000703c98, 0x72, 0x1000, 0x1000, 0xffffffffffffffff)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/internal/poll/fd_poll_runtime.go:87 +0x45
Nov 09 13:15:38 zeus teleport[853588]: internal/poll.(*pollDesc).waitRead(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/internal/poll/fd_poll_runtime.go:92
Nov 09 13:15:38 zeus teleport[853588]: internal/poll.(*FD).Read(0xc000703c80, 0xc0005b8000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/internal/poll/fd_unix.go:169 +0x19b
Nov 09 13:15:38 zeus teleport[853588]: net.(*netFD).Read(0xc000703c80, 0xc0005b8000, 0x1000, 0x1000, 0x7fce18ea5fff, 0xc0005a9c80, 0x4284cc)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/net/fd_unix.go:202 +0x4f
Nov 09 13:15:38 zeus teleport[853588]: net.(*conn).Read(0xc0005b5580, 0xc0005b8000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/net/net.go:184 +0x8e
Nov 09 13:15:38 zeus teleport[853588]: bufio.(*Reader).Read(0xc000448420, 0xc00060e560, 0x4, 0x4, 0xc0005a9d20, 0x41c654, 0x7fce180c0c10)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/bufio/bufio.go:226 +0x24f
Nov 09 13:15:38 zeus teleport[853588]: io.ReadAtLeast(0x2bc4f60, 0xc000448420, 0xc00060e560, 0x4, 0x4, 0x4, 0x10100000000000c, 0x0, 0x4d)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:310 +0x87
Nov 09 13:15:38 zeus teleport[853588]: io.ReadFull(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:329
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*gcmCipher).readCipherPacket(0xc00060e550, 0xc000018958, 0x2bc4f60, 0xc000448420, 0xc0006f2001, 0x4d, 0x6af, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/cipher.go:373 +0x83
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*connectionState).readPacket(0xc000563440, 0xc000448420, 0xc0005a9f30, 0x415903, 0xc000448540, 0xc0004481e0, 0xc0005a9fb0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:130 +0x68
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*transport).readPacket(0xc000563440, 0xc000c04000, 0x4d, 0x4d, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:114 +0x37
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).readOnePacket(0xc0000e0f20, 0xc0005a9f00, 0xc000c04000, 0x4d, 0x4d, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:374 +0x50
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).readLoop(0xc0000e0f20)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:197 +0x4d
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.newClientTransport
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:134 +0x181
Nov 09 13:15:38 zeus teleport[853588]: goroutine 36995 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/sshutils.(*Server).HandleConnection(0xc0008c2700, 0x2c4c7e0, 0xc000649b90)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/sshutils/server.go:482 +0x837
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleConnection(0xc000958fc0, 0x2c4c7e0, 0xc000649b90)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:289 +0x43
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/reversetunnel.(*transport).start(0xc000246cc0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/transport.go:255 +0x60e
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/reversetunnel.(*Agent).processRequests
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:443 +0x752
Nov 09 13:15:38 zeus teleport[853588]: goroutine 205 [select, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.newClientStream.func5(0xc00068c380, 0xc000436ea0, 0x2c2cde0, 0xc0005a02d0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:319 +0xd7
Nov 09 13:15:38 zeus teleport[853588]: created by google.golang.org/grpc.newClientStream
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:318 +0xbbf
Nov 09 13:15:38 zeus teleport[853588]: goroutine 206 [select]:
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc/internal/transport.(*recvBufferReader).readClient(0xc000226f00, 0xc00053a590, 0x5, 0x5, 0x23bdc60, 0xc0008a0bf0, 0x10d)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:186 +0xd3
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc/internal/transport.(*recvBufferReader).Read(0xc000226f00, 0xc00053a590, 0x5, 0x5, 0xc00050e760, 0x27cdce8, 0xc000813990)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:166 +0x18a
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc/internal/transport.(*transportReader).Read(0xc0005a0360, 0xc00053a590, 0x5, 0x5, 0xc000813a4c, 0xc0008139b8, 0xd7a59c)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:479 +0x55
Nov 09 13:15:38 zeus teleport[853588]: io.ReadAtLeast(0x2bc98c0, 0xc0005a0360, 0xc00053a590, 0x5, 0x5, 0x5, 0x4bc57c, 0xc0005a02d0, 0xc00013ede0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:310 +0x87
Nov 09 13:15:38 zeus teleport[853588]: io.ReadFull(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:329
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc/internal/transport.(*Stream).Read(0xc0001c6b00, 0xc00053a590, 0x5, 0x5, 0xc000b00000, 0x2379140, 0x2639ac0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:463 +0xcd
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.(*parser).recvMsg(0xc00053a580, 0x400000, 0xc0007a3440, 0xc000182360, 0x112, 0x112, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/rpc_util.go:508 +0x63
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.recvAndDecompress(0xc00053a580, 0xc0001c6b00, 0x0, 0x0, 0x400000, 0x0, 0x0, 0x0, 0xc000182360, 0x112, ...)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/rpc_util.go:639 +0x4d
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.recv(0xc00053a580, 0x7fce182d5240, 0x42d2cd0, 0xc0001c6b00, 0x0, 0x0, 0x2639ac0, 0xc00097af40, 0x400000, 0x0, ...)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/rpc_util.go:707 +0x9b
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.(*csAttempt).recvMsg(0xc000539e80, 0x2639ac0, 0xc00097af40, 0x0, 0xc000813d38, 0x474870)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:885 +0xed
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.(*clientStream).RecvMsg.func1(0xc000539e80, 0x203000, 0x203000)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:736 +0x46
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.(*clientStream).withRetry(0xc000436ea0, 0xc000813e48, 0xc000813e18, 0x1, 0x20)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:590 +0x361
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.(*clientStream).RecvMsg(0xc000436ea0, 0x2639ac0, 0xc00097af40, 0xc000813f0c, 0x2)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:735 +0x103
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/auth/proto.(*authServiceWatchEventsClient).Recv(0xc00019bf80, 0xc000813f08, 0x2, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/auth/proto/authservice.pb.go:2198 +0x62
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/auth.(*streamWatcher).receiveEvents(0xc0007dac00)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/auth/clt.go:874 +0x4e
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/auth.(*Client).NewWatcher
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/auth/clt.go:842 +0x365
Nov 09 13:15:38 zeus teleport[853588]: goroutine 236 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/cache.(*Cache).fetchAndWatch(0xc00000c1e0, 0x2c2cd20, 0xc000435ac0, 0x2c383e0, 0xc0007daae0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/cache/cache.go:476 +0x68e
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/cache.(*Cache).update(0xc00000c1e0, 0x2c2cd20, 0xc000435ac0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/cache/cache.go:329 +0xf6
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/cache.New
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/cache/cache.go:298 +0x6db
Nov 09 13:15:38 zeus teleport[853588]: goroutine 241 [select, 4084 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/events.(*AsyncEmitter).forward(0xc00097abc0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/emitter.go:89 +0xd0
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/events.NewAsyncEmitter
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/emitter.go:68 +0x150
Nov 09 13:15:38 zeus teleport[853588]: goroutine 244 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/events.(*Uploader).Serve(0xc00058ef20, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/uploader.go:156 +0x11c
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).initUploaderService.func1(0x2258f20, 0xc0003ff3f0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:1820 +0x37
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalService).Serve(0xc000701520, 0x26db3cc, 0x14)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:450 +0x2a
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve.func1(0xc000247e00, 0x2c2d460, 0xc000701520)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:242 +0x30b
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:237 +0x6e
Nov 09 13:15:38 zeus teleport[853588]: goroutine 245 [chan receive, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).onExit.func1(0x2258f20, 0xc0003ff470)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:1327 +0xd1
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalService).Serve(0xc0007015c0, 0x26db3cc, 0x14)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:450 +0x2a
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve.func1(0xc000247e00, 0x2c2d460, 0xc0007015c0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:242 +0x30b
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:237 +0x6e
Nov 09 13:15:38 zeus teleport[853588]: goroutine 246 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/events/filesessions.(*Uploader).Serve(0xc000468dc0, 0x77fadc, 0xc0000a616c)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/filesessions/fileasync.go:181 +0x1e9
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).initUploaderService.func3(0x2258f20, 0xc000625b00)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:1845 +0x48
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalService).Serve(0xc000701660, 0x26db3cc, 0x14)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:450 +0x2a
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve.func1(0xc000247e00, 0x2c2d460, 0xc000701660)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:242 +0x30b
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:237 +0x6e
Nov 09 13:15:38 zeus teleport[853588]: goroutine 247 [chan receive, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*TeleportProcess).onExit.func1(0x2258f20, 0xc0005a2070)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/service.go:1327 +0xd1
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalService).Serve(0xc000701700, 0x26db3cc, 0x14)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:450 +0x2a
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve.func1(0xc000247e00, 0x2c2d460, 0xc000701700)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:242 +0x30b
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/service.(*LocalSupervisor).serve
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/service/supervisor.go:237 +0x6e
Nov 09 13:15:38 zeus teleport[853588]: goroutine 248 [select, 3 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).periodicUpdateLabel(0xc000958fc0, 0xc000331340, 0x12, 0x2c477a0, 0xc00012b4f0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:759 +0x181
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).updateLabels
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:733 +0xfc
Nov 09 13:15:38 zeus teleport[853588]: goroutine 249 [select, 3 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).periodicUpdateLabel(0xc000958fc0, 0xc000331440, 0x12, 0x2c477a0, 0xc00012b540)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:759 +0x181
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).updateLabels
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:733 +0xfc
Nov 09 13:15:38 zeus teleport[853588]: goroutine 250 [select, 7 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).periodicUpdateLabel(0xc000958fc0, 0xc00021c288, 0x8, 0x2c477a0, 0xc00012b590)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:759 +0x181
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).updateLabels
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:733 +0xfc
Nov 09 13:15:38 zeus teleport[853588]: goroutine 251 [select, 7 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).periodicUpdateLabel(0xc000958fc0, 0xc00021c2d0, 0x10, 0x2c477a0, 0xc00012b5e0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:759 +0x181
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).updateLabels
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:733 +0xfc
Nov 09 13:15:38 zeus teleport[853588]: goroutine 252 [select, 7 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).periodicUpdateLabel(0xc000958fc0, 0xc00021c378, 0x4, 0x2c477a0, 0xc00012b630)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:759 +0x181
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).updateLabels
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:733 +0xfc
Nov 09 13:15:38 zeus teleport[853588]: goroutine 253 [select, 7 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).periodicUpdateLabel(0xc000958fc0, 0xc00021c3c0, 0xd, 0x2c477a0, 0xc00012b680)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:759 +0x181
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).updateLabels
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:733 +0xfc
Nov 09 13:15:38 zeus teleport[853588]: goroutine 254 [select, 7 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).periodicUpdateLabel(0xc000958fc0, 0xc00021c490, 0xd, 0x2c477a0, 0xc00012b6d0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:759 +0x181
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).updateLabels
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:733 +0xfc
Nov 09 13:15:38 zeus teleport[853588]: goroutine 255 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*Heartbeat).Run(0xc00070cc60, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/heartbeat.go:251 +0x1fe
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).Start
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:257 +0x137
Nov 09 13:15:38 zeus teleport[853588]: goroutine 256 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/reversetunnel/track.(*Tracker).run(0xc00097be40, 0x2c2cd20, 0xc00097bd80)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/track/tracker.go:93 +0x122
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/reversetunnel/track.New
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/track/tracker.go:85 +0x139
Nov 09 13:15:38 zeus teleport[853588]: goroutine 257 [select, 7 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/utils/workpool.(*group).run(0xc000906300)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/utils/workpool/workpool.go:216 +0x18e
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/utils/workpool.(*Pool).start
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/utils/workpool/workpool.go:106 +0x187
Nov 09 13:15:38 zeus teleport[853588]: goroutine 258 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/reversetunnel.(*AgentPool).pollAndSyncAgents(0xc0008c6280)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agentpool.go:240 +0x13f
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/reversetunnel.(*AgentPool).Start
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agentpool.go:165 +0xfd
Nov 09 13:15:38 zeus teleport[853588]: goroutine 259 [select, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/reversetunnel.(*AgentPool).processSeekEvents(0xc0008c6280)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agentpool.go:183 +0x14f
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/reversetunnel.(*AgentPool).Start
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agentpool.go:166 +0x11f
Nov 09 13:15:38 zeus teleport[853588]: goroutine 1253433 [semacquire]:
Nov 09 13:15:38 zeus teleport[853588]: sync.runtime_SemacquireMutex(0xc000629aa4, 0xc0006f0700, 0x1)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/sema.go:71 +0x47
Nov 09 13:15:38 zeus teleport[853588]: sync.(*Mutex).lockSlow(0xc000629aa0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/sync/mutex.go:138 +0xfc
Nov 09 13:15:38 zeus teleport[853588]: sync.(*Mutex).Lock(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/sync/mutex.go:81
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*SessionRegistry).addSession(0xc000629aa0, 0xc000794210)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:97 +0xd9
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*SessionRegistry).OpenSession(0xc000629aa0, 0x2c42820, 0xc0007e3440, 0xc0009c0780, 0xc0007f5200, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:204 +0x268
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*TermHandlers).HandleShell(0xc0005b53e0, 0x2c42820, 0xc0007e3440, 0xc0009c0780, 0xc0007f5200, 0x2, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/termhandlers.go:113 +0xc3
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).dispatch(0xc000958fc0, 0x2c42820, 0xc0007e3440, 0xc0009c0780, 0xc0007f5200, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1318 +0x67e
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).handleSessionRequests(0xc000958fc0, 0x2c2cd20, 0xc000631a00, 0xc0002c0460, 0xc001227590, 0x9, 0xc001900c10, 0x3, 0xc000af8000, 0x74e, ...)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1257 +0x791
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleNewChan.func1(0xc000958fc0, 0x2c2cd20, 0xc000631a00, 0xc0002c0460, 0xc0004301b0, 0x2c41140, 0xc0007e6cc0, 0xc000f927e0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1038 +0xf9
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleNewChan
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1037 +0xc8c
Nov 09 13:15:38 zeus teleport[853588]: goroutine 212 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/reversetunnel.(*Agent).processRequests(0xc000151dc0, 0xc0006bcdc0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:400 +0x35d
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/reversetunnel.(*Agent).run.func1()
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:355 +0x121
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/reversetunnel/track.(*Tracker).WithProxy(0xc00097be40, 0xc000815f38, 0xc000906300, 0x1, 0xc0002f0d60, 0xc00089d800, 0x6, 0x6, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/track/tracker.go:187 +0x1d8
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/reversetunnel.(*Agent).run(0xc000151dc0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:364 +0x399
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/reversetunnel.(*Agent).Start
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:199 +0x3f
Nov 09 13:15:38 zeus teleport[853588]: goroutine 308 [select, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.newClientStream.func5(0xc00068c380, 0xc00077e6c0, 0x2c2cde0, 0xc0004cc9f0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:319 +0xd7
Nov 09 13:15:38 zeus teleport[853588]: created by google.golang.org/grpc.newClientStream
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:318 +0xbbf
Nov 09 13:15:38 zeus teleport[853588]: goroutine 309 [select, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc/internal/transport.(*recvBufferReader).readClient(0xc0006bc5a0, 0xc0006271d0, 0x5, 0x5, 0x456dfc, 0xc0006423b8, 0x1)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:186 +0xd3
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc/internal/transport.(*recvBufferReader).Read(0xc0006bc5a0, 0xc0006271d0, 0x5, 0x5, 0xc000617880, 0x27cdce8, 0xc00080b990)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:166 +0x18a
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc/internal/transport.(*transportReader).Read(0xc0004cca80, 0xc0006271d0, 0x5, 0x5, 0xc00080ba4c, 0xc00080b9b8, 0xd7a59c)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:479 +0x55
Nov 09 13:15:38 zeus teleport[853588]: io.ReadAtLeast(0x2bc98c0, 0xc0004cca80, 0xc0006271d0, 0x5, 0x5, 0x5, 0x4bc57c, 0xc0004cc9f0, 0xc000642420)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:310 +0x87
Nov 09 13:15:38 zeus teleport[853588]: io.ReadFull(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:329
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc/internal/transport.(*Stream).Read(0xc00054c000, 0xc0006271d0, 0x5, 0x5, 0xc00003c000, 0x2379140, 0x2639ac0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:463 +0xcd
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.(*parser).recvMsg(0xc0006271c0, 0x400000, 0xc000434ec0, 0x0, 0x0, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/rpc_util.go:508 +0x63
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.recvAndDecompress(0xc0006271c0, 0xc00054c000, 0x0, 0x0, 0x400000, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/rpc_util.go:639 +0x4d
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.recv(0xc0006271c0, 0x7fce182d5240, 0x42d2cd0, 0xc00054c000, 0x0, 0x0, 0x2639ac0, 0xc0005ccdc0, 0x400000, 0x0, ...)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/rpc_util.go:707 +0x9b
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.(*csAttempt).recvMsg(0xc0005c8f00, 0x2639ac0, 0xc0005ccdc0, 0x0, 0xc00080bd38, 0x474870)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:885 +0xed
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.(*clientStream).RecvMsg.func1(0xc0005c8f00, 0x203000, 0x203000)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:736 +0x46
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.(*clientStream).withRetry(0xc00077e6c0, 0xc00080be48, 0xc00080be18, 0x1, 0x20)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:590 +0x361
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.(*clientStream).RecvMsg(0xc00077e6c0, 0x2639ac0, 0xc0005ccdc0, 0xc00080bf0c, 0x2)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:735 +0x103
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/auth/proto.(*authServiceWatchEventsClient).Recv(0xc000117960, 0xc00080bf08, 0x2, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/auth/proto/authservice.pb.go:2198 +0x62
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/auth.(*streamWatcher).receiveEvents(0xc0004881e0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/auth/clt.go:874 +0x4e
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/auth.(*Client).NewWatcher
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/auth/clt.go:842 +0x365
Nov 09 13:15:38 zeus teleport[853588]: goroutine 1220081 [chan receive, 121 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*Client).handleChannelOpens(0xc000ad6c30, 0xc000de3620)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:147 +0x52
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.NewClient
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:60 +0xc3
Nov 09 13:15:38 zeus teleport[853588]: goroutine 346 [select, 7 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/reversetunnel.(*Agent).handleDiscovery(0xc000151dc0, 0x2c41140, 0xc000481140, 0xc00089de00)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:472 +0x15b
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/reversetunnel.(*Agent).processRequests
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:455 +0xa0d
Nov 09 13:15:38 zeus teleport[853588]: goroutine 341 [chan receive]:
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).readPacket(0xc0000e0f20, 0xc000c04000, 0x4d, 0x4d, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:187 +0x4e
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*mux).onePacket(0xc0001e65b0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:215 +0x37
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*mux).loop(0xc0001e65b0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:190 +0x35
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.newMux
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:128 +0x153
Nov 09 13:15:38 zeus teleport[853588]: goroutine 1220133 [select, 121 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).kexLoop(0xc000a42dc0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:268 +0xfa
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.newClientTransport
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:135 +0x1a3
Nov 09 13:15:38 zeus teleport[853588]: goroutine 434347 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/auth.(*streamKeepAliver).forwardKeepAlives(0xc000f93140)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/auth/clt.go:773 +0xea
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/auth.(*Client).NewKeepAliver
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/auth/clt.go:753 +0x1be
Nov 09 13:15:38 zeus teleport[853588]: goroutine 343 [chan receive]:
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*Client).handleChannelOpens(0xc0006bcdc0, 0xc00089d560)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:147 +0x52
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.NewClient
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:60 +0xc3
Nov 09 13:15:38 zeus teleport[853588]: goroutine 344 [sync.Cond.Wait, 4506 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: runtime.goparkunlock(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/proc.go:310
Nov 09 13:15:38 zeus teleport[853588]: sync.runtime_notifyListWait(0xc00097a790, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 09 13:15:38 zeus teleport[853588]: sync.(*Cond).Wait(0xc00097a780)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*mux).Wait(0xc0001e65b0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:110 +0x84
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.NewClient.func1(0xc0006bcdc0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:62 +0x33
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.NewClient
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:61 +0xe5
Nov 09 13:15:38 zeus teleport[853588]: goroutine 345 [select, 3 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/reversetunnel.(*Agent).handleGlobalRequests(0xc000151dc0, 0x2c2cd20, 0xc000631a40, 0xc00089d620)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:282 +0xe2
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/reversetunnel.(*Agent).connect
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:272 +0x58c
Nov 09 13:15:38 zeus teleport[853588]: goroutine 36650 [syscall, 4377 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: syscall.Syscall6(0xf7, 0x1, 0x216c97, 0xc00075bcf8, 0x1000004, 0x0, 0x0, 0x60, 0x60, 0x60)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/syscall/asm_linux_amd64.s:41 +0x5
Nov 09 13:15:38 zeus teleport[853588]: os.(*Process).blockUntilWaitable(0xc000a91320, 0xc000502780, 0x3, 0xc00075bde0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/os/wait_waitid.go:31 +0x98
Nov 09 13:15:38 zeus teleport[853588]: os.(*Process).wait(0xc000a91320, 0x2, 0x2, 0xc00075bf64)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/os/exec_unix.go:22 +0x39
Nov 09 13:15:38 zeus teleport[853588]: os.(*Process).Wait(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/os/exec.go:125
Nov 09 13:15:38 zeus teleport[853588]: os/exec.(*Cmd).Wait(0xc000982840, 0x60, 0x1)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/os/exec/exec.go:507 +0x60
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*terminal).Wait(0xc0003fa690, 0x27cdb30, 0xc00005a000, 0xc00075bf68)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/term.go:204 +0x33
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*session).startInteractive.func2(0xc000c5af20, 0xc0007f5680, 0xc0005b6690, 0xc0001bdb00)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:804 +0x4c
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv.(*session).startInteractive
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:803 +0xd7b
Nov 09 13:15:38 zeus teleport[853588]: goroutine 36649 [select, 2868 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/events.(*AuditWriter).EmitAuditEvent(0xc00046d200, 0x2c2cd20, 0xc00097ac00, 0x2c5c160, 0xc000f83ee0, 0xed738d030, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/auditwriter.go:194 +0x18c
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/events.(*AuditWriter).Write(0xc00046d200, 0xc000a9c000, 0x1b, 0x8000, 0xffffffffffffffff, 0xc0007a3f40, 0x27cdd00)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/auditwriter.go:173 +0x2a0
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*multiWriter).Write(0xc0007a3f40, 0xc000a9c000, 0x1b, 0x8000, 0x1b, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:1303 +0x10a
Nov 09 13:15:38 zeus teleport[853588]: io.copyBuffer(0x2bc6880, 0xc0007a3f40, 0x2bcadc0, 0xc0006b6520, 0xc000a9c000, 0x8000, 0x8000, 0x415efb, 0xc0002786c0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:407 +0x1fb
Nov 09 13:15:38 zeus teleport[853588]: io.Copy(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:364
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*session).startInteractive.func1(0xc000c5af20, 0xc0005b6690)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:789 +0xdf
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv.(*session).startInteractive
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:786 +0xd2f
Nov 09 13:15:38 zeus teleport[853588]: goroutine 1220080 [chan receive]:
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*Client).handleGlobalRequests(0xc000ad6c30, 0xc000de36e0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:138 +0x6d
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.NewClient
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:59 +0x97
Nov 09 13:15:38 zeus teleport[853588]: goroutine 37006 [chan receive, 4376 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*session).startInteractive.func3(0xc000794bb0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:841 +0x3c
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv.(*session).startInteractive
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:840 +0xda0
Nov 09 13:15:38 zeus teleport[853588]: goroutine 36991 [sync.Cond.Wait]:
Nov 09 13:15:38 zeus teleport[853588]: runtime.goparkunlock(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/proc.go:310
Nov 09 13:15:38 zeus teleport[853588]: sync.runtime_notifyListWait(0xc0001b5d10, 0x6f1)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 09 13:15:38 zeus teleport[853588]: sync.(*Cond).Wait(0xc0001b5d00)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*buffer).Read(0xc00053b200, 0xc000ae4000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/buffer.go:94 +0x1de
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*channel).ReadExtended(0xc000246c00, 0xc000ae4000, 0x1000, 0x1000, 0x0, 0x1c, 0xc000ac78a8, 0x4673e9)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:351 +0x1e5
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*channel).Read(0xc000246c00, 0xc000ae4000, 0x1000, 0x1000, 0x4a1190, 0xc000ac78e0, 0x384)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:528 +0x5b
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/utils.(*TimeoutConn).Read(0xc000649bc0, 0xc000ae4000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/utils/timeout.go:58 +0xa1
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/utils.(*TrackingConn).Read(0xc00053b3e0, 0xc000ae4000, 0x1000, 0x1000, 0x1c, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/utils/conn.go:130 +0x55
Nov 09 13:15:38 zeus teleport[853588]: io.(*multiReader).Read(0xc000700660, 0xc000ae4000, 0x1000, 0x1000, 0x1c, 0x79280f, 0xc000ac7aa8)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/multi.go:26 +0xac
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/sshutils.(*connectionWrapper).Read(0xc000649bf0, 0xc000ae4000, 0x1000, 0x1000, 0xc000ac7c20, 0xc000ac7d40, 0x20)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/sshutils/server.go:640 +0x6c6
Nov 09 13:15:38 zeus teleport[853588]: bufio.(*Reader).Read(0xc00089d8c0, 0xc000457800, 0x4, 0x684, 0x13d34adb457e6938, 0x20, 0xc000ac7c20)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/bufio/bufio.go:226 +0x24f
Nov 09 13:15:38 zeus teleport[853588]: io.ReadAtLeast(0x2bc4f60, 0xc00089d8c0, 0xc000457800, 0x4, 0x684, 0x4, 0x20, 0xc000ac7d10, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:310 +0x87
Nov 09 13:15:38 zeus teleport[853588]: io.ReadFull(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:329
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*chacha20Poly1305Cipher).readCipherPacket(0xc000de3380, 0xc0000006f8, 0x2bc4f60, 0xc00089d8c0, 0xc000457805, 0x1, 0x67f, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/cipher.go:675 +0x21b
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*connectionState).readPacket(0xc000a3c7e0, 0xc00089d8c0, 0xc000ac7f30, 0x415903, 0xc00089db60, 0xc0009041e0, 0xc000ac7fb0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:130 +0x68
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*transport).readPacket(0xc000a3c7e0, 0xc000606b80, 0x1, 0x1, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:114 +0x37
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).readOnePacket(0xc00028f340, 0xc000ac7f00, 0xc000606b80, 0x1, 0x1, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:374 +0x50
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).readLoop(0xc00028f340)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:197 +0x4d
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.newServerTransport
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:142 +0xe3
Nov 09 13:15:38 zeus teleport[853588]: goroutine 1253291 [chan receive]:
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).readPacket(0xc0008cb600, 0xc000241b90, 0x27, 0x27, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:187 +0x4e
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*mux).onePacket(0xc0002c03f0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:215 +0x37
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*mux).loop(0xc0002c03f0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:190 +0x35
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.newMux
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:128 +0x153
Nov 09 13:15:38 zeus teleport[853588]: goroutine 37005 [syscall, 4376 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: syscall.Syscall6(0xf7, 0x1, 0x218f1b, 0xc00091ecf8, 0x1000004, 0x0, 0x0, 0x60, 0x25f9b00, 0xc00091ed30)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/syscall/asm_linux_amd64.s:41 +0x5
Nov 09 13:15:38 zeus teleport[853588]: os.(*Process).blockUntilWaitable(0xc000ef8390, 0xc000502780, 0x3, 0xc00091ede0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/os/wait_waitid.go:31 +0x98
Nov 09 13:15:38 zeus teleport[853588]: os.(*Process).wait(0xc000ef8390, 0x10, 0x10, 0xc0005a33d0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/os/exec_unix.go:22 +0x39
Nov 09 13:15:38 zeus teleport[853588]: os.(*Process).Wait(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/os/exec.go:125
Nov 09 13:15:38 zeus teleport[853588]: os/exec.(*Cmd).Wait(0xc000f00160, 0x60, 0x1)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/os/exec/exec.go:507 +0x60
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*terminal).Wait(0xc000115500, 0x27cdb30, 0xc00005f000, 0xc00091ef68)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/term.go:204 +0x33
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*session).startInteractive.func2(0xc000794bb0, 0xc000c646c0, 0xc000252460, 0xc0009fc680)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:804 +0x4c
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv.(*session).startInteractive
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:803 +0xd7b
Nov 09 13:15:38 zeus teleport[853588]: goroutine 36644 [select, 2868 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/events.(*ProtoStream).Close(0xc0009be240, 0x2c2cd20, 0xc00097ac00, 0x2, 0xc000dd4000)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/stream.go:415 +0x106
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/events.(*TeeStream).Close(0xc0005db3c0, 0x2c2cd20, 0xc00097ac00, 0xc000dd4000, 0xc000ac94d8)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/emitter.go:529 +0x48
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/events.(*CheckingStream).Close(0xc0009f4fc0, 0x2c2cd20, 0xc00097ac00, 0x63, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/emitter.go:438 +0x47
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/events.(*AuditWriter).recoverStream(0xc00046d200, 0x2744731, 0x37)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/auditwriter.go:277 +0x64
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/events.(*AuditWriter).processEvents(0xc00046d200)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/auditwriter.go:260 +0x819
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/events.NewAuditWriter
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/auditwriter.go:57 +0x41d
Nov 09 13:15:38 zeus teleport[853588]: goroutine 36632 [select, 131 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/events.(*AuditWriter).EmitAuditEvent(0xc00046d200, 0x2c2cd20, 0xc00097ac00, 0x2c5c060, 0xc000685500, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/auditwriter.go:194 +0x18c
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*SessionRegistry).emitSessionLeaveEvent(0xc000629aa0, 0xc000246b40)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:263 +0x1fe
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*SessionRegistry).leaveSession(0xc000629aa0, 0xc000246b40, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:292 +0x85
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*party).Close.func1()
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:1384 +0xc5
Nov 09 13:15:38 zeus teleport[853588]: sync.(*Once).doSlow(0xc000246bf0, 0xc00088b9f0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/sync/once.go:66 +0xec
Nov 09 13:15:38 zeus teleport[853588]: sync.(*Once).Do(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/sync/once.go:57
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*party).Close(0xc000246b40, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:1382 +0x82
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.closeAll(0xc000d06e40, 0x6, 0x6, 0x6, 0xc000a98f40)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/ctx.go:767 +0xb6
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*ServerContext).Close(0xc0007f5680, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/ctx.go:583 +0xc9
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).handleSessionRequests(0xc000958fc0, 0x2c2cd20, 0xc000bd6700, 0xc00027a2a0, 0xc000536b30, 0x9, 0xc0008002f0, 0x3, 0xc000a25000, 0x74e, ...)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1255 +0x9d1
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleNewChan.func1(0xc000958fc0, 0x2c2cd20, 0xc000bd6700, 0xc00027a2a0, 0xc0007e94d0, 0x2c41140, 0xc000450f00, 0xc00086e420, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1038 +0xf9
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleNewChan
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1037 +0xc8c
Nov 09 13:15:38 zeus teleport[853588]: goroutine 434348 [select, 2948 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc/internal/transport.(*Stream).waitOnHeader(0xc000b9f000)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:318 +0xcc
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc/internal/transport.(*Stream).RecvCompress(...)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/internal/transport/transport.go:333
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.(*csAttempt).recvMsg(0xc000978c00, 0x243aac0, 0xc000dc1cb0, 0x0, 0xc000679768, 0x2)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:871 +0x755
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.(*clientStream).RecvMsg.func1(0xc000978c00, 0x2, 0x8)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:736 +0x46
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.(*clientStream).withRetry(0xc000dc3b00, 0xc000669f28, 0xc000669ef8, 0xc000679750, 0x41caf6)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:594 +0x9c
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.(*clientStream).RecvMsg(0xc000dc3b00, 0x243aac0, 0xc000dc1cb0, 0x415e01, 0xc000dc1cb0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:735 +0x103
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/auth.(*streamKeepAliver).recv(0xc000f93140)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/auth/clt.go:799 +0x5e
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/auth.(*Client).NewKeepAliver
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/auth/clt.go:754 +0x1e0
Nov 09 13:15:38 zeus teleport[853588]: goroutine 1220164 [select]:
Nov 09 13:15:38 zeus teleport[853588]: net/http.(*persistConn).writeLoop(0xc00086c7e0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/net/http/transport.go:2277 +0x11c
Nov 09 13:15:38 zeus teleport[853588]: created by net/http.(*Transport).dialConn
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/net/http/transport.go:1648 +0xc7b
Nov 09 13:15:38 zeus teleport[853588]: goroutine 434346 [select, 2948 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: google.golang.org/grpc.newClientStream.func5(0xc00068c380, 0xc000dc3b00, 0x2c2cde0, 0xc000dc1bf0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:319 +0xd7
Nov 09 13:15:38 zeus teleport[853588]: created by google.golang.org/grpc.newClientStream
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/google.golang.org/grpc/stream.go:318 +0xbbf
Nov 09 13:15:38 zeus teleport[853588]: goroutine 1253438 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.StartKeepAliveLoop(0xc00114bec0, 0x1, 0x1, 0x45d964b800, 0x3, 0x2c2cd20, 0xc000631a00, 0xc00114bd60)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/keepalive.go:71 +0x335
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).handleSessionRequests
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1218 +0x5a5
Nov 09 13:15:38 zeus teleport[853588]: goroutine 36648 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*session).heartbeat(0xc000c5af20, 0xc0007f5680)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:1164 +0x275
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv.(*session).startInteractive
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:777 +0xcb4
Nov 09 13:15:38 zeus teleport[853588]: goroutine 1220079 [chan receive]:
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).readPacket(0xc000a42dc0, 0xc000c7d3b0, 0xed, 0xed, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:187 +0x4e
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*mux).onePacket(0xc0002c0000, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:215 +0x37
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*mux).loop(0xc0002c0000)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:190 +0x35
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.newMux
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:128 +0x153
Nov 09 13:15:38 zeus teleport[853588]: goroutine 37003 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*session).heartbeat(0xc000794bb0, 0xc000c646c0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:1164 +0x275
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv.(*session).startInteractive
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:777 +0xcb4
Nov 09 13:15:38 zeus teleport[853588]: goroutine 36992 [select, 4376 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).kexLoop(0xc00028f340)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:268 +0xfa
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.newServerTransport
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:143 +0x105
Nov 09 13:15:38 zeus teleport[853588]: goroutine 1253393 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/sshutils.(*Server).HandleConnection(0xc0008c2700, 0x2c4c7e0, 0xc00095ecf0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/sshutils/server.go:482 +0x837
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleConnection(0xc000958fc0, 0x2c4c7e0, 0xc00095ecf0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:289 +0x43
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/reversetunnel.(*transport).start(0xc000480d80)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/transport.go:255 +0x60e
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/reversetunnel.(*Agent).processRequests
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/reversetunnel/agent.go:443 +0x752
Nov 09 13:15:38 zeus teleport[853588]: goroutine 37004 [syscall, 4376 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: syscall.Syscall(0x0, 0x13, 0xc000f24000, 0x8000, 0x4aa225, 0x7fce1807e848, 0x72)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/syscall/asm_linux_amd64.s:18 +0x5
Nov 09 13:15:38 zeus teleport[853588]: syscall.read(0x13, 0xc000f24000, 0x8000, 0x8000, 0x0, 0x28, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/syscall/zsyscall_linux_amd64.go:686 +0x5a
Nov 09 13:15:38 zeus teleport[853588]: syscall.Read(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/syscall/syscall_unix.go:189
Nov 09 13:15:38 zeus teleport[853588]: internal/poll.(*FD).Read(0xc000f40d80, 0xc000f24000, 0x8000, 0x8000, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/internal/poll/fd_unix.go:165 +0x133
Nov 09 13:15:38 zeus teleport[853588]: os.(*File).read(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/os/file_unix.go:263
Nov 09 13:15:38 zeus teleport[853588]: os.(*File).Read(0xc000118de8, 0xc000f24000, 0x8000, 0x8000, 0x28, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/os/file.go:116 +0x71
Nov 09 13:15:38 zeus teleport[853588]: io.copyBuffer(0x2bc6880, 0xc000bd6dc0, 0x2bcadc0, 0xc000118de8, 0xc000f24000, 0x8000, 0x8000, 0x0, 0x44e1f6, 0x27cda10)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:405 +0x122
Nov 09 13:15:38 zeus teleport[853588]: io.Copy(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:364
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*session).startInteractive.func1(0xc000794bb0, 0xc000252460)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:789 +0xdf
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv.(*session).startInteractive
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:786 +0xd2f
Nov 09 13:15:38 zeus teleport[853588]: goroutine 37017 [chan receive]:
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).readPacket(0xc00028f340, 0xc000606b80, 0x1, 0x1, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:187 +0x4e
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*mux).onePacket(0xc000280e00, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:215 +0x37
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*mux).loop(0xc000280e00)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:190 +0x35
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.newMux
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:128 +0x153
Nov 09 13:15:38 zeus teleport[853588]: goroutine 1220132 [IO wait]:
Nov 09 13:15:38 zeus teleport[853588]: internal/poll.runtime_pollWait(0x7fce1807eae8, 0x72, 0xffffffffffffffff)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/netpoll.go:203 +0x55
Nov 09 13:15:38 zeus teleport[853588]: internal/poll.(*pollDesc).wait(0xc0007a7d98, 0x72, 0x1000, 0x1000, 0xffffffffffffffff)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/internal/poll/fd_poll_runtime.go:87 +0x45
Nov 09 13:15:38 zeus teleport[853588]: internal/poll.(*pollDesc).waitRead(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/internal/poll/fd_poll_runtime.go:92
Nov 09 13:15:38 zeus teleport[853588]: internal/poll.(*FD).Read(0xc0007a7d80, 0xc000aef000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/internal/poll/fd_unix.go:169 +0x19b
Nov 09 13:15:38 zeus teleport[853588]: net.(*netFD).Read(0xc0007a7d80, 0xc000aef000, 0x1000, 0x1000, 0xc000883cd0, 0x10, 0x10)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/net/fd_unix.go:202 +0x4f
Nov 09 13:15:38 zeus teleport[853588]: net.(*conn).Read(0xc0006b65b0, 0xc000aef000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/net/net.go:184 +0x8e
Nov 09 13:15:38 zeus teleport[853588]: bufio.(*Reader).Read(0xc00138ef00, 0xc000e2f320, 0x4, 0x4, 0x1000000168536c5, 0xc000166400, 0xc000166500)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/bufio/bufio.go:226 +0x24f
Nov 09 13:15:38 zeus teleport[853588]: io.ReadAtLeast(0x2bc4f60, 0xc00138ef00, 0xc000e2f320, 0x4, 0x4, 0x4, 0x10000000000000c, 0x0, 0xed)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:310 +0x87
Nov 09 13:15:38 zeus teleport[853588]: io.ReadFull(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:329
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*gcmCipher).readCipherPacket(0xc000e2f310, 0xc0000038b1, 0x2bc4f60, 0xc00138ef00, 0xc000166401, 0xed, 0xa8f, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/cipher.go:373 +0x83
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*connectionState).readPacket(0xc00086ca20, 0xc00138ef00, 0xc000883f30, 0x415903, 0xc00138f020, 0xc000f41260, 0xc000883fb0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:130 +0x68
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*transport).readPacket(0xc00086ca20, 0xc000c7d3b0, 0xed, 0xed, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:114 +0x37
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).readOnePacket(0xc000a42dc0, 0xc000883f00, 0xc000c7d3b0, 0xed, 0xed, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:374 +0x50
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).readLoop(0xc000a42dc0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:197 +0x4d
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.newClientTransport
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:134 +0x181
Nov 09 13:15:38 zeus teleport[853588]: goroutine 37002 [sync.Cond.Wait, 4376 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: runtime.goparkunlock(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/proc.go:310
Nov 09 13:15:38 zeus teleport[853588]: sync.runtime_notifyListWait(0xc000a99390, 0x10)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 09 13:15:38 zeus teleport[853588]: sync.(*Cond).Wait(0xc000a99380)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*buffer).Read(0xc000512f40, 0xc000e18000, 0x8000, 0x8000, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/buffer.go:94 +0x1de
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*channel).ReadExtended(0xc000450b40, 0xc000e18000, 0x8000, 0x8000, 0xc000000000, 0x4aa072, 0xc000f40d80, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:351 +0x1e5
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*channel).Read(0xc000450b40, 0xc000e18000, 0x8000, 0x8000, 0x4673e9, 0x2dfb5c115dfcea, 0x450e163)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:528 +0x5b
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.trackingChannel.Read(0x2c41140, 0xc000450b40, 0x2be4ae0, 0xc000c646c0, 0xc000e18000, 0x8000, 0x8000, 0x1, 0xc000246f00, 0x27cdce8)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/monitor.go:226 +0x5c
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*party).Read(0xc000246f00, 0xc000e18000, 0x8000, 0x8000, 0x1, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:1370 +0x60
Nov 09 13:15:38 zeus teleport[853588]: io.copyBuffer(0x2bcae00, 0xc000118de8, 0x2bc68c0, 0xc000246f00, 0xc000e18000, 0x8000, 0x8000, 0xc0005c9400, 0x2bc53c0, 0xc0000881a0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:405 +0x122
Nov 09 13:15:38 zeus teleport[853588]: io.Copy(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:364
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.(*session).addParty.func2(0xc000794bb0, 0xc000246f00)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:1228 +0xd7
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv.(*session).addParty
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/sess.go:1226 +0x52b
Nov 09 13:15:38 zeus teleport[853588]: goroutine 1220146 [sync.Cond.Wait, 121 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: runtime.goparkunlock(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/proc.go:310
Nov 09 13:15:38 zeus teleport[853588]: sync.runtime_notifyListWait(0xc0006313d0, 0xc000000000)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 09 13:15:38 zeus teleport[853588]: sync.(*Cond).Wait(0xc0006313c0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*mux).Wait(0xc0002c0000, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/mux.go:110 +0x84
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.NewClient.func1(0xc000ad6c30)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:62 +0x33
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.NewClient
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/client.go:61 +0xe5
Nov 09 13:15:38 zeus teleport[853588]: goroutine 36998 [select, 4370 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/events.(*sliceWriter).receiveAndUpload(0xc000f410e0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/stream.go:473 +0x20c
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/events.NewProtoStream
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/stream.go:299 +0x3d9
Nov 09 13:15:38 zeus teleport[853588]: goroutine 36999 [select, 4370 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/events.(*AuditWriter).processEvents(0xc00054cd00)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/auditwriter.go:241 +0x223
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/events.NewAuditWriter
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/events/auditwriter.go:57 +0x41d
Nov 09 13:15:38 zeus teleport[853588]: goroutine 37031 [select, 4376 minutes]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).handleSessionRequests(0xc000958fc0, 0x2c2cd20, 0xc000434d00, 0xc000280e70, 0xc001027840, 0x9, 0xc00069c478, 0x3, 0xc000a24000, 0x74e, ...)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1245 +0x723
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleNewChan.func1(0xc000958fc0, 0x2c2cd20, 0xc000434d00, 0xc000280e70, 0xc000afe120, 0x2c41140, 0xc000450b40, 0xc000de3500, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1038 +0xf9
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).HandleNewChan
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1037 +0xc8c
Nov 09 13:15:38 zeus teleport[853588]: goroutine 37036 [select]:
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/srv.StartKeepAliveLoop(0xc000602ca0, 0x1, 0x1, 0x45d964b800, 0x3, 0x2c2cd20, 0xc000434d00, 0xc000602920)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/keepalive.go:71 +0x335
Nov 09 13:15:38 zeus teleport[853588]: created by github.com/gravitational/teleport/lib/srv/regular.(*Server).handleSessionRequests
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/srv/regular/sshserver.go:1218 +0x5a5
Nov 09 13:15:38 zeus teleport[853588]: goroutine 1253410 [sync.Cond.Wait]:
Nov 09 13:15:38 zeus teleport[853588]: runtime.goparkunlock(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/proc.go:310
Nov 09 13:15:38 zeus teleport[853588]: sync.runtime_notifyListWait(0xc00004e910, 0xb)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 09 13:15:38 zeus teleport[853588]: sync.(*Cond).Wait(0xc00004e900)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*buffer).Read(0xc0001109e0, 0xc000261000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/buffer.go:94 +0x1de
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*channel).ReadExtended(0xc0007e6180, 0xc000261000, 0x1000, 0x1000, 0x0, 0x44, 0xc00088d8a8, 0x4673e9)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:351 +0x1e5
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*channel).Read(0xc0007e6180, 0xc000261000, 0x1000, 0x1000, 0x4a1190, 0xc00088d8e0, 0x384)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:528 +0x5b
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/utils.(*TimeoutConn).Read(0xc00095ed20, 0xc000261000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/utils/timeout.go:58 +0xa1
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/utils.(*TrackingConn).Read(0xc000cc1140, 0xc000261000, 0x1000, 0x1000, 0x44, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/utils/conn.go:130 +0x55
Nov 09 13:15:38 zeus teleport[853588]: io.(*multiReader).Read(0xc000cc11e0, 0xc000261000, 0x1000, 0x1000, 0x44, 0x79280f, 0xc00088daa8)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/multi.go:26 +0xac
Nov 09 13:15:38 zeus teleport[853588]: github.com/gravitational/teleport/lib/sshutils.(*connectionWrapper).Read(0xc00095ed50, 0xc000261000, 0x1000, 0x1000, 0xc00088dc20, 0xc00088dd40, 0x20)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/lib/sshutils/server.go:640 +0x6c6
Nov 09 13:15:38 zeus teleport[853588]: bufio.(*Reader).Read(0xc00084d680, 0xc000454700, 0x4, 0x684, 0xdd9fbfb371c923bf, 0x20, 0xc00088dc20)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/bufio/bufio.go:226 +0x24f
Nov 09 13:15:38 zeus teleport[853588]: io.ReadAtLeast(0x2bc4f60, 0xc00084d680, 0xc000454700, 0x4, 0x684, 0x4, 0x20, 0xc00088dd10, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:310 +0x87
Nov 09 13:15:38 zeus teleport[853588]: io.ReadFull(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/io/io.go:329
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*chacha20Poly1305Cipher).readCipherPacket(0xc001119500, 0xc000000012, 0x2bc4f60, 0xc00084d680, 0xc000454705, 0x27, 0x67f, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/cipher.go:675 +0x21b
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*connectionState).readPacket(0xc0008d3c20, 0xc00084d680, 0xc00088df30, 0x415903, 0xc00084d7a0, 0xc000b676e0, 0xc00088dfb0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:130 +0x68
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*transport).readPacket(0xc0008d3c20, 0xc000241b90, 0x27, 0x27, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/transport.go:114 +0x37
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).readOnePacket(0xc0008cb600, 0xc00088df00, 0xc000241b90, 0x27, 0x27, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:374 +0x50
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*handshakeTransport).readLoop(0xc0008cb600)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:197 +0x4d
Nov 09 13:15:38 zeus teleport[853588]: created by golang.org/x/crypto/ssh.newServerTransport
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/handshake.go:142 +0xe3
Nov 09 13:15:38 zeus teleport[853588]: goroutine 1220163 [sync.Cond.Wait]:
Nov 09 13:15:38 zeus teleport[853588]: runtime.goparkunlock(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/proc.go:310
Nov 09 13:15:38 zeus teleport[853588]: sync.runtime_notifyListWait(0xc000631490, 0xc000001c47)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/runtime/sema.go:513 +0xf8
Nov 09 13:15:38 zeus teleport[853588]: sync.(*Cond).Wait(0xc000631480)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/sync/cond.go:56 +0x9d
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*buffer).Read(0xc000cc1b40, 0xc000124800, 0x15d3, 0x15d3, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/buffer.go:94 +0x1de
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*channel).ReadExtended(0xc0007e6300, 0xc000124800, 0x15d3, 0x15d3, 0x20300000000000, 0x7fce18ea5fff, 0x203000, 0x203000)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:351 +0x1e5
Nov 09 13:15:38 zeus teleport[853588]: golang.org/x/crypto/ssh.(*channel).Read(0xc0007e6300, 0xc000124800, 0x15d3, 0x15d3, 0x12, 0xf44, 0x2030000000000a)
Nov 09 13:15:38 zeus teleport[853588]:         /go/src/github.com/gravitational/teleport/vendor/golang.org/x/crypto/ssh/channel.go:528 +0x5b
Nov 09 13:15:38 zeus teleport[853588]: crypto/tls.(*atLeastReader).Read(0xc000fa3840, 0xc000124800, 0x15d3, 0x15d3, 0x0, 0xc000f96660, 0xc000173918)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/crypto/tls/conn.go:760 +0x60
Nov 09 13:15:38 zeus teleport[853588]: bytes.(*Buffer).ReadFrom(0xc000da85d8, 0x2bc51a0, 0xc000fa3840, 0x41aa45, 0x237ffc0, 0x255ada0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/bytes/buffer.go:204 +0xb1
Nov 09 13:15:38 zeus teleport[853588]: crypto/tls.(*Conn).readFromUntil(0xc000da8380, 0x7fce1834d790, 0xc000966a50, 0x5, 0xc000966a50, 0xc00063b690)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/crypto/tls/conn.go:782 +0xec
Nov 09 13:15:38 zeus teleport[853588]: crypto/tls.(*Conn).readRecordOrCCS(0xc000da8380, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/crypto/tls/conn.go:589 +0x115
Nov 09 13:15:38 zeus teleport[853588]: crypto/tls.(*Conn).readRecord(...)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/crypto/tls/conn.go:557
Nov 09 13:15:38 zeus teleport[853588]: crypto/tls.(*Conn).Read(0xc000da8380, 0xc0001b6000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/crypto/tls/conn.go:1233 +0x15b
Nov 09 13:15:38 zeus teleport[853588]: net/http.(*persistConn).Read(0xc00086c7e0, 0xc0001b6000, 0x1000, 0x1000, 0xc0006430e0, 0xc000173c70, 0x4153e5)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/net/http/transport.go:1825 +0x75
Nov 09 13:15:38 zeus teleport[853588]: bufio.(*Reader).fill(0xc000f96660)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/bufio/bufio.go:100 +0x103
Nov 09 13:15:38 zeus teleport[853588]: bufio.(*Reader).Peek(0xc000f96660, 0x1, 0x0, 0x0, 0x1, 0xc000c2fc00, 0x0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/bufio/bufio.go:138 +0x4f
Nov 09 13:15:38 zeus teleport[853588]: net/http.(*persistConn).readLoop(0xc00086c7e0)
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/net/http/transport.go:1978 +0x1a8
Nov 09 13:15:38 zeus teleport[853588]: created by net/http.(*Transport).dialConn
Nov 09 13:15:38 zeus teleport[853588]:         /opt/go/src/net/http/transport.go:1647 +0xc56

lib/events/emitter_test.go Outdated Show resolved Hide resolved
lib/events/emitter_test.go Outdated Show resolved Hide resolved
lib/events/emitter_test.go Outdated Show resolved Hide resolved
lib/events/emitter_test.go Outdated Show resolved Hide resolved
lib/events/stream_test.go Outdated Show resolved Hide resolved
lib/events/stream_test.go Outdated Show resolved Hide resolved
lib/events/stream_test.go Outdated Show resolved Hide resolved
lib/events/stream_test.go Outdated Show resolved Hide resolved
@klizhentas
Copy link
Contributor Author

@webvictim these goroutine dumps you provide are super helpful - thanks for collecting them for me!

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 klizhentas merged commit b39e2cc into branch/4.4 Nov 12, 2020
@klizhentas klizhentas deleted the sasha/nohang branch November 12, 2020 21:16
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 this pull request may close these issues.

5 participants