Mar 28 20:15:02 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:02+01:00" level=debug msg="fetched chunk 7/11, size: 524288" uri="spotify:track:4J9oreH8X23uV8dhjo9uwq" Mar 28 20:15:04 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 20:15:04 raspberry-spotify volumio[1121]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 28 20:15:04 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 28 20:15:04 raspberry-spotify volumio[1121]: info: Received Get System Version Mar 28 20:15:04 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 20:15:04 raspberry-spotify volumio[1121]: info: Received Get System Info Mar 28 20:15:04 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 20:15:04 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 20:15:04 raspberry-spotify volumio[1121]: info: Discovery: Getting this device information Mar 28 20:15:04 raspberry-spotify volumio[1121]: info: CoreCommandRouter::volumioGetState Mar 28 20:15:04 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 20:15:07 raspberry-spotify volumio[1121]: info: CALLMETHOD: miscellanea appearance setVolumio3UI [object Object] Mar 28 20:15:07 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , setVolumio3UI Mar 28 20:15:07 raspberry-spotify volumio[1121]: info: Setting active UI to: {"uiPrettyName":"Classic","uiName":"classic","uiPath":"/volumio/http/www"} Mar 28 20:15:07 raspberry-spotify sudo[2406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/active_volumio_ui Mar 28 20:15:07 raspberry-spotify sudo[2406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:15:07 raspberry-spotify sudo[2406]: pam_unix(sudo:session): session closed for user root Mar 28 20:15:08 raspberry-spotify volumio[1121]: info: CoreCommandRouter::Reload Ui Mar 28 20:15:08 raspberry-spotify sudo[2409]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 20:15:08 raspberry-spotify sudo[2409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:15:08 raspberry-spotify sudo[2409]: pam_unix(sudo:session): session closed for user root Mar 28 20:15:08 raspberry-spotify sudo[2411]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 20:15:08 raspberry-spotify sudo[2411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:15:08 raspberry-spotify sudo[2411]: pam_unix(sudo:session): session closed for user root Mar 28 20:15:08 raspberry-spotify volumio[1121]: verbose: New Socket.io Connection to 192.168.0.227 from 192.168.0.229 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Edg/146.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Mar 28 20:15:08 raspberry-spotify volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 20:15:08 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 20:15:08 raspberry-spotify volumio[1121]: info: CoreCommandRouter::volumioGetState Mar 28 20:15:08 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 28 20:15:08 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 28 20:15:08 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 28 20:15:08 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 20:15:08 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 20:15:08 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 20:15:08 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Mar 28 20:15:08 raspberry-spotify volumio[1121]: info: Received Get System Version Mar 28 20:15:08 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 20:15:08 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Mar 28 20:15:10 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 20:15:10 raspberry-spotify volumio[1121]: info: Received Get System Info Mar 28 20:15:10 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 20:15:10 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 20:15:10 raspberry-spotify volumio[1121]: info: Discovery: Getting this device information Mar 28 20:15:10 raspberry-spotify volumio[1121]: info: CoreCommandRouter::volumioGetState Mar 28 20:15:10 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 20:15:10 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 20:15:10 raspberry-spotify volumio[1121]: info: Received Get System Info Mar 28 20:15:10 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 20:15:10 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 20:15:10 raspberry-spotify volumio[1121]: info: Discovery: Getting this device information Mar 28 20:15:10 raspberry-spotify volumio[1121]: info: CoreCommandRouter::volumioGetState Mar 28 20:15:10 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 20:15:13 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:13+01:00" level=debug msg="fetched chunk 8/11, size: 524288" uri="spotify:track:4J9oreH8X23uV8dhjo9uwq" Mar 28 20:15:15 raspberry-spotify volumio[1121]: info: CALLMETHOD: miscellanea appearance setVolumio3UI [object Object] Mar 28 20:15:15 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , setVolumio3UI Mar 28 20:15:15 raspberry-spotify volumio[1121]: info: Setting active UI to: {"uiPrettyName":"Contemporary","uiName":"contemporary","uiPath":"/volumio/http/www3"} Mar 28 20:15:15 raspberry-spotify sudo[2450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/active_volumio_ui Mar 28 20:15:15 raspberry-spotify sudo[2450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:15:15 raspberry-spotify sudo[2450]: pam_unix(sudo:session): session closed for user root Mar 28 20:15:16 raspberry-spotify volumio[1121]: info: CoreCommandRouter::Reload Ui Mar 28 20:15:16 raspberry-spotify sudo[2456]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 20:15:16 raspberry-spotify sudo[2456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:15:16 raspberry-spotify sudo[2456]: pam_unix(sudo:session): session closed for user root Mar 28 20:15:16 raspberry-spotify sudo[2458]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 20:15:16 raspberry-spotify sudo[2458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:15:16 raspberry-spotify sudo[2458]: pam_unix(sudo:session): session closed for user root Mar 28 20:15:16 raspberry-spotify volumio[1121]: verbose: New Socket.io Connection to 192.168.0.227 from 192.168.0.229 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Edg/146.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Mar 28 20:15:16 raspberry-spotify volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 20:15:16 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 20:15:17 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 28 20:15:17 raspberry-spotify volumio[1121]: info: CoreCommandRouter::volumioGetState Mar 28 20:15:17 raspberry-spotify volumio[1121]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 20:15:17 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 20:15:17 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 28 20:15:17 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 28 20:15:17 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 28 20:15:17 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 20:15:17 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 20:15:17 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 20:15:17 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Mar 28 20:15:17 raspberry-spotify volumio[1121]: info: Received Get System Version Mar 28 20:15:17 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 20:15:17 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Mar 28 20:15:17 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:17+01:00" level=trace msg="sent dealer ping" Mar 28 20:15:17 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:17+01:00" level=trace msg="received dealer pong" Mar 28 20:15:19 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 20:15:19 raspberry-spotify volumio[1121]: info: Received Get System Info Mar 28 20:15:19 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 20:15:19 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 20:15:19 raspberry-spotify volumio[1121]: info: Discovery: Getting this device information Mar 28 20:15:19 raspberry-spotify volumio[1121]: info: CoreCommandRouter::volumioGetState Mar 28 20:15:19 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 20:15:19 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 20:15:19 raspberry-spotify volumio[1121]: info: Received Get System Info Mar 28 20:15:19 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 20:15:19 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 20:15:19 raspberry-spotify volumio[1121]: info: Discovery: Getting this device information Mar 28 20:15:19 raspberry-spotify volumio[1121]: info: CoreCommandRouter::volumioGetState Mar 28 20:15:19 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 20:15:24 raspberry-spotify volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 20:15:24 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 20:15:25 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 20:15:25 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 20:15:25 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Mar 28 20:15:25 raspberry-spotify volumio[1121]: info: Received Get System Version Mar 28 20:15:25 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 20:15:25 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Mar 28 20:15:25 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:25+01:00" level=debug msg="fetched chunk 9/11, size: 524288" uri="spotify:track:4J9oreH8X23uV8dhjo9uwq" Mar 28 20:15:34 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 20:15:34 raspberry-spotify volumio[1121]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 28 20:15:34 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 28 20:15:34 raspberry-spotify volumio[1121]: info: Received Get System Version Mar 28 20:15:34 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 20:15:34 raspberry-spotify volumio[1121]: info: Received Get System Info Mar 28 20:15:34 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 20:15:34 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 20:15:34 raspberry-spotify volumio[1121]: info: Discovery: Getting this device information Mar 28 20:15:34 raspberry-spotify volumio[1121]: info: CoreCommandRouter::volumioGetState Mar 28 20:15:34 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 20:15:37 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:37+01:00" level=debug msg="fetched chunk 10/11, size: 524288" uri="spotify:track:4J9oreH8X23uV8dhjo9uwq" Mar 28 20:15:47 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:47+01:00" level=trace msg="sent dealer ping" Mar 28 20:15:47 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:47+01:00" level=trace msg="received dealer pong" Mar 28 20:15:49 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:49+01:00" level=debug msg="fetched chunk 11/11, size: 7889" uri="spotify:track:4J9oreH8X23uV8dhjo9uwq" Mar 28 20:15:50 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 28 20:15:50 raspberry-spotify volumio[1121]: info: In handleBrowseUri, curUri=spotify Mar 28 20:15:50 raspberry-spotify volumio[1121]: info: CoreCommandRouter::volumioGetQueue Mar 28 20:15:50 raspberry-spotify volumio[1121]: info: CoreStateMachine::getQueue Mar 28 20:15:50 raspberry-spotify volumio[1121]: info: CorePlayQueue::getQueue Mar 28 20:15:50 raspberry-spotify volumio[1121]: info: Listing playlists Mar 28 20:15:50 raspberry-spotify volumio[1121]: info: Preload queue cleared Mar 28 20:15:50 raspberry-spotify volumio[1121]: info: Preload queue cleared Mar 28 20:15:50 raspberry-spotify volumio[1121]: info: Preload queue cleared Mar 28 20:15:50 raspberry-spotify volumio[1121]: info: Preload queue cleared Mar 28 20:15:55 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 20:15:55 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 28 20:15:57 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:57+01:00" level=debug msg="prefetching next track" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Mar 28 20:15:57 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:57+01:00" level=debug msg="selected format OGG_VORBIS_320 (2222ca1cd72ceea445ab041586754cdface96161)" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Mar 28 20:15:57 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:57+01:00" level=debug msg="requested aes key for file 2222ca1cd72ceea445ab041586754cdface96161, gid: 0h3Xy4V4apMraB5NuM8U7Z" Mar 28 20:15:57 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:57+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Mar 28 20:15:57 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:57+01:00" level=debug msg="fetched first chunk of 16, total size is 8153903 bytes" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Mar 28 20:15:57 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:57+01:00" level=info msg="prefetched track \"Stumblin' In\" (duration: 213363ms)" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Mar 28 20:15:57 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:57+01:00" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Mar 28 20:15:57 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:57+01:00" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Mar 28 20:15:57 raspberry-spotify go-librespot[1573]: time="2026-03-28T20:15:57+01:00" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Mar 28 20:15:58 raspberry-spotify volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 20:16:11 raspberry-spotify volumio[1121]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Mar 28 20:16:11 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Mar 28 20:16:11 raspberry-spotify volumio[1121]: info: Creating Spotify config file Mar 28 20:16:11 raspberry-spotify volumio[1121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 20:16:11 raspberry-spotify volumio[1121]: info: Spotify config file written Mar 28 20:16:11 raspberry-spotify sudo[2594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 28 20:16:11 raspberry-spotify sudo[2594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:16:11 raspberry-spotify systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 28 20:16:11 raspberry-spotify volumio[1121]: info: Connection to go-librespot Websocket closed Mar 28 20:16:11 raspberry-spotify systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 28 20:16:11 raspberry-spotify systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 20:16:11 raspberry-spotify systemd[1]: go-librespot-daemon.service: Consumed 30.547s CPU time. Mar 28 20:16:11 raspberry-spotify systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 20:16:11 raspberry-spotify go-librespot[2596]: go-librespot daemon starting... Mar 28 20:16:11 raspberry-spotify sudo[2594]: pam_unix(sudo:session): session closed for user root Mar 28 20:16:11 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:11+01:00" level=info msg="running go-librespot 0.7.1" Mar 28 20:16:11 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:11+01:00" level=debug msg="app state loaded" Mar 28 20:16:11 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=info msg="zeroconf server listening on port 43349" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=debug msg="obtained new client token: AAAWwIEPh0gIp4Jq1DekwDgCRmJgqTk69G490JJDbR3KP7eIKYUieKG4DpZW6cc1khpwQTZEP0Uv/qNxLPkjaTvq77otN2E0gADqs5Yp+2PlWXIXyNVdukLaAeAm9z1vkFv5W9yAsCKn5/EA7pon4p4HrKwZtAL3Fmqylm0zRr5VVhcQJFC4CF4ushsemzASfe+BLFfFBjmWmooTbrfe/V2XKiRDP3f3Psyth2wE95oKAxBdWjMBr58=" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=debug msg="completed keyexchange" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=debug msg="completed challenge" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=info msg="authenticated AP" username="le************io" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=info msg="authenticated Login5" username="le************io" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=debug msg="initializing zeroconf session" username="le************io" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=debug msg="dealer connection opened" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=trace msg="starting accesspoint recv loop" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=trace msg="starting dealer recv loop" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=trace msg="received accesspoint ping" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=debug msg="received connection id: NDdlMjU5MzUtNTI4...RTQ4MUVBN0I2NQ==" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=trace msg="received accesspoint pong ack" Mar 28 20:16:12 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:12+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 28 20:16:14 raspberry-spotify volumio[1121]: info: Initializing connection to go-librespot Websocket Mar 28 20:16:14 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:14+01:00" level=debug msg="new websocket client" Mar 28 20:16:14 raspberry-spotify volumio[1121]: info: Connection to go-librespot Websocket established Mar 28 20:16:14 raspberry-spotify volumio[1121]: info: go-librespot daemon successfully initialized Mar 28 20:16:17 raspberry-spotify volumio[1121]: info: Getting Spotify volume Mar 28 20:16:17 raspberry-spotify volumio[1121]: info: Spotify volume: 100 Mar 28 20:16:17 raspberry-spotify volumio[1121]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 28 20:16:17 raspberry-spotify volumio[1121]: info: Initializing connection to go-librespot Websocket Mar 28 20:16:17 raspberry-spotify go-librespot[2597]: time="2026-03-28T20:16:17+01:00" level=debug msg="new websocket client" Mar 28 20:16:17 raspberry-spotify volumio[1121]: info: Connection to go-librespot Websocket established Mar 28 20:16:17 raspberry-spotify volumio[1121]: info: CoreCommandRouter::volumioGetState Mar 28 20:16:17 raspberry-spotify volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Mar 28 20:16:17 raspberry-spotify volumio[1121]: SPOTIFY: SPOTIFY VOLUME 100 Mar 28 20:16:17 raspberry-spotify volumio[1121]: SPOTIFY: VOLUMIO VOLUME 74 Mar 28 20:16:17 raspberry-spotify volumio[1121]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 28 20:16:17 raspberry-spotify volumio[1121]: info: Setting Spotify Volume from Volumio: 74 Mar 28 20:16:18 raspberry-spotify volumio[1121]: info: CoreCommandRouter::volumioNext Mar 28 20:16:18 raspberry-spotify volumio[1121]: info: CoreStateMachine::next Mar 28 20:16:18 raspberry-spotify volumio[1121]: info: Spotify next Mar 28 20:16:18 raspberry-spotify volumio[1121]: info: Sending Spotify command to local API: /player/next Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: panic: runtime error: invalid memory address or nil pointer dereference Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x4 pc=0x6b5cd0] Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 58 gp=0x1a36b48 m=4 mp=0x184d808 [running]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: panic({0x841d88, 0xfa0250}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/panic.go:802 +0x148 fp=0x1c15538 sp=0x1c154e4 pc=0xa92ec Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.panicmem(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/panic.go:262 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.sigpanic() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/signal_unix.go:925 +0x384 fp=0x1c15568 sp=0x1c15538 pc=0xabca4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*AppPlayer).loadCurrentTrack(0x1bd4190, {0xa3da8c, 0x1938eb0}, 0x1, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/controls.go:308 +0x13c fp=0x1c15720 sp=0x1c1556c pc=0x6b5cd0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*AppPlayer).advanceNext(0x1bd4190, {0xa3da8c, 0x1938eb0}, 0x1, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/controls.go:700 +0x4dc fp=0x1c157d0 sp=0x1c15720 pc=0x6b9a84 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*AppPlayer).skipNext(0x1bd4190, {0xa3da8c, 0x1938eb0}, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/controls.go:604 +0x4e8 fp=0x1c1586c sp=0x1c157d0 pc=0x6b9408 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*AppPlayer).handleApiRequest(0x1bd4190, {0xa3da1c, 0x1005090}, {{0x919857, 0x4}, {0x83ac98, 0x0}, 0x1c01e80}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/player.go:475 +0x174 fp=0x1c159e0 sp=0x1c1586c pc=0x6c2cac Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*AppPlayer).Run(0x1bd4190, {0xa3da1c, 0x1005090}, 0x1890b40, 0x1890c00) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/player.go:697 +0x5b0 fp=0x1c15fd4 sp=0x1c159e0 pc=0x6c5b98 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*App).withAppPlayer.gowrap1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/main.go:274 +0x48 fp=0x1c15fec sp=0x1c15fd4 pc=0x6bd9f0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c15fec sp=0x1c15fec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by main.(*App).withAppPlayer in goroutine 1 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/main.go:274 +0x46c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 1 gp=0x1802128 m=nil [select]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1856ce0 sp=0x1856ccc pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.selectgo(0x1856df0, 0x1856dbc, 0x0, 0x0, 0x2, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1856d90 sp=0x1856ce0 pc=0x85000 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x1ba2b40, 0x1856e90) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/zeroconf/zeroconf.go:300 +0x228 fp=0x1856e38 sp=0x1856d90 pc=0x62f43c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*App).withAppPlayer(0x1956400, {0xa3da1c, 0x1005090}, 0x191f570) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/main.go:340 +0x5dc fp=0x1856ed0 sp=0x1856e38 pc=0x6bcc60 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*App).withCredentials(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/main.go:209 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*App).SpotifyToken(0x1956400, {0xa3da1c, 0x1005090}, {0x193ef40, 0x10}, {0x197a140, 0x12e}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x1856f00 sp=0x1856ed0 pc=0x6bc02c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.main() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/main.go:620 +0x660 fp=0x1856fa8 sp=0x1856f00 pc=0x6bf56c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.main() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x1856fec sp=0x1856fa8 pc=0x6f390 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1856fec sp=0x1856fec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 2 gp=0x18027e8 m=nil [force gc (idle)]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97746c, 0xff8fa8, 0x11, 0xa, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1848fd4 sp=0x1848fc0 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goparkunlock(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:466 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.forcegchelper() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x1848fec sp=0x1848fd4 pc=0x6f7f4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1848fec sp=0x1848fec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by runtime.init.6 in goroutine 1 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:361 +0x1c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 3 gp=0x1802b48 m=nil [GC sweep wait]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97746c, 0xff9850, 0xc, 0x9, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x18497c4 sp=0x18497b0 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goparkunlock(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:466 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.bgsweep(0x182e050) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x18497e4 sp=0x18497c4 pc=0x5768c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gcenable.gowrap1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x18497ec sp=0x18497e4 pc=0x46c3c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x18497ec sp=0x18497ec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by runtime.gcenable in goroutine 1 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgc.go:212 +0x74 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 4 gp=0x1802c68 m=nil [GC scavenge wait]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97746c, 0xffa170, 0xd, 0xa, 0x2) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1849fb4 sp=0x1849fa0 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goparkunlock(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:466 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.(*scavengerState).park(0xffa170) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x1849fc8 sp=0x1849fb4 pc=0x54a28 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.bgscavenge(0x182e050) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x1849fe4 sp=0x1849fc8 pc=0x5516c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gcenable.gowrap2() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x1849fec sp=0x1849fe4 pc=0x46be8 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1849fec sp=0x1849fec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by runtime.gcenable in goroutine 1 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgc.go:213 +0xbc Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 18 gp=0x1882248 m=nil [GOMAXPROCS updater (idle)]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97746c, 0xff95e8, 0x12, 0xa, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x18447a4 sp=0x1844790 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goparkunlock(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:466 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.updateMaxProcsGoroutine() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x18447ec sp=0x18447a4 pc=0x7f174 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x18447ec sp=0x18447ec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:6708 +0x40 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 19 gp=0x18825a8 m=nil [finalizer wait]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x977314, 0x1005180, 0x10, 0xa, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1844f88 sp=0x1844f74 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.runFinalizers() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x1844fec sp=0x1844f88 pc=0x45888 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1844fec sp=0x1844fec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by runtime.createfing in goroutine 1 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 34 gp=0x191ad88 m=nil [cleanup wait]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97746c, 0xffa348, 0x2e, 0xa, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x19707a4 sp=0x1970790 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goparkunlock(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:466 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.(*cleanupQueue).dequeue(0xffa2e8) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x19707c4 sp=0x19707a4 pc=0x41cb0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.runCleanups() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x19707ec sp=0x19707c4 pc=0x4284c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x19707ec sp=0x19707ec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 37 gp=0x1a36008 m=nil [IO wait]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97745c, 0x76076010, 0x2, 0x2, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x19714f0 sp=0x19714dc pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.netpollblock(0x76076000, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1971508 sp=0x19714f0 pc=0x675a0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.runtime_pollWait(0x76076000, 0x72) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x197151c sp=0x1971508 pc=0xa8864 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).wait(0x1939378, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1971530 sp=0x197151c pc=0x136044 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).waitRead(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*FD).Accept(0x1939360) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x1971578 sp=0x1971530 pc=0x13a800 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*netFD).accept(0x1939360) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x19715e0 sp=0x1971578 pc=0x1abc28 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*TCPListener).accept(0x1944420) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1971634 sp=0x19715e0 pc=0x1c3554 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*TCPListener).Accept(0x1944420) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x1971650 sp=0x1971634 pc=0x1c259c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*onceCloseListener).Accept(0x18a7440) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: :1 +0x34 fp=0x1971668 sp=0x1971650 pc=0x367f9c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*Server).Serve(0x18fe588, {0xa3d218, 0x1944420}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x1971700 sp=0x1971668 pc=0x341154 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.Serve(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:2971 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*ConcreteApiServer).serve(0x1926600) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x19717e4 sp=0x1971700 pc=0x6b17bc Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.NewApiServer.gowrap1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x19717ec sp=0x19717e4 pc=0x6b04f4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x19717ec sp=0x19717ec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by main.NewApiServer in goroutine 1 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/api_server.go:332 +0x310 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 28 gp=0x1a36128 m=nil [IO wait]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97745c, 0x76075a10, 0x2, 0x2, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1a6bcbc sp=0x1a6bca8 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.netpollblock(0x76075a00, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1a6bcd4 sp=0x1a6bcbc pc=0x675a0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.runtime_pollWait(0x76075a00, 0x72) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1a6bce8 sp=0x1a6bcd4 pc=0xa8864 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).wait(0x1ba5878, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1a6bcfc sp=0x1a6bce8 pc=0x136044 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).waitRead(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*FD).ReadMsg(0x1ba5860, {0x1b562d0, 0x10, 0x10}, {0x1a82018, 0x1000, 0x1000}, 0x40000000) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_unix.go:295 +0x2c0 fp=0x1a6bd6c sp=0x1a6bcfc pc=0x1381bc Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*netFD).readMsg(0x1ba5860, {0x1b562d0, 0x10, 0x10}, {0x1a82018, 0x1000, 0x1000}, 0x40000000) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/fd_posix.go:91 +0x58 fp=0x1a6bdc8 sp=0x1a6bd6c pc=0x1aa2c8 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*UnixConn).readMsg(0x1b65518, {0x1b562d0, 0x10, 0x10}, {0x1a82018, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/unixsock_posix.go:115 +0x58 fp=0x1a6be28 sp=0x1a6bdc8 pc=0x1c9d80 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*UnixConn).ReadMsgUnix(0x1b65518, {0x1b562d0, 0x10, 0x10}, {0x1a82018, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/unixsock.go:143 +0x58 fp=0x1a6be78 sp=0x1a6be28 pc=0x1c820c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/godbus/dbus/v5.(*oobReader).Read(0x1a82008, {0x1b562d0, 0x10, 0x10}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:41 +0x50 fp=0x1a6bec8 sp=0x1a6be78 pc=0x4c2af0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: io.ReadAtLeast({0xa39d58, 0x1a82008}, {0x1b562d0, 0x10, 0x10}, 0x10) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1a6bef4 sp=0x1a6bec8 pc=0xee954 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: io.ReadFull(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/io/io.go:354 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/godbus/dbus/v5.(*unixTransport).ReadMessage(0x1b71960) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:124 +0x224 fp=0x1a6bfa0 sp=0x1a6bef4 pc=0x4c32c4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/godbus/dbus/v5.(*Conn).inWorker(0x1956200) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:390 +0x44 fp=0x1a6bfe4 sp=0x1a6bfa0 pc=0x4aaef4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/godbus/dbus/v5.(*Conn).Auth.gowrap1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x28 fp=0x1a6bfec sp=0x1a6bfe4 pc=0x4a8318 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1a6bfec sp=0x1a6bfec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by github.com/godbus/dbus/v5.(*Conn).Auth in goroutine 1 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x77c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 42 gp=0x1a36488 m=nil [IO wait]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97745c, 0x76075e10, 0x2, 0x2, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1a70af4 sp=0x1a70ae0 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.netpollblock(0x76075e00, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1a70b0c sp=0x1a70af4 pc=0x675a0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.runtime_pollWait(0x76075e00, 0x72) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1a70b20 sp=0x1a70b0c pc=0xa8864 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).wait(0x18a41a8, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1a70b34 sp=0x1a70b20 pc=0x136044 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).waitRead(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*FD).Read(0x18a4190, {0x1993000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1a70b7c sp=0x1a70b34 pc=0x1371c4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*netFD).Read(0x18a4190, {0x1993000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1a70ba8 sp=0x1a70b7c pc=0x1a9e78 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*conn).Read(0x189c4e8, {0x1993000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1a70bd4 sp=0x1a70ba8 pc=0x1b967c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*atLeastReader).Read(0x1ac58b0, {0x1993000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x1a70c00 sp=0x1a70bd4 pc=0x2674c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: bytes.(*Buffer).ReadFrom(0x18c6f0c, {0xa3a068, 0x1ac58b0}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x1a70c3c sp=0x1a70c00 pc=0x162058 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).readFromUntil(0x18c6d88, {0xa38fa8, 0x189c4e8}, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x1a70c64 sp=0x1a70c3c pc=0x26771c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).readRecordOrCCS(0x18c6d88, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x1a70dc4 sp=0x1a70c64 pc=0x264e40 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).readRecord(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:589 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).Read(0x18c6d88, {0x1a7b000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x1a70df4 sp=0x1a70dc4 pc=0x26ac04 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: bufio.(*Reader).Read(0x1a761e0, {0x18ce4a4, 0x9, 0x9}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x1a70e18 sp=0x1a70df4 pc=0x2b72a0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: io.ReadAtLeast({0xa391f8, 0x1a761e0}, {0x18ce4a4, 0x9, 0x9}, 0x9) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1a70e44 sp=0x1a70e18 pc=0xee954 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: io.ReadFull(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/io/io.go:354 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.http2readFrameHeader({0x18ce4a4, 0x9, 0x9}, {0xa391f8, 0x1a761e0}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x1a70e6c sp=0x1a70e44 pc=0x2f86ac Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*http2Framer).ReadFrame(0x18ce480) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x1a70ef4 sp=0x1a70e6c pc=0x2f8e04 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*http2clientConnReadLoop).run(0x1a70fdc) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x1a70fa4 sp=0x1a70ef4 pc=0x31d33c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*http2ClientConn).readLoop(0x19e8fc8) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x1a70fe4 sp=0x1a70fa4 pc=0x31c604 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x1a70fec sp=0x1a70fe4 pc=0x316960 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1a70fec sp=0x1a70fec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by net/http.(*http2Transport).newClientConn in goroutine 41 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 24 gp=0x19e90e8 m=nil [GC worker (idle)]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x977320, 0x1aac400, 0x1c, 0xa, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1848788 sp=0x1848774 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gcBgMarkWorker(0x1b8bfc0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x18487e4 sp=0x1848788 pc=0x49f34 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x18487ec sp=0x18487e4 pc=0x49e14 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x18487ec sp=0x18487ec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by runtime.gcBgMarkStartWorkers in goroutine 22 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 25 gp=0x19e9208 m=nil [GC worker (idle)]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x977320, 0x1aac600, 0x1c, 0xa, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1845788 sp=0x1845774 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gcBgMarkWorker(0x1b8bfc0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x18457e4 sp=0x1845788 pc=0x49f34 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x18457ec sp=0x18457e4 pc=0x49e14 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x18457ec sp=0x18457ec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by runtime.gcBgMarkStartWorkers in goroutine 22 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 26 gp=0x19e9568 m=nil [GC worker (idle)]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x977320, 0x1aac800, 0x1c, 0xa, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1845f88 sp=0x1845f74 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gcBgMarkWorker(0x1b8bfc0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x1845fe4 sp=0x1845f88 pc=0x49f34 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x1845fec sp=0x1845fe4 pc=0x49e14 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1845fec sp=0x1845fec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by runtime.gcBgMarkStartWorkers in goroutine 22 Mar 28 20:16:18 raspberry-spotify volumio[1121]: error: Failed to send command to Spotify local API: /player/next: Error: socket hang up Mar 28 20:16:18 raspberry-spotify volumio[1121]: info: Connection to go-librespot Websocket closed Mar 28 20:16:18 raspberry-spotify volumio[1121]: info: Connection to go-librespot Websocket closed Mar 28 20:16:18 raspberry-spotify systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 27 gp=0x19e9688 m=nil [GC worker (idle)]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x977320, 0x1aaca00, 0x1c, 0xa, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1a6af88 sp=0x1a6af74 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gcBgMarkWorker(0x1b8bfc0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x1a6afe4 sp=0x1a6af88 pc=0x49f34 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x1a6afec sp=0x1a6afe4 pc=0x49e14 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1a6afec sp=0x1a6afec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by runtime.gcBgMarkStartWorkers in goroutine 22 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 5 gp=0x1802fc8 m=nil [chan receive]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x9772f4, 0x1bbecb8, 0xe, 0x7, 0x2) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x196ef78 sp=0x196ef64 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.chanrecv(0x1bbec80, 0x0, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x196efc0 sp=0x196ef78 pc=0x3414c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.chanrecv1(0x1bbec80, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/chan.go:509 +0x20 fp=0x196efd4 sp=0x196efc0 pc=0x33bcc Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/godbus/dbus/v5.newConn.func1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:303 +0x40 fp=0x196efec sp=0x196efd4 pc=0x4aa8ec Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x196efec sp=0x196efec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by github.com/godbus/dbus/v5.newConn in goroutine 1 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:302 +0x52c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 85 gp=0x1a365a8 m=nil [select]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1971ea4 sp=0x1971e90 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.selectgo(0x1971fd4, 0x1971f78, 0x0, 0x0, 0x2, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1971f54 sp=0x1971ea4 pc=0x85000 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x18ffce8) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/ap/ap.go:368 +0x8c fp=0x1971fe4 sp=0x1971f54 pc=0x4f7cf8 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Mar 28 20:16:18 raspberry-spotify systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/ap/ap.go:277 +0x28 fp=0x1971fec sp=0x1971fe4 pc=0x4f73c4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1971fec sp=0x1971fec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 58 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/ap/ap.go:277 +0x15c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 91 gp=0x1803208 m=nil [IO wait]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97745c, 0x75646010, 0x2, 0x2, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1a6daf4 sp=0x1a6dae0 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.netpollblock(0x75646000, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1a6db0c sp=0x1a6daf4 pc=0x675a0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.runtime_pollWait(0x75646000, 0x72) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1a6db20 sp=0x1a6db0c pc=0xa8864 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).wait(0x1938c48, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1a6db34 sp=0x1a6db20 pc=0x136044 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).waitRead(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*FD).Read(0x1938c30, {0x187a000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1a6db7c sp=0x1a6db34 pc=0x1371c4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*netFD).Read(0x1938c30, {0x187a000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1a6dba8 sp=0x1a6db7c pc=0x1a9e78 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*conn).Read(0x1832900, {0x187a000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1a6dbd4 sp=0x1a6dba8 pc=0x1b967c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*atLeastReader).Read(0x1c98930, {0x187a000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x1a6dc00 sp=0x1a6dbd4 pc=0x2674c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: bytes.(*Buffer).ReadFrom(0x1a1d80c, {0xa3a068, 0x1c98930}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x1a6dc3c sp=0x1a6dc00 pc=0x162058 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).readFromUntil(0x1a1d688, {0xa38fa8, 0x1832900}, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x1a6dc64 sp=0x1a6dc3c pc=0x26771c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).readRecordOrCCS(0x1a1d688, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x1a6ddc4 sp=0x1a6dc64 pc=0x264e40 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).readRecord(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:589 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).Read(0x1a1d688, {0x187c000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x1a6ddf4 sp=0x1a6ddc4 pc=0x26ac04 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: bufio.(*Reader).Read(0x18b1290, {0x18ce524, 0x9, 0x9}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x1a6de18 sp=0x1a6ddf4 pc=0x2b72a0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: io.ReadAtLeast({0xa391f8, 0x18b1290}, {0x18ce524, 0x9, 0x9}, 0x9) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1a6de44 sp=0x1a6de18 pc=0xee954 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: io.ReadFull(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/io/io.go:354 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.http2readFrameHeader({0x18ce524, 0x9, 0x9}, {0xa391f8, 0x18b1290}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x1a6de6c sp=0x1a6de44 pc=0x2f86ac Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*http2Framer).ReadFrame(0x18ce500) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x1a6def4 sp=0x1a6de6c pc=0x2f8e04 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*http2clientConnReadLoop).run(0x1a6dfdc) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x1a6dfa4 sp=0x1a6def4 pc=0x31d33c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*http2ClientConn).readLoop(0x1803b08) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x1a6dfe4 sp=0x1a6dfa4 pc=0x31c604 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x1a6dfec sp=0x1a6dfe4 pc=0x316960 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1a6dfec sp=0x1a6dfec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by net/http.(*http2Transport).newClientConn in goroutine 90 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 13 gp=0x1a367e8 m=nil [IO wait]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97745c, 0x76075810, 0x2, 0x2, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1859af4 sp=0x1859ae0 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.netpollblock(0x76075800, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1859b0c sp=0x1859af4 pc=0x675a0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.runtime_pollWait(0x76075800, 0x72) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1859b20 sp=0x1859b0c pc=0xa8864 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).wait(0x1bd58c8, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1859b34 sp=0x1859b20 pc=0x136044 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).waitRead(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*FD).Read(0x1bd58b0, {0x1ccb000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1859b7c sp=0x1859b34 pc=0x1371c4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*netFD).Read(0x1bd58b0, {0x1ccb000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1859ba8 sp=0x1859b7c pc=0x1a9e78 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*conn).Read(0x1b65880, {0x1ccb000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1859bd4 sp=0x1859ba8 pc=0x1b967c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*atLeastReader).Read(0x1810510, {0x1ccb000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x1859c00 sp=0x1859bd4 pc=0x2674c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: bytes.(*Buffer).ReadFrom(0x1a1ca8c, {0xa3a068, 0x1810510}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x1859c3c sp=0x1859c00 pc=0x162058 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).readFromUntil(0x1a1c908, {0xa38fa8, 0x1b65880}, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x1859c64 sp=0x1859c3c pc=0x26771c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).readRecordOrCCS(0x1a1c908, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x1859dc4 sp=0x1859c64 pc=0x264e40 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).readRecord(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:589 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).Read(0x1a1c908, {0x1ce5000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x1859df4 sp=0x1859dc4 pc=0x26ac04 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: bufio.(*Reader).Read(0x1ccd110, {0x1956824, 0x9, 0x9}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x1859e18 sp=0x1859df4 pc=0x2b72a0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: io.ReadAtLeast({0xa391f8, 0x1ccd110}, {0x1956824, 0x9, 0x9}, 0x9) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1859e44 sp=0x1859e18 pc=0xee954 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: io.ReadFull(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/io/io.go:354 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.http2readFrameHeader({0x1956824, 0x9, 0x9}, {0xa391f8, 0x1ccd110}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x1859e6c sp=0x1859e44 pc=0x2f86ac Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*http2Framer).ReadFrame(0x1956800) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x1859ef4 sp=0x1859e6c pc=0x2f8e04 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*http2clientConnReadLoop).run(0x1859fdc) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x1859fa4 sp=0x1859ef4 pc=0x31d33c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*http2ClientConn).readLoop(0x1803448) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x1859fe4 sp=0x1859fa4 pc=0x31c604 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x1859fec sp=0x1859fe4 pc=0x316960 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1859fec sp=0x1859fec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by net/http.(*http2Transport).newClientConn in goroutine 12 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 69 gp=0x1a36908 m=nil [IO wait]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97745c, 0x76075410, 0x2, 0x2, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x185caf4 sp=0x185cae0 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.netpollblock(0x76075400, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x185cb0c sp=0x185caf4 pc=0x675a0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.runtime_pollWait(0x76075400, 0x72) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x185cb20 sp=0x185cb0c pc=0xa8864 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).wait(0x1bb6298, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x185cb34 sp=0x185cb20 pc=0x136044 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).waitRead(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*FD).Read(0x1bb6280, {0x1c4a000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x185cb7c sp=0x185cb34 pc=0x1371c4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*netFD).Read(0x1bb6280, {0x1c4a000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x185cba8 sp=0x185cb7c pc=0x1a9e78 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*conn).Read(0x1c04000, {0x1c4a000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x185cbd4 sp=0x185cba8 pc=0x1b967c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*atLeastReader).Read(0x1c082f0, {0x1c4a000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x185cc00 sp=0x185cbd4 pc=0x2674c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: bytes.(*Buffer).ReadFrom(0x1c0618c, {0xa3a068, 0x1c082f0}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x185cc3c sp=0x185cc00 pc=0x162058 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).readFromUntil(0x1c06008, {0xa38fa8, 0x1c04000}, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x185cc64 sp=0x185cc3c pc=0x26771c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).readRecordOrCCS(0x1c06008, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x185cdc4 sp=0x185cc64 pc=0x264e40 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).readRecord(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:589 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).Read(0x1c06008, {0x1c64000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x185cdf4 sp=0x185cdc4 pc=0x26ac04 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: bufio.(*Reader).Read(0x1c0f020, {0x1c66024, 0x9, 0x9}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x185ce18 sp=0x185cdf4 pc=0x2b72a0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: io.ReadAtLeast({0xa391f8, 0x1c0f020}, {0x1c66024, 0x9, 0x9}, 0x9) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x185ce44 sp=0x185ce18 pc=0xee954 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: io.ReadFull(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/io/io.go:354 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.http2readFrameHeader({0x1c66024, 0x9, 0x9}, {0xa391f8, 0x1c0f020}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x185ce6c sp=0x185ce44 pc=0x2f86ac Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*http2Framer).ReadFrame(0x1c66000) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x185cef4 sp=0x185ce6c pc=0x2f8e04 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*http2clientConnReadLoop).run(0x185cfdc) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x185cfa4 sp=0x185cef4 pc=0x31d33c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*http2ClientConn).readLoop(0x1882908) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x185cfe4 sp=0x185cfa4 pc=0x31c604 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x185cfec sp=0x185cfe4 pc=0x316960 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x185cfec sp=0x185cfec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by net/http.(*http2Transport).newClientConn in goroutine 68 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 57 gp=0x1a36a28 m=nil [select]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1bdd674 sp=0x1bdd660 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.selectgo(0x1bdd780, 0x1bdd74c, 0x0, 0x0, 0x3, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1bdd724 sp=0x1bdd674 pc=0x85000 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x1bb6d20) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/player/player.go:215 +0x1f4 fp=0x1bdd7e4 sp=0x1bdd724 pc=0x582954 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/player/player.go:197 +0x28 fp=0x1bdd7ec sp=0x1bdd7e4 pc=0x58253c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1bdd7ec sp=0x1bdd7ec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/player/player.go:197 +0x220 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 59 gp=0x1a36c68 m=nil [chan receive]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x9772f4, 0x1a12178, 0xe, 0x7, 0x2) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x196c73c sp=0x196c728 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.chanrecv(0x1a12140, 0x196c7d0, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x196c784 sp=0x196c73c pc=0x3414c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.chanrecv2(0x1a12140, 0x196c7d0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x196c798 sp=0x196c784 pc=0x33bf0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*App).withAppPlayer.func1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/main.go:284 +0x68 fp=0x196c7ec sp=0x196c798 pc=0x6bd918 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x196c7ec sp=0x196c7ec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by main.(*App).withAppPlayer in goroutine 1 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/main.go:281 +0x4f8 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 60 gp=0x1a36d88 m=nil [chan receive]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x9772f4, 0x1a12138, 0xe, 0x7, 0x2) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x196cf40 sp=0x196cf2c pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.chanrecv(0x1a12100, 0x196cfe0, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x196cf88 sp=0x196cf40 pc=0x3414c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.chanrecv2(0x1a12100, 0x196cfe0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x196cf9c sp=0x196cf88 pc=0x33bf0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*App).withAppPlayer.func2() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/main.go:300 +0x6c fp=0x196cfec sp=0x196cf9c pc=0x6bd47c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x196cfec sp=0x196cfec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by main.(*App).withAppPlayer in goroutine 1 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/main.go:297 +0x598 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 61 gp=0x1a36ea8 m=nil [IO wait]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97745c, 0x76075c10, 0x2, 0x2, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x196d5ac sp=0x196d598 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.netpollblock(0x76075c00, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x196d5c4 sp=0x196d5ac pc=0x675a0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.runtime_pollWait(0x76075c00, 0x72) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x196d5d8 sp=0x196d5c4 pc=0xa8864 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).wait(0x1ba5828, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x196d5ec sp=0x196d5d8 pc=0x136044 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).waitRead(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*FD).Accept(0x1ba5810) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x196d634 sp=0x196d5ec pc=0x13a800 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*netFD).accept(0x1ba5810) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x196d69c sp=0x196d634 pc=0x1abc28 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*TCPListener).accept(0x1b6dd40) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x196d6f0 sp=0x196d69c pc=0x1c3554 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*TCPListener).Accept(0x1b6dd40) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x196d70c sp=0x196d6f0 pc=0x1c259c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*onceCloseListener).Accept(0x1c965a0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: :1 +0x34 fp=0x196d724 sp=0x196d70c pc=0x367f9c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*Server).Serve(0x1bd0e78, {0xa3d218, 0x1b6dd40}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x196d7bc sp=0x196d724 pc=0x341154 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.Serve(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:2971 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/zeroconf/zeroconf.go:297 +0x8c fp=0x196d7ec sp=0x196d7bc pc=0x62f538 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x196d7ec sp=0x196d7ec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/zeroconf/zeroconf.go:297 +0x160 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 84 gp=0x1a370e8 m=nil [IO wait]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97745c, 0x76075610, 0x2, 0x2, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1858d04 sp=0x1858cf0 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.netpollblock(0x76075600, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1858d1c sp=0x1858d04 pc=0x675a0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.runtime_pollWait(0x76075600, 0x72) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1858d30 sp=0x1858d1c pc=0xa8864 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).wait(0x1bd5918, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1858d44 sp=0x1858d30 pc=0x136044 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).waitRead(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*FD).Read(0x1bd5900, {0x1813a20, 0x3, 0x3}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1858d8c sp=0x1858d44 pc=0x1371c4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*netFD).Read(0x1bd5900, {0x1813a20, 0x3, 0x3}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1858db8 sp=0x1858d8c pc=0x1a9e78 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*conn).Read(0x189ce10, {0x1813a20, 0x3, 0x3}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1858de4 sp=0x1858db8 pc=0x1b967c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: io.ReadAtLeast({0xa38fa8, 0x189ce10}, {0x1813a20, 0x3, 0x3}, 0x3) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1858e10 sp=0x1858de4 pc=0xee954 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: io.ReadFull(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/io/io.go:354 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/devgianlu/go-librespot/ap.(*shannonConn).receivePacket(0x1870cc0, {0xa3da1c, 0x1005090}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/ap/shannon.go:124 +0x370 fp=0x1858ed4 sp=0x1858e10 pc=0x4fac0c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x18ffce8) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/ap/ap.go:289 +0x68 fp=0x1858fe4 sp=0x1858ed4 pc=0x4f74ac Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/ap/ap.go:273 +0x28 fp=0x1858fec sp=0x1858fe4 pc=0x4f7418 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1858fec sp=0x1858fec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 58 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/ap/ap.go:273 +0x98 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 94 gp=0x1a37328 m=nil [IO wait]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97745c, 0x75645e10, 0x2, 0x2, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c17908 sp=0x1c178f4 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.netpollblock(0x75645e00, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1c17920 sp=0x1c17908 pc=0x675a0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.runtime_pollWait(0x75645e00, 0x72) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1c17934 sp=0x1c17920 pc=0xa8864 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).wait(0x1bb7968, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c17948 sp=0x1c17934 pc=0x136044 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).waitRead(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*FD).Read(0x1bb7950, {0x187d000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1c17990 sp=0x1c17948 pc=0x1371c4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*netFD).Read(0x1bb7950, {0x187d000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1c179bc sp=0x1c17990 pc=0x1a9e78 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*conn).Read(0x1832d48, {0x187d000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1c179e8 sp=0x1c179bc pc=0x1b967c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: io.(*multiReader).Read(0x1c98be0, {0x187d000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x1c17a14 sp=0x1c179e8 pc=0xefb88 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: bufio.(*Reader).fill(0x18b1410) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x1c17a38 sp=0x1c17a14 pc=0x2b6c1c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: bufio.(*Reader).ReadByte(0x18b1410) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x1c17a44 sp=0x1c17a38 pc=0x2b7498 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.readFrameHeader(0x18b1410, {0x198aab8, 0x8, 0x8}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x1c17a84 sp=0x1c17a44 pc=0x372a08 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.(*Conn).readFrameHeader(0x198aa88, {0xa3da00, 0x1005090}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x1c17b08 sp=0x1c17a84 pc=0x375bd4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.(*Conn).readLoop(0x198aa88, {0xa3da00, 0x1005090}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x1c17bcc sp=0x1c17b08 pc=0x375390 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.(*Conn).reader(0x198aa88, {0xa3da00, 0x1005090}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x1c17c50 sp=0x1c17bcc pc=0x376798 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.(*Conn).Reader(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.(*Conn).Read(0x198aa88, {0xa3da00, 0x1005090}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x1c17c78 sp=0x1c17c50 pc=0x374944 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*ConcreteApiServer).serve.func17({0xa3d2d8, 0x1bd0c68}, 0x1bd0b08) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/api_server.go:636 +0x3b4 fp=0x1c17cfc sp=0x1c17c78 pc=0x6b2040 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.HandlerFunc.ServeHTTP(0x189c450, {0xa3d2d8, 0x1bd0c68}, 0x1bd0b08) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x1c17d0c sp=0x1c17cfc pc=0x33d7d8 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*ServeMux).ServeHTTP(0x18ce400, {0xa3d2d8, 0x1bd0c68}, 0x1bd0b08) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x1c17d4c sp=0x1c17d0c pc=0x33f6c8 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d2d8, 0x1bd0c68}, 0x1bd0b08) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x1c17d74 sp=0x1c17d4c pc=0x6b19b8 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.HandlerFunc.ServeHTTP(0x18ac4c0, {0xa3d2d8, 0x1bd0c68}, 0x1bd0b08) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x1c17d84 sp=0x1c17d74 pc=0x33d7d8 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.serverHandler.ServeHTTP({0x18fe588}, {0xa3d2d8, 0x1bd0c68}, 0x1bd0b08) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x1c17da0 sp=0x1c17d84 pc=0x35cb44 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*conn).serve(0x1ba21e0, {0xa3da38, 0x18b25a0}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x1c17fdc sp=0x1c17da0 pc=0x33b894 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*Server).Serve.gowrap3() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x1c17fec sp=0x1c17fdc pc=0x3415cc Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c17fec sp=0x1c17fec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by net/http.(*Server).Serve in goroutine 37 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:3493 +0x42c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 86 gp=0x18037a8 m=nil [IO wait]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97745c, 0x76075210, 0x2, 0x2, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1a1b998 sp=0x1a1b984 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.netpollblock(0x76075200, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1a1b9b0 sp=0x1a1b998 pc=0x675a0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.runtime_pollWait(0x76075200, 0x72) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1a1b9c4 sp=0x1a1b9b0 pc=0xa8864 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).wait(0x1ce2158, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1a1b9d8 sp=0x1a1b9c4 pc=0x136044 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).waitRead(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*FD).Read(0x1ce2140, {0x1c65000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1a1ba20 sp=0x1a1b9d8 pc=0x1371c4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*netFD).Read(0x1ce2140, {0x1c65000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1a1ba4c sp=0x1a1ba20 pc=0x1a9e78 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*conn).Read(0x1832008, {0x1c65000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1a1ba78 sp=0x1a1ba4c pc=0x1b967c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*atLeastReader).Read(0x1810f20, {0x1c65000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x1a1baa4 sp=0x1a1ba78 pc=0x2674c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: bytes.(*Buffer).ReadFrom(0x1a1c18c, {0xa3a068, 0x1810f20}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x1a1bae0 sp=0x1a1baa4 pc=0x162058 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).readFromUntil(0x1a1c008, {0xa38fa8, 0x1832008}, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x1a1bb08 sp=0x1a1bae0 pc=0x26771c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).readRecordOrCCS(0x1a1c008, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x1a1bc68 sp=0x1a1bb08 pc=0x264e40 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).readRecord(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:589 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: crypto/tls.(*Conn).Read(0x1a1c008, {0x1816000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x1a1bc98 sp=0x1a1bc68 pc=0x26ac04 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*readWriteCloserBody).Read(0x1aae000, {0x1816000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/transport.go:2578 +0xec fp=0x1a1bcb8 sp=0x1a1bc98 pc=0x358048 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: bufio.(*Reader).fill(0x1ba7800) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x1a1bcdc sp=0x1a1bcb8 pc=0x2b6c1c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: bufio.(*Reader).ReadByte(0x1ba7800) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x1a1bce8 sp=0x1a1bcdc pc=0x2b7498 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.readFrameHeader(0x1ba7800, {0x198a938, 0x8, 0x8}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x1a1bd28 sp=0x1a1bce8 pc=0x372a08 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.(*Conn).readFrameHeader(0x198a908, {0xa3da00, 0x1005090}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x1a1bdac sp=0x1a1bd28 pc=0x375bd4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.(*Conn).readLoop(0x198a908, {0xa3da00, 0x1005090}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x1a1be70 sp=0x1a1bdac pc=0x375390 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.(*Conn).reader(0x198a908, {0xa3da00, 0x1005090}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x1a1bef4 sp=0x1a1be70 pc=0x376798 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.(*Conn).Reader(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.(*Conn).Read(0x198a908, {0xa3da00, 0x1005090}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x1a1bf1c sp=0x1a1bef4 pc=0x374944 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x1bd0dc8) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/dealer/dealer.go:188 +0x58 fp=0x1a1bfe4 sp=0x1a1bf1c pc=0x5a222c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/dealer/dealer.go:125 +0x28 fp=0x1a1bfec sp=0x1a1bfe4 pc=0x5a3d28 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1a1bfec sp=0x1a1bfec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 58 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/dealer/dealer.go:125 +0x98 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 87 gp=0x18038c8 m=nil [select]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1972e84 sp=0x1972e70 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.selectgo(0x1972fd4, 0x1972f5c, 0x0, 0x0, 0x2, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1972f34 sp=0x1972e84 pc=0x85000 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x1bd0dc8) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/dealer/dealer.go:138 +0x8c fp=0x1972fe4 sp=0x1972f34 pc=0x5a1d90 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/dealer/dealer.go:129 +0x28 fp=0x1972fec sp=0x1972fe4 pc=0x5a3cd4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1972fec sp=0x1972fec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 58 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/dealer/dealer.go:129 +0x15c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 77 gp=0x1a379e8 m=nil [IO wait]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97745c, 0x75645c10, 0x2, 0x2, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1a17908 sp=0x1a178f4 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.netpollblock(0x75645c00, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1a17920 sp=0x1a17908 pc=0x675a0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.runtime_pollWait(0x75645c00, 0x72) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1a17934 sp=0x1a17920 pc=0xa8864 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).wait(0x1938ce8, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1a17948 sp=0x1a17934 pc=0x136044 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).waitRead(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*FD).Read(0x1938cd0, {0x1a81000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1a17990 sp=0x1a17948 pc=0x1371c4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*netFD).Read(0x1938cd0, {0x1a81000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1a179bc sp=0x1a17990 pc=0x1a9e78 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*conn).Read(0x189d6b8, {0x1a81000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1a179e8 sp=0x1a179bc pc=0x1b967c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: io.(*multiReader).Read(0x1aafa40, {0x1a81000, 0x1000, 0x1000}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x1a17a14 sp=0x1a179e8 pc=0xefb88 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: bufio.(*Reader).fill(0x18b1560) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x1a17a38 sp=0x1a17a14 pc=0x2b6c1c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: bufio.(*Reader).ReadByte(0x18b1560) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x1a17a44 sp=0x1a17a38 pc=0x2b7498 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.readFrameHeader(0x18b1560, {0x1932338, 0x8, 0x8}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x1a17a84 sp=0x1a17a44 pc=0x372a08 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.(*Conn).readFrameHeader(0x1932308, {0xa3da00, 0x1005090}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x1a17b08 sp=0x1a17a84 pc=0x375bd4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.(*Conn).readLoop(0x1932308, {0xa3da00, 0x1005090}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x1a17bcc sp=0x1a17b08 pc=0x375390 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.(*Conn).reader(0x1932308, {0xa3da00, 0x1005090}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x1a17c50 sp=0x1a17bcc pc=0x376798 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.(*Conn).Reader(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: github.com/coder/websocket.(*Conn).Read(0x1932308, {0xa3da00, 0x1005090}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x1a17c78 sp=0x1a17c50 pc=0x374944 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*ConcreteApiServer).serve.func17({0xa3d2d8, 0x1af8bb8}, 0x1bd1a28) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/api_server.go:636 +0x3b4 fp=0x1a17cfc sp=0x1a17c78 pc=0x6b2040 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.HandlerFunc.ServeHTTP(0x189c450, {0xa3d2d8, 0x1af8bb8}, 0x1bd1a28) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x1a17d0c sp=0x1a17cfc pc=0x33d7d8 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*ServeMux).ServeHTTP(0x18ce400, {0xa3d2d8, 0x1af8bb8}, 0x1bd1a28) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x1a17d4c sp=0x1a17d0c pc=0x33f6c8 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d2d8, 0x1af8bb8}, 0x1bd1a28) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x1a17d74 sp=0x1a17d4c pc=0x6b19b8 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.HandlerFunc.ServeHTTP(0x18ac4c0, {0xa3d2d8, 0x1af8bb8}, 0x1bd1a28) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x1a17d84 sp=0x1a17d74 pc=0x33d7d8 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.serverHandler.ServeHTTP({0x18fe588}, {0xa3d2d8, 0x1af8bb8}, 0x1bd1a28) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x1a17da0 sp=0x1a17d84 pc=0x35cb44 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*conn).serve(0x182a180, {0xa3da38, 0x18b25a0}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x1a17fdc sp=0x1a17da0 pc=0x33b894 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*Server).Serve.gowrap3() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x1a17fec sp=0x1a17fdc pc=0x3415cc Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1a17fec sp=0x1a17fec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by net/http.(*Server).Serve in goroutine 37 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:3493 +0x42c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 79 gp=0x1a37b08 m=nil [chan receive]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x9772f4, 0x1c01eb8, 0xe, 0x7, 0x2) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1857bd8 sp=0x1857bc4 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.chanrecv(0x1c01e80, 0x1857cac, 0x1) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x1857c20 sp=0x1857bd8 pc=0x3414c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.chanrecv1(0x1c01e80, 0x1857cac) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/chan.go:509 +0x20 fp=0x1857c34 sp=0x1857c20 pc=0x33bcc Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*ConcreteApiServer).handleRequest(0x1926600, {{0x919857, 0x4}, {0x83ac98, 0x0}, 0x1c01e80}, {0xa3d2d8, 0x1af8f28}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/api_server.go:355 +0x74 fp=0x1857cbc sp=0x1857c34 pc=0x6b06a4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*ConcreteApiServer).serve.func8({0xa3d2d8, 0x1af8f28}, 0x1af8dc8) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/cmd/daemon/api_server.go:486 +0x12c fp=0x1857cfc sp=0x1857cbc pc=0x6b2e58 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.HandlerFunc.ServeHTTP(0x189c368, {0xa3d2d8, 0x1af8f28}, 0x1af8dc8) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x1857d0c sp=0x1857cfc pc=0x33d7d8 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*ServeMux).ServeHTTP(0x18ce400, {0xa3d2d8, 0x1af8f28}, 0x1af8dc8) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x1857d4c sp=0x1857d0c pc=0x33f6c8 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d2d8, 0x1af8f28}, 0x1af8dc8) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x1857d74 sp=0x1857d4c pc=0x6b19b8 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.HandlerFunc.ServeHTTP(0x18ac4c0, {0xa3d2d8, 0x1af8f28}, 0x1af8dc8) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x1857d84 sp=0x1857d74 pc=0x33d7d8 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.serverHandler.ServeHTTP({0x18fe588}, {0xa3d2d8, 0x1af8f28}, 0x1af8dc8) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x1857da0 sp=0x1857d84 pc=0x35cb44 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*conn).serve(0x182a1e0, {0xa3da38, 0x18b25a0}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x1857fdc sp=0x1857da0 pc=0x33b894 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*Server).Serve.gowrap3() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x1857fec sp=0x1857fdc pc=0x3415cc Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1857fec sp=0x1857fec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by net/http.(*Server).Serve in goroutine 37 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:3493 +0x42c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: goroutine 80 gp=0x1a37c28 m=nil [IO wait]: Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.gopark(0x97745c, 0x75645a10, 0x2, 0x2, 0x5) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1bda6d8 sp=0x1bda6c4 pc=0xa97c0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.netpollblock(0x75645a00, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1bda6f0 sp=0x1bda6d8 pc=0x675a0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.runtime_pollWait(0x75645a00, 0x72) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1bda704 sp=0x1bda6f0 pc=0xa8864 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).wait(0x1938e28, 0x72, 0x0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1bda718 sp=0x1bda704 pc=0x136044 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*pollDesc).waitRead(...) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: internal/poll.(*FD).Read(0x1938e10, {0x1944fd5, 0x1, 0x1}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1bda760 sp=0x1bda718 pc=0x1371c4 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*netFD).Read(0x1938e10, {0x1944fd5, 0x1, 0x1}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1bda78c sp=0x1bda760 pc=0x1a9e78 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net.(*conn).Read(0x189d7f8, {0x1944fd5, 0x1, 0x1}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1bda7b8 sp=0x1bda78c pc=0x1b967c Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*connReader).backgroundRead(0x1944fc0) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:702 +0x3c fp=0x1bda7e4 sp=0x1bda7b8 pc=0x335ac0 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: net/http.(*connReader).startBackgroundRead.gowrap2() Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:698 +0x28 fp=0x1bda7ec sp=0x1bda7e4 pc=0x3359fc Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: runtime.goexit({}) Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1bda7ec sp=0x1bda7ec pc=0xb0874 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: created by net/http.(*connReader).startBackgroundRead in goroutine 79 Mar 28 20:16:18 raspberry-spotify go-librespot[2597]: /usr/local/go/src/net/http/server.go:698 +0xd4 Mar 28 20:16:18 raspberry-spotify go-librespot[2596]: Aborted Mar 28 20:16:19 raspberry-spotify volumio[1121]: SPOTIFY: SETTING SPOTIFY VOLUME 74 Mar 28 20:16:19 raspberry-spotify volumio[1121]: info: Sending Spotify command with payload to local API: /player/volume Mar 28 20:16:19 raspberry-spotify volumio[1121]: error: Failed to send command to Spotify local API: /player/volume: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 20:16:20 raspberry-spotify volumio[1121]: info: Getting Spotify volume Mar 28 20:16:20 raspberry-spotify volumio[1121]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 20:16:20 raspberry-spotify volumio[1121]: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 20:16:20 raspberry-spotify volumio[1121]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 28 20:16:20 raspberry-spotify volumio[1121]: errno: -111, Mar 28 20:16:20 raspberry-spotify volumio[1121]: code: 'ECONNREFUSED', Mar 28 20:16:20 raspberry-spotify volumio[1121]: syscall: 'connect', Mar 28 20:16:20 raspberry-spotify volumio[1121]: address: '127.0.0.1', Mar 28 20:16:20 raspberry-spotify volumio[1121]: port: 9879, Mar 28 20:16:20 raspberry-spotify volumio[1121]: response: undefined Mar 28 20:16:20 raspberry-spotify volumio[1121]: } Mar 28 20:16:20 raspberry-spotify volumio[1121]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 20:16:21 raspberry-spotify systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Mar 28 20:16:21 raspberry-spotify systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 20:16:21 raspberry-spotify systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 20:16:21 raspberry-spotify go-librespot[2634]: go-librespot daemon starting... Mar 28 20:16:21 raspberry-spotify go-librespot[2635]: time="2026-03-28T20:16:21+01:00" level=info msg="running go-librespot 0.7.1" Mar 28 20:16:21 raspberry-spotify go-librespot[2635]: time="2026-03-28T20:16:21+01:00" level=debug msg="app state loaded" Mar 28 20:16:21 raspberry-spotify go-librespot[2635]: time="2026-03-28T20:16:21+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 20:16:21 raspberry-spotify sudo[2644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 20:15' Mar 28 20:16:21 raspberry-spotify sudo[2644]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"