Lavalink Ignore voiceUpdate events when a connection is already established in place.

Ignore voiceUpdate events when a connection is already established in place.

Open applebee1558 opened this issue 4 years ago • 3 comments

Description

As a client, you can't keep track of the exact state of the websocket connections. Every time I get disconnected for something like 1000 or 4006, (basically any code that isn't due to VC disconnection) I would assume something such as network issue broke the connection and send another voiceUpdate. The problem with this is, the server would break the current connection and start a new one, triggering another 4006 or another 1000 (idk why the code is 1000 but yeah) and the client would end up infinite-looping. It would be best if the server could match the passed info (session id, endpoint, token, guild id) and prevent new connections if a current connection with the exact same info is matching. The lavalink server probably has a much better "state" of how websocket connections are doing. Another fact is, on voice state updates for the bot user itself, the library (lavalink.py) also sends a voiceUpdate to the lavalink server, which would cut the old connection and reconnect although the payloads haven't even changed at all.

Version info

Client used: Lavalink.py Output of java -version:

Using the Docker Image:

openjdk 13.0.2 2020-01-14
OpenJDK Runtime Environment Zulu13.29+9-CA (build 13.0.2+6-MTS)
OpenJDK 64-Bit Server VM Zulu13.29+9-CA (build 13.0.2+6-MTS, mixed mode, sharing)

This happens randomly while playing music a lot and I would just reconnect whenever the websocket closes, but then it sometimes daisy-chains down.

The random websocket disconnects: 

2020-06-04 23:58:08.320 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":6,"d":1591315088296}
2020-06-04 23:58:22.047 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":3,"d":1591315102046}
2020-06-04 23:58:22.069 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":6,"d":1591315102046}
2020-06-04 23:58:35.797 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":3,"d":1591315115796}
2020-06-04 23:58:49.546 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":3,"d":1591315129546}
2020-06-04 23:59:03.296 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":3,"d":1591315143296}
2020-06-04 23:59:13.156 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":5,"d":{"speaking":0,"delay":0,"ssrc":45913}}
2020-06-04 23:59:13.162  INFO 1 --- [   XNIO-1 I/O-2] lavalink.server.io.SocketServer          : {"op": "play", "guildId": "665809317198430209", "track": "QAAAogIAOUlkaW5hIE1lbnplbCwgQVVST1JBIC0gSW50byB0aGUgVW5rbm93biAoRnJvbSAiRnJvemVuIDIiKQAPRGlzbmV5TXVzaWNWRVZPAAAAAAADNFAAC2dJT3lCOVpYbjhzAAEAK2h0dHBzOi8vd3d3LnlvdXR1YmUuY29tL3dhdGNoP3Y9Z0lPeUI5WlhuOHMAB3lvdXR1YmUAAAAAAAAAAA=="}
2020-06-04 23:59:14.789 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":5,"d":{"speaking":1,"delay":0,"ssrc":45913}}
2020-06-04 23:59:17.046 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":3,"d":1591315157046}
2020-06-04 23:59:17.076 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : Websocket closed, code: 1000, reason:

This is what happens if I just send a voice update on disconnects. Gateway/spam with disconnects and invalid sessions:

2020-06-04 23:37:03.405 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Got session description: {"video_codec":"H264","mode":"xsalsa20_poly1305","secret_key":[32,26,21,20,154,44,191,212,144,3,198,39,163,199,92,226,102,217,22,82,163,85,15,203,195,235,68,223,169,140,107,114],"media_session_id":"6f9b17a59140b7d9519a8a90fbe2335f","audio_codec":"opus"}
2020-06-04 23:37:05.228  INFO 1 --- [   XNIO-1 I/O-2] lavalink.server.io.SocketServer          : {"op": "voiceUpdate", "guildId": "665809317198430209", "sessionId": "08ce63d524f15f79b806af55fb26cd68", "event": {"token": "2635a7381cadccbe", "guild_id": "665809317198430209", "endpoint": "us-west449.discord.media:80"}}
2020-06-04 23:37:05.229 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.koe.internal.VoiceConnectionImpl     : Disconnecting...
2020-06-04 23:37:05.241 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.k.g.AbstractVoiceGatewayConnection   : Connecting to wss://us-west449.discord.media:443/?v=4
2020-06-04 23:37:05.251  INFO 1 --- [   XNIO-1 I/O-2] lavalink.server.io.SocketServer          : {"op": "voiceUpdate", "guildId": "665809317198430209", "sessionId": "08ce63d524f15f79b806af55fb26cd68", "event": {"token": "2635a7381cadccbe", "guild_id": "665809317198430209", "endpoint": "us-west449.discord.media:80"}}
2020-06-04 23:37:05.252 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.koe.internal.VoiceConnectionImpl     : Disconnecting...
2020-06-04 23:37:05.252 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.k.g.AbstractVoiceGatewayConnection   : Connecting to wss://us-west449.discord.media:443/?v=4
2020-06-04 23:37:05.262  INFO 1 --- [   XNIO-1 I/O-2] lavalink.server.io.SocketServer          : {"op": "voiceUpdate", "guildId": "593317393120624651", "sessionId": "08ce63d524f15f79b806af55fb26cd68", "event": {"token": "d6f1e25a95de0d37", "guild_id": "593317393120624651", "endpoint": "us-west789.discord.media:80"}}
2020-06-04 23:37:05.263 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.koe.internal.VoiceConnectionImpl     : Disconnecting...
2020-06-04 23:37:05.265 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.k.g.AbstractVoiceGatewayConnection   : Connecting to wss://us-west789.discord.media:443/?v=4
2020-06-04 23:37:05.271  INFO 1 --- [   XNIO-1 I/O-2] lavalink.server.io.SocketServer          : {"op": "voiceUpdate", "guildId": "593317393120624651", "sessionId": "08ce63d524f15f79b806af55fb26cd68", "event": {"token": "d6f1e25a95de0d37", "guild_id": "593317393120624651", "endpoint": "us-west789.discord.media:80"}}
2020-06-04 23:37:05.275 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.koe.internal.VoiceConnectionImpl     : Disconnecting...
2020-06-04 23:37:05.278 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.k.g.AbstractVoiceGatewayConnection   : Connecting to wss://us-west789.discord.media:443/?v=4
2020-06-04 23:37:05.369 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":8,"d":{"v":4,"heartbeat_interval":13750.0}}
2020-06-04 23:37:05.370 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Received HELLO, heartbeat interval: 13750
2020-06-04 23:37:05.370 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Identifying...
2020-06-04 23:37:05.371 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":0,"d":{"user_id":"629563525722931200","session_id":"08ce63d524f15f79b806af55fb26cd68","server_id":"665809317198430209","token":"2635a7381cadccbe"}}
2020-06-04 23:37:05.380 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":8,"d":{"v":4,"heartbeat_interval":13750.0}}
2020-06-04 23:37:05.381 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Received HELLO, heartbeat interval: 13750
2020-06-04 23:37:05.382 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Identifying...
2020-06-04 23:37:05.382 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":0,"d":{"user_id":"629563525722931200","session_id":"08ce63d524f15f79b806af55fb26cd68","server_id":"665809317198430209","token":"2635a7381cadccbe"}}
2020-06-04 23:37:05.391 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":8,"d":{"v":4,"heartbeat_interval":13750.0}}
2020-06-04 23:37:05.392 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Received HELLO, heartbeat interval: 13750
2020-06-04 23:37:05.392 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Identifying...
2020-06-04 23:37:05.393 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":0,"d":{"user_id":"629563525722931200","session_id":"08ce63d524f15f79b806af55fb26cd68","server_id":"593317393120624651","token":"d6f1e25a95de0d37"}}
2020-06-04 23:37:05.391 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":2,"d":{"modes":["aead_aes256_gcm","xsalsa20_poly1305_lite","xsalsa20_poly1305_suffix","xsalsa20_poly1305"],"port":50002,"ssrc":45913,"ip":"107.160.222.86","experiments":["bwe_conservative_link_estimate","bwe_remote_locus_client"]}}
2020-06-04 23:37:05.395 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Voice READY, ssrc: 45913
2020-06-04 23:37:05.395 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Selected preferred encryption mode: xsalsa20_poly1305
2020-06-04 23:37:05.396 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Generated new connection id: 5bfa875a-c7fa-4184-93e4-0e72168f41b5
2020-06-04 23:37:05.396 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.i.handler.DiscordUDPConnection     : Connecting to /107.160.222.86:50002...
2020-06-04 23:37:05.398 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Waiting for session description...
2020-06-04 23:37:05.399 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":8,"d":{"v":4,"heartbeat_interval":13750.0}}
2020-06-04 23:37:05.399 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.internal.handler.HolepunchHandler  : Holepunch [attempt 1/10, local ip: /172.17.0.2:55717]
2020-06-04 23:37:05.401 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Received HELLO, heartbeat interval: 13750
2020-06-04 23:37:05.401 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Identifying...
2020-06-04 23:37:05.402 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":0,"d":{"user_id":"629563525722931200","session_id":"08ce63d524f15f79b806af55fb26cd68","server_id":"593317393120624651","token":"d6f1e25a95de0d37"}}
2020-06-04 23:37:05.402 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Connected, our external address is: /194.242.2.126:55717
2020-06-04 23:37:05.403 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":1,"d":{"rtc_connection_id":"5bfa875a-c7fa-4184-93e4-0e72168f41b5","mode":"xsalsa20_poly1305","protocol":"udp","address":"194.242.2.126","data":{"mode":"xsalsa20_poly1305","address":"194.242.2.126","port":55717},"port":55717,"codecs":[{"payload_type":120,"name":"opus","type":"audio","priority":1000}]}}
2020-06-04 23:37:05.403 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":12,"d":{"audio_ssrc":45913,"video_ssrc":0,"rtx_ssrc":0}}
2020-06-04 23:37:05.405 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":2,"d":{"modes":["aead_aes256_gcm","xsalsa20_poly1305_lite","xsalsa20_poly1305_suffix","xsalsa20_poly1305"],"port":50002,"ssrc":45913,"ip":"107.160.222.86","experiments":["bwe_conservative_link_estimate","bwe_remote_locus_client"]}}
2020-06-04 23:37:05.405 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Voice READY, ssrc: 45913
2020-06-04 23:37:05.405 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Selected preferred encryption mode: xsalsa20_poly1305
2020-06-04 23:37:05.405 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Generated new connection id: 006ee203-ea62-4e18-a2e8-20c84ffdb4d9
2020-06-04 23:37:05.405 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.i.handler.DiscordUDPConnection     : Connecting to /107.160.222.86:50002...
2020-06-04 23:37:05.408 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : Websocket closed, code: 4006, reason: Session is no longer valid.
2020-06-04 23:37:05.409 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Waiting for session description...
2020-06-04 23:37:05.411 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.internal.handler.HolepunchHandler  : Holepunch [attempt 1/10, local ip: /172.17.0.2:48588]
2020-06-04 23:37:05.412 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : Websocket closed, code: 4006, reason: Session is no longer valid.
2020-06-04 23:37:05.414 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Connected, our external address is: /194.242.2.126:48588
2020-06-04 23:37:05.415 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":1,"d":{"rtc_connection_id":"006ee203-ea62-4e18-a2e8-20c84ffdb4d9","mode":"xsalsa20_poly1305","protocol":"udp","address":"194.242.2.126","data":{"mode":"xsalsa20_poly1305","address":"194.242.2.126","port":48588},"port":48588,"codecs":[{"payload_type":120,"name":"opus","type":"audio","priority":1000}]}}
2020-06-04 23:37:05.416 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":12,"d":{"audio_ssrc":45913,"video_ssrc":0,"rtx_ssrc":0}}
2020-06-04 23:37:05.422 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : Websocket closed, code: 4006, reason: Session is no longer valid.
2020-06-04 23:37:05.436 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":4,"d":{"video_codec":"H264","mode":"xsalsa20_poly1305","secret_key":[99,252,118,244,165,24,109,6,173,105,52,49,108,226,6,105,96,173,7,179,201,135,183,32,145,37,5,98,17,34,241,171],"media_session_id":"6f9b17a59140b7d9519a8a90fbe2335f","audio_codec":"opus"}}
2020-06-04 23:37:05.437 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Got session description: {"video_codec":"H264","mode":"xsalsa20_poly1305","secret_key":[99,252,118,244,165,24,109,6,173,105,52,49,108,226,6,105,96,173,7,179,201,135,183,32,145,37,5,98,17,34,241,171],"media_session_id":"6f9b17a59140b7d9519a8a90fbe2335f","audio_codec":"opus"}
2020-06-04 23:37:07.246  INFO 1 --- [   XNIO-1 I/O-2] lavalink.server.io.SocketServer          : {"op": "voiceUpdate", "guildId": "665809317198430209", "sessionId": "08ce63d524f15f79b806af55fb26cd68", "event": {"token": "2635a7381cadccbe", "guild_id": "665809317198430209", "endpoint": "us-west449.discord.media:80"}}
2020-06-04 23:37:07.246 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.koe.internal.VoiceConnectionImpl     : Disconnecting...
2020-06-04 23:37:07.250 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.k.g.AbstractVoiceGatewayConnection   : Connecting to wss://us-west449.discord.media:443/?v=4
2020-06-04 23:37:07.261  INFO 1 --- [   XNIO-1 I/O-2] lavalink.server.io.SocketServer          : {"op": "voiceUpdate", "guildId": "665809317198430209", "sessionId": "08ce63d524f15f79b806af55fb26cd68", "event": {"token": "2635a7381cadccbe", "guild_id": "665809317198430209", "endpoint": "us-west449.discord.media:80"}}
2020-06-04 23:37:07.262 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.koe.internal.VoiceConnectionImpl     : Disconnecting...
2020-06-04 23:37:07.264 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.k.g.AbstractVoiceGatewayConnection   : Connecting to wss://us-west449.discord.media:443/?v=4
2020-06-04 23:37:07.266  INFO 1 --- [   XNIO-1 I/O-2] lavalink.server.io.SocketServer          : {"op": "voiceUpdate", "guildId": "665809317198430209", "sessionId": "08ce63d524f15f79b806af55fb26cd68", "event": {"token": "2635a7381cadccbe", "guild_id": "665809317198430209", "endpoint": "us-west449.discord.media:80"}}
2020-06-04 23:37:07.270 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.koe.internal.VoiceConnectionImpl     : Disconnecting...
2020-06-04 23:37:07.272 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.k.g.AbstractVoiceGatewayConnection   : Connecting to wss://us-west449.discord.media:443/?v=4
2020-06-04 23:37:07.273  INFO 1 --- [   XNIO-1 I/O-2] lavalink.server.io.SocketServer          : {"op": "voiceUpdate", "guildId": "665809317198430209", "sessionId": "08ce63d524f15f79b806af55fb26cd68", "event": {"token": "2635a7381cadccbe", "guild_id": "665809317198430209", "endpoint": "us-west449.discord.media:80"}}
2020-06-04 23:37:07.275 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.koe.internal.VoiceConnectionImpl     : Disconnecting...
2020-06-04 23:37:07.280 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.k.g.AbstractVoiceGatewayConnection   : Connecting to wss://us-west449.discord.media:443/?v=4
2020-06-04 23:37:07.369 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":8,"d":{"v":4,"heartbeat_interval":13750.0}}
2020-06-04 23:37:07.370 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Received HELLO, heartbeat interval: 13750
2020-06-04 23:37:07.371 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Identifying...
2020-06-04 23:37:07.371 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":0,"d":{"user_id":"629563525722931200","session_id":"08ce63d524f15f79b806af55fb26cd68","server_id":"665809317198430209","token":"2635a7381cadccbe"}}
2020-06-04 23:37:07.386 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":8,"d":{"v":4,"heartbeat_interval":13750.0}}
2020-06-04 23:37:07.390 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Received HELLO, heartbeat interval: 13750
2020-06-04 23:37:07.390 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Identifying...
2020-06-04 23:37:07.390 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":0,"d":{"user_id":"629563525722931200","session_id":"08ce63d524f15f79b806af55fb26cd68","server_id":"665809317198430209","token":"2635a7381cadccbe"}}
2020-06-04 23:37:07.397 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":2,"d":{"modes":["aead_aes256_gcm","xsalsa20_poly1305_lite","xsalsa20_poly1305_suffix","xsalsa20_poly1305"],"port":50002,"ssrc":45913,"ip":"107.160.222.86","experiments":["bwe_conservative_link_estimate","bwe_remote_locus_client"]}}
2020-06-04 23:37:07.397 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Voice READY, ssrc: 45913
2020-06-04 23:37:07.397 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Selected preferred encryption mode: xsalsa20_poly1305
2020-06-04 23:37:07.397 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Generated new connection id: 2d00cc58-fa72-49e1-8116-e44bed56ceb9
2020-06-04 23:37:07.398 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.i.handler.DiscordUDPConnection     : Connecting to /107.160.222.86:50002...
2020-06-04 23:37:07.399 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.internal.handler.HolepunchHandler  : Holepunch [attempt 1/10, local ip: /172.17.0.2:40051]
2020-06-04 23:37:07.404 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Connected, our external address is: /194.242.2.126:40051
2020-06-04 23:37:07.404 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":1,"d":{"rtc_connection_id":"2d00cc58-fa72-49e1-8116-e44bed56ceb9","mode":"xsalsa20_poly1305","protocol":"udp","address":"194.242.2.126","data":{"mode":"xsalsa20_poly1305","address":"194.242.2.126","port":40051},"port":40051,"codecs":[{"payload_type":120,"name":"opus","type":"audio","priority":1000}]}}
2020-06-04 23:37:07.404 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":12,"d":{"audio_ssrc":45913,"video_ssrc":0,"rtx_ssrc":0}}
2020-06-04 23:37:07.405 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Waiting for session description...
2020-06-04 23:37:07.405 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":8,"d":{"v":4,"heartbeat_interval":13750.0}}
2020-06-04 23:37:07.405 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Received HELLO, heartbeat interval: 13750
2020-06-04 23:37:07.405 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Identifying...
2020-06-04 23:37:07.405 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":0,"d":{"user_id":"629563525722931200","session_id":"08ce63d524f15f79b806af55fb26cd68","server_id":"665809317198430209","token":"2635a7381cadccbe"}}
2020-06-04 23:37:07.410 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":2,"d":{"modes":["aead_aes256_gcm","xsalsa20_poly1305_lite","xsalsa20_poly1305_suffix","xsalsa20_poly1305"],"port":50002,"ssrc":45913,"ip":"107.160.222.86","experiments":["bwe_conservative_link_estimate","bwe_remote_locus_client"]}}
2020-06-04 23:37:07.412 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Voice READY, ssrc: 45913
2020-06-04 23:37:07.412 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Selected preferred encryption mode: xsalsa20_poly1305
2020-06-04 23:37:07.412 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Generated new connection id: 0205bfe2-4730-43c4-a306-87394570b0c5
2020-06-04 23:37:07.412 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.i.handler.DiscordUDPConnection     : Connecting to /107.160.222.86:50002...
2020-06-04 23:37:07.412 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Waiting for session description...
2020-06-04 23:37:07.413 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":8,"d":{"v":4,"heartbeat_interval":13750.0}}
2020-06-04 23:37:07.413 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Received HELLO, heartbeat interval: 13750
2020-06-04 23:37:07.413 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Identifying...
2020-06-04 23:37:07.413 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":0,"d":{"user_id":"629563525722931200","session_id":"08ce63d524f15f79b806af55fb26cd68","server_id":"665809317198430209","token":"2635a7381cadccbe"}}
2020-06-04 23:37:07.414 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : Websocket closed, code: 4006, reason: Session is no longer valid.
2020-06-04 23:37:07.415 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.internal.handler.HolepunchHandler  : Holepunch [attempt 1/10, local ip: /172.17.0.2:58331]
2020-06-04 23:37:07.420 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Connected, our external address is: /194.242.2.126:58331
2020-06-04 23:37:07.420 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":1,"d":{"rtc_connection_id":"0205bfe2-4730-43c4-a306-87394570b0c5","mode":"xsalsa20_poly1305","protocol":"udp","address":"194.242.2.126","data":{"mode":"xsalsa20_poly1305","address":"194.242.2.126","port":58331},"port":58331,"codecs":[{"payload_type":120,"name":"opus","type":"audio","priority":1000}]}}
2020-06-04 23:37:07.420 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":12,"d":{"audio_ssrc":45913,"video_ssrc":0,"rtx_ssrc":0}}
2020-06-04 23:37:07.428 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":2,"d":{"modes":["aead_aes256_gcm","xsalsa20_poly1305_lite","xsalsa20_poly1305_suffix","xsalsa20_poly1305"],"port":50002,"ssrc":45913,"ip":"107.160.222.86","experiments":["bwe_conservative_link_estimate","bwe_remote_locus_client"]}}
2020-06-04 23:37:07.429 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Voice READY, ssrc: 45913
2020-06-04 23:37:07.430 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Selected preferred encryption mode: xsalsa20_poly1305
2020-06-04 23:37:07.430 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Generated new connection id: 72b47ec3-ea45-4084-b16c-d58f53ec736d
2020-06-04 23:37:07.431 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.i.handler.DiscordUDPConnection     : Connecting to /107.160.222.86:50002...
2020-06-04 23:37:07.432 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.internal.handler.HolepunchHandler  : Holepunch [attempt 1/10, local ip: /172.17.0.2:47609]
2020-06-04 23:37:07.434 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : Websocket closed, code: 4006, reason: Session is no longer valid.
2020-06-04 23:37:07.436 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":2,"d":{"modes":["aead_aes256_gcm","xsalsa20_poly1305_lite","xsalsa20_poly1305_suffix","xsalsa20_poly1305"],"port":50002,"ssrc":45913,"ip":"107.160.222.86","experiments":["bwe_conservative_link_estimate","bwe_remote_locus_client"]}}
2020-06-04 23:37:07.437 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Voice READY, ssrc: 45913
2020-06-04 23:37:07.437 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Selected preferred encryption mode: xsalsa20_poly1305
2020-06-04 23:37:07.438 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Generated new connection id: 1920b3c0-e5fa-41fd-a6cb-6a936f184c92
2020-06-04 23:37:07.439 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.i.handler.DiscordUDPConnection     : Connecting to /107.160.222.86:50002...
2020-06-04 23:37:07.440 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Waiting for session description...
2020-06-04 23:37:07.441 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Connected, our external address is: /194.242.2.126:47609
2020-06-04 23:37:07.441 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":1,"d":{"rtc_connection_id":"72b47ec3-ea45-4084-b16c-d58f53ec736d","mode":"xsalsa20_poly1305","protocol":"udp","address":"194.242.2.126","data":{"mode":"xsalsa20_poly1305","address":"194.242.2.126","port":47609},"port":47609,"codecs":[{"payload_type":120,"name":"opus","type":"audio","priority":1000}]}}
2020-06-04 23:37:07.442 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":12,"d":{"audio_ssrc":45913,"video_ssrc":0,"rtx_ssrc":0}}
2020-06-04 23:37:07.442 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Waiting for session description...
2020-06-04 23:37:07.442 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.internal.handler.HolepunchHandler  : Holepunch [attempt 1/10, local ip: /172.17.0.2:35993]
2020-06-04 23:37:07.445 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : Websocket closed, code: 4006, reason: Session is no longer valid.
2020-06-04 23:37:07.446 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Connected, our external address is: /194.242.2.126:35993
2020-06-04 23:37:07.451 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":1,"d":{"rtc_connection_id":"1920b3c0-e5fa-41fd-a6cb-6a936f184c92","mode":"xsalsa20_poly1305","protocol":"udp","address":"194.242.2.126","data":{"mode":"xsalsa20_poly1305","address":"194.242.2.126","port":35993},"port":35993,"codecs":[{"payload_type":120,"name":"opus","type":"audio","priority":1000}]}}
2020-06-04 23:37:07.452 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":12,"d":{"audio_ssrc":45913,"video_ssrc":0,"rtx_ssrc":0}}
2020-06-04 23:37:07.472 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":4,"d":{"video_codec":"H264","mode":"xsalsa20_poly1305","secret_key":[176,38,53,72,18,107,100,166,254,86,95,156,226,50,59,12,2,67,46,144,211,43,99,64,218,7,21,72,187,160,240,168],"media_session_id":"6f9b17a59140b7d9519a8a90fbe2335f","audio_codec":"opus"}}
2020-06-04 23:37:07.472 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Got session description: {"video_codec":"H264","mode":"xsalsa20_poly1305","secret_key":[176,38,53,72,18,107,100,166,254,86,95,156,226,50,59,12,2,67,46,144,211,43,99,64,218,7,21,72,187,160,240,168],"media_session_id":"6f9b17a59140b7d9519a8a90fbe2335f","audio_codec":"opus"}
2020-06-04 23:37:09.241  INFO 1 --- [   XNIO-1 I/O-2] lavalink.server.io.SocketServer          : {"op": "voiceUpdate", "guildId": "665809317198430209", "sessionId": "08ce63d524f15f79b806af55fb26cd68", "event": {"token": "2635a7381cadccbe", "guild_id": "665809317198430209", "endpoint": "us-west449.discord.media:80"}}
2020-06-04 23:37:09.241 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.koe.internal.VoiceConnectionImpl     : Disconnecting...
2020-06-04 23:37:09.243 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.k.g.AbstractVoiceGatewayConnection   : Connecting to wss://us-west449.discord.media:443/?v=4
2020-06-04 23:37:09.246  INFO 1 --- [   XNIO-1 I/O-2] lavalink.server.io.SocketServer          : {"op": "voiceUpdate", "guildId": "665809317198430209", "sessionId": "08ce63d524f15f79b806af55fb26cd68", "event": {"token": "2635a7381cadccbe", "guild_id": "665809317198430209", "endpoint": "us-west449.discord.media:80"}}
2020-06-04 23:37:09.246 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.koe.internal.VoiceConnectionImpl     : Disconnecting...
2020-06-04 23:37:09.246 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.k.g.AbstractVoiceGatewayConnection   : Connecting to wss://us-west449.discord.media:443/?v=4
2020-06-04 23:37:09.246  INFO 1 --- [   XNIO-1 I/O-2] lavalink.server.io.SocketServer          : {"op": "voiceUpdate", "guildId": "665809317198430209", "sessionId": "08ce63d524f15f79b806af55fb26cd68", "event": {"token": "2635a7381cadccbe", "guild_id": "665809317198430209", "endpoint": "us-west449.discord.media:80"}}
2020-06-04 23:37:09.246 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.koe.internal.VoiceConnectionImpl     : Disconnecting...
2020-06-04 23:37:09.246 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.k.g.AbstractVoiceGatewayConnection   : Connecting to wss://us-west449.discord.media:443/?v=4
2020-06-04 23:37:09.252  INFO 1 --- [   XNIO-1 I/O-2] lavalink.server.io.SocketServer          : {"op": "voiceUpdate", "guildId": "665809317198430209", "sessionId": "08ce63d524f15f79b806af55fb26cd68", "event": {"token": "2635a7381cadccbe", "guild_id": "665809317198430209", "endpoint": "us-west449.discord.media:80"}}
2020-06-04 23:37:09.252 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.koe.internal.VoiceConnectionImpl     : Disconnecting...
2020-06-04 23:37:09.252 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.k.g.AbstractVoiceGatewayConnection   : Connecting to wss://us-west449.discord.media:443/?v=4
2020-06-04 23:37:09.385 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":8,"d":{"v":4,"heartbeat_interval":13750.0}}
2020-06-04 23:37:09.385 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Received HELLO, heartbeat interval: 13750
2020-06-04 23:37:09.385 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Identifying...
2020-06-04 23:37:09.385 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":0,"d":{"user_id":"629563525722931200","session_id":"08ce63d524f15f79b806af55fb26cd68","server_id":"665809317198430209","token":"2635a7381cadccbe"}}
2020-06-04 23:37:09.388 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":8,"d":{"v":4,"heartbeat_interval":13750.0}}
2020-06-04 23:37:09.388 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Received HELLO, heartbeat interval: 13750
2020-06-04 23:37:09.388 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Identifying...
2020-06-04 23:37:09.388 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":0,"d":{"user_id":"629563525722931200","session_id":"08ce63d524f15f79b806af55fb26cd68","server_id":"665809317198430209","token":"2635a7381cadccbe"}}
2020-06-04 23:37:09.391 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":8,"d":{"v":4,"heartbeat_interval":13750.0}}
2020-06-04 23:37:09.392 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Received HELLO, heartbeat interval: 13750
2020-06-04 23:37:09.392 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Identifying...
2020-06-04 23:37:09.392 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":0,"d":{"user_id":"629563525722931200","session_id":"08ce63d524f15f79b806af55fb26cd68","server_id":"665809317198430209","token":"2635a7381cadccbe"}}
2020-06-04 23:37:09.394 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":8,"d":{"v":4,"heartbeat_interval":13750.0}}
2020-06-04 23:37:09.394 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Received HELLO, heartbeat interval: 13750
2020-06-04 23:37:09.394 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Identifying...
2020-06-04 23:37:09.394 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":0,"d":{"user_id":"629563525722931200","session_id":"08ce63d524f15f79b806af55fb26cd68","server_id":"665809317198430209","token":"2635a7381cadccbe"}}
2020-06-04 23:37:09.404 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":2,"d":{"modes":["aead_aes256_gcm","xsalsa20_poly1305_lite","xsalsa20_poly1305_suffix","xsalsa20_poly1305"],"port":50002,"ssrc":45913,"ip":"107.160.222.86","experiments":["bwe_conservative_link_estimate","bwe_remote_locus_client"]}}
2020-06-04 23:37:09.404 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Voice READY, ssrc: 45913
2020-06-04 23:37:09.404 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Selected preferred encryption mode: xsalsa20_poly1305
2020-06-04 23:37:09.404 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Generated new connection id: 72cee09b-c229-4844-a025-7b495ab7676b
2020-06-04 23:37:09.404 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.i.handler.DiscordUDPConnection     : Connecting to /107.160.222.86:50002...
2020-06-04 23:37:09.408 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":2,"d":{"modes":["aead_aes256_gcm","xsalsa20_poly1305_lite","xsalsa20_poly1305_suffix","xsalsa20_poly1305"],"port":50002,"ssrc":45913,"ip":"107.160.222.86","experiments":["bwe_conservative_link_estimate","bwe_remote_locus_client"]}}
2020-06-04 23:37:09.411 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Voice READY, ssrc: 45913
2020-06-04 23:37:09.411 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Selected preferred encryption mode: xsalsa20_poly1305
2020-06-04 23:37:09.411 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Generated new connection id: 9e3a7cd6-0fb3-4909-ae5a-f9de96dbba72
2020-06-04 23:37:09.411 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.i.handler.DiscordUDPConnection     : Connecting to /107.160.222.86:50002...
2020-06-04 23:37:09.411 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Waiting for session description...
2020-06-04 23:37:09.411 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : Websocket closed, code: 4006, reason: Session is no longer valid.
2020-06-04 23:37:09.415 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":2,"d":{"modes":["aead_aes256_gcm","xsalsa20_poly1305_lite","xsalsa20_poly1305_suffix","xsalsa20_poly1305"],"port":50002,"ssrc":45913,"ip":"107.160.222.86","experiments":["bwe_conservative_link_estimate","bwe_remote_locus_client"]}}
2020-06-04 23:37:09.415 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Voice READY, ssrc: 45913
2020-06-04 23:37:09.415 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Selected preferred encryption mode: xsalsa20_poly1305
2020-06-04 23:37:09.415 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Generated new connection id: ba8d3a90-a299-4651-becd-f8786fe04310
2020-06-04 23:37:09.415 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.i.handler.DiscordUDPConnection     : Connecting to /107.160.222.86:50002...
2020-06-04 23:37:09.415 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Waiting for session description...
2020-06-04 23:37:09.416 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.internal.handler.HolepunchHandler  : Holepunch [attempt 1/10, local ip: /172.17.0.2:56936]
2020-06-04 23:37:09.416 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Waiting for session description...
2020-06-04 23:37:09.416 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.internal.handler.HolepunchHandler  : Holepunch [attempt 1/10, local ip: /172.17.0.2:53602]
2020-06-04 23:37:09.416 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.internal.handler.HolepunchHandler  : Holepunch [attempt 1/10, local ip: /172.17.0.2:42874]
2020-06-04 23:37:09.416 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : Websocket closed, code: 4006, reason: Session is no longer valid.
2020-06-04 23:37:09.424 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Connected, our external address is: /194.242.2.126:56936
2020-06-04 23:37:09.424 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":1,"d":{"rtc_connection_id":"ba8d3a90-a299-4651-becd-f8786fe04310","mode":"xsalsa20_poly1305","protocol":"udp","address":"194.242.2.126","data":{"mode":"xsalsa20_poly1305","address":"194.242.2.126","port":56936},"port":56936,"codecs":[{"payload_type":120,"name":"opus","type":"audio","priority":1000}]}}
2020-06-04 23:37:09.424 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":12,"d":{"audio_ssrc":45913,"video_ssrc":0,"rtx_ssrc":0}}
2020-06-04 23:37:09.421 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":2,"d":{"modes":["aead_aes256_gcm","xsalsa20_poly1305_lite","xsalsa20_poly1305_suffix","xsalsa20_poly1305"],"port":50002,"ssrc":45913,"ip":"107.160.222.86","experiments":["bwe_conservative_link_estimate","bwe_remote_locus_client"]}}
2020-06-04 23:37:09.425 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Voice READY, ssrc: 45913
2020-06-04 23:37:09.425 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Selected preferred encryption mode: xsalsa20_poly1305
2020-06-04 23:37:09.425 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Generated new connection id: 002d30f9-4dad-482a-a384-32c34f875190
2020-06-04 23:37:09.430 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.i.handler.DiscordUDPConnection     : Connecting to /107.160.222.86:50002...
2020-06-04 23:37:09.430 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Waiting for session description...
2020-06-04 23:37:09.430 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : Websocket closed, code: 4006, reason: Session is no longer valid.
2020-06-04 23:37:09.431 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.internal.handler.HolepunchHandler  : Holepunch [attempt 1/10, local ip: /172.17.0.2:53235]
2020-06-04 23:37:09.433 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Connected, our external address is: /194.242.2.126:53602
2020-06-04 23:37:09.434 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Connected, our external address is: /194.242.2.126:42874
2020-06-04 23:37:09.434 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Connected, our external address is: /194.242.2.126:53235
2020-06-04 23:37:09.445 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":4,"d":{"video_codec":"H264","mode":"xsalsa20_poly1305","secret_key":[64,158,55,157,24,192,226,185,11,66,48,179,182,92,243,203,209,124,74,160,247,229,124,225,108,14,97,84,232,236,249,160],"media_session_id":"6f9b17a59140b7d9519a8a90fbe2335f","audio_codec":"opus"}}
2020-06-04 23:37:09.445 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Got session description: {"video_codec":"H264","mode":"xsalsa20_poly1305","secret_key":[64,158,55,157,24,192,226,185,11,66,48,179,182,92,243,203,209,124,74,160,247,229,124,225,108,14,97,84,232,236,249,160],"media_session_id":"6f9b17a59140b7d9519a8a90fbe2335f","audio_codec":"opus"}
2020-06-04 23:37:21.243  INFO 1 --- [   XNIO-1 I/O-2] lavalink.server.io.SocketServer          : {"op": "voiceUpdate", "guildId": "593317393120624651", "sessionId": "08ce63d524f15f79b806af55fb26cd68", "event": {"token": "d6f1e25a95de0d37", "guild_id": "593317393120624651", "endpoint": "us-west789.discord.media:80"}}
2020-06-04 23:37:21.244 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.koe.internal.VoiceConnectionImpl     : Disconnecting...
2020-06-04 23:37:21.255 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.k.g.AbstractVoiceGatewayConnection   : Connecting to wss://us-west789.discord.media:443/?v=4
2020-06-04 23:37:21.269  INFO 1 --- [   XNIO-1 I/O-2] lavalink.server.io.SocketServer          : {"op": "voiceUpdate", "guildId": "593317393120624651", "sessionId": "08ce63d524f15f79b806af55fb26cd68", "event": {"token": "d6f1e25a95de0d37", "guild_id": "593317393120624651", "endpoint": "us-west789.discord.media:80"}}
2020-06-04 23:37:21.269 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.koe.internal.VoiceConnectionImpl     : Disconnecting...
2020-06-04 23:37:21.270 DEBUG 1 --- [   XNIO-1 I/O-2] m.k.k.g.AbstractVoiceGatewayConnection   : Connecting to wss://us-west789.discord.media:443/?v=4
2020-06-04 23:37:21.406 TRACE 1 --- [ntLoopGroup-2-2] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":8,"d":{"v":4,"heartbeat_interval":13750.0}}
2020-06-04 23:37:21.408 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : -> {"op":8,"d":{"v":4,"heartbeat_interval":13750.0}}
2020-06-04 23:37:21.409 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Received HELLO, heartbeat interval: 13750
2020-06-04 23:37:21.409 DEBUG 1 --- [ntLoopGroup-2-1] m.k.k.gateway.VoiceGatewayV4Connection   : Identifying...
2020-06-04 23:37:21.410 TRACE 1 --- [ntLoopGroup-2-1] m.k.k.g.AbstractVoiceGatewayConnection   : <- {"op":0,"d":{"user_id":"629563525722931200","session_id":"08ce63d524f15f79b806af55fb26cd68","server_id":"593317393120624651","token":"d6f1e25a95de0d37"}}
2020-06-04 23:37:21.409 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Received HELLO, heartbeat interval: 13750
2020-06-04 23:37:21.412 DEBUG 1 --- [ntLoopGroup-2-2] m.k.k.gateway.VoiceGatewayV4Connection   : Identifying...

Jun 04 '20 23:06 applebee1558

This is a bug. You appear to be on the dev version, where we recently switched from Magma to Koe as our Discord voice library.

I actually added idempotency to voice server update handling in Magma. Looks like Koe doesn't check for that.

CC @alula

Jun 05 '20 00:06 freyacodes

Yes, I am on the dev branch since there was another error on the main branch (the disconnections after re connection). Should I switch back to master and just send connections after a disconnection? Thanks for clarifying the reason behind this.

Jun 05 '20 00:06 applebee1558

Ok, using master, which seems to still use magma based on the build.gradle file, the 1000 close code random disconnects seemed to go away too. EDIT: it seems to still happen but lavalink takes care of it

2020-06-05 04:46:17.413  INFO 1 --- [     parallel-1] s.n.m.impl.connections.AudioWebSocket    : Websocket to wss://us-west449.discord.media/?v=4 closed with code 1000 and reason
2020-06-05 04:46:17.430  INFO 1 --- [     parallel-1] s.n.m.impl.connections.AudioWebSocket    : Resuming

Jun 05 '20 04:06 applebee1558

This seems to be resolved so I'm gonna close it. If the issue persists feel free to reopen the issue

Oct 31 '22 11:10 topi314

Lavalink Lavalink copied to clipboard

Ignore voiceUpdate events when a connection is already established in place.

Description

Version info

Lavalink
Lavalink copied to clipboard