No audio is played even though no error appears

[Fyphen1223]

Description

As the title mentions, no audio is played even though I sent the request and lavalink's log shows literally no error.

Expected behavior

Should play audio.

Version info

Client used:
Version: 4.0.4
Build time: 10.03.2024 17:32:10 UTC
Branch HEAD
Commit: bb5e126
Commit time: 10.03.2024 17:29:08 UTC
JVM: 20.0.1
Lavaplayer 2.1.1

Shoukaku 4.0.1

Output of java -version:

openjdk 20.0.1 2023-04-18
OpenJDK Runtime Environment (build 20.0.1+9-29)
OpenJDK 64-Bit Server VM (build 20.0.1+9-29, mixed mode, sharing)

Lavalink Logs:

2024-03-17T09:43:18.670+09:00  INFO 3056 --- [  XNIO-1 task-3] lavalink.server.player.AudioLoader       : Loaded playlist Search results for:
2024-03-17T09:43:18.677+09:00  INFO 3056 --- [  XNIO-1 task-3] l.server.io.RequestLoggingFilter         : GET 
/v4/loadtracks?identifier=ytsearch%3A, client=0:0:0:0:0:0:0:1
2024-03-17T09:43:20.689+09:00  INFO 3056 --- [  XNIO-1 task-3] l.server.player.AudioLoaderRestHandler   : Got 
request to load for identifier "avicii wake me up"
2024-03-17T09:43:20.690+09:00  INFO 3056 --- [  XNIO-1 task-3] lavalink.server.player.AudioLoader       : No matches found
2024-03-17T09:43:20.693+09:00  INFO 3056 --- [  XNIO-1 task-3] l.server.io.RequestLoggingFilter         : GET 
/v4/loadtracks?identifier=avicii+wake+me+up, client=0:0:0:0:0:0:0:1
2024-03-17T09:43:20.698+09:00  INFO 3056 --- [  XNIO-1 task-3] l.server.player.AudioLoaderRestHandler   : Got 
request to load for identifier "ytsearch:avicii wake me up"
2024-03-17T09:43:21.153+09:00  INFO 3056 --- [  XNIO-1 task-3] lavalink.server.player.AudioLoader       : Loaded playlist Search results for: avicii wake me up
2024-03-17T09:43:21.166+09:00  INFO 3056 --- [  XNIO-1 task-3] l.server.io.RequestLoggingFilter         : GET 
/v4/loadtracks?identifier=ytsearch%3Aavicii+wake+me+up, client=0:0:0:0:0:0:0:1
2024-03-17T09:43:21.437+09:00  INFO 3056 --- [  XNIO-1 task-3] l.server.io.RequestLoggingFilter         : PATCH /v4/sessions/54b66a3o0d9x075r/players/919809544648020008?noReplace=false, client=0:0:0:0:0:0:0:1, payload={"voice":{"token":"1b8f0529ac256249","endpoint":"japan9605.discord.media:443","sessionId":"6d1f6e9550d93d033975d48d153789ec"}}
2024-03-17T09:43:21.442+09:00  INFO 3056 --- [  XNIO-1 task-3] l.server.player.AudioLoaderRestHandler   : Got 
request to load for identifier "avicii wake me up"
2024-03-17T09:43:21.443+09:00  INFO 3056 --- [  XNIO-1 task-3] lavalink.server.player.AudioLoader       : No matches found
2024-03-17T09:43:21.444+09:00  INFO 3056 --- [  XNIO-1 task-3] l.server.io.RequestLoggingFilter         : GET 
/v4/loadtracks?identifier=avicii+wake+me+up, client=0:0:0:0:0:0:0:1
2024-03-17T09:43:21.447+09:00  INFO 3056 --- [  XNIO-1 task-3] l.server.player.AudioLoaderRestHandler   : Got 
request to load for identifier "ytsearch:avicii wake me up"
2024-03-17T09:43:21.907+09:00  INFO 3056 --- [  XNIO-1 task-3] lavalink.server.player.AudioLoader       : Loaded playlist Search results for: avicii wake me up
2024-03-17T09:43:21.910+09:00  INFO 3056 --- [  XNIO-1 task-3] l.server.io.RequestLoggingFilter         : GET 
/v4/loadtracks?identifier=ytsearch%3Aavicii+wake+me+up, client=0:0:0:0:0:0:0:1
2024-03-17T09:43:22.207+09:00  INFO 3056 --- [  XNIO-1 task-3] l.server.io.RequestLoggingFilter         : PATCH /v4/sessions/54b66a3o0d9x075r/players/919809544648020008?noReplace=false, client=0:0:0:0:0:0:0:1, payload={"encoded":"QAAAvAMAJEF2aWNpaSAtIFdha2UgTWUgVXAgKE9mZmljaWFsIFZpZGVvKQAGQXZpY2lpAAAAAAAEKmgAC0ljcmJNMWxfQm9JAAEAK2h0dHBzOi8vd3d3LnlvdXR1YmUuY29tL3dhdGNoP3Y9SWNyYk0xbF9Cb0kBADRodHRwczovL2kueXRpbWcuY29tL3ZpL0ljcmJNMWxfQm9JL21heHJlc2RlZmF1bHQuanBnAAAHeW91dHViZQAAAAAAAAAA"}

Additional context

[topi314]

can you check and update the logs?
https://lavalink.dev/getting-started/troubleshooting.html#configuring-more-detailed-logging

[Fyphen1223]

Ok, I'll post it as soon as possible! (currently away with my PC)

[Fyphen1223]

I found this with DEBUG log level:

2024-03-18T08:35:56.981+09:00 DEBUG 20512 --- [  XNIO-1 task-3] org.apache.http.wire                     : http-outgoing-1 << "[0x98]6[0xc0][0xb4][0x1][0xa6][\r]0m[0x80]i[0x3]L[0x9b]}[0x0][0xa6][\r]0m.[\n]"
2024-03-18T08:35:56.981+09:00 DEBUG 20512 --- [  XNIO-1 task-3] org.apache.http.wire                     : http-outgoing-1 << "[0x2][0xa6][\r]0m[0x80]i[0x3]L[0x1b]`[0xda][0x0][0xd3][0x6][0x98]6G)[0xc0][0xb4][0x1][0xa6][0xcd]'[0x8d][0x1][0xd3][0xc6]B[0x8]0m[0x80]i[0x3]L[0x1b][0xb]1[0xc0][0xb4][0xf9]r[0x9d][0x3][0xd3][0xe6][0x81]L[0x1b][0xd8][0xa4][0xf7][0xc7]l[0xd2][0xeb][0xb2]|[0xff][0xca][0xd7][0xec][0x8b][0xbb]|8[0xad][0xdb][0x90][0xa5][0xa8][0x16][0xdd][0xde]9![0xfb]]V[0xf]<($[0x16][0xf3][0xc5][0x19]!h[0x0][0xad][\n]"
2024-03-18T08:35:56.982+09:00 DEBUG 20512 --- [  XNIO-1 task-3] org.apache.http.wire                     : http-outgoing-1 << "|`[0xa7][0xf2][0x87][0x9f][0x10]bu2[0x8f]\Y[0xb]r,[0x15][0x89]\?[0xda][0xed][0xdb][0xa6][0xd0][0xfa],[0x11][0x97]@[0x9a][0x1a][0xb9]no[0xde][0x8a][0xd3]dcEpq6[0xd1]d:[0xe3]0[0xd7][0x1f][0xf5]j[0xf3]T[0xab][0xd2][0x97][0xa5][0xc5]z[0x93]/[0x16][0x16][0x12][0x95][0xca][0xd5]JX[0xa3][0xd2]Kl[0xde]W[0x12][0xdb][0xcc][0x84][0xba][0xb3]F[I[0xc][0xb7][0xf1]\*[0xcc]6[0x8a][0x85][0xdf]*[0xe3]/z[0x93][\r][0x93]>[0x8e]s#[0xa1]\[0x9e][0x86][0xe8][0x15]5[0xab][0xb8][0x82][0xde]DQ[0xde][0x82]7Q[0x14][0xb0][0x9b]<t[0x80] [0xb0][0x9b][0xf6][0x82][0x9d]<;z[0xbc]c[0xfa]|[0x17]7[0xe1]$[0xbb][0xc9][0xbc][0xf3][0xf9]FD[0xe7][0xf][0xf6]sD![0xee][0xc3][0x8a]8U[0xcf][0x80][0x15]9[0x9]>[0xb7][0xb9][0xc0]nr[0xca][0xce][0xf6]wX[0x9f][0xef][0x12]D[0x1c]c7=[0xd5][0xc8]:[0xa2][\r][0xf7][0x19]Y[0xa7]*[0x19][0x8c][0xec]I[0xf0][0xb9][0x91][0x5]v[0xd3]'[0xc1]NZ^n[0x87][0x5]j[0x0][0xbb][0xe9]D_[0xae]b79RI[0xc0]n[0xfa]![0xd4][0x9][0xb0][0x9b]Nd;i[0x9d][0x1c][0xf8]z[0xa3]'[0xe0]Cv[0x93][0x9d][0x3][0xd0],[0xf3][0xf5][0xe2]#v[0x93][0xcd][0xf6][0xf1]pk[0xe6]Q[0x8][0x9][0xb0][0x9b][0x8e]B[0x1d][0xe6][0x91][0xb4]w[0xc0]"[0xce][0x15][0xec][0xa6][0xa7][0xe]o[0x1d]Q[0x5][0xb0][0x9b][0x80][0xdd]t[0x88][0xb][0xec]&_[0xb1][0x9b][0x9c][0xf2][0xfa]~[0x84][0xdd][0xb4][0xaa][0xbf]Oc[0xa5][0x14][0xb5]([0xa6][0x7][0x18][0xbf][0xf2][0x1f][0xbb][0xe9]G}[0xaa][0xb]w[0xee]c7}[0xb3]}[0x0][0xbb][0xc9][0x14].~Q[0xf7][0x1e][0xbb][0xe9][0xda]i[0xe0]F[0xf0][0xc8][0xa2][0xac][0xbb][0xce][\n]"
2024-03-18T08:35:56.988+09:00 DEBUG 20512 --- [  XNIO-1 task-3] org.apache.http.wire                     : http-outgoing-1 << "[0xbf]][0xb2]?[0x18][0xde]d[0xf]}[0x81][0xee][[0xaa][0xdc][0x82][0xe2]tX[0xbe][0x8a][0xae][0xf]+z[0xd0]u[0xe8][0x11]([0xa7][0x93][0xbf][0xcf][0xb4]ii(+[0x95][0xc5][0x86]iGb[0xf6]M[0xf9]1[0x8d][0xfd][0xd5]![0x9d][0x17]m[0xc3][0x3]R[0x12][0xfb]yr[0xc3]\[0x11]/[0xda]\}[0xcc][0xf4][0xa8]ee[0x9d][0x8][0xd3]2[0x9b][0x98][0x5][0xe3][0x89]Kg[0xc8]Gg[0xed][0x8][0xd6][0x94]j[0xcd]d[0xb4][0xd9][0x10][0xe3][0x15],[0x99][0xaa]5>[0xb2][0xb5]F6^H[0xad]gm[0x2][0xcf][0xd5][0xc7][0xf8][0xbb][0x90][0xfa][0xa8]v[0xeb]b[0xee][0x83][0xe0][0x93][0xf5][0x4][0xd5][0xee][0xa6]>[0xea][0x85]t4[0xd6][0x92][0xa6]2[0xdb],&[0xbb][0x8d]b[0xb4]QO[0xd6][0x1b][0xe3][0xa6][0xca]N[0x8a][0xf3][0xda][0x98]*6[0xc9][0xc1][0xa4][0xd1][0xc2][0xa8][0xda][0x84][0x97][0xaa][0x8d]u[0xb3][0x80]Ql[0x13][0xe3][0xf0]Z#[0xfc].[0xc4]?[0xaa][0x85][0x16][0x1e][0xaf]4[0x93]X#U\T[0xea][0xd1]A[0xa1].[0xb2][0xed]z[0xb4]YhU0-}A[0x9c][0xce]>Zx[0xab][0xd6][0xe4][0xdf][0x1b]"_d[0xf1]iIO?9^[0xb1][0x9][0xb1]Qm[\r]2[0xcd][0x14][0x15][0xab]&[0x1b][0xab]*[0x86][0xd2]"
2024-03-18T08:35:56.990+09:00 DEBUG 20512 --- [  XNIO-1 task-3] org.apache.http.wire                     : http-outgoing-1 << "[0xaf][0xa6]>[0x9a][0xd5]q[0xb5]A)-[0x91][0x1f][0xb3][0xd8][0xb4][0xdc]L[0x8a][0xd9][0xca][0xb8][0xb0][0xaa][0x8d][0x7],KF[0x89]V[0xb]_[0xd5][0x1a]T[0xb2]1[0x99]J[0x1f]X[0xb3][0xd0][0x14][0x7][0xb5]*F)\j=`'}[0xbc][0xd5][0x9a][0xd6][0x91][0xe][0xaa]|b[0xd0][0xe8]6[0xa7][0xb5][0xd6][0x4][0xf][0xf2]b[0xb2][0xc9][0xa7]p[0xb6][0x98][0x10]c[0x95]m4[0xc7][0xb6][0x9a][0x8b][0x9a]4m[0x17][0xa4]A[0xa5]5[0x8a]b[0xbb][0xf2]}4[0x13]T[0xa1]=[0x1e]|[0xf0]D1Q[0x9b][0xe0]%A,[0xea][0xe5]k5[0x1b][0xdb][0xfa][0xbb][0xf8]Qh[0x88]T[0x13][0xe5][0xa3][0xd0]J[0xe8][0xe5]+[0x88][0xd3][0xe5]Gk]j&[0xdb][0xeb]R[0xeb][0xe3][0xbd][0x9b][0xcc][0xa6][0x8c][0xf2][\r][0xe8][0xca][0x84][0xfa]h[0xa5][\n]"
2024-03-18T08:35:56.992+09:00 DEBUG 20512 --- [  XNIO-1 task-3] org.apache.http.wire                     : http-outgoing-1 << "k[0xe4][0x11][0xd5]Z[0xc9][0x8f][0xf6]GC/_[0xfe]#5[0x95][0xbb][\r]\[0xa9][0xbd]WK])[0xaa][0xd4]1[0xad]|E[0xba].[0x15][0xeb][0xd5][0xf7][\n]"
2024-03-18T08:35:56.993+09:00 DEBUG 20512 --- [  XNIO-1 task-3] org.apache.http.wire                     : http-outgoing-1 << "[0xd6][0xc6]y[0x99]oP[0x8d][0xaa][0x98][0xd5][0xcb][0xf7][0xd1][0xa2]V[0xd5]T[0x96]n[0x88][0x1f][0xcd]n3;[0xaf][0xe3][0x8d][0x8d]^[0xbe]4[0xdf]h[0xa5][0xa3][0xf1]:[0xaa][0xf][0xf4]|[0x86][0x8d]G[0xc7]E[0xc][0x95]o[0xc2]/[0xab][0x8d]b[0xac][0x8e][0xda][0xc3][0xc7][0xa6][0xb1][0xad][0xc6][0xd0][0xff][0xd1]a4X[0xea][0x9f][0xef][0x94][0xb8][0xec]d[0x16]*[0x9b].[0x17][0xcb][0xc][\n]"
2024-03-18T08:35:56.995+09:00 DEBUG 20512 --- [  XNIO-1 task-3] org.apache.http.wire                     : http-outgoing-1 << "[0x19][0xa2]P[0x8f]J[0xdd]%3[0x88][0x14][0x9a]$[0x16][0xc2][0xa3]U[0x82][0x17]+b[0xe9][0xc2]{[0xf1]j[0xdd][0xec][0x15]A[0xdc][0x9d]\wg[0xc3][0x1f]&j[0x89]V[0x92]`[[0xc5])[[0x8b][0x92][0xed][0xd6]\[0xcc][0xa4]p1[0x93]n[0xae]R[0xfd]u[0xf2][0x3][0x13][0xab][0x95]f3[A[0x8d][0xa7]Z[0xc7][0xda]}!.[0xa7][0xac]OJ[0xbf]\[0xd8]L[0x5][0xbd]%_)[0xec]'#`ef[0x12][0x95][0xc5][0x90][0xb1]7[0x98][0x99][0xb3]J_[0xd8][0xcd][0xa6][0xaa][0
xc8]dr[0x83][0xb7][0x9e] [0xf0]F[0xf2]Zr[0xba]~-[0x93][0x89]T[0x96][0xa9][0xf1][0xed]d^[0x6][0xfb][0xa3][0xf1][0xc][0xcd][0xef][0x92])[0xc8][0xa8][0xef][0x10][0x8c]c[0xf3],<[0x98][0x97][0x9e][0xbe]![0xfe][0xf4][0xe3][0xf6]n[0xa7]GM[0x17]`[0xec][0x98]O)[0xf2]bz[0xc1][0x3][0xda][0xa5]dz[0xf0][0xb3]ws[0xde]g[0x9d][0xc4])M/O[0x1c][uv7[0x16][0xbe][0xef][0xb2][0xbf][[0xd9][0xfa]R[0x15]Da[0xc9]j[0x7]<[0xd9][0xf6]#U[0xdd][0xe1][0xd3][0xc7]*[0x89]H5[0x96][0xee]$3[0xf9]z[0xa2][0xda][0xa9][0xd5]#[0xf5][0x86][0xb6]92y[0x9][0x81]v[0x12][0xc8][0xa4][0xc9][0
x8a][0xb][0xdd][0x8b]Pee[0xde][0xe9]n~[0xef][0xfe][0xed]([0x97]3c[0x91][0x95][0xef][0x17][0xbe]1[0xd5]^[0xcf][0x80][0xb6]/[0xd8][0xb1][0xe2][0xc7][0x13][0xdf]P[0xc0]B[0xcf]P[0xc7]:C?[0xa2][0x2]m[0xa9]3[0xf2]}[0xb4][0xa3][0xa9]][0xa1][0x1][0xcd]w[0xea]\[0xc8][0xcf][0x8f](@;*D[0xea][0xbb][0xa3][0xf0][0xca][0x85][0xbc][0x9c];}[0x9f][0x9c]fcO[0xfd][0xee][0xc4]0[0xe1][0xb0][0x9b][0xef][0xe2]~[0xec]+|[0xb9]3[0xf5][0xd4]P[0xd6][0x90]W|[0x9e][0xa7][0xd3][0x1c][0x9d]i[0xea][0x89][0xe6],"[0x8a]O[0xaa]J[0xbd][0x88][0x1d][0xa3][0xc3][0xf8][0x8d][0xaa][0xc3]X`[0xf4][0xa8][0x97][0xf8][0xc2][0xa8][0xe2][0xc7][\r][0xf8]I[0xa9]/[0xc]n~[0xa4][0xc8]1c[0xf4][0xe5][0x86][0xca][0xe6].e[0xe5]G[\n]"
2024-03-18T08:35:56.998+09:00 DEBUG 20512 --- [  XNIO-1 task-3] org.apache.http.wire                     : http-outgoing-1 << "^[0xbe][0xf2][0x1d][0xee][0xd1]%[0xbf][0xfc]M[0xf0]G[0x8a]^[0x90]Q[0xef][0xe0][0x86]rO[0xac]3[0xf2]D3[0xad][0xcf][0x1e]{[0xc5]FK[0x1a][0xae][0xf5]9[0xe][0xe7][0xa9][0x95][0xbe][0x94][0x91][0x1f]i[0xbd][0xda][0xb6]x[0x9d][0x3][0xee][0x86][0x16]l[0x9d][0x8f][0x1f])w][0xe6][0xd9]O[0xfe][0xc3]3[0xca]<[0xb7][0xce][0xc8][0xcf][0x14]z0T[0x3]+A[0x18][0xbb][0xa1][0xe0][0xd6][0xf9][0xf8][0xb9]rOdi>pC[0xc1]/d[0xe4][0xe7]J[0xae][0xcd][0xf7][0xba][0xa1][0xe0][0xd6][0xf9]xb[0xff]te[0xa4][0xed][0xe2]n[0xca][0x15]][0x14][0xbf];[0xe6][0xe6]Qs"[0xda],V [0xb8][0xff][0xf4][0xe1][0x86][0xd6][0xac][0xef][0xc9]~P[0xf1][0x83][0x81][0xb7][0x0][0x81][0xb9][0xa9][0xf4][0x13][0x81][0xb7]\_[0xfe]#[0xc5][0xd7][0xbe][0xeb][0xba][0xac][0xfc][0x1a]+[0xde]U[0xb6],~[0xe1][0x85]t[0xab]![0xd3][0x8f]:vCEZ[0xe6][0xe3]g[0xde][0xe2][0x9c][0x1b][\n]"
2024-03-18T08:35:57.001+09:00 DEBUG 20512 --- [  XNIO-1 task-3] org.apache.http.wire                     : http-outgoing-1 << "[0x1c]|[0x94][0xd7][0x95][0xb6]\[0xd6][0xfa][0xf0]1[0x5][0xff][0xa0][0xe2][0xd6][0x16]][0xfd]eU[0xff][0xb2]>p[0xe7][0xe1][0x13]@[0xdc][0xa3]&[0xbd][0x14][0x81][0xd5]v[[0x6]b[0xfa][0xe9]`[0xae]0[0xcf][0xdc].O[0xdc][0xa5],[0xfd][0x88]&H[0x1a][0xfb][0xff][0xfe]_7[0x14]_[0x9d][0xe][0x4]E[0xdb][0x13][0xf6][0xa8][0xa9][0xde]*[0xce]X[0x1e]+[0xf7][0xf0][0xb9]?[0xe3][0xb0][0xf4][0xce][0xa3][0x15]@[0xba][0xc2][0xd6][0x91][0x8f][0xb2]u[0xe9][0xb8][0xe5][0xce][0xab][0xc7][0xdb][0xf6]GM[0x17][0xe5]e[0xee][0xc2]p[0xe7][0x19][0xae][0xe7][0xa5][0xbc][0xfc][0xcc][0xd4][0xfe][0x2][0xa5][0xcd][0xaa][0x82]E[0xdb]z[0xc2][0xfb]}93[0xdf][0xf1][0xbd][0xad][0xe][0xab][0xb5][0xef]z'Q_[0xb3]P>[0x8f]K[0xce]\o[0xd3]_[0xc7][0x5][0xf4][0xfb]_[0x8d][0x8f][0xab]Jxc[0xdb]~T[0
x9e][0xcf][0xe5]Im [0x8]s[0xf3][0xd2][0x8e]][0x96][0xff][0xed][0xbf][0xfe][0xed][0xff][0x7][0x4][0x94][0xd2][0xc7]0[0xf4][0x7][0x0][\r][\n]"
2024-03-18T08:35:57.004+09:00 DEBUG 20512 --- [  XNIO-1 task-3] org.apache.http.wire                     : http-outgoing-1 << "0[\r][\n]"
2024-03-18T08:35:57.005+09:00 DEBUG 20512 --- [  XNIO-1 task-3] org.apache.http.wire                     : http-outgoing-1 << "[\r][\n]"
2024-03-18T08:35:57.005+09:00 DEBUG 20512 --- [  XNIO-1 task-3] h.i.c.PoolingHttpClientConnectionManager : Connection [id: 1][route: {s}->https://youtubei.googleapis.com:443] can be kept alive indefinitely
2024-03-18T08:35:57.005+09:00 DEBUG 20512 --- [  XNIO-1 task-3] h.i.c.DefaultManagedHttpClientConnection : http-outgoing-1: set socket timeout to 0
2024-03-18T08:35:57.006+09:00 DEBUG 20512 --- [  XNIO-1 task-3] h.i.c.PoolingHttpClientConnectionManager : Connection released: [id: 1][route: {s}->https://youtubei.googleapis.com:443][total available: 2; route allocated: 2 of 1500; total allocated: 2 of 3000]
2024-03-18T08:35:57.010+09:00 DEBUG 20512 --- [  XNIO-1 task-3] c.s.d.l.s.youtube.YoutubeSearchProvider  : Attempting to parse results from search page
2024-03-18T08:35:57.012+09:00 DEBUG 20512 --- [  XNIO-1 task-3] c.s.d.l.p.DefaultAudioPlayerManager      : Loaded a playlist with identifier ytsearch:avicii wake me up using YoutubeAudioSourceManager.
2024-03-18T08:35:57.013+09:00  INFO 20512 --- [  XNIO-1 task-3] lavalink.server.player.AudioLoader       : Loaded playlist Search results for: avicii wake me up
2024-03-18T08:35:57.015+09:00 DEBUG 20512 --- [  XNIO-1 task-3] o.s.w.s.m.m.a.HttpEntityMethodProcessor  : Using 'application/json', given [*/*] and supported [application/json, application/*+json]
2024-03-18T08:35:57.022+09:00 DEBUG 20512 --- [  XNIO-1 task-3] o.s.w.s.m.m.a.HttpEntityMethodProcessor  : Writing [SearchResult(loadType=SEARCH, data=Data(tracks=[Track(encoded=QAAAvAMAJEF2aWNpaSAtIFdha2UgTWUgVXAgKE (truncated)...]
2024-03-18T08:35:57.029+09:00 DEBUG 20512 --- [  XNIO-1 task-3] o.s.web.servlet.DispatcherServlet        : Completed 200 OK
2024-03-18T08:35:57.030+09:00  INFO 20512 --- [  XNIO-1 task-3] l.server.io.RequestLoggingFilter         : GET /v4/loadtracks?identifier=ytsearch%3Aavicii+wake+me+up, client=0:0:0:0:0:0:0:1
2024-03-18T08:35:57.411+09:00 DEBUG 20512 --- [  XNIO-1 task-3] io.undertow.request.security             : Attempting to authenticate /v4/sessions/evewo3n840cfq5cs/players/919809544648020008, authentication required: false
2024-03-18T08:35:57.413+09:00 DEBUG 20512 --- [  XNIO-1 task-3] io.undertow.request.security             : Authentication outcome was NOT_ATTEMPTED with method io.undertow.security.impl.CachedAuthenticatedSessionMechanism@4d573e39 for /v4/sessions/evewo3n840cfq5cs/players/919809544648020008
2024-03-18T08:35:57.413+09:00 DEBUG 20512 --- [  XNIO-1 task-3] io.undertow.request.security             : Authentication result was ATTEMPTED for /v4/sessions/evewo3n840cfq5cs/players/919809544648020008
2024-03-18T08:35:57.414+09:00 DEBUG 20512 --- [  XNIO-1 task-3] o.s.web.servlet.DispatcherServlet        : PATCH "/v4/sessions/evewo3n840cfq5cs/players/919809544648020008?noReplace=false", parameters={masked}
2024-03-18T08:35:57.415+09:00 DEBUG 20512 --- [  XNIO-1 task-3] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped to lavalink.server.player.PlayerRestHandler#patchPlayer(PlayerUpdate, String, long, boolean)
2024-03-18T08:35:57.419+09:00 DEBUG 20512 --- [  XNIO-1 task-3] m.m.a.RequestResponseBodyMethodProcessor : Read "application/json;charset=UTF-8" to [PlayerUpdate(encodedTrack=OMITTED, identifier=OMITTED, track=OMITTED, position=OMITTED, endTime=OMIT (truncated)...]
2024-03-18T08:35:57.424+09:00 DEBUG 20512 --- [  XNIO-1 task-3] o.s.w.s.m.m.a.HttpEntityMethodProcessor  : Using 'application/json', given [*/*] and supported [application/json, application/*+json]
2024-03-18T08:35:57.425+09:00 DEBUG 20512 --- [  XNIO-1 task-3] o.s.w.s.m.m.a.HttpEntityMethodProcessor  : Writing [Player(guildId=919809544648020008, track=null, volume=100, paused=false, state=PlayerState(time=1710 (truncated)...]
2024-03-18T08:35:57.428+09:00 DEBUG 20512 --- [  XNIO-1 task-3] o.s.web.servlet.DispatcherServlet        : Completed 200 OK
2024-03-18T08:35:57.429+09:00  INFO 20512 --- [  XNIO-1 task-3] l.server.io.RequestLoggingFilter         : PATCH /v4/sessions/evewo3n840cfq5cs/players/919809544648020008?noReplace=false, client=0:0:0:0:0:0:0:1, payload={"encoded":"QAAAvAMAJEF2aWNpaSAtIFdha2UgTWUgVXAgKE9mZmljaWFsIFZpZGVvKQAGQXZpY2lpAAAAAAAEKmgAC0ljcmJNMWxfQm9JAAEAK2h0dHBzOi8vd3d3LnlvdXR1YmUuY29tL3dhdGNoP3Y9SWNyYk0xbF9Cb0kBADRodHRwczovL2kueXRpbWcuY29tL3ZpL0ljcmJNMWxfQm9JL21heHJlc2RlZmF1bHQuanBnAAAHeW91dHViZQAAAAAAAAAA"}
2024-03-18T08:35:57.684+09:00 DEBUG 20512 --- [ntLoopGroup-2-2] i.netty.channel.DefaultChannelPipeline   : Discarded inbound message DatagramPacket(/66.22.210.33:50007 => /192.168.11.30:60373, PooledUnsafeDirectByteBuf(ridx: 0, widx: 52, cap: 2048)) that reached at the tail of the pipeline. Please check your pipeline configuration.
2024-03-18T08:35:57.685+09:00 DEBUG 20512 --- [ntLoopGroup-2-2] i.netty.channel.DefaultChannelPipeline   : Discarded message pipeline : [DefaultChannelPipeline$TailContext#0]. Channel : [id: 0x69b4217a, L:/192.168.11.30:60373 - R:/66.22.210.33:50007].
2024-03-18T08:35:58.666+09:00 DEBUG 20512 --- [ntLoopGroup-2-2] i.netty.channel.DefaultChannelPipeline   : Discarded inbound message DatagramPacket(/66.22.210.33:50007 => /192.168.11.30:60373, PooledUnsafeDirectByteBuf(ridx: 0, widx: 52, cap: 2048)) that reached at the tail of the pipeline. Please check your pipeline configuration.
2024-03-18T08:35:58.668+09:00 DEBUG 20512 --- [ntLoopGroup-2-2] i.netty.channel.DefaultChannelPipeline   : Discarded message pipeline : [DefaultChannelPipeline$TailContext#0]. Channel : [id: 0x69b4217a, L:/192.168.11.30:60373 - R:/66.22.210.33:50007].
2024-03-18T08:35:59.645+09:00 DEBUG 20512 --- [ntLoopGroup-2-2] i.netty.channel.DefaultChannelPipeline   : Discarded inbound message DatagramPacket(/66.22.210.33:50007 => /192.168.11.30:60373, PooledUnsafeDirectByteBuf(ridx: 0, widx: 52, cap: 2048)) that reached at the tail of the pipeline. Please check your pipeline configuration.

[topi314]

this is not at all what I asked for, don't put root to debug also include the whole thing

[Fyphen1223]

wdym with the whole thing? Sorry I couldn't get it

[Fyphen1223]

Is this okay

2024-03-18T08:43:37.057+09:00  INFO 6976 --- [           main] lavalink.server.Launcher                 : Starting Launcher v4.0.4 using Java 20.0.1 with PID 6976 (C:\Users\shoot\Desktop\Program\CosmoMusic\lavalink\Lavalink.jar started by shoot in C:\Users\shoot\Desktop\Program\CosmoMusic\lavalink)
2024-03-18T08:43:37.070+09:00 DEBUG 6976 --- [           main] lavalink.server.Launcher                 : Running with Spring Boot v3.1.0, Spring v6.0.9
2024-03-18T08:43:37.072+09:00  INFO 6976 --- [           main] lavalink.server.Launcher                 : No active profile set, falling back to 1 default profile: "default"
2024-03-18T08:43:39.736+09:00  INFO 6976 --- [           main] l.server.bootstrap.PluginManager         : Found plugin 'java-lyrics-plugin' version 1.6.3
2024-03-18T08:43:39.737+09:00  INFO 6976 --- [           main] l.server.bootstrap.PluginManager         : Found plugin 'lavasrc-plugin' version 4.0.1
2024-03-18T08:43:39.738+09:00  INFO 6976 --- [           main] l.server.bootstrap.PluginManager         : Found plugin 'sponsorblock-plugin' version 3.0.0
2024-03-18T08:43:39.757+09:00  INFO 6976 --- [           main] l.server.bootstrap.PluginManager         : Loaded java-lyrics-plugin-1.6.3.jar (2 classes)
2024-03-18T08:43:39.987+09:00  INFO 6976 --- [           main] l.server.bootstrap.PluginManager         : Loaded lavasrc-plugin-4.0.1.jar (123 classes)
2024-03-18T08:43:40.129+09:00  INFO 6976 --- [           main] l.server.bootstrap.PluginManager         : Loaded sponsorblock-plugin-3.0.0.jar (90 classes)
2024-03-18T08:43:40.410+09:00  INFO 6976 --- [           main] lavalink.server.Launcher                 : Started Launcher in 4.602 seconds (process running for 5.747)
2024-03-18T08:43:40.624+09:00  INFO 6976 --- [           main] lavalink.server.Launcher                 : 

       .   _                  _ _       _    __ _ _
      /\\ | | __ ___   ____ _| (_)_ __ | | __\ \ \ \
     ( ( )| |/ _` \ \ / / _` | | | '_ \| |/ / \ \ \ \
      \\/ | | (_| |\ V / (_| | | | | | |   <   ) ) ) )
       '  |_|\__,_| \_/ \__,_|_|_|_| |_|_|\_\ / / / /
    =========================================/_/_/_/

        Version:        4.0.4
        Build time:     10.03.2024 17:32:10 UTC
        Branch          HEAD
        Commit:         bb5e126
        Commit time:    10.03.2024 17:29:08 UTC
        JVM:            20.0.1
        Lavaplayer      2.1.1

2024-03-18T08:43:40.646+09:00  INFO 6976 --- [           main] lavalink.server.Launcher                 : No active profile set, falling back to 1 default profile: "default"
2024-03-18T08:43:42.404+09:00  WARN 6976 --- [           main] io.undertow.websockets.jsr               : UT026010: Buffer pool was not set on WebSocketDeploymentInfo, the default pool will be used
2024-03-18T08:43:42.468+09:00  INFO 6976 --- [           main] io.undertow.servlet                      : Initializing Spring embedded WebApplicationContext
2024-03-18T08:43:42.470+09:00  INFO 6976 --- [           main] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 1814 ms
2024-03-18T08:43:42.608+09:00 DEBUG 6976 --- [           main] l.s.config.AudioPlayerConfiguration      : No rate limit config block found, skipping setup of route planner
2024-03-18T08:43:42.647+09:00  INFO 6976 --- [           main] c.g.t.lavasrc.plugin.LavaSrcPlugin       : Loading LavaSrc plugin...
2024-03-18T08:43:42.900+09:00  INFO 6976 --- [           main] c.s.d.l.tools.GarbageCollectionMonitor   : GC monitoring enabled, reporting results every 2 minutes.
2024-03-18T08:43:42.901+09:00 DEBUG 6976 --- [           main] l.s.config.AudioPlayerConfiguration      : Setting frame buffer duration to 5000ms
2024-03-18T08:43:42.902+09:00 DEBUG 6976 --- [           main] l.s.config.AudioPlayerConfiguration      : Setting opusEncodingQuality to 10
2024-03-18T08:43:42.902+09:00 DEBUG 6976 --- [           main] l.s.config.AudioPlayerConfiguration      : Setting resamplingQuality to LOW
2024-03-18T08:43:42.903+09:00 DEBUG 6976 --- [           main] l.s.config.AudioPlayerConfiguration      : Setting Track Stuck Threshold to 10000ms
2024-03-18T08:43:42.904+09:00 DEBUG 6976 --- [           main] l.s.config.AudioPlayerConfiguration      : Setting useSeekGhosting to true
2024-03-18T08:43:42.936+09:00 DEBUG 6976 --- [           main] l.s.config.AudioPlayerConfiguration      : Youtube config block is not found
2024-03-18T08:43:43.540+09:00  INFO 6976 --- [           main] c.g.t.lavasrc.plugin.LavaSrcPlugin       : Registering Spotify audio source manager...
2024-03-18T08:43:43.779+09:00  INFO 6976 --- [           main] l.server.config.KoeConfiguration         : OS: 
WINDOWS, Arch: X86_64
2024-03-18T08:43:43.779+09:00  INFO 6976 --- [           main] l.server.config.KoeConfiguration         : Enabling JDA-NAS
2024-03-18T08:43:43.785+09:00  INFO 6976 --- [           main] c.s.l.c.natives.NativeLibraryLoader      : Native library udpqueue: loading with filter null
2024-03-18T08:43:43.879+09:00  INFO 6976 --- [           main] c.s.l.c.natives.NativeLibraryLoader      : Native library udpqueue: successfully loaded.
2024-03-18T08:43:43.887+09:00  INFO 6976 --- [           main] c.g.t.s.plugin.SponsorBlockPlugin        : Loading SponsorBlock Plugin...
2024-03-18T08:43:43.992+09:00  WARN 6976 --- [           main] l.server.config.SentryConfiguration      : Turning off sentry
2024-03-18T08:43:44.878+09:00 DEBUG 6976 --- [           main] l.server.io.RequestLoggingFilter         : Filter 'logFilter' configured for use
2024-03-18T08:43:44.881+09:00 DEBUG 6976 --- [           main] l.server.io.ResponseHeaderFilter         : Filter 'responseHeaderFilter' configured for use
2024-03-18T08:43:44.885+09:00  INFO 6976 --- [           main] io.undertow                              : starting server: Undertow - 2.3.6.Final
2024-03-18T08:43:44.910+09:00  INFO 6976 --- [           main] org.xnio                                 : XNIO version 3.8.8.Final
2024-03-18T08:43:44.929+09:00  INFO 6976 --- [           main] org.xnio.nio                             : XNIO NIO Implementation Version 3.8.8.Final
2024-03-18T08:43:45.131+09:00  INFO 6976 --- [           main] org.jboss.threads                        : JBoss Threads version 3.5.0.Final
2024-03-18T08:43:45.235+09:00  INFO 6976 --- [           main] o.s.b.w.e.undertow.UndertowWebServer     : Undertow started on port(s) 2333 (http)
2024-03-18T08:43:45.251+09:00  INFO 6976 --- [           main] lavalink.server.Launcher                 : Started Launcher in 4.83 seconds (process running for 10.589)
2024-03-18T08:43:45.262+09:00  INFO 6976 --- [           main] lavalink.server.Launcher                 : Lavalink is ready to accept connections.
2024-03-18T08:43:52.292+09:00  INFO 6976 --- [  XNIO-1 task-2] io.undertow.servlet                      : Initializing Spring DispatcherServlet 'dispatcherServlet'
2024-03-18T08:43:52.293+09:00  INFO 6976 --- [  XNIO-1 task-2] o.s.web.servlet.DispatcherServlet        : Initializing Servlet 'dispatcherServlet'
2024-03-18T08:43:52.296+09:00  INFO 6976 --- [  XNIO-1 task-2] o.s.web.servlet.DispatcherServlet        : Completed initialization in 2 ms
2024-03-18T08:43:52.345+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.io.HandshakeInterceptorImpl     : Incoming connection from /[0:0:0:0:0:0:0:1]:56212
2024-03-18T08:43:52.391+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.io.RequestLoggingFilter         : GET 
/v4/websocket, client=0:0:0:0:0:0:0:1
2024-03-18T08:43:52.504+09:00  INFO 6976 --- [  XNIO-1 task-2] lavalink.server.io.SocketServer          : Connection successfully established from Cosmo Music/0.0.1
2024-03-18T08:43:52.509+09:00 TRACE 6976 --- [   XNIO-1 I/O-3] lavalink.server.io.SocketContext         : Sent {"op":"ready","resumed":false,"sessionId":"pg5bc18h8pk33ouw"}
2024-03-18T08:43:52.714+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.io.RequestLoggingFilter         : PATCH /v4/sessions/pg5bc18h8pk33ouw, client=0:0:0:0:0:0:0:1, payload={"resuming":true,"timeout":0}
2024-03-18T08:43:54.554+09:00 TRACE 6976 --- [pool-4-thread-1] lavalink.server.io.SocketContext         : Sent {"op":"stats","frameStats":null,"players":0,"playingPlayers":0,"uptime":17020,"memory":{"free":66094720,"used":40860032,"allocated":106954752,"reservable":3191865344},"cpu":{"cores":4,"systemLoad":1.0,"lavalinkLoad":0.28065378031979293}}
2024-03-18T08:43:59.187+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.player.AudioLoaderRestHandler   : Got 
request to load for identifier ""
2024-03-18T08:43:59.191+09:00  INFO 6976 --- [  XNIO-1 task-2] lavalink.server.player.AudioLoader       : No matches found
2024-03-18T08:43:59.209+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.io.RequestLoggingFilter         : GET 
/v4/loadtracks?identifier=, client=0:0:0:0:0:0:0:1
2024-03-18T08:43:59.215+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.player.AudioLoaderRestHandler   : Got 
request to load for identifier "ytsearch:"
2024-03-18T08:43:59.746+09:00  INFO 6976 --- [  XNIO-1 task-2] lavalink.server.player.AudioLoader       : Loaded playlist Search results for:
2024-03-18T08:43:59.785+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.io.RequestLoggingFilter         : GET 
/v4/loadtracks?identifier=ytsearch%3A, client=0:0:0:0:0:0:0:1
2024-03-18T08:44:01.768+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.player.AudioLoaderRestHandler   : Got 
request to load for identifier "avicii wake me up"
2024-03-18T08:44:01.769+09:00  INFO 6976 --- [  XNIO-1 task-2] lavalink.server.player.AudioLoader       : No matches found
2024-03-18T08:44:01.772+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.io.RequestLoggingFilter         : GET 
/v4/loadtracks?identifier=avicii+wake+me+up, client=0:0:0:0:0:0:0:1
2024-03-18T08:44:01.775+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.player.AudioLoaderRestHandler   : Got 
request to load for identifier "ytsearch:avicii wake me up"
2024-03-18T08:44:02.188+09:00  INFO 6976 --- [  XNIO-1 task-2] lavalink.server.player.AudioLoader       : Loaded playlist Search results for: avicii wake me up
2024-03-18T08:44:02.194+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.io.RequestLoggingFilter         : GET 
/v4/loadtracks?identifier=ytsearch%3Aavicii+wake+me+up, client=0:0:0:0:0:0:0:1
2024-03-18T08:44:03.422+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.io.RequestLoggingFilter         : PATCH /v4/sessions/pg5bc18h8pk33ouw/players/919809544648020008?noReplace=false, client=0:0:0:0:0:0:0:1, payload={"voice":{"token":"771afb0672016125","endpoint":"japan787.discord.media:443","sessionId":"c3d2d1371144c8859021aa97318a948b"}}
2024-03-18T08:44:03.427+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.player.AudioLoaderRestHandler   : Got 
request to load for identifier "avicii wake me up"
2024-03-18T08:44:03.427+09:00  INFO 6976 --- [  XNIO-1 task-2] lavalink.server.player.AudioLoader       : No matches found
2024-03-18T08:44:03.435+09:00 TRACE 6976 --- [   XNIO-1 I/O-3] lavalink.server.io.SocketContext         : Sent {"op":"playerUpdate","state":{"time":1710719043433,"position":0,"connected":true,"ping":0},"guildId":"919809544648020008"}
2024-03-18T08:44:03.440+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.io.RequestLoggingFilter         : GET 
/v4/loadtracks?identifier=avicii+wake+me+up, client=0:0:0:0:0:0:0:1
2024-03-18T08:44:03.444+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.player.AudioLoaderRestHandler   : Got 
request to load for identifier "ytsearch:avicii wake me up"
2024-03-18T08:44:03.807+09:00  INFO 6976 --- [  XNIO-1 task-2] lavalink.server.player.AudioLoader       : Loaded playlist Search results for: avicii wake me up
2024-03-18T08:44:03.812+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.io.RequestLoggingFilter         : GET 
/v4/loadtracks?identifier=ytsearch%3Aavicii+wake+me+up, client=0:0:0:0:0:0:0:1
2024-03-18T08:44:04.194+09:00  INFO 6976 --- [  XNIO-1 task-2] l.server.io.RequestLoggingFilter         : PATCH /v4/sessions/pg5bc18h8pk33ouw/players/919809544648020008?noReplace=false, client=0:0:0:0:0:0:0:1, payload={"encoded":"QAAAvAMAJEF2aWNpaSAtIFdha2UgTWUgVXAgKE9mZmljaWFsIFZpZGVvKQAGQXZpY2lpAAAAAAAEKmgAC0ljcmJNMWxfQm9JAAEAK2h0dHBzOi8vd3d3LnlvdXR1YmUuY29tL3dhdGNoP3Y9SWNyYk0xbF9Cb0kBADRodHRwczovL2kueXRpbWcuY29tL3ZpL0ljcmJNMWxfQm9JL21heHJlc2RlZmF1bHQuanBnAAAHeW91dHViZQAAAAAAAAAA"}

[topi314]

Your client Cosmo music is sending an invalid payload
Please refer to the docs for the correct JSON fields to send: https://lavalink.dev/api/rest.html#update-player

[Fyphen1223]

Humm, but I'm using Shoukaku client...

[Fyphen1223]

It's just a user-agent, so I don't think it will affect.

[topi314]

Connection successfully established from Cosmo Music/0.0.1
Then shoukaku uses the client name header wrong

It's supposed to be the client you are using

Anyway this is not a lavalink issue but an issue with your client

[Fyphen1223]

Ooooh, then I will ask this to Shoukaku! Thanks!