Dec 17 21:20:01 retrostream volumio[1188]: info: CoreCommandRouter::volumioVolatilePlay Dec 17 21:20:01 retrostream volumio[1188]: info: CoreStateMachine::volatilePlay Dec 17 21:20:01 retrostream volumio[1188]: info: Spotify Play Dec 17 21:20:01 retrostream volumio[1188]: info: Sending Spotify command to local API: /player/resume Dec 17 21:20:01 retrostream go-librespot[8298]: time="2025-12-17T21:20:01Z" level=trace msg="seek to 5750ms (diff: 95ms, samples: 253575, bytes: 220529)" uri="spotify:track:0BRHnOFm6sjxN1i9LJrUDu" Dec 17 21:20:01 retrostream go-librespot[8298]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for IQaudIODAC Dec 17 21:20:02 retrostream volumio[1188]: info: CoreCommandRouter::volumioVolatilePlay Dec 17 21:20:02 retrostream volumio[1188]: info: CoreStateMachine::volatilePlay Dec 17 21:20:02 retrostream volumio[1188]: info: Spotify Play Dec 17 21:20:02 retrostream volumio[1188]: info: Sending Spotify command to local API: /player/resume Dec 17 21:20:02 retrostream go-librespot[8298]: time="2025-12-17T21:20:02Z" level=trace msg="seek to 6989ms (diff: 83ms, samples: 308214, bytes: 278200)" uri="spotify:track:0BRHnOFm6sjxN1i9LJrUDu" Dec 17 21:20:02 retrostream go-librespot[8298]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for IQaudIODAC Dec 17 21:20:11 retrostream go-librespot[8298]: time="2025-12-17T21:20:11Z" level=debug msg="handling pause player command from 85620f0c4bdc3191512c82cfe895453834cca8d0" Dec 17 21:20:11 retrostream go-librespot[8298]: time="2025-12-17T21:20:11Z" level=debug msg="pause track at 6906ms" Dec 17 21:20:11 retrostream go-librespot[8298]: time="2025-12-17T21:20:11Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 17 21:20:11 retrostream go-librespot[8298]: time="2025-12-17T21:20:11Z" level=debug msg="sending successful reply for dealer request" Dec 17 21:20:11 retrostream go-librespot[8298]: time="2025-12-17T21:20:11Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 17 21:20:11 retrostream go-librespot[8298]: time="2025-12-17T21:20:11Z" level=trace msg="emitting websocket event: paused" Dec 17 21:20:11 retrostream volumio[1188]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","play_origin":"playlist/ondemand"}} Dec 17 21:20:11 retrostream volumio[1188]: SPOTIFY: PUSH STATE SPOTIFY Dec 17 21:20:11 retrostream volumio[1188]: SPOTIFY: {"status":"pause","service":"spop","title":"Juke-Joint Jezebel (Metropolis Mix)","artist":"KMFDM","album":"EXTRA Volume 3","albumart":"https://i.scdn.co/image/ab67616d00001e02ecbb9fee181ab0050f2992a2","uri":"spotify:track:6OE4xugLDqjCikJGJaUp2K","trackType":"spotify","seek":281978,"duration":317,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 17 21:20:11 retrostream volumio[1188]: info: CoreCommandRouter::servicePushState Dec 17 21:20:11 retrostream volumio[1188]: info: CoreStateMachine::pushState Dec 17 21:20:11 retrostream volumio[1188]: info: CoreCommandRouter::volumioPushState Dec 17 21:20:11 retrostream volumio[1188]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","play_origin":"playlist/ondemand"}} Dec 17 21:20:11 retrostream volumio[1188]: SPOTIFY: PUSH STATE SPOTIFY Dec 17 21:20:11 retrostream volumio[1188]: SPOTIFY: {"status":"pause","service":"spop","title":"Juke-Joint Jezebel (Metropolis Mix)","artist":"KMFDM","album":"EXTRA Volume 3","albumart":"https://i.scdn.co/image/ab67616d00001e02ecbb9fee181ab0050f2992a2","uri":"spotify:track:6OE4xugLDqjCikJGJaUp2K","trackType":"spotify","seek":281978,"duration":317,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 17 21:20:11 retrostream volumio[1188]: info: CoreCommandRouter::servicePushState Dec 17 21:20:11 retrostream volumio[1188]: info: CoreStateMachine::pushState Dec 17 21:20:11 retrostream volumio[1188]: info: CoreCommandRouter::volumioPushState Dec 17 21:20:11 retrostream volumio[1188]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","play_origin":"playlist/ondemand"}} Dec 17 21:20:11 retrostream volumio[1188]: SPOTIFY: PUSH STATE SPOTIFY Dec 17 21:20:11 retrostream volumio[1188]: SPOTIFY: {"status":"pause","service":"spop","title":"Juke-Joint Jezebel (Metropolis Mix)","artist":"KMFDM","album":"EXTRA Volume 3","albumart":"https://i.scdn.co/image/ab67616d00001e02ecbb9fee181ab0050f2992a2","uri":"spotify:track:6OE4xugLDqjCikJGJaUp2K","trackType":"spotify","seek":281978,"duration":317,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 17 21:20:11 retrostream volumio[1188]: info: CoreCommandRouter::servicePushState Dec 17 21:20:11 retrostream volumio[1188]: info: CoreStateMachine::pushState Dec 17 21:20:11 retrostream volumio[1188]: info: CoreCommandRouter::volumioPushState Dec 17 21:20:11 retrostream volumio[1188]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","play_origin":"playlist/ondemand"}} Dec 17 21:20:11 retrostream volumio[1188]: SPOTIFY: PUSH STATE SPOTIFY Dec 17 21:20:11 retrostream volumio[1188]: SPOTIFY: {"status":"pause","service":"spop","title":"Juke-Joint Jezebel (Metropolis Mix)","artist":"KMFDM","album":"EXTRA Volume 3","albumart":"https://i.scdn.co/image/ab67616d00001e02ecbb9fee181ab0050f2992a2","uri":"spotify:track:6OE4xugLDqjCikJGJaUp2K","trackType":"spotify","seek":281978,"duration":317,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 17 21:20:11 retrostream volumio[1188]: info: CoreCommandRouter::servicePushState Dec 17 21:20:11 retrostream volumio[1188]: info: CoreStateMachine::pushState Dec 17 21:20:11 retrostream volumio[1188]: info: CoreCommandRouter::volumioPushState Dec 17 21:20:11 retrostream volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Dec 17 21:20:11 retrostream volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Dec 17 21:20:11 retrostream volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Dec 17 21:20:11 retrostream volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=debug msg="requested logout out" username="vi********ar" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=debug msg="dealer connection closed" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=debug msg="dealer recv loop stopped" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=debug msg="obtained new client token: AAB/rkPOybHLeH+B347VjMMvLGgiTX029acI8QeHqXcrh7m1EwZNVupzNgwlHy7M8oReVqZfVqYygwaMOYn7rpSTbpFGdudtd+DpN+7zR9Sc4YyaqZzrYsVhf29RlC6SjuoYgALxBtRap6Ur0lls682hR0b5IdOeFwYF4sA8/DXqYzdGOu/6clN/id+KOZhhIpEXPXJG3UzWUnK82mWJv477xKqNwAivkIzOTd7rHOsDFVZLvX8kQwXcikE=" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=debug msg="completed keyexchange" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=debug msg="completed challenge" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=info msg="authenticated AP" username="vi********ar" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=info msg="authenticated Login5" username="vi********ar" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=debug msg="restored session after logout" username="vi********ar" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=debug msg="dealer connection opened" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=trace msg="starting accesspoint recv loop" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=trace msg="starting dealer recv loop" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=trace msg="received accesspoint ping" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=debug msg="received connection id: OTc2NTNlNTUtOTc5...ODlEMkM0OUIwRg==" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=debug msg="put connect state because NEW_DEVICE" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 17 21:20:20 retrostream go-librespot[8298]: time="2025-12-17T21:20:20Z" level=trace msg="received accesspoint pong ack" Dec 17 21:20:23 retrostream go-librespot[8298]: time="2025-12-17T21:20:23Z" level=debug msg="handling transfer player command from 85620f0c4bdc3191512c82cfe895453834cca8d0" Dec 17 21:20:23 retrostream go-librespot[8298]: time="2025-12-17T21:20:23Z" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1EQn4jwNIohw50" Dec 17 21:20:23 retrostream go-librespot[8298]: time="2025-12-17T21:20:23Z" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1EQn4jwNIohw50" Dec 17 21:20:23 retrostream go-librespot[8298]: time="2025-12-17T21:20:23Z" level=debug msg="shuffled context with seed 9428713815392923716 (len: 50, keep: 29)" uri="spotify:playlist:37i9dQZF1EQn4jwNIohw50" Dec 17 21:20:23 retrostream go-librespot[8298]: time="2025-12-17T21:20:23Z" level=debug msg="loading track (paused: true, position: 6906ms)" uri="spotify:track:0BRHnOFm6sjxN1i9LJrUDu" Dec 17 21:20:23 retrostream go-librespot[8298]: time="2025-12-17T21:20:23Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 17 21:20:23 retrostream go-librespot[8298]: time="2025-12-17T21:20:23Z" level=trace msg="emitting websocket event: will_play" Dec 17 21:20:23 retrostream volumio[1188]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","play_origin":"playlist/ondemand"}} Dec 17 21:20:23 retrostream volumio[1188]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","play_origin":"playlist/ondemand"}} Dec 17 21:20:23 retrostream volumio[1188]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","play_origin":"playlist/ondemand"}} Dec 17 21:20:23 retrostream volumio[1188]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","play_origin":"playlist/ondemand"}} Dec 17 21:20:23 retrostream go-librespot[8298]: time="2025-12-17T21:20:23Z" level=debug msg="selected format OGG_VORBIS_320 (aa81aff4d2678615b928b11eb3ad781bb124acb7)" uri="spotify:track:0BRHnOFm6sjxN1i9LJrUDu" Dec 17 21:20:23 retrostream go-librespot[8298]: time="2025-12-17T21:20:23Z" level=debug msg="requested aes key for file aa81aff4d2678615b928b11eb3ad781bb124acb7, gid: 0BRHnOFm6sjxN1i9LJrUDu" Dec 17 21:20:23 retrostream go-librespot[8298]: time="2025-12-17T21:20:23Z" level=trace msg="found 2 cdn urls" uri="spotify:track:0BRHnOFm6sjxN1i9LJrUDu" Dec 17 21:20:24 retrostream go-librespot[8298]: time="2025-12-17T21:20:24Z" level=debug msg="fetched first chunk of 15, total size is 7591224 bytes" uri="spotify:track:0BRHnOFm6sjxN1i9LJrUDu" Dec 17 21:20:24 retrostream go-librespot[8298]: time="2025-12-17T21:20:24Z" level=trace msg="seek to 6906ms (diff: 102ms, samples: 304554, bytes: 274337)" uri="spotify:track:0BRHnOFm6sjxN1i9LJrUDu" Dec 17 21:20:24 retrostream go-librespot[8298]: time="2025-12-17T21:20:24Z" level=debug msg="created new output device" Dec 17 21:20:24 retrostream go-librespot[8298]: time="2025-12-17T21:20:24Z" level=info msg="loaded track \"The Anthem\" (paused: true, position: 6906ms, duration: 175093ms, prefetched: false)" uri="spotify:track:0BRHnOFm6sjxN1i9LJrUDu" Dec 17 21:20:24 retrostream go-librespot[8298]: time="2025-12-17T21:20:24Z" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:0BRHnOFm6sjxN1i9LJrUDu" Dec 17 21:20:24 retrostream go-librespot[8298]: time="2025-12-17T21:20:24Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 17 21:20:24 retrostream go-librespot[8298]: time="2025-12-17T21:20:24Z" level=trace msg="emitting websocket event: metadata" Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","name":"The Anthem","artist_names":["Good Charlotte"],"album_name":"The Young and The Hopeless","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0283d6ab0b823bf7a56e4cd808","position":6906,"duration":175093,"release_date":"year:2002 month:10 day:4","track_number":2,"disc_number":1}} Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","name":"The Anthem","artist_names":["Good Charlotte"],"album_name":"The Young and The Hopeless","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0283d6ab0b823bf7a56e4cd808","position":6906,"duration":175093,"release_date":"year:2002 month:10 day:4","track_number":2,"disc_number":1}} Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","name":"The Anthem","artist_names":["Good Charlotte"],"album_name":"The Young and The Hopeless","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0283d6ab0b823bf7a56e4cd808","position":6906,"duration":175093,"release_date":"year:2002 month:10 day:4","track_number":2,"disc_number":1}} Dec 17 21:20:24 retrostream go-librespot[8298]: time="2025-12-17T21:20:24Z" level=trace msg="emitting websocket event: active" Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","name":"The Anthem","artist_names":["Good Charlotte"],"album_name":"The Young and The Hopeless","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0283d6ab0b823bf7a56e4cd808","position":6906,"duration":175093,"release_date":"year:2002 month:10 day:4","track_number":2,"disc_number":1}} Dec 17 21:20:24 retrostream go-librespot[8298]: time="2025-12-17T21:20:24Z" level=debug msg="sending successful reply for dealer request" Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: received: {"type":"active","data":null} Dec 17 21:20:24 retrostream volumio[1188]: info: Aligning Spotify Volume to Volumio Volume Dec 17 21:20:24 retrostream volumio[1188]: info: CoreCommandRouter::volumioGetState Dec 17 21:20:24 retrostream volumio[1188]: info: Setting Spotify Volume from Volumio: 86 Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: received: {"type":"active","data":null} Dec 17 21:20:24 retrostream volumio[1188]: info: Aligning Spotify Volume to Volumio Volume Dec 17 21:20:24 retrostream volumio[1188]: info: CoreCommandRouter::volumioGetState Dec 17 21:20:24 retrostream volumio[1188]: info: Setting Spotify Volume from Volumio: 86 Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: received: {"type":"active","data":null} Dec 17 21:20:24 retrostream volumio[1188]: info: Aligning Spotify Volume to Volumio Volume Dec 17 21:20:24 retrostream volumio[1188]: info: CoreCommandRouter::volumioGetState Dec 17 21:20:24 retrostream volumio[1188]: info: Setting Spotify Volume from Volumio: 86 Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: received: {"type":"active","data":null} Dec 17 21:20:24 retrostream volumio[1188]: info: Aligning Spotify Volume to Volumio Volume Dec 17 21:20:24 retrostream volumio[1188]: info: CoreCommandRouter::volumioGetState Dec 17 21:20:24 retrostream volumio[1188]: info: Setting Spotify Volume from Volumio: 86 Dec 17 21:20:24 retrostream go-librespot[8298]: time="2025-12-17T21:20:24Z" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:0BRHnOFm6sjxN1i9LJrUDu" Dec 17 21:20:24 retrostream go-librespot[8298]: time="2025-12-17T21:20:24Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 17 21:20:24 retrostream go-librespot[8298]: time="2025-12-17T21:20:24Z" level=trace msg="emitting websocket event: paused" Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","play_origin":"playlist/ondemand"}} Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: PUSH STATE SPOTIFY Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: {"status":"pause","service":"spop","title":"The Anthem","artist":"Good Charlotte","album":"The Young and The Hopeless","albumart":"https://i.scdn.co/image/ab67616d00001e0283d6ab0b823bf7a56e4cd808","uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","trackType":"spotify","seek":6906,"duration":175,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 17 21:20:24 retrostream volumio[1188]: info: CoreCommandRouter::servicePushState Dec 17 21:20:24 retrostream volumio[1188]: info: CoreStateMachine::pushState Dec 17 21:20:24 retrostream volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 21:20:24 retrostream volumio[1188]: info: CoreCommandRouter::volumioPushState Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","play_origin":"playlist/ondemand"}} Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: PUSH STATE SPOTIFY Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: {"status":"pause","service":"spop","title":"The Anthem","artist":"Good Charlotte","album":"The Young and The Hopeless","albumart":"https://i.scdn.co/image/ab67616d00001e0283d6ab0b823bf7a56e4cd808","uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","trackType":"spotify","seek":6906,"duration":175,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 17 21:20:24 retrostream volumio[1188]: info: CoreCommandRouter::servicePushState Dec 17 21:20:24 retrostream volumio[1188]: info: CoreStateMachine::pushState Dec 17 21:20:24 retrostream volumio[1188]: info: CoreCommandRouter::volumioPushState Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","play_origin":"playlist/ondemand"}} Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: PUSH STATE SPOTIFY Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: {"status":"pause","service":"spop","title":"The Anthem","artist":"Good Charlotte","album":"The Young and The Hopeless","albumart":"https://i.scdn.co/image/ab67616d00001e0283d6ab0b823bf7a56e4cd808","uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","trackType":"spotify","seek":6906,"duration":175,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 17 21:20:24 retrostream volumio[1188]: info: CoreCommandRouter::servicePushState Dec 17 21:20:24 retrostream volumio[1188]: info: CoreStateMachine::pushState Dec 17 21:20:24 retrostream volumio[1188]: info: CoreCommandRouter::volumioPushState Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","play_origin":"playlist/ondemand"}} Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: PUSH STATE SPOTIFY Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: {"status":"pause","service":"spop","title":"The Anthem","artist":"Good Charlotte","album":"The Young and The Hopeless","albumart":"https://i.scdn.co/image/ab67616d00001e0283d6ab0b823bf7a56e4cd808","uri":"spotify:track:0BRHnOFm6sjxN1i9LJrUDu","trackType":"spotify","seek":6906,"duration":175,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 17 21:20:24 retrostream volumio[1188]: info: CoreCommandRouter::servicePushState Dec 17 21:20:24 retrostream volumio[1188]: info: CoreStateMachine::pushState Dec 17 21:20:24 retrostream volumio[1188]: info: CoreCommandRouter::volumioPushState Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Dec 17 21:20:24 retrostream volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Dec 17 21:20:24 retrostream go-librespot[8298]: time="2025-12-17T21:20:24Z" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:0BRHnOFm6sjxN1i9LJrUDu" Dec 17 21:20:25 retrostream volumio[1188]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Dec 17 21:20:25 retrostream volumio[1188]: info: Sending Spotify command with payload to local API: /player/volume Dec 17 21:20:25 retrostream go-librespot[8298]: time="2025-12-17T21:20:25Z" level=debug msg="update volume requested to 56360/65535" Dec 17 21:20:26 retrostream go-librespot[8298]: time="2025-12-17T21:20:26Z" level=debug msg="put connect state because VOLUME_CHANGED" Dec 17 21:20:26 retrostream go-librespot[8298]: time="2025-12-17T21:20:26Z" level=trace msg="emitting websocket event: volume" Dec 17 21:20:26 retrostream volumio[1188]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Dec 17 21:20:26 retrostream volumio[1188]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Dec 17 21:20:26 retrostream volumio[1188]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Dec 17 21:20:26 retrostream volumio[1188]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Dec 17 21:20:26 retrostream volumio[1188]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Dec 17 21:20:26 retrostream volumio[1188]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Dec 17 21:20:26 retrostream volumio[1188]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Dec 17 21:20:26 retrostream volumio[1188]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Dec 17 21:20:26 retrostream go-librespot[8298]: time="2025-12-17T21:20:26Z" level=debug msg="handling resume player command from 85620f0c4bdc3191512c82cfe895453834cca8d0" Dec 17 21:20:26 retrostream go-librespot[8298]: time="2025-12-17T21:20:26Z" level=trace msg="seek to 6906ms (diff: 102ms, samples: 304554, bytes: 274337)" uri="spotify:track:0BRHnOFm6sjxN1i9LJrUDu" Dec 17 21:20:26 retrostream go-librespot[8298]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for IQaudIODAC Dec 17 21:20:26 retrostream go-librespot[8298]: time="2025-12-17T21:20:26Z" level=warning msg="failed handling dealer request" error="failed starting playback: ALSA error at snd_pcm_open: No such device" Dec 17 21:20:32 retrostream go-librespot[8298]: time="2025-12-17T21:20:32Z" level=debug msg="handling skip_next player command from 85620f0c4bdc3191512c82cfe895453834cca8d0" Dec 17 21:20:32 retrostream go-librespot[8298]: time="2025-12-17T21:20:32Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0At5h2X2njCdQqHyaEdxuO" Dec 17 21:20:32 retrostream go-librespot[8298]: time="2025-12-17T21:20:32Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 17 21:20:32 retrostream go-librespot[8298]: time="2025-12-17T21:20:32Z" level=trace msg="emitting websocket event: will_play" Dec 17 21:20:32 retrostream volumio[1188]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:0At5h2X2njCdQqHyaEdxuO","play_origin":"playlist/ondemand"}} Dec 17 21:20:32 retrostream volumio[1188]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:0At5h2X2njCdQqHyaEdxuO","play_origin":"playlist/ondemand"}} Dec 17 21:20:32 retrostream volumio[1188]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:0At5h2X2njCdQqHyaEdxuO","play_origin":"playlist/ondemand"}} Dec 17 21:20:32 retrostream volumio[1188]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:0At5h2X2njCdQqHyaEdxuO","play_origin":"playlist/ondemand"}} Dec 17 21:20:32 retrostream go-librespot[8298]: time="2025-12-17T21:20:32Z" level=debug msg="selected format OGG_VORBIS_320 (7e86ccc5ef7c192205e6d91dee90b49a2c8ee5b5)" uri="spotify:track:0At5h2X2njCdQqHyaEdxuO" Dec 17 21:20:32 retrostream go-librespot[8298]: time="2025-12-17T21:20:32Z" level=debug msg="requested aes key for file 7e86ccc5ef7c192205e6d91dee90b49a2c8ee5b5, gid: 0At5h2X2njCdQqHyaEdxuO" Dec 17 21:20:32 retrostream go-librespot[8298]: time="2025-12-17T21:20:32Z" level=trace msg="found 2 cdn urls" uri="spotify:track:0At5h2X2njCdQqHyaEdxuO" Dec 17 21:20:33 retrostream go-librespot[8298]: time="2025-12-17T21:20:33Z" level=debug msg="fetched first chunk of 22, total size is 11154372 bytes" uri="spotify:track:0At5h2X2njCdQqHyaEdxuO" Dec 17 21:20:33 retrostream go-librespot[8298]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for IQaudIODAC Dec 17 21:20:33 retrostream go-librespot[8298]: time="2025-12-17T21:20:33Z" level=warning msg="failed handling dealer request" error="failed skipping to next track: failed loading current track (advance to spotify:track:0At5h2X2njCdQqHyaEdxuO): failed setting stream for spotify:track:0At5h2X2njCdQqHyaEdxuO: ALSA error at snd_pcm_open: No such device" Dec 17 21:20:33 retrostream go-librespot[8298]: time="2025-12-17T21:20:33Z" level=debug msg="fetched chunk 3/21, size: 524288" uri="spotify:track:0At5h2X2njCdQqHyaEdxuO" Dec 17 21:20:33 retrostream go-librespot[8298]: time="2025-12-17T21:20:33Z" level=debug msg="fetched chunk 1/21, size: 524288" uri="spotify:track:0At5h2X2njCdQqHyaEdxuO" Dec 17 21:20:33 retrostream go-librespot[8298]: time="2025-12-17T21:20:33Z" level=debug msg="fetched chunk 2/21, size: 524288" uri="spotify:track:0At5h2X2njCdQqHyaEdxuO" Dec 17 21:20:50 retrostream go-librespot[8298]: time="2025-12-17T21:20:50Z" level=trace msg="sent dealer ping" Dec 17 21:20:50 retrostream go-librespot[8298]: time="2025-12-17T21:20:50Z" level=trace msg="received dealer pong" Dec 17 21:20:53 retrostream volumio[1188]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Dec 17 21:20:53 retrostream volumio[1188]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Dec 17 21:20:53 retrostream volumio[1188]: info: Creating Spotify config file Dec 17 21:20:53 retrostream volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:20:53 retrostream volumio[1188]: info: Spotify config file written Dec 17 21:20:53 retrostream sudo[8452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 17 21:20:53 retrostream sudo[8452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:20:53 retrostream systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 17 21:20:53 retrostream systemd[1]: go-librespot-daemon.service: Killing process 8303 (go-librespot) with signal SIGKILL. Dec 17 21:20:53 retrostream volumio[1188]: info: Connection to go-librespot Websocket closed Dec 17 21:20:53 retrostream volumio[1188]: info: Connection to go-librespot Websocket closed Dec 17 21:20:53 retrostream systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 17 21:20:53 retrostream systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 17 21:20:53 retrostream systemd[1]: go-librespot-daemon.service: Consumed 3.981s CPU time. Dec 17 21:20:53 retrostream volumio[1188]: info: Connection to go-librespot Websocket closed Dec 17 21:20:53 retrostream volumio[1188]: info: Connection to go-librespot Websocket closed Dec 17 21:20:53 retrostream systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 17 21:20:53 retrostream go-librespot[8454]: go-librespot daemon starting... Dec 17 21:20:53 retrostream sudo[8452]: pam_unix(sudo:session): session closed for user root Dec 17 21:20:53 retrostream go-librespot[8455]: time="2025-12-17T21:20:53Z" level=info msg="running go-librespot 0.4.0" Dec 17 21:20:53 retrostream go-librespot[8455]: time="2025-12-17T21:20:53Z" level=debug msg="app state loaded" Dec 17 21:20:53 retrostream go-librespot[8455]: time="2025-12-17T21:20:53Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 17 21:20:53 retrostream go-librespot[8455]: time="2025-12-17T21:20:53Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 17 21:20:53 retrostream go-librespot[8455]: time="2025-12-17T21:20:53Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 17 21:20:53 retrostream go-librespot[8455]: time="2025-12-17T21:20:53Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 17 21:20:53 retrostream go-librespot[8455]: time="2025-12-17T21:20:53Z" level=info msg="zeroconf server listening on port 43191" Dec 17 21:20:54 retrostream go-librespot[8455]: time="2025-12-17T21:20:54Z" level=debug msg="obtained new client token: AABAmhVXfvb2Ueov9/9Y4ssEfl//hID22KkUzuxayCW/Acjy4xgSsZGUAEzm+jmW5+Xp/qlKxJYqcgpXtrgSVOBYzOkrVwDj0cY4soyUfXZxxBQ03mxYPjwtY8xtyprHPEUX2kkSIGw+5VrhxzNP3RmHkwpE4IgK2Uu7JMeXgHvpd8jvw4dACkA1RwMI/lxoBKaZPDGb7SZTvZk6wrgERNRs3MQAWqnKJQ5wSDSGtph5uJPDoUMhyTy41g0=" Dec 17 21:20:54 retrostream go-librespot[8455]: time="2025-12-17T21:20:54Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Dec 17 21:20:54 retrostream go-librespot[8455]: time="2025-12-17T21:20:54Z" level=debug msg="completed keyexchange" Dec 17 21:20:54 retrostream go-librespot[8455]: time="2025-12-17T21:20:54Z" level=debug msg="completed challenge" Dec 17 21:20:54 retrostream go-librespot[8455]: time="2025-12-17T21:20:54Z" level=info msg="authenticated AP" username="vi********ar" Dec 17 21:20:54 retrostream go-librespot[8455]: time="2025-12-17T21:20:54Z" level=info msg="authenticated Login5" username="vi********ar" Dec 17 21:20:54 retrostream go-librespot[8455]: time="2025-12-17T21:20:54Z" level=debug msg="initializing zeroconf session" username="vi********ar" Dec 17 21:20:55 retrostream volumio[1188]: info: CoreCommandRouter::volumioGetState Dec 17 21:20:56 retrostream volumio[1188]: info: Initializing connection to go-librespot Websocket Dec 17 21:20:56 retrostream volumio[1188]: info: Connection to go-librespot Websocket established Dec 17 21:20:56 retrostream go-librespot[8455]: time="2025-12-17T21:20:56Z" level=debug msg="new websocket client" Dec 17 21:20:56 retrostream volumio[1188]: info: go-librespot daemon successfully initialized Dec 17 21:20:56 retrostream go-librespot[8455]: time="2025-12-17T21:20:56Z" level=debug msg="dealer connection opened" Dec 17 21:20:56 retrostream go-librespot[8455]: time="2025-12-17T21:20:56Z" level=trace msg="starting accesspoint recv loop" Dec 17 21:20:56 retrostream go-librespot[8455]: time="2025-12-17T21:20:56Z" level=trace msg="starting dealer recv loop" Dec 17 21:20:56 retrostream go-librespot[8455]: time="2025-12-17T21:20:56Z" level=trace msg="received accesspoint ping" Dec 17 21:20:56 retrostream go-librespot[8455]: time="2025-12-17T21:20:56Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 17 21:20:56 retrostream go-librespot[8455]: time="2025-12-17T21:20:56Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 17 21:20:56 retrostream go-librespot[8455]: time="2025-12-17T21:20:56Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 17 21:20:56 retrostream go-librespot[8455]: time="2025-12-17T21:20:56Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 17 21:20:56 retrostream go-librespot[8455]: time="2025-12-17T21:20:56Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 17 21:20:56 retrostream go-librespot[8455]: time="2025-12-17T21:20:56Z" level=debug msg="received connection id: Y2VhYTBkMmYtMzcw...MENDRDU2RTJFOQ==" Dec 17 21:20:56 retrostream go-librespot[8455]: time="2025-12-17T21:20:56Z" level=trace msg="received accesspoint pong ack" Dec 17 21:20:57 retrostream go-librespot[8455]: time="2025-12-17T21:20:57Z" level=debug msg="put connect state because NEW_DEVICE" Dec 17 21:20:58 retrostream volumio[1188]: info: CoreCommandRouter::volumioNext Dec 17 21:20:58 retrostream volumio[1188]: info: CoreStateMachine::next Dec 17 21:20:58 retrostream volumio[1188]: info: Spotify next Dec 17 21:20:58 retrostream volumio[1188]: info: Sending Spotify command to local API: /player/next Dec 17 21:20:58 retrostream go-librespot[8455]: panic: runtime error: invalid memory address or nil pointer dereference Dec 17 21:20:58 retrostream go-librespot[8455]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x4 pc=0x6372e0] Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 58 gp=0x1084ea8 m=7 mp=0x1049608 [running]: Dec 17 21:20:58 retrostream go-librespot[8455]: panic({0x6cfb00, 0xca02c0}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/panic.go:779 +0x12c fp=0x1183398 sp=0x1183344 pc=0x58bf8 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.panicmem(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/panic.go:261 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.sigpanic() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/signal_unix.go:881 +0x39c fp=0x11833c8 sp=0x1183398 pc=0x74be4 Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*AppPlayer).loadCurrentTrack(0x118a190, {0x8874f0, 0xd0ad00}, 0x1, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/controls.go:255 +0x144 fp=0x11835f8 sp=0x11833cc pc=0x6372e0 Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*AppPlayer).advanceNext(0x118a190, {0x8874f0, 0xd0ad00}, 0x1, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/controls.go:634 +0x448 fp=0x11836b8 sp=0x11835f8 pc=0x63af6c Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*AppPlayer).skipNext(0x118a190, {0x8874f0, 0xd0ad00}, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/controls.go:545 +0x4dc fp=0x1183754 sp=0x11836b8 pc=0x63a978 Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*AppPlayer).handleApiRequest(0x118a190, {0x8874f0, 0xd0ad00}, {{0x791346, 0x4}, {0x6c9bb0, 0x0}, 0x122ddc0}) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/player.go:478 +0x12c fp=0x11838e4 sp=0x1183754 pc=0x643810 Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*AppPlayer).Run(0x118a190, {0x8874f0, 0xd0ad00}, 0x12ca600) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/player.go:610 +0x864 fp=0x1183fd8 sp=0x11838e4 pc=0x645db0 Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*App).withAppPlayer.gowrap1() Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/main.go:258 +0x40 fp=0x1183fec sp=0x1183fd8 pc=0x63e874 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1183fec sp=0x1183fec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by main.(*App).withAppPlayer in goroutine 1 Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/main.go:258 +0x3d8 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 1 gp=0x1002128 m=nil [select]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x137dd00 sp=0x137dcec pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.selectgo(0x137de00, 0x1185dcc, 0x0, 0x0, 0x2, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x137dda0 sp=0x137dd00 pc=0x70e78 Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x122af00, 0x137de90) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/zeroconf/zeroconf.go:283 +0x228 fp=0x137de44 sp=0x137dda0 pc=0x5a8088 Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*App).withAppPlayer(0x1198400, {0x8874f0, 0xd0ad00}, 0x100f140) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/main.go:321 +0x554 fp=0x137ded0 sp=0x137de44 pc=0x63db54 Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*App).withCredentials(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/main.go:193 Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*App).SpotifyToken(0x1198400, {0x8874f0, 0xd0ad00}, {0x1010f30, 0xc}, {0x11ae140, 0x127}) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/main.go:185 +0xd8 fp=0x137df00 sp=0x137ded0 pc=0x63cfa8 Dec 17 21:20:58 retrostream go-librespot[8455]: main.main() Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/main.go:557 +0x588 fp=0x137dfa8 sp=0x137df00 pc=0x63feb4 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.main() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x137dfec sp=0x137dfa8 pc=0x5cb30 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x137dfec sp=0x137dfec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 2 gp=0x10027e8 m=nil [force gc (idle)]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e4100, 0xcdf428, 0x11, 0xa, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1044fd4 sp=0x1044fc0 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goparkunlock(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:408 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.forcegchelper() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x1044fec sp=0x1044fd4 pc=0x5cefc Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1044fec sp=0x1044fec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by runtime.init.5 in goroutine 1 Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:314 +0x1c Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 18 gp=0x1084248 m=nil [GC sweep wait]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e4100, 0xcdfbe0, 0xc, 0x9, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x10407c4 sp=0x10407b0 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goparkunlock(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:408 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.bgsweep(0x1090000) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x10407e4 sp=0x10407c4 pc=0x45848 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gcenable.gowrap1() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x10407ec sp=0x10407e4 pc=0x35968 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10407ec sp=0x10407ec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by runtime.gcenable in goroutine 1 Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 19 gp=0x1084368 m=nil [GC scavenge wait]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e4100, 0xce0208, 0xd, 0xa, 0x2) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1040fb4 sp=0x1040fa0 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goparkunlock(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:408 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.(*scavengerState).park(0xce0208) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x1040fc8 sp=0x1040fb4 pc=0x42b68 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.bgscavenge(0x1090000) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x1040fe4 sp=0x1040fc8 pc=0x432a0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gcenable.gowrap2() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x1040fec sp=0x1040fe4 pc=0x35914 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1040fec sp=0x1040fec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by runtime.gcenable in goroutine 1 Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 20 gp=0x10846c8 m=nil [finalizer wait]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e3fac, 0xd0ad9c, 0x10, 0xa, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x104178c sp=0x1041778 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.runfinq() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x10417ec sp=0x104178c pc=0x34754 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10417ec sp=0x10417ec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by runtime.createfing in goroutine 1 Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 5 gp=0x1003e68 m=nil [IO wait]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e40f0, 0xb6081f08, 0x2, 0x2, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1045d28 sp=0x1045d14 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.netpollblock(0xb6081ef8, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1045d40 sp=0x1045d28 pc=0x548cc Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.runtime_pollWait(0xb6081ef8, 0x72) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1045d54 sp=0x1045d40 pc=0x8f4a8 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).wait(0x118b1e8, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1045d68 sp=0x1045d54 pc=0x10a318 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).waitRead(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*FD).Accept(0x118b1d0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1045db0 sp=0x1045d68 pc=0x10eb84 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*netFD).accept(0x118b1d0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x1045e18 sp=0x1045db0 pc=0x17e4c4 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*TCPListener).accept(0x1026720) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1045e30 sp=0x1045e18 pc=0x196900 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*TCPListener).Accept(0x1026720) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x1045e4c sp=0x1045e30 pc=0x19596c Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*onceCloseListener).Accept(0x1224340) Dec 17 21:20:58 retrostream go-librespot[8455]: :1 +0x34 fp=0x1045e64 sp=0x1045e4c pc=0x362b54 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*Server).Serve(0x121c0a8, {0x886cb8, 0x1026720}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x1045efc sp=0x1045e64 pc=0x341210 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.Serve(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:2794 Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*ConcreteApiServer).serve(0x1028b40) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/api_server.go:630 +0x974 fp=0x1045fe4 sp=0x1045efc pc=0x6330b8 Dec 17 21:20:58 retrostream go-librespot[8455]: main.NewApiServer.gowrap1() Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/api_server.go:296 +0x28 fp=0x1045fec sp=0x1045fe4 pc=0x631de4 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1045fec sp=0x1045fec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by main.NewApiServer in goroutine 1 Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/api_server.go:296 +0x310 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 50 gp=0x11f2008 m=nil [IO wait]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e40f0, 0xb6081d28, 0x2, 0x2, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1059e84 sp=0x1059e70 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.netpollblock(0xb6081d18, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1059e9c sp=0x1059e84 pc=0x548cc Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.runtime_pollWait(0xb6081d18, 0x72) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1059eb0 sp=0x1059e9c pc=0x8f4a8 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).wait(0x118a068, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1059ec4 sp=0x1059eb0 pc=0x10a318 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).waitRead(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*FD).RawRead(0x118a050, 0x1434450) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1059ef0 sp=0x1059ec4 pc=0x10f788 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*rawConn).Read(0x10300c8, 0x1434450) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1059f10 sp=0x1059ef0 pc=0x190d28 Dec 17 21:20:58 retrostream go-librespot[8455]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x131c140, 0x1434420, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x1059f40 sp=0x1059f10 pc=0x548824 Dec 17 21:20:58 retrostream go-librespot[8455]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Dec 17 21:20:58 retrostream go-librespot[8455]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x1212158, {0x13a2000, 0x10000, 0x10000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x1059f9c sp=0x1059f40 pc=0x54c80c Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/grandcat/zeroconf.(*Server).recv4(0x10967c0, 0x1212150) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x1059fe0 sp=0x1059f9c pc=0x5a309c Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x1059fec sp=0x1059fe0 pc=0x5a2d54 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1059fec sp=0x1059fec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 13 Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 36 gp=0x11f2248 m=nil [GC worker (idle)]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e3fbc, 0x102b0b0, 0x1a, 0xa, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1045790 sp=0x104577c pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gcBgMarkWorker() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x10457ec sp=0x1045790 pc=0x38590 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10457ec sp=0x10457ec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 23 gp=0x11f2368 m=nil [IO wait]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e40f0, 0xb6081e68, 0x2, 0x2, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x137cad4 sp=0x137cac0 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.netpollblock(0xb6081e58, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x137caec sp=0x137cad4 pc=0x548cc Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.runtime_pollWait(0xb6081e58, 0x72) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x137cb00 sp=0x137caec pc=0x8f4a8 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).wait(0x118b378, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x137cb14 sp=0x137cb00 pc=0x10a318 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).waitRead(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*FD).Read(0x118b360, {0x1256000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x137cb5c sp=0x137cb14 pc=0x10b504 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*netFD).Read(0x118b360, {0x1256000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x137cb88 sp=0x137cb5c pc=0x17c5d0 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*conn).Read(0x121a1f0, {0x1256000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x137cbb4 sp=0x137cb88 pc=0x18caec Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*TCPConn).Read(0x121a1f0, {0x1256000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: :1 +0x44 fp=0x137cbd4 sp=0x137cbb4 pc=0x19fd40 Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*atLeastReader).Read(0x131c040, {0x1256000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x137cc00 sp=0x137cbd4 pc=0x27c680 Dec 17 21:20:58 retrostream go-librespot[8455]: bytes.(*Buffer).ReadFrom(0x123618c, {0x883674, 0x131c040}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x137cc3c sp=0x137cc00 pc=0x139bd0 Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).readFromUntil(0x1236008, {0x882848, 0x121a1f0}, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x137cc64 sp=0x137cc3c pc=0x27c8dc Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).readRecordOrCCS(0x1236008, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x137cdc8 sp=0x137cc64 pc=0x279fdc Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).readRecord(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:588 Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).Read(0x1236008, {0x110f000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x137cdf8 sp=0x137cdc8 pc=0x27fe4c Dec 17 21:20:58 retrostream go-librespot[8455]: bufio.(*Reader).Read(0x1000d20, {0x1198024, 0x9, 0x9}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x137ce1c sp=0x137cdf8 pc=0x2be440 Dec 17 21:20:58 retrostream go-librespot[8455]: io.ReadAtLeast({0x882a3c, 0x1000d20}, {0x1198024, 0x9, 0x9}, 0x9) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x137ce48 sp=0x137ce1c pc=0x1042c0 Dec 17 21:20:58 retrostream go-librespot[8455]: io.ReadFull(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/io/io.go:354 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.http2readFrameHeader({0x1198024, 0x9, 0x9}, {0x882a3c, 0x1000d20}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x137ce70 sp=0x137ce48 pc=0x2f733c Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*http2Framer).ReadFrame(0x1198000) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x137ceec sp=0x137ce70 pc=0x2f7ad4 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*http2clientConnReadLoop).run(0x137cfdc) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x137cfa4 sp=0x137ceec pc=0x31c2f0 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*http2ClientConn).readLoop(0x10f1d08) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x137cfe4 sp=0x137cfa4 pc=0x31b7e4 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*http2Transport).newClientConn.gowrap1() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x137cfec sp=0x137cfe4 pc=0x314830 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x137cfec sp=0x137cfec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by net/http.(*http2Transport).newClientConn in goroutine 22 Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 11 gp=0x11f26c8 m=nil [GC worker (idle)]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e3fbc, 0x102b0c8, 0x1a, 0xa, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1046f90 sp=0x1046f7c pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gcBgMarkWorker() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1046fec sp=0x1046f90 pc=0x38590 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1046fec sp=0x1046fec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 12 gp=0x11f27e8 m=nil [GC worker (idle)]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e3fbc, 0x102b0e0, 0x1a, 0xa, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1047790 sp=0x104777c pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gcBgMarkWorker() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x10477ec sp=0x1047790 pc=0x38590 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10477ec sp=0x10477ec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 21 gp=0x1084d88 m=nil [GC worker (idle)]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e3fbc, 0x10bf6c8, 0x1a, 0xa, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1044790 sp=0x104477c pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gcBgMarkWorker() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x10447ec sp=0x1044790 pc=0x38590 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10447ec sp=0x10447ec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 51 gp=0x10847e8 m=nil [IO wait]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e40f0, 0xb6081c88, 0x2, 0x2, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1367e5c sp=0x1367e48 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.netpollblock(0xb6081c78, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1367e74 sp=0x1367e5c pc=0x548cc Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.runtime_pollWait(0xb6081c78, 0x72) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1367e88 sp=0x1367e74 pc=0x8f4a8 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).wait(0x118a158, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1367e9c sp=0x1367e88 pc=0x10a318 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).waitRead(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*FD).RawRead(0x118a140, 0x1328030) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1367ec8 sp=0x1367e9c pc=0x10f788 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*rawConn).Read(0x10300f8, 0x1328030) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1367ee8 sp=0x1367ec8 pc=0x190d28 Dec 17 21:20:58 retrostream go-librespot[8455]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x131c150, 0x1328000, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x1367f18 sp=0x1367ee8 pc=0x548824 Dec 17 21:20:58 retrostream go-librespot[8455]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Dec 17 21:20:58 retrostream go-librespot[8455]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x1212428, {0x1392000, 0x10000, 0x10000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x1367f9c sp=0x1367f18 pc=0x550194 Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/grandcat/zeroconf.(*Server).recv6(0x10967c0, 0x1212420) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x1367fe0 sp=0x1367f9c pc=0x5a3290 Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x1367fec sp=0x1367fe0 pc=0x5a2cf8 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1367fec sp=0x1367fec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 13 Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 39 gp=0x1240c68 m=nil [select]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x13646b8 sp=0x13646a4 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.selectgo(0x13647d4, 0x136477c, 0x0, 0x0, 0x2, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1364758 sp=0x13646b8 pc=0x70e78 Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x12d2168) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/ap/ap.go:355 +0x8c fp=0x13647e4 sp=0x1364758 pc=0x48cb50 Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Dec 17 21:20:58 retrostream go-librespot[8455]: /src/ap/ap.go:264 +0x28 fp=0x13647ec sp=0x13647e4 pc=0x48c248 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x13647ec sp=0x13647ec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 58 Dec 17 21:20:58 retrostream go-librespot[8455]: /src/ap/ap.go:264 +0x15c Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 68 gp=0x1240ea8 m=nil [IO wait]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e40f0, 0xb6081be8, 0x2, 0x2, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1379ad4 sp=0x1379ac0 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.netpollblock(0xb6081bd8, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1379aec sp=0x1379ad4 pc=0x548cc Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.runtime_pollWait(0xb6081bd8, 0x72) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1379b00 sp=0x1379aec pc=0x8f4a8 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).wait(0x10a7198, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1379b14 sp=0x1379b00 pc=0x10a318 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).waitRead(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*FD).Read(0x10a7180, {0x1203000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1379b5c sp=0x1379b14 pc=0x10b504 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*netFD).Read(0x10a7180, {0x1203000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1379b88 sp=0x1379b5c pc=0x17c5d0 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*conn).Read(0x13b9138, {0x1203000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1379bb4 sp=0x1379b88 pc=0x18caec Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*TCPConn).Read(0x13b9138, {0x1203000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: :1 +0x44 fp=0x1379bd4 sp=0x1379bb4 pc=0x19fd40 Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*atLeastReader).Read(0x100e480, {0x1203000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x1379c00 sp=0x1379bd4 pc=0x27c680 Dec 17 21:20:58 retrostream go-librespot[8455]: bytes.(*Buffer).ReadFrom(0x11c95cc, {0x883674, 0x100e480}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x1379c3c sp=0x1379c00 pc=0x139bd0 Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).readFromUntil(0x11c9448, {0x882848, 0x13b9138}, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x1379c64 sp=0x1379c3c pc=0x27c8dc Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).readRecordOrCCS(0x11c9448, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x1379dc8 sp=0x1379c64 pc=0x279fdc Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).readRecord(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:588 Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).Read(0x11c9448, {0x1315000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x1379df8 sp=0x1379dc8 pc=0x27fe4c Dec 17 21:20:58 retrostream go-librespot[8455]: bufio.(*Reader).Read(0x1213950, {0x1210524, 0x9, 0x9}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x1379e1c sp=0x1379df8 pc=0x2be440 Dec 17 21:20:58 retrostream go-librespot[8455]: io.ReadAtLeast({0x882a3c, 0x1213950}, {0x1210524, 0x9, 0x9}, 0x9) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1379e48 sp=0x1379e1c pc=0x1042c0 Dec 17 21:20:58 retrostream go-librespot[8455]: io.ReadFull(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/io/io.go:354 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.http2readFrameHeader({0x1210524, 0x9, 0x9}, {0x882a3c, 0x1213950}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x1379e70 sp=0x1379e48 pc=0x2f733c Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*http2Framer).ReadFrame(0x1210500) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x1379eec sp=0x1379e70 pc=0x2f7ad4 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*http2clientConnReadLoop).run(0x1379fdc) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x1379fa4 sp=0x1379eec pc=0x31c2f0 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*http2ClientConn).readLoop(0x126c008) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x1379fe4 sp=0x1379fa4 pc=0x31b7e4 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*http2Transport).newClientConn.gowrap1() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x1379fec sp=0x1379fe4 pc=0x314830 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1379fec sp=0x1379fec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by net/http.(*http2Transport).newClientConn in goroutine 67 Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 32 gp=0x1003688 m=nil [IO wait]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e40f0, 0xb6081aa8, 0x2, 0x2, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x14baad4 sp=0x14baac0 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.netpollblock(0xb6081a98, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x14baaec sp=0x14baad4 pc=0x548cc Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.runtime_pollWait(0xb6081a98, 0x72) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x14bab00 sp=0x14baaec pc=0x8f4a8 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).wait(0x125ee28, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x14bab14 sp=0x14bab00 pc=0x10a318 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).waitRead(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*FD).Read(0x125ee10, {0x118f000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x14bab5c sp=0x14bab14 pc=0x10b504 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*netFD).Read(0x125ee10, {0x118f000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x14bab88 sp=0x14bab5c pc=0x17c5d0 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*conn).Read(0x13b91a0, {0x118f000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x14babb4 sp=0x14bab88 pc=0x18caec Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*TCPConn).Read(0x13b91a0, {0x118f000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: :1 +0x44 fp=0x14babd4 sp=0x14babb4 pc=0x19fd40 Dec 17 21:20:58 retrostream systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Dec 17 21:20:58 retrostream volumio[1188]: error: Failed to send command to Spotify local API: /player/next: Error: socket hang up Dec 17 21:20:58 retrostream volumio[1188]: info: Connection to go-librespot Websocket closed Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*atLeastReader).Read(0x100e670, {0x118f000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x14bac00 sp=0x14babd4 pc=0x27c680 Dec 17 21:20:58 retrostream go-librespot[8455]: bytes.(*Buffer).ReadFrom(0x11c9a4c, {0x883674, 0x100e670}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x14bac3c sp=0x14bac00 pc=0x139bd0 Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).readFromUntil(0x11c98c8, {0x882848, 0x13b91a0}, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x14bac64 sp=0x14bac3c pc=0x27c8dc Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).readRecordOrCCS(0x11c98c8, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x14badc8 sp=0x14bac64 pc=0x279fdc Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).readRecord(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:588 Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).Read(0x11c98c8, {0x150b000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x14badf8 sp=0x14badc8 pc=0x27fe4c Dec 17 21:20:58 retrostream go-librespot[8455]: bufio.(*Reader).Read(0x11e8930, {0x1198124, 0x9, 0x9}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x14bae1c sp=0x14badf8 pc=0x2be440 Dec 17 21:20:58 retrostream go-librespot[8455]: io.ReadAtLeast({0x882a3c, 0x11e8930}, {0x1198124, 0x9, 0x9}, 0x9) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x14bae48 sp=0x14bae1c pc=0x1042c0 Dec 17 21:20:58 retrostream go-librespot[8455]: io.ReadFull(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/io/io.go:354 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.http2readFrameHeader({0x1198124, 0x9, 0x9}, {0x882a3c, 0x11e8930}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x14bae70 sp=0x14bae48 pc=0x2f733c Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*http2Framer).ReadFrame(0x1198100) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x14baeec sp=0x14bae70 pc=0x2f7ad4 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*http2clientConnReadLoop).run(0x14bafdc) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x14bafa4 sp=0x14baeec pc=0x31c2f0 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*http2ClientConn).readLoop(0x10f1f08) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x14bafe4 sp=0x14bafa4 pc=0x31b7e4 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*http2Transport).newClientConn.gowrap1() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x14bafec sp=0x14bafe4 pc=0x314830 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x14bafec sp=0x14bafec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by net/http.(*http2Transport).newClientConn in goroutine 31 Dec 17 21:20:58 retrostream systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 57 gp=0x1084c68 m=nil [select]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x11d5674 sp=0x11d5660 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.selectgo(0x11d5774, 0x11d573c, 0x0, 0x0, 0x3, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x11d5714 sp=0x11d5674 pc=0x70e78 Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x125fae0) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/player/player.go:201 +0x1fc fp=0x11d57e4 sp=0x11d5714 pc=0x513660 Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Dec 17 21:20:58 retrostream go-librespot[8455]: /src/player/player.go:183 +0x28 fp=0x11d57ec sp=0x11d57e4 pc=0x513240 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x11d57ec sp=0x11d57ec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Dec 17 21:20:58 retrostream go-librespot[8455]: /src/player/player.go:183 +0x218 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 59 gp=0x1084fc8 m=nil [chan receive]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e3f90, 0x11a81b0, 0xe, 0x7, 0x2) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x11d2748 sp=0x11d2734 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.chanrecv(0x11a8180, 0x11d27d0, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x11d2784 sp=0x11d2748 pc=0x20078 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.chanrecv2(0x11a8180, 0x11d27d0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x11d2798 sp=0x11d2784 pc=0x1fbbc Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*App).withAppPlayer.func1() Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/main.go:268 +0x68 fp=0x11d27ec sp=0x11d2798 pc=0x63e794 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x11d27ec sp=0x11d27ec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by main.(*App).withAppPlayer in goroutine 1 Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/main.go:265 +0x464 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 60 gp=0x10850e8 m=nil [chan receive]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e3f90, 0x11a8170, 0xe, 0x7, 0x2) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x11d2f40 sp=0x11d2f2c pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.chanrecv(0x11a8140, 0x11d2fe0, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x11d2f7c sp=0x11d2f40 pc=0x20078 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.chanrecv2(0x11a8140, 0x11d2fe0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x11d2f90 sp=0x11d2f7c pc=0x1fbbc Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*App).withAppPlayer.func2() Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/main.go:284 +0x6c fp=0x11d2fec sp=0x11d2f90 pc=0x63e330 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x11d2fec sp=0x11d2fec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by main.(*App).withAppPlayer in goroutine 1 Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/main.go:281 +0x504 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 61 gp=0x1085208 m=nil [IO wait]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e40f0, 0xb6081dc8, 0x2, 0x2, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x11d35e8 sp=0x11d35d4 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.netpollblock(0xb6081db8, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x11d3600 sp=0x11d35e8 pc=0x548cc Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.runtime_pollWait(0xb6081db8, 0x72) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x11d3614 sp=0x11d3600 pc=0x8f4a8 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).wait(0x118a018, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x11d3628 sp=0x11d3614 pc=0x10a318 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).waitRead(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*FD).Accept(0x118a000) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x11d3670 sp=0x11d3628 pc=0x10eb84 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*netFD).accept(0x118a000) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x11d36d8 sp=0x11d3670 pc=0x17e4c4 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*TCPListener).accept(0x137e060) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x11d36f0 sp=0x11d36d8 pc=0x196900 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*TCPListener).Accept(0x137e060) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x11d370c sp=0x11d36f0 pc=0x19596c Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*onceCloseListener).Accept(0x1189bc0) Dec 17 21:20:58 retrostream go-librespot[8455]: :1 +0x34 fp=0x11d3724 sp=0x11d370c pc=0x362b54 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*Server).Serve(0x11666e8, {0x886cb8, 0x137e060}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x11d37bc sp=0x11d3724 pc=0x341210 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.Serve(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:2794 Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Dec 17 21:20:58 retrostream go-librespot[8455]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x11d37ec sp=0x11d37bc pc=0x5a8184 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x11d37ec sp=0x11d37ec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Dec 17 21:20:58 retrostream go-librespot[8455]: /src/zeroconf/zeroconf.go:280 +0x160 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 82 gp=0x10037a8 m=nil [IO wait]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e40f0, 0xb6081968, 0x2, 0x2, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x137687c sp=0x1376868 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.netpollblock(0xb6081958, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1376894 sp=0x137687c pc=0x548cc Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.runtime_pollWait(0xb6081958, 0x72) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x13768a8 sp=0x1376894 pc=0x8f4a8 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).wait(0x125fdc8, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x13768bc sp=0x13768a8 pc=0x10a318 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).waitRead(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*FD).Read(0x125fdb0, {0x1444000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1376904 sp=0x13768bc pc=0x10b504 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*netFD).Read(0x125fdb0, {0x1444000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1376930 sp=0x1376904 pc=0x17c5d0 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*conn).Read(0x1092cb0, {0x1444000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x137695c sp=0x1376930 pc=0x18caec Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*TCPConn).Read(0x1092cb0, {0x1444000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: :1 +0x44 fp=0x137697c sp=0x137695c pc=0x19fd40 Dec 17 21:20:58 retrostream go-librespot[8455]: io.(*multiReader).Read(0x1496990, {0x1444000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x13769a8 sp=0x137697c pc=0x105528 Dec 17 21:20:58 retrostream go-librespot[8455]: bufio.(*Reader).fill(0x14344e0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x13769cc sp=0x13769a8 pc=0x2bddb4 Dec 17 21:20:58 retrostream go-librespot[8455]: bufio.(*Reader).ReadByte(0x14344e0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x13769d8 sp=0x13769cc pc=0x2be638 Dec 17 21:20:58 retrostream go-librespot[8455]: nhooyr.io/websocket.readFrameHeader(0x14344e0, {0x11aa538, 0x8, 0x8}) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x1376a18 sp=0x13769d8 pc=0x47788c Dec 17 21:20:58 retrostream go-librespot[8455]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x11aa508, {0x8874d0, 0xd0ad00}) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1376ad8 sp=0x1376a18 pc=0x47a4f0 Dec 17 21:20:58 retrostream go-librespot[8455]: nhooyr.io/websocket.(*Conn).readLoop(0x11aa508, {0x8874d0, 0xd0ad00}) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1376b9c sp=0x1376ad8 pc=0x479fd8 Dec 17 21:20:58 retrostream go-librespot[8455]: nhooyr.io/websocket.(*Conn).reader(0x11aa508, {0x8874d0, 0xd0ad00}) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1376c28 sp=0x1376b9c pc=0x47b1ec Dec 17 21:20:58 retrostream go-librespot[8455]: nhooyr.io/websocket.(*Conn).Reader(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Dec 17 21:20:58 retrostream go-librespot[8455]: nhooyr.io/websocket.(*Conn).Read(0x11aa508, {0x8874d0, 0xd0ad00}) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1376c50 sp=0x1376c28 pc=0x4798bc Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*ConcreteApiServer).serve.func17({0x886dec, 0x1166828}, 0x1166788) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x1376ccc sp=0x1376c50 pc=0x633988 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.HandlerFunc.ServeHTTP(0x121a180, {0x886dec, 0x1166828}, 0x1166788) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1376cdc sp=0x1376ccc pc=0x33d7f4 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*ServeMux).ServeHTTP(0x1210000, {0x886dec, 0x1166828}, 0x1166788) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x1376d18 sp=0x1376cdc pc=0x33f654 Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x1166828}, 0x1166788) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x1376d40 sp=0x1376d18 pc=0x633388 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.HandlerFunc.ServeHTTP(0x1214160, {0x886dec, 0x1166828}, 0x1166788) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1376d50 sp=0x1376d40 pc=0x33d7f4 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.serverHandler.ServeHTTP({0x121c0a8}, {0x886dec, 0x1166828}, 0x1166788) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x1376d6c sp=0x1376d50 pc=0x340de4 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*conn).serve(0x12da960, {0x887510, 0x1226180}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x1376fdc sp=0x1376d6c pc=0x33c034 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*Server).Serve.gowrap3() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x1376fec sp=0x1376fdc pc=0x341688 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1376fec sp=0x1376fec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by net/http.(*Server).Serve in goroutine 5 Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:3285 +0x468 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 38 gp=0x10038c8 m=nil [IO wait]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e40f0, 0xb6081b48, 0x2, 0x2, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1377d14 sp=0x1377d00 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.netpollblock(0xb6081b38, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1377d2c sp=0x1377d14 pc=0x548cc Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.runtime_pollWait(0xb6081b38, 0x72) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1377d40 sp=0x1377d2c pc=0x8f4a8 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).wait(0x125ed88, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1377d54 sp=0x1377d40 pc=0x10a318 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).waitRead(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*FD).Read(0x125ed70, {0x159084c, 0x3, 0x3}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1377d9c sp=0x1377d54 pc=0x10b504 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*netFD).Read(0x125ed70, {0x159084c, 0x3, 0x3}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1377dc8 sp=0x1377d9c pc=0x17c5d0 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*conn).Read(0x1030850, {0x159084c, 0x3, 0x3}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1377df4 sp=0x1377dc8 pc=0x18caec Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*TCPConn).Read(0x1030850, {0x159084c, 0x3, 0x3}) Dec 17 21:20:58 retrostream go-librespot[8455]: :1 +0x44 fp=0x1377e14 sp=0x1377df4 pc=0x19fd40 Dec 17 21:20:58 retrostream go-librespot[8455]: io.ReadAtLeast({0x882848, 0x1030850}, {0x159084c, 0x3, 0x3}, 0x3) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1377e40 sp=0x1377e14 pc=0x1042c0 Dec 17 21:20:58 retrostream go-librespot[8455]: io.ReadFull(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/io/io.go:354 Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/devgianlu/go-librespot/ap.(*shannonConn).receivePacket(0x118cec0, {0x8874f0, 0xd0ad00}) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/ap/shannon.go:124 +0x3a0 fp=0x1377f04 sp=0x1377e40 pc=0x48faf4 Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x12d2168) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/ap/ap.go:276 +0x68 fp=0x1377fe4 sp=0x1377f04 pc=0x48c330 Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Dec 17 21:20:58 retrostream go-librespot[8455]: /src/ap/ap.go:260 +0x28 fp=0x1377fec sp=0x1377fe4 pc=0x48c29c Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1377fec sp=0x1377fec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 58 Dec 17 21:20:58 retrostream go-librespot[8455]: /src/ap/ap.go:260 +0x98 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 37 gp=0x10039e8 m=nil [select]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x13636a8 sp=0x1363694 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.selectgo(0x13637a4, 0x136376c, 0x0, 0x0, 0x5, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1363748 sp=0x13636a8 pc=0x70e78 Dec 17 21:20:58 retrostream go-librespot[8455]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x15a0008) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x13637e4 sp=0x1363748 pc=0x474738 Dec 17 21:20:58 retrostream go-librespot[8455]: nhooyr.io/websocket.newConn.gowrap1() Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x13637ec sp=0x13637e4 pc=0x47432c Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x13637ec sp=0x13637ec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by nhooyr.io/websocket.newConn in goroutine 58 Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 78 gp=0x1085448 m=nil [select]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x13676a8 sp=0x1367694 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.selectgo(0x13677a4, 0x136776c, 0x0, 0x0, 0x5, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1367748 sp=0x13676a8 pc=0x70e78 Dec 17 21:20:58 retrostream go-librespot[8455]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x11aa508) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x13677e4 sp=0x1367748 pc=0x474738 Dec 17 21:20:58 retrostream go-librespot[8455]: nhooyr.io/websocket.newConn.gowrap1() Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x13677ec sp=0x13677e4 pc=0x47432c Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x13677ec sp=0x13677ec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by nhooyr.io/websocket.newConn in goroutine 82 Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 40 gp=0x15ae008 m=nil [IO wait]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e40f0, 0xb6081a08, 0x2, 0x2, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1185940 sp=0x118592c pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.netpollblock(0xb60819f8, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1185958 sp=0x1185940 pc=0x548cc Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.runtime_pollWait(0xb60819f8, 0x72) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x118596c sp=0x1185958 pc=0x8f4a8 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).wait(0x14b2248, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1185980 sp=0x118596c pc=0x10a318 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).waitRead(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*FD).Read(0x14b2230, {0x12f5000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x11859c8 sp=0x1185980 pc=0x10b504 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*netFD).Read(0x14b2230, {0x12f5000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x11859f4 sp=0x11859c8 pc=0x17c5d0 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*conn).Read(0x1092960, {0x12f5000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1185a20 sp=0x11859f4 pc=0x18caec Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*TCPConn).Read(0x1092960, {0x12f5000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: :1 +0x44 fp=0x1185a40 sp=0x1185a20 pc=0x19fd40 Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*atLeastReader).Read(0x100e070, {0x12f5000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x1185a6c sp=0x1185a40 pc=0x27c680 Dec 17 21:20:58 retrostream go-librespot[8455]: bytes.(*Buffer).ReadFrom(0x140e3cc, {0x883674, 0x100e070}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x1185aa8 sp=0x1185a6c pc=0x139bd0 Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).readFromUntil(0x140e248, {0x882848, 0x1092960}, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x1185ad0 sp=0x1185aa8 pc=0x27c8dc Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).readRecordOrCCS(0x140e248, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x1185c34 sp=0x1185ad0 pc=0x279fdc Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).readRecord(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:588 Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).Read(0x140e248, {0x159e000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x1185c64 sp=0x1185c34 pc=0x27fe4c Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*readWriteCloserBody).Read(0x158c060, {0x159e000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/transport.go:2429 +0xec fp=0x1185c84 sp=0x1185c64 pc=0x357b00 Dec 17 21:20:58 retrostream go-librespot[8455]: bufio.(*Reader).fill(0x1582180) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x1185ca8 sp=0x1185c84 pc=0x2bddb4 Dec 17 21:20:58 retrostream go-librespot[8455]: bufio.(*Reader).ReadByte(0x1582180) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x1185cb4 sp=0x1185ca8 pc=0x2be638 Dec 17 21:20:58 retrostream go-librespot[8455]: nhooyr.io/websocket.readFrameHeader(0x1582180, {0x15a0038, 0x8, 0x8}) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x1185cf4 sp=0x1185cb4 pc=0x47788c Dec 17 21:20:58 retrostream go-librespot[8455]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x15a0008, {0x8874d0, 0xd0ad00}) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1185db4 sp=0x1185cf4 pc=0x47a4f0 Dec 17 21:20:58 retrostream go-librespot[8455]: nhooyr.io/websocket.(*Conn).readLoop(0x15a0008, {0x8874d0, 0xd0ad00}) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1185e78 sp=0x1185db4 pc=0x479fd8 Dec 17 21:20:58 retrostream go-librespot[8455]: nhooyr.io/websocket.(*Conn).reader(0x15a0008, {0x8874d0, 0xd0ad00}) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1185f04 sp=0x1185e78 pc=0x47b1ec Dec 17 21:20:58 retrostream go-librespot[8455]: nhooyr.io/websocket.(*Conn).Reader(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Dec 17 21:20:58 retrostream go-librespot[8455]: nhooyr.io/websocket.(*Conn).Read(0x15a0008, {0x8874d0, 0xd0ad00}) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1185f2c sp=0x1185f04 pc=0x4798bc Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x12d2218) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/dealer/dealer.go:188 +0x58 fp=0x1185fe4 sp=0x1185f2c pc=0x531d64 Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Dec 17 21:20:58 retrostream go-librespot[8455]: /src/dealer/dealer.go:125 +0x28 fp=0x1185fec sp=0x1185fe4 pc=0x53383c Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1185fec sp=0x1185fec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 58 Dec 17 21:20:58 retrostream go-librespot[8455]: /src/dealer/dealer.go:125 +0x98 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 41 gp=0x15ae128 m=nil [select]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1365698 sp=0x1365684 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.selectgo(0x13657d4, 0x1365760, 0x0, 0x0, 0x2, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1365738 sp=0x1365698 pc=0x70e78 Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x12d2218) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/dealer/dealer.go:138 +0x8c fp=0x13657e4 sp=0x1365738 pc=0x5318dc Dec 17 21:20:58 retrostream go-librespot[8455]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Dec 17 21:20:58 retrostream go-librespot[8455]: /src/dealer/dealer.go:129 +0x28 fp=0x13657ec sp=0x13657e4 pc=0x5337e8 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x13657ec sp=0x13657ec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 58 Dec 17 21:20:58 retrostream go-librespot[8455]: /src/dealer/dealer.go:129 +0x15c Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 103 gp=0x11f30e8 m=nil [chan receive]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e3f90, 0x122ddf0, 0xe, 0x7, 0x2) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1056bb0 sp=0x1056b9c pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.chanrecv(0x122ddc0, 0x1056c7c, 0x1) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1056bec sp=0x1056bb0 pc=0x20078 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.chanrecv1(0x122ddc0, 0x1056c7c) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x1056c00 sp=0x1056bec pc=0x1fb98 Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*ConcreteApiServer).handleRequest(0x1028b40, {{0x791346, 0x4}, {0x6c9bb0, 0x0}, 0x122ddc0}, {0x886dec, 0x121d0e8}) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/api_server.go:319 +0x74 fp=0x1056c8c sp=0x1056c00 pc=0x631f94 Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*ConcreteApiServer).serve.func8({0x886dec, 0x121d0e8}, 0x121d048) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/cmd/daemon/api_server.go:450 +0x12c fp=0x1056ccc sp=0x1056c8c pc=0x6347cc Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.HandlerFunc.ServeHTTP(0x121a0a8, {0x886dec, 0x121d0e8}, 0x121d048) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1056cdc sp=0x1056ccc pc=0x33d7f4 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*ServeMux).ServeHTTP(0x1210000, {0x886dec, 0x121d0e8}, 0x121d048) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x1056d18 sp=0x1056cdc pc=0x33f654 Dec 17 21:20:58 retrostream go-librespot[8455]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x121d0e8}, 0x121d048) Dec 17 21:20:58 retrostream go-librespot[8455]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x1056d40 sp=0x1056d18 pc=0x633388 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.HandlerFunc.ServeHTTP(0x1214160, {0x886dec, 0x121d0e8}, 0x121d048) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1056d50 sp=0x1056d40 pc=0x33d7f4 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.serverHandler.ServeHTTP({0x121c0a8}, {0x886dec, 0x121d0e8}, 0x121d048) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x1056d6c sp=0x1056d50 pc=0x340de4 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*conn).serve(0x122af60, {0x887510, 0x1226180}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x1056fdc sp=0x1056d6c pc=0x33c034 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*Server).Serve.gowrap3() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x1056fec sp=0x1056fdc pc=0x341688 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1056fec sp=0x1056fec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by net/http.(*Server).Serve in goroutine 5 Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:3285 +0x468 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 104 gp=0x11f3328 m=nil [IO wait]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e40f0, 0xb6081828, 0x2, 0x2, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x11d4eb4 sp=0x11d4ea0 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.netpollblock(0xb6081818, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x11d4ecc sp=0x11d4eb4 pc=0x548cc Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.runtime_pollWait(0xb6081818, 0x72) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x11d4ee0 sp=0x11d4ecc pc=0x8f4a8 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).wait(0x14d61a8, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x11d4ef4 sp=0x11d4ee0 pc=0x10a318 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).waitRead(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*FD).Read(0x14d6190, {0x1225eed, 0x1, 0x1}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x11d4f3c sp=0x11d4ef4 pc=0x10b504 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*netFD).Read(0x14d6190, {0x1225eed, 0x1, 0x1}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x11d4f68 sp=0x11d4f3c pc=0x17c5d0 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*conn).Read(0x13b8b58, {0x1225eed, 0x1, 0x1}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x11d4f94 sp=0x11d4f68 pc=0x18caec Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*TCPConn).Read(0x13b8b58, {0x1225eed, 0x1, 0x1}) Dec 17 21:20:58 retrostream go-librespot[8455]: :1 +0x44 fp=0x11d4fb4 sp=0x11d4f94 pc=0x19fd40 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*connReader).backgroundRead(0x1225ee0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:681 +0x40 fp=0x11d4fe4 sp=0x11d4fb4 pc=0x335cfc Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*connReader).startBackgroundRead.gowrap2() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:677 +0x28 fp=0x11d4fec sp=0x11d4fe4 pc=0x335c34 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x11d4fec sp=0x11d4fec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by net/http.(*connReader).startBackgroundRead in goroutine 103 Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/server.go:677 +0x160 Dec 17 21:20:58 retrostream go-librespot[8455]: goroutine 101 gp=0x11f3448 m=nil [IO wait]: Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.gopark(0x7e40f0, 0xb60818c8, 0x2, 0x2, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x105aad4 sp=0x105aac0 pc=0x5d0c0 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.netpollblock(0xb60818b8, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x105aaec sp=0x105aad4 pc=0x548cc Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.runtime_pollWait(0xb60818b8, 0x72) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x105ab00 sp=0x105aaec pc=0x8f4a8 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).wait(0x15b2068, 0x72, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x105ab14 sp=0x105ab00 pc=0x10a318 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*pollDesc).waitRead(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 17 21:20:58 retrostream go-librespot[8455]: internal/poll.(*FD).Read(0x15b2050, {0x14bf000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x105ab5c sp=0x105ab14 pc=0x10b504 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*netFD).Read(0x15b2050, {0x14bf000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x105ab88 sp=0x105ab5c pc=0x17c5d0 Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*conn).Read(0x13b8aa0, {0x14bf000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x105abb4 sp=0x105ab88 pc=0x18caec Dec 17 21:20:58 retrostream go-librespot[8455]: net.(*TCPConn).Read(0x13b8aa0, {0x14bf000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: :1 +0x44 fp=0x105abd4 sp=0x105abb4 pc=0x19fd40 Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*atLeastReader).Read(0x158def0, {0x14bf000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x105ac00 sp=0x105abd4 pc=0x27c680 Dec 17 21:20:58 retrostream go-librespot[8455]: bytes.(*Buffer).ReadFrom(0x159d80c, {0x883674, 0x158def0}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x105ac3c sp=0x105ac00 pc=0x139bd0 Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).readFromUntil(0x159d688, {0x882848, 0x13b8aa0}, 0x5) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x105ac64 sp=0x105ac3c pc=0x27c8dc Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).readRecordOrCCS(0x159d688, 0x0) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x105adc8 sp=0x105ac64 pc=0x279fdc Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).readRecord(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:588 Dec 17 21:20:58 retrostream go-librespot[8455]: crypto/tls.(*Conn).Read(0x159d688, {0x12f4000, 0x1000, 0x1000}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x105adf8 sp=0x105adc8 pc=0x27fe4c Dec 17 21:20:58 retrostream go-librespot[8455]: bufio.(*Reader).Read(0x1434300, {0x15ac524, 0x9, 0x9}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x105ae1c sp=0x105adf8 pc=0x2be440 Dec 17 21:20:58 retrostream go-librespot[8455]: io.ReadAtLeast({0x882a3c, 0x1434300}, {0x15ac524, 0x9, 0x9}, 0x9) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x105ae48 sp=0x105ae1c pc=0x1042c0 Dec 17 21:20:58 retrostream go-librespot[8455]: io.ReadFull(...) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/io/io.go:354 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.http2readFrameHeader({0x15ac524, 0x9, 0x9}, {0x882a3c, 0x1434300}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x105ae70 sp=0x105ae48 pc=0x2f733c Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*http2Framer).ReadFrame(0x15ac500) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x105aeec sp=0x105ae70 pc=0x2f7ad4 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*http2clientConnReadLoop).run(0x105afdc) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x105afa4 sp=0x105aeec pc=0x31c2f0 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*http2ClientConn).readLoop(0x126c108) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x105afe4 sp=0x105afa4 pc=0x31b7e4 Dec 17 21:20:58 retrostream go-librespot[8455]: net/http.(*http2Transport).newClientConn.gowrap1() Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x105afec sp=0x105afe4 pc=0x314830 Dec 17 21:20:58 retrostream go-librespot[8455]: runtime.goexit({}) Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x105afec sp=0x105afec pc=0x93d6c Dec 17 21:20:58 retrostream go-librespot[8455]: created by net/http.(*http2Transport).newClientConn in goroutine 100 Dec 17 21:20:58 retrostream go-librespot[8455]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Dec 17 21:20:58 retrostream go-librespot[8454]: Aborted Dec 17 21:20:59 retrostream volumio[1188]: info: Getting Spotify volume Dec 17 21:20:59 retrostream volumio[1188]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 21:20:59 retrostream volumio[1188]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 17 21:20:59 retrostream volumio[1188]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 17 21:20:59 retrostream volumio[1188]: errno: -111, Dec 17 21:20:59 retrostream volumio[1188]: code: 'ECONNREFUSED', Dec 17 21:20:59 retrostream volumio[1188]: syscall: 'connect', Dec 17 21:20:59 retrostream volumio[1188]: address: '127.0.0.1', Dec 17 21:20:59 retrostream volumio[1188]: port: 9879, Dec 17 21:20:59 retrostream volumio[1188]: response: undefined Dec 17 21:20:59 retrostream volumio[1188]: } Dec 17 21:20:59 retrostream volumio[1188]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 21:21:00 retrostream sudo[8497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-17 21:20' Dec 17 21:21:00 retrostream sudo[8497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"