-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-02-16 11:59:19 CET. -- Feb 16 11:58:59 volumio systemd-timedated[997]: Changed local time to Sun Feb 16 11:58:59 2025 Feb 16 11:58:59 volumio sudo[995]: pam_unix(sudo:session): session closed for user root Feb 16 11:58:59 volumio volumio-time-update[607]: volumio-time-update-util: System time updated successfully. Feb 16 11:58:59 volumio systemd[1]: Starting Daily apt download activities... Feb 16 11:58:59 volumio systemd[1]: Started Volumio Time Update Utility. Feb 16 11:58:59 volumio systemd[1]: Reached target Multi-User System. Feb 16 11:58:59 volumio systemd[1]: Reached target Graphical Interface. Feb 16 11:58:59 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 16 11:58:59 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 16 11:58:59 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 16 11:59:00 volumio volumio[962]: info: ------------------------------------------- Feb 16 11:59:00 volumio volumio[962]: info: ----- Volumio3 ---- Feb 16 11:59:00 volumio volumio[962]: info: ------------------------------------------- Feb 16 11:59:00 volumio volumio[962]: info: ----- System startup ---- Feb 16 11:59:00 volumio volumio[962]: info: ------------------------------------------- Feb 16 11:59:00 volumio systemd[1]: apt-daily.service: Succeeded. Feb 16 11:59:00 volumio systemd[1]: Started Daily apt download activities. Feb 16 11:59:00 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Feb 16 11:59:01 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Feb 16 11:59:01 volumio systemd[1]: Started Daily apt upgrade and clean activities. Feb 16 11:59:01 volumio systemd[1]: Startup finished in 14.367s (kernel) + 25.449s (userspace) = 39.817s. Feb 16 11:59:01 volumio volumio[962]: info: MYVOLUMIO Environment detected Feb 16 11:59:01 volumio volumio[962]: info: Plugin folders cleanup Feb 16 11:59:01 volumio volumio[962]: info: Scanning into folder /volumio/app/plugins/ Feb 16 11:59:01 volumio volumio[962]: info: Scanning category audio_interface Feb 16 11:59:01 volumio volumio[962]: info: Scanning category miscellanea Feb 16 11:59:01 volumio volumio[962]: info: Scanning category music_service Feb 16 11:59:01 volumio volumio[962]: info: Scanning category plugins.json Feb 16 11:59:01 volumio volumio[962]: info: Scanning category system_controller Feb 16 11:59:01 volumio volumio[962]: info: Scanning category user_interface Feb 16 11:59:01 volumio volumio[962]: info: Scanning into folder /data/plugins/ Feb 16 11:59:01 volumio volumio[962]: info: Scanning category music_service Feb 16 11:59:01 volumio volumio[962]: info: Plugin folders cleanup completed Feb 16 11:59:01 volumio volumio[962]: info: ------------------------------------------- Feb 16 11:59:01 volumio volumio[962]: info: ----- Core plugins startup ---- Feb 16 11:59:01 volumio volumio[962]: info: ------------------------------------------- Feb 16 11:59:01 volumio volumio[962]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 11:59:01 volumio volumio[962]: info: Adding plugin upnp to MyMusic Plugins Feb 16 11:59:01 volumio volumio[962]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 11:59:01 volumio volumio[962]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 11:59:01 volumio volumio[962]: info: Loading plugins from folder /data/plugins/ Feb 16 11:59:01 volumio volumio[962]: info: Loading plugin "system"... Feb 16 11:59:01 volumio volumio[962]: info: Loading plugin "appearance"... Feb 16 11:59:03 volumio volumio[962]: info: Loading plugin "network"... Feb 16 11:59:03 volumio volumio[962]: info: Refreshing Cached IP Addresses Feb 16 11:59:03 volumio sudo[1116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 11:59:03 volumio sudo[1116]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 11:59:03 volumio sudo[1118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 11:59:03 volumio sudo[1118]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 11:59:03 volumio sudo[1116]: pam_unix(sudo:session): session closed for user root Feb 16 11:59:03 volumio volumio[962]: info: Loading plugin "services"... Feb 16 11:59:03 volumio sudo[1118]: pam_unix(sudo:session): session closed for user root Feb 16 11:59:03 volumio volumio[962]: info: Loading plugin "alsa_controller"... Feb 16 11:59:03 volumio sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 16 11:59:03 volumio sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 11:59:03 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 11:59:03 volumio volumio[962]: info: Loading plugin "wizard"... Feb 16 11:59:03 volumio volumio[962]: info: Loading plugin "networkfs"... Feb 16 11:59:03 volumio volumio[962]: info: Starting Udev Watcher for removable devices Feb 16 11:59:03 volumio volumio[962]: info: Ignoring mount for partition: boot Feb 16 11:59:03 volumio volumio[962]: info: Ignoring mount for partition: volumio Feb 16 11:59:03 volumio volumio[962]: info: Ignoring mount for partition: volumio_data Feb 16 11:59:03 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 11:59:03 volumio volumio[962]: info: Loading plugin "volumio_command_line_client"... Feb 16 11:59:03 volumio volumio[962]: info: Loading plugin "upnp"... Feb 16 11:59:03 volumio volumio[962]: info: [1739703543811] Starting Upmpd Daemon Feb 16 11:59:03 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 11:59:03 volumio volumio[962]: info: Loading plugin "my_music"... Feb 16 11:59:03 volumio volumio[962]: info: Loading plugin "mpd"... Feb 16 11:59:04 volumio volumio[962]: info: Loading plugin "upnp_browser"... Feb 16 11:59:04 volumio sudo[1127]: pam_unix(sudo:session): session closed for user root Feb 16 11:59:04 volumio volumio[962]: info: Loading plugin "alarm-clock"... Feb 16 11:59:05 volumio volumio[962]: info: Loading plugin "airplay_emulation"... Feb 16 11:59:05 volumio volumio[962]: info: Starting Shairport Sync Feb 16 11:59:05 volumio volumio[962]: info: Loading plugin "last_100"... Feb 16 11:59:05 volumio volumio[962]: info: Loading plugin "webradio"... Feb 16 11:59:05 volumio volumio[962]: info: Loading plugin "i2s_dacs"... Feb 16 11:59:05 volumio volumio[962]: info: Loading plugin "volumiodiscovery"... Feb 16 11:59:05 volumio volumio[962]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 11:59:05 volumio volumio[962]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 11:59:05 volumio volumio[962]: *** WARNING *** For more information see Feb 16 11:59:05 volumio node[962]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 11:59:05 volumio volumio[962]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 11:59:05 volumio volumio[962]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 11:59:05 volumio volumio[962]: *** WARNING *** For more information see Feb 16 11:59:05 volumio node[962]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 11:59:05 volumio node[962]: *** WARNING *** For more information see Feb 16 11:59:05 volumio node[962]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 11:59:05 volumio node[962]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 11:59:05 volumio node[962]: *** WARNING *** For more information see Feb 16 11:59:05 volumio volumio[962]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 11:59:05 volumio volumio[962]: info: Discovery: Started advertising with name: Volumio Feb 16 11:59:05 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 11:59:05 volumio volumio[962]: info: Loading plugin "spop"... Feb 16 11:59:05 volumio volumio-remote-updater[612]: [2025-02-16 11:59:05] [connect] Successful connection Feb 16 11:59:07 volumio volumio[962]: info: Loading plugin "outputs"... Feb 16 11:59:07 volumio volumio[962]: info: Loading plugin "albumart"... Feb 16 11:59:07 volumio volumio[962]: info: Plugin example_plugin is not enabled Feb 16 11:59:07 volumio volumio[962]: info: Loading plugin "inputs"... Feb 16 11:59:07 volumio volumio[962]: info: Loading plugin "updater_comm"... Feb 16 11:59:07 volumio volumio[962]: info: Plugin mpdemulation is not enabled Feb 16 11:59:07 volumio volumio[962]: info: Loading plugin "rest_api"... Feb 16 11:59:07 volumio volumio[962]: info: Loading plugin "websocket"... Feb 16 11:59:07 volumio volumio[962]: info: Starting Socket.io Server version 2.3.0 Feb 16 11:59:07 volumio volumio[962]: info: Loading i18n strings for locale nl Feb 16 11:59:07 volumio volumio[962]: Updating browse sources language Feb 16 11:59:07 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 11:59:07 volumio volumio[962]: Forking 3 albumart workers Feb 16 11:59:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 11:59:08 volumio volumio[962]: info: CoreCommandRouter::initPlayerControls Feb 16 11:59:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 11:59:08 volumio volumio[962]: Express server listening on port 3000 Feb 16 11:59:08 volumio volumio[962]: [Metrics] WebUI: 9s 517.13ms Feb 16 11:59:08 volumio volumio[962]: info: CoreStateMachine::resetVolumioState Feb 16 11:59:08 volumio volumio[962]: info: CoreStateMachine::getcurrentVolume Feb 16 11:59:08 volumio volumio[962]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 11:59:08 volumio volumio[962]: info: Volumio Network Manager: Network status updated: 2 Feb 16 11:59:08 volumio volumio-remote-updater[612]: [2025-02-16 11:59:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739703545 101 Feb 16 11:59:08 volumio volumio[962]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Feb 16 11:59:08 volumio volumio[962]: info: Reloading queue from file Feb 16 11:59:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 11:59:08 volumio volumio[962]: info: Setting Device type: Raspberry PI Feb 16 11:59:08 volumio volumio[962]: info: CoreStateMachine::setRepeat true single undefined Feb 16 11:59:08 volumio volumio[962]: info: CoreStateMachine::pushState Feb 16 11:59:08 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Feb 16 11:59:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 11:59:08 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Feb 16 11:59:08 volumio volumio[962]: info: CoreStateMachine::setRandom undefined Feb 16 11:59:08 volumio volumio[962]: info: CoreStateMachine::pushState Feb 16 11:59:08 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Feb 16 11:59:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 11:59:08 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Feb 16 11:59:08 volumio volumio[962]: info: VolumeController:: Volume=48 Mute =false Feb 16 11:59:08 volumio volumio[962]: info: CoreStateMachine::pushState Feb 16 11:59:08 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Feb 16 11:59:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 11:59:08 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Feb 16 11:59:08 volumio volumio[962]: info: CoreStateMachine::updateTrackBlock Feb 16 11:59:08 volumio volumio[962]: info: CorePlayQueue::getTrackBlock Feb 16 11:59:08 volumio volumio[962]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 11:59:08 volumio volumio[962]: info: Completed loading Core Plugins Feb 16 11:59:08 volumio volumio[962]: info: Preparing to generate the ALSA configuration file Feb 16 11:59:08 volumio volumio[962]: info: Discovery: adding 0c76f65e-b8fb-439b-a4c1-3e9fecb30071 Feb 16 11:59:08 volumio volumio[962]: info: Discovery: Found device Volumio Feb 16 11:59:08 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Feb 16 11:59:08 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Feb 16 11:59:08 volumio volumio[962]: info: Asound.conf file unchanged, so no further update is needed Feb 16 11:59:08 volumio volumio[962]: info: Output device has changed, restarting MPD Feb 16 11:59:08 volumio volumio[962]: info: Output device has changed, restarting Shairport Sync Feb 16 11:59:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 11:59:09 volumio sudo[1186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 11:59:09 volumio sudo[1186]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 11:59:09 volumio sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 11:59:09 volumio sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 11:59:09 volumio sudo[1186]: pam_unix(sudo:session): session closed for user root Feb 16 11:59:09 volumio volumio[962]: Starting albumart workers Feb 16 11:59:09 volumio systemd[1]: Stopping Music Player Daemon... Feb 16 11:59:09 volumio volumio[962]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 11:59:09 volumio volumio[962]: info: ___________ START PLUGINS ___________ Feb 16 11:59:09 volumio volumio[962]: Starting albumart workers Feb 16 11:59:09 volumio volumio[962]: info: ControllerMpd::onStart: Initializing MPD Feb 16 11:59:09 volumio volumio[962]: Starting albumart workers Feb 16 11:59:09 volumio volumio[962]: info: Creating MPD Configuration file Feb 16 11:59:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 11:59:09 volumio volumio[962]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 11:59:09 volumio volumio[962]: info: [1739703549141] CoreMusicLibrary::Adding element Media Servers Feb 16 11:59:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 11:59:09 volumio sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 11:59:09 volumio sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 11:59:09 volumio sudo[1195]: pam_unix(sudo:session): session closed for user root Feb 16 11:59:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 11:59:09 volumio sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 11:59:09 volumio sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 11:59:09 volumio volumio[962]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 11:59:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 11:59:09 volumio systemd[1]: mpd.service: Succeeded. Feb 16 11:59:09 volumio systemd[1]: Stopped Music Player Daemon. Feb 16 11:59:09 volumio systemd[1]: Starting Music Player Daemon... Feb 16 11:59:09 volumio volumio[962]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 11:59:09 volumio volumio[962]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 11:59:09 volumio volumio[962]: info: [1739703549436] CoreMusicLibrary::Adding element Last_100 Feb 16 11:59:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 11:59:09 volumio volumio[962]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 11:59:09 volumio volumio[962]: info: [1739703549441] CoreMusicLibrary::Adding element Webradio Feb 16 11:59:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 11:59:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 11:59:09 volumio volumio[962]: info: Initializing BBC Radios Feb 16 11:59:09 volumio sudo[1206]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 11:59:09 volumio sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 11:59:09 volumio sudo[1206]: pam_unix(sudo:session): session closed for user root Feb 16 11:59:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 11:59:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 11:59:09 volumio volumio[962]: info: Creating Spotify config file Feb 16 11:59:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:09 volumio volumio[962]: info: Volumio Calling Home Feb 16 11:59:09 volumio systemd[1]: systemd-fsckd.service: Succeeded. Feb 16 11:59:10 volumio volumio[962]: info: MPD Permissions set Feb 16 11:59:10 volumio volumio[962]: info: MPD Permissions set Feb 16 11:59:10 volumio volumio[962]: info: VolumeController:: Volume=48 Mute =false Feb 16 11:59:10 volumio volumio[962]: info: CoreStateMachine::pushState Feb 16 11:59:10 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Feb 16 11:59:10 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Feb 16 11:59:10 volumio volumio[962]: info: Volumio called home Feb 16 11:59:10 volumio volumio[962]: info: Spotify config file written Feb 16 11:59:10 volumio sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 16 11:59:10 volumio sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 11:59:10 volumio volumio[962]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 16 11:59:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 11:59:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 11:59:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 11:59:10 volumio volumio[962]: info: No need to fix Spotify hosts Feb 16 11:59:10 volumio systemd[1]: Started go-librespot Daemon. Feb 16 11:59:10 volumio go-librespot[1255]: Librespot-go daemon starting... Feb 16 11:59:10 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Feb 16 11:59:10 volumio volumio[962]: info: Starting Shairport Sync Feb 16 11:59:10 volumio volumio[962]: info: Starting Shairport Sync Feb 16 11:59:10 volumio volumio[962]: info: Starting Shairport Sync Feb 16 11:59:10 volumio sudo[1267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 11:59:10 volumio sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 11:59:10 volumio sudo[1269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 11:59:10 volumio sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 11:59:10 volumio sudo[1269]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 11:59:10 volumio sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 11:59:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 11:59:10 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 16 11:59:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 16 11:59:10 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Feb 16 11:59:10 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Feb 16 11:59:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 11:59:10 volumio sudo[1267]: pam_unix(sudo:session): session closed for user root Feb 16 11:59:10 volumio volumio[962]: info: Shairport-Sync Started Feb 16 11:59:10 volumio volumio[962]: Error adding Membership: Error: addMembership EINVAL Feb 16 11:59:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 11:59:10 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 16 11:59:10 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 16 11:59:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 16 11:59:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 11:59:11 volumio sudo[1269]: pam_unix(sudo:session): session closed for user root Feb 16 11:59:11 volumio sudo[1272]: pam_unix(sudo:session): session closed for user root Feb 16 11:59:11 volumio volumio[962]: info: Shairport-Sync Started Feb 16 11:59:11 volumio volumio[962]: info: Shairport-Sync Started Feb 16 11:59:11 volumio go-librespot[1255]: time="2025-02-16T11:59:11+01:00" level=info msg="generated new device id: 77182f518333a457a5a2d28a6a2538455f9e72f1" Feb 16 11:59:11 volumio mpd[1218]: Feb 16 11:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 16 11:59:11 volumio systemd[1]: Started Music Player Daemon. Feb 16 11:59:11 volumio sudo[1188]: pam_unix(sudo:session): session closed for user root Feb 16 11:59:11 volumio sudo[1197]: pam_unix(sudo:session): session closed for user root Feb 16 11:59:11 volumio volumio[962]: info: Completed starting Core Plugins Feb 16 11:59:11 volumio volumio[962]: info: ------------------------------------------- Feb 16 11:59:11 volumio volumio[962]: info: ----- MyVolumio plugins startup ---- Feb 16 11:59:11 volumio volumio[962]: info: ------------------------------------------- Feb 16 11:59:11 volumio volumio[962]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 16 11:59:11 volumio volumio[962]: error: MPD error: The expression evaluated to a falsy value: Feb 16 11:59:11 volumio volumio[962]: assert.ok(self.idling) Feb 16 11:59:11 volumio volumio[962]: error: The expression evaluated to a falsy value: Feb 16 11:59:11 volumio volumio[962]: assert.ok(self.idling) Feb 16 11:59:11 volumio volumio[962]: error: updateQueue error: null Feb 16 11:59:11 volumio volumio[962]: info: MPD running with PID1218 Feb 16 11:59:11 volumio volumio[962]: ,establishing connection Feb 16 11:59:11 volumio volumio[962]: error: updateQueue error: null Feb 16 11:59:11 volumio go-librespot[1255]: time="2025-02-16T11:59:11+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-gae2.spotify.com:80]" Feb 16 11:59:11 volumio go-librespot[1255]: time="2025-02-16T11:59:11+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 16 11:59:11 volumio go-librespot[1255]: time="2025-02-16T11:59:11+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 16 11:59:11 volumio go-librespot[1255]: time="2025-02-16T11:59:11+01:00" level=debug msg="zeroconf server listening on port 39593" Feb 16 11:59:12 volumio sh[511]: timed out Feb 16 11:59:12 volumio dhcpcd[554]: timed out Feb 16 11:59:12 volumio sh[511]: dhcpcd exited Feb 16 11:59:12 volumio dhcpcd[554]: dhcpcd exited Feb 16 11:59:12 volumio sh[511]: ifup: failed to bring up eth0 Feb 16 11:59:12 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Feb 16 11:59:12 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Feb 16 11:59:13 volumio volumio[962]: info: go-librespot daemon successfully initialized Feb 16 11:59:13 volumio sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 11:59:13 volumio sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 11:59:13 volumio sudo[1302]: pam_unix(sudo:session): session closed for user root Feb 16 11:59:13 volumio sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 11:59:13 volumio sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 11:59:13 volumio sudo[1304]: pam_unix(sudo:session): session closed for user root Feb 16 11:59:13 volumio sudo[1308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 16 11:59:13 volumio sudo[1308]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 11:59:13 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 16 11:59:13 volumio sudo[1308]: pam_unix(sudo:session): session closed for user root Feb 16 11:59:13 volumio volumio[962]: info: Upmpdcli Daemon Started Feb 16 11:59:14 volumio volumio[1310]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 16 11:59:15 volumio go-librespot[1255]: time="2025-02-16T11:59:15+01:00" level=debug msg="obtained new client token: AACQw3myf8Qk9xUUlwNfJ1ZzyIAxFlPEPmXHl/wRKlm7UPsNArzUflgmkudEA7kLdx7Z1PmwjrPD6MV61GYNommkte2T87OkwNB4f1urPmm8oHV+fbr1xIDP2N/eCGQ3eD0VjtfrOoP0ChxjFMuWFXb5rx3Z2X/vd4jcgG65E6Fw7BwkpdWag9Uyb7wwRjlggKr1zINEhbON5GznW/Sy0Udc7duee4EE8/I3o0W8SDL/4vGXUw3kgz6O4C7Z7A==" Feb 16 11:59:15 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Feb 16 11:59:15 volumio go-librespot[1255]: time="2025-02-16T11:59:15+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 16 11:59:15 volumio go-librespot[1255]: time="2025-02-16T11:59:15+01:00" level=debug msg="completed keyexchange" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=debug msg="completed challenge" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=debug msg="authenticated as 29pbn0xyvcdfzx31ifu2nimbj" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=debug msg="authenticated as 29pbn0xyvcdfzx31ifu2nimbj" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=debug msg="dealer connection opened" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=info msg="accepted zeroconf user 29pbn0xyvcdfzx31ifu2nimbj from iPhone" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=debug msg="autoplay enabled: false" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=debug msg="received connection id: MTMzNzcwZTYtOTEzMy00YjllLWE1ZWItNDJhYjRiODkyOWI3K2RlYWxlcit0Y3A6Ly8wYWNhNTliOS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNENGNzM3RUIyQzYxMzdERTUyRTlBREU4QTg5NDEyODI3MEE1QThDMDJDOEI5MkVDRkQ0Q0UxOTNFMEZCMzE1NQ==" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 16 11:59:16 volumio volumio[962]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=debug msg="handling transfer player command from 4209704cd53758bdcc217ef15f048f8d8d58a519" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=debug msg="resolved context of track" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page 0 with 10 items (list: 10)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page 1 with 7 items (list: 17)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page 2 with 17 items (list: 34)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page 3 with 7 items (list: 41)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio volumio[962]: info: Initializing connection to go-librespot Websocket Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=debug msg="new websocket client" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page 4 with 2 items (list: 43)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio volumio[962]: info: Connection to go-librespot Websocket established Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page 5 with 1 items (list: 44)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page 6 with 1 items (list: 45)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page 7 with 5 items (list: 50)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page 8 with 5 items (list: 55)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page 9 with 1 items (list: 56)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page 10 with 1 items (list: 57)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page 11 with 1 items (list: 58)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page 12 with 5 items (list: 63)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page 13 with 5 items (list: 68)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:16 volumio go-librespot[1255]: time="2025-02-16T11:59:16+01:00" level=trace msg="fetched new page 14 with 1 items (list: 69)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page 15 with 1 items (list: 70)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page 16 with 6 items (list: 76)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page 17 with 2 items (list: 78)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page 18 with 6 items (list: 84)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page 19 with 2 items (list: 86)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page 20 with 6 items (list: 92)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page 21 with 1 items (list: 93)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page 22 with 6 items (list: 99)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page 23 with 6 items (list: 105)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page 24 with 1 items (list: 106)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page 25 with 1 items (list: 107)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page 26 with 3 items (list: 110)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page 27 with 2 items (list: 112)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page from (has next: false)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="fetched new page 28 with 1 items (list: 113)" uri="spotify:artist:33NVpKoXjItPwUJTMZIOiY" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=debug msg="shuffled context with seed 15290707484942476799 (len: 113, keep: 18)" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=debug msg="loading track (paused: false, position: 557446ms)" uri="spotify:track:3sIBXOBOnwkkKAdGpW2Q23" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=trace msg="emitting websocket event: will_play" Feb 16 11:59:17 volumio volumio[962]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3sIBXOBOnwkkKAdGpW2Q23","play_origin":"com.spotify.service.freetierartist"}} Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=debug msg="selected format OGG_VORBIS_320 (f6125f1d80392ddedcc151fa0dfba03b116b192a)" uri="spotify:track:3sIBXOBOnwkkKAdGpW2Q23" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=debug msg="requested aes key for file f6125f1d80392ddedcc151fa0dfba03b116b192a, gid: 3sIBXOBOnwkkKAdGpW2Q23" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1360" Feb 16 11:59:17 volumio volumio[1310]: .......................++++ Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 250" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1364" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1124" Feb 16 11:59:17 volumio go-librespot[1255]: time="2025-02-16T11:59:17+01:00" level=debug msg="fetched first chunk of 15, total size is 7794087 bytes" uri="spotify:track:3sIBXOBOnwkkKAdGpW2Q23" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:3sIBXOBOnwkkKAdGpW2Q23" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="fetched chunk 14/14, size: 454055" uri="spotify:track:3sIBXOBOnwkkKAdGpW2Q23" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=trace msg="seek to 186545ms (diff: 425ms, samples: 8226634, bytes: 7782870)" uri="spotify:track:3sIBXOBOnwkkKAdGpW2Q23" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 10068 us, period size = 444 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 49 frames" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="created new output device" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=info msg="loaded track \"Ends With You\" (paused: false, position: 557446ms, duration: 186545ms, prefetched: false)" uri="spotify:track:3sIBXOBOnwkkKAdGpW2Q23" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=trace msg="cleared closed output device" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:3sIBXOBOnwkkKAdGpW2Q23" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:3sIBXOBOnwkkKAdGpW2Q23" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=trace msg="scheduling prefetch in 157s" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=trace msg="emitting websocket event: metadata" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=trace msg="emitting websocket event: active" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="sending successful reply for dealer request" Feb 16 11:59:18 volumio volumio[962]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3sIBXOBOnwkkKAdGpW2Q23","name":"Ends With You","artist_names":["David Kushner"],"album_name":"20 Years From Now","album_cover_url":"https://i.scdn.co/image/ab67616d00001e024b418c7907527f8c3040ddf8","position":557446,"duration":186545,"release_date":"year:2025 month:2 day:14","track_number":3,"disc_number":1}} Feb 16 11:59:18 volumio volumio[962]: SPOTIFY: received: {"type":"active","data":null} Feb 16 11:59:18 volumio volumio[962]: info: Aligning Spotify Volume to Volumio Volume Feb 16 11:59:18 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Feb 16 11:59:18 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Feb 16 11:59:18 volumio volumio[962]: info: Setting Spotify Volume from Volumio: 48 Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=trace msg="emitting websocket event: playing" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=trace msg="emitting websocket event: not_playing" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0uqpdJxs85XN81guZUMpvq" Feb 16 11:59:18 volumio volumio[962]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:3sIBXOBOnwkkKAdGpW2Q23","play_origin":"com.spotify.service.freetierartist"}} Feb 16 11:59:18 volumio volumio[962]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 11:59:18 volumio volumio[962]: TypeError: Cannot read property 'service' of undefined Feb 16 11:59:18 volumio volumio[962]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Feb 16 11:59:18 volumio volumio[962]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:263:18) Feb 16 11:59:18 volumio volumio[962]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Feb 16 11:59:18 volumio volumio[962]: at WebSocket.emit (events.js:315:20) Feb 16 11:59:18 volumio volumio[962]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Feb 16 11:59:18 volumio volumio[962]: at Receiver.emit (events.js:315:20) Feb 16 11:59:18 volumio volumio[962]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Feb 16 11:59:18 volumio volumio[962]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Feb 16 11:59:18 volumio volumio[962]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Feb 16 11:59:18 volumio volumio[962]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Feb 16 11:59:18 volumio volumio[962]: at writeOrBuffer (internal/streams/writable.js:358:12) Feb 16 11:59:18 volumio volumio[962]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Feb 16 11:59:18 volumio volumio[962]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Feb 16 11:59:18 volumio volumio[962]: at Socket.emit (events.js:315:20) Feb 16 11:59:18 volumio volumio[962]: at addChunk (internal/streams/readable.js:309:12) Feb 16 11:59:18 volumio volumio[962]: at readableAddChunk (internal/streams/readable.js:284:9) Feb 16 11:59:18 volumio volumio[962]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1360" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=trace msg="emitting websocket event: will_play" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="selected format OGG_VORBIS_320 (42b7f873b1686d578dde4da5d394f3e1517eddb3)" uri="spotify:track:0uqpdJxs85XN81guZUMpvq" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="requested aes key for file 42b7f873b1686d578dde4da5d394f3e1517eddb3, gid: 0uqpdJxs85XN81guZUMpvq" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="fetched first chunk of 18, total size is 9095634 bytes" uri="spotify:track:0uqpdJxs85XN81guZUMpvq" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:0uqpdJxs85XN81guZUMpvq" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 10068 us, period size = 444 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 49 frames" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="created new output device" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=info msg="loaded track \"Hero - Slowed + Reverb\" (paused: false, position: 0ms, duration: 224104ms, prefetched: false)" uri="spotify:track:0uqpdJxs85XN81guZUMpvq" Feb 16 11:59:18 volumio go-librespot[1255]: time="2025-02-16T11:59:18+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1124" Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=trace msg="scheduling prefetch in 194s" Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=trace msg="emitting websocket event: metadata" Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=trace msg="closed output device because of stop command" Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=debug msg="fetched chunk 2/17, size: 524288" uri="spotify:track:0uqpdJxs85XN81guZUMpvq" Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=debug msg="put connect state inactive" Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=trace msg="emitting websocket event: inactive" Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=trace msg="emitting websocket event: stopped" Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=debug msg="fetched chunk 3/17, size: 524288" uri="spotify:track:0uqpdJxs85XN81guZUMpvq" Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=error msg="failed receiving dealer message" error="failed to get reader: received close frame: status = StatusGoingAway and reason = \"\"" Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.1.30:39460->34.158.1.133:4070: use of closed network connection" Feb 16 11:59:19 volumio go-librespot[1255]: time="2025-02-16T11:59:19+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 16 11:59:19 volumio go-librespot[1255]: panic: runtime error: invalid memory address or nil pointer dereference Feb 16 11:59:19 volumio go-librespot[1255]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x14 pc=0x572f28] Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 78 gp=0x2207208 m=0 mp=0xaa9b38 [running]: Feb 16 11:59:19 volumio go-librespot[1255]: panic({0x5eab20, 0xa60098}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:779 +0x12c fp=0x243b82c sp=0x243b7d8 pc=0x58364 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.panicmem(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:261 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.sigpanic() Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/signal_unix.go:881 +0x39c fp=0x243b85c sp=0x243b82c pc=0x74350 Feb 16 11:59:19 volumio go-librespot[1255]: main.(*AppPlayer).handlePlayerEvent(0x20260f0, 0x243ba54) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/controls.go:74 +0x194 fp=0x243b8fc sp=0x243b860 pc=0x572f28 Feb 16 11:59:19 volumio go-librespot[1255]: main.(*AppPlayer).Run(0x20260f0, 0x240d080) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/player.go:453 +0x5a4 fp=0x243bfe0 sp=0x243b8fc pc=0x57e2f8 Feb 16 11:59:19 volumio go-librespot[1255]: main.(*App).withAppPlayer.func3.gowrap1() Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:321 +0x30 fp=0x243bfec sp=0x243bfe0 pc=0x578f08 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x243bfec sp=0x243bfec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by main.(*App).withAppPlayer.func3 in goroutine 1 Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:321 +0x2f8 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 1 gp=0x2002128 m=nil [select]: Feb 16 11:59:19 volumio sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-16 11:58 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2077d68 sp=0x2077d54 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.selectgo(0x2077e68, 0x21c1e34, 0x0, 0x0, 0x2, 0x1) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2077e08 sp=0x2077d68 pc=0x705e4 Feb 16 11:59:19 volumio go-librespot[1255]: go-librespot/zeroconf.(*Zeroconf).Serve(0x21360f0, 0x2077ee4) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:268 +0x228 fp=0x2077eac sp=0x2077e08 pc=0x528c80 Feb 16 11:59:19 volumio go-librespot[1255]: main.(*App).withAppPlayer(0x20d2300, 0x68f2cc) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:297 +0x4b0 fp=0x2077f1c sp=0x2077eac pc=0x578aac Feb 16 11:59:19 volumio go-librespot[1255]: main.(*App).Zeroconf(...) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:127 Feb 16 11:59:19 volumio go-librespot[1255]: main.main() Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:458 +0x78c fp=0x2077fa8 sp=0x2077f1c pc=0x57a328 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.main() Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:271 +0x2fc fp=0x2077fec sp=0x2077fa8 pc=0x5c29c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2077fec sp=0x2077fec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 18 gp=0x2084248 m=nil [force gc (idle)]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f938, 0xaa84c0, 0x11, 0xa, 0x1) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x203e7d4 sp=0x203e7c0 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goparkunlock(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.forcegchelper() Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:326 +0xe4 fp=0x203e7ec sp=0x203e7d4 pc=0x5c668 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x203e7ec sp=0x203e7ec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by runtime.init.5 in goroutine 1 Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:314 +0x1c Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 19 gp=0x2084368 m=nil [GC sweep wait]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f938, 0xaa8a10, 0xc, 0x9, 0x1) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x203efc4 sp=0x203efb0 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goparkunlock(...) Feb 16 11:59:19 volumio sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.bgsweep(0x2092000) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcsweep.go:318 +0x11c fp=0x203efe4 sp=0x203efc4 pc=0x4506c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gcenable.gowrap1() Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:203 +0x28 fp=0x203efec sp=0x203efe4 pc=0x3518c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x203efec sp=0x203efec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by runtime.gcenable in goroutine 1 Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:203 +0x74 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 20 gp=0x2084488 m=nil [GC scavenge wait]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f938, 0xaa9020, 0xd, 0xa, 0x2) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x203f7b4 sp=0x203f7a0 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goparkunlock(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.(*scavengerState).park(0xaa9020) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcscavenge.go:425 +0x68 fp=0x203f7c8 sp=0x203f7b4 pc=0x4238c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.bgscavenge(0x2092000) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcscavenge.go:658 +0x60 fp=0x203f7e4 sp=0x203f7c8 pc=0x42ac4 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gcenable.gowrap2() Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:204 +0x28 fp=0x203f7ec sp=0x203f7e4 pc=0x35138 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x203f7ec sp=0x203f7ec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by runtime.gcenable in goroutine 1 Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:204 +0xbc Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 2 gp=0x2002c68 m=nil [finalizer wait]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f7e4, 0xad3bac, 0x10, 0xa, 0x1) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2042f8c sp=0x2042f78 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.runfinq() Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mfinal.go:194 +0x110 fp=0x2042fec sp=0x2042f8c pc=0x33f78 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2042fec sp=0x2042fec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by runtime.createfing in goroutine 1 Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mfinal.go:164 +0x5c Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 21 gp=0x2084c68 m=nil [IO wait]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f928, 0x74ebef18, 0x2, 0x2, 0x5) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x20425dc sp=0x20425c8 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.netpollblock(0x74ebef08, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x20425f4 sp=0x20425dc pc=0x540f0 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.runtime_pollWait(0x74ebef08, 0x72) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2042608 sp=0x20425f4 pc=0x8eba8 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).wait(0x209cd88, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x204261c sp=0x2042608 pc=0x108108 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).waitRead(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*FD).Accept(0x209cd70) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:611 +0x294 fp=0x2042664 sp=0x204261c pc=0x10c974 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*netFD).accept(0x209cd70) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_unix.go:172 +0x20 fp=0x20426cc sp=0x2042664 pc=0x2a2504 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*TCPListener).accept(0x20ac3a8) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock_posix.go:159 +0x20 fp=0x20426e4 sp=0x20426cc pc=0x2ba604 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*TCPListener).Accept(0x20ac3a8) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock.go:327 +0x30 fp=0x2042700 sp=0x20426e4 pc=0x2b9670 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*onceCloseListener).Accept(0x2116380) Feb 16 11:59:19 volumio go-librespot[1255]: :1 +0x34 fp=0x2042718 sp=0x2042700 pc=0x45e914 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*Server).Serve(0x21260a8, {0x72527c, 0x20ac3a8}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3255 +0x314 fp=0x20427b0 sp=0x2042718 pc=0x43cfd0 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.Serve(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2794 Feb 16 11:59:19 volumio go-librespot[1255]: main.(*ApiServer).serve(0x2099900) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:470 +0x5ec fp=0x20427e4 sp=0x20427b0 pc=0x57084c Feb 16 11:59:19 volumio go-librespot[1255]: main.NewApiServer.gowrap1() Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:240 +0x28 fp=0x20427ec sp=0x20427e4 pc=0x56ff20 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x20427ec sp=0x20427ec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by main.NewApiServer in goroutine 1 Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:240 +0x224 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 29 gp=0x2084d88 m=nil [IO wait]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f928, 0x74ebed38, 0x2, 0x2, 0x5) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x207be84 sp=0x207be70 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.netpollblock(0x74ebed28, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x207be9c sp=0x207be84 pc=0x540f0 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.runtime_pollWait(0x74ebed28, 0x72) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x207beb0 sp=0x207be9c pc=0x8eba8 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).wait(0x21361a8, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x207bec4 sp=0x207beb0 pc=0x108108 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).waitRead(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*FD).RawRead(0x2136190, 0x20d3cb0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:708 +0x164 fp=0x207bef0 sp=0x207bec4 pc=0x10d578 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*rawConn).Read(0x2094008, 0x20d3cb0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/rawconn.go:44 +0x38 fp=0x207bf10 sp=0x207bef0 pc=0x2b4a2c Feb 16 11:59:19 volumio go-librespot[1255]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2538130, 0x20d3c80, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/rawconn_msg.go:28 +0x154 fp=0x207bf40 sp=0x207bf10 pc=0x4c9804 Feb 16 11:59:19 volumio go-librespot[1255]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/socket.go:247 Feb 16 11:59:19 volumio go-librespot[1255]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x2012008, {0x24c8000, 0x10000, 0x10000}) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/ipv4/payload_cmsg.go:32 +0x44c fp=0x207bf9c sp=0x207bf40 pc=0x4cd7ec Feb 16 11:59:19 volumio go-librespot[1255]: github.com/grandcat/zeroconf.(*Server).recv4(0x21fe000, 0x2012000) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x207bfe0 sp=0x207bf9c pc=0x52407c Feb 16 11:59:19 volumio go-librespot[1255]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x207bfec sp=0x207bfe0 pc=0x523d34 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x207bfec sp=0x207bfec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 38 Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 30 gp=0x2084ea8 m=nil [IO wait]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f928, 0x74ebec98, 0x2, 0x2, 0x5) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2138e5c sp=0x2138e48 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.netpollblock(0x74ebec88, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2138e74 sp=0x2138e5c pc=0x540f0 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.runtime_pollWait(0x74ebec88, 0x72) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2138e88 sp=0x2138e74 pc=0x8eba8 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).wait(0x2026068, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2138e9c sp=0x2138e88 pc=0x108108 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).waitRead(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*FD).RawRead(0x2026050, 0x21012f0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:708 +0x164 fp=0x2138ec8 sp=0x2138e9c pc=0x10d578 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*rawConn).Read(0x20940b0, 0x21012f0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/rawconn.go:44 +0x38 fp=0x2138ee8 sp=0x2138ec8 pc=0x2b4a2c Feb 16 11:59:19 volumio go-librespot[1255]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2538140, 0x21012c0, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/rawconn_msg.go:28 +0x154 fp=0x2138f18 sp=0x2138ee8 pc=0x4c9804 Feb 16 11:59:19 volumio go-librespot[1255]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/socket.go:247 Feb 16 11:59:19 volumio go-librespot[1255]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x2012038, {0x2370000, 0x10000, 0x10000}) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/ipv6/payload_cmsg.go:32 +0x2ec fp=0x2138f9c sp=0x2138f18 pc=0x4d1174 Feb 16 11:59:19 volumio go-librespot[1255]: github.com/grandcat/zeroconf.(*Server).recv6(0x21fe000, 0x2012030) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x2138fe0 sp=0x2138f9c pc=0x524270 Feb 16 11:59:19 volumio go-librespot[1255]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x2138fec sp=0x2138fe0 pc=0x523cd8 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2138fec sp=0x2138fec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 38 Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 27 gp=0x2084fc8 m=nil [IO wait]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f928, 0x74ebee78, 0x2, 0x2, 0x5) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x207aad4 sp=0x207aac0 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.netpollblock(0x74ebee68, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x207aaec sp=0x207aad4 pc=0x540f0 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.runtime_pollWait(0x74ebee68, 0x72) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x207ab00 sp=0x207aaec pc=0x8eba8 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).wait(0x2136068, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x207ab14 sp=0x207ab00 pc=0x108108 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).waitRead(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*FD).Read(0x2136050, {0x2210000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x207ab5c sp=0x207ab14 pc=0x1092f4 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*netFD).Read(0x2136050, {0x2210000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x207ab88 sp=0x207ab5c pc=0x2a0610 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*conn).Read(0x2094610, {0x2210000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x207abb4 sp=0x207ab88 pc=0x2b07f0 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*TCPConn).Read(0x2094610, {0x2210000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: :1 +0x44 fp=0x207abd4 sp=0x207abb4 pc=0x2c3a44 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*atLeastReader).Read(0x2538120, {0x2210000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x207ac00 sp=0x207abd4 pc=0x386018 Feb 16 11:59:19 volumio go-librespot[1255]: bytes.(*Buffer).ReadFrom(0x20b114c, {0x723a98, 0x2538120}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x207ac3c sp=0x207ac00 pc=0x136d6c Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).readFromUntil(0x20b0fc8, {0x722604, 0x2094610}, 0x5) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x207ac64 sp=0x207ac3c pc=0x386274 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).readRecordOrCCS(0x20b0fc8, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x207adc8 sp=0x207ac64 pc=0x383974 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).readRecord(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).Read(0x20b0fc8, {0x2362000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x207adf8 sp=0x207adc8 pc=0x3897e4 Feb 16 11:59:19 volumio go-librespot[1255]: bufio.(*Reader).Read(0x21011a0, {0x20b4624, 0x9, 0x9}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:241 +0x214 fp=0x207ae1c sp=0x207adf8 pc=0x15ab44 Feb 16 11:59:19 volumio go-librespot[1255]: io.ReadAtLeast({0x7227f8, 0x21011a0}, {0x20b4624, 0x9, 0x9}, 0x9) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:335 +0x90 fp=0x207ae48 sp=0x207ae1c pc=0x1020b0 Feb 16 11:59:19 volumio go-librespot[1255]: io.ReadFull(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:354 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.http2readFrameHeader({0x20b4624, 0x9, 0x9}, {0x7227f8, 0x21011a0}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1638 +0x54 fp=0x207ae70 sp=0x207ae48 pc=0x3f30fc Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*http2Framer).ReadFrame(0x20b4600) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1902 +0x88 fp=0x207aeec sp=0x207ae70 pc=0x3f3894 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*http2clientConnReadLoop).run(0x207afdc) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9334 +0x150 fp=0x207afa4 sp=0x207aeec pc=0x4180b0 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*http2ClientConn).readLoop(0x20d7208) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9229 +0x68 fp=0x207afe4 sp=0x207afa4 pc=0x4175a4 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*http2Transport).newClientConn.gowrap1() Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0x28 fp=0x207afec sp=0x207afe4 pc=0x4105f0 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x207afec sp=0x207afec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by net/http.(*http2Transport).newClientConn in goroutine 26 Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0xcb0 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 3 gp=0x2002d88 m=nil [GC worker (idle)]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f7f4, 0x23142d0, 0x1a, 0xa, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2043790 sp=0x204377c pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gcBgMarkWorker() Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x20437ec sp=0x2043790 pc=0x37db4 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x20437ec sp=0x20437ec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 50 gp=0x2536008 m=nil [GC worker (idle)]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f7f4, 0x2140870, 0x1a, 0xa, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2138790 sp=0x213877c pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gcBgMarkWorker() Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x21387ec sp=0x2138790 pc=0x37db4 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x21387ec sp=0x21387ec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 36 gp=0x2108248 m=nil [GC worker (idle)]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f7f4, 0x2140888, 0x1a, 0xa, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x213c790 sp=0x213c77c pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gcBgMarkWorker() Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x213c7ec sp=0x213c790 pc=0x37db4 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x213c7ec sp=0x213c7ec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 37 gp=0x2108368 m=nil [GC worker (idle)]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f7f4, 0x21408a0, 0x1a, 0xa, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x213cf90 sp=0x213cf7c pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gcBgMarkWorker() Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x213cfec sp=0x213cf90 pc=0x37db4 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x213cfec sp=0x213cfec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 40 gp=0x2085208 m=nil [chan receive]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f7c8, 0x20921f0, 0xe, 0x7, 0x2) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2041748 sp=0x2041734 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.chanrecv(0x20921c0, 0x20417d0, 0x1) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:583 +0x4b0 fp=0x2041784 sp=0x2041748 pc=0x1fdc4 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.chanrecv2(0x20921c0, 0x20417d0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:447 +0x20 fp=0x2041798 sp=0x2041784 pc=0x1f908 Feb 16 11:59:19 volumio go-librespot[1255]: main.(*App).withAppPlayer.func1() Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:245 +0x54 fp=0x20417ec sp=0x2041798 pc=0x57937c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x20417ec sp=0x20417ec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by main.(*App).withAppPlayer in goroutine 1 Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:242 +0x3d4 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 41 gp=0x2085568 m=nil [chan receive]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f7c8, 0x2092170, 0xe, 0x7, 0x2) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x224af3c sp=0x224af28 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.chanrecv(0x2092140, 0x224afe0, 0x1) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:583 +0x4b0 fp=0x224af78 sp=0x224af3c pc=0x1fdc4 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.chanrecv2(0x2092140, 0x224afe0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:447 +0x20 fp=0x224af8c sp=0x224af78 pc=0x1f908 Feb 16 11:59:19 volumio go-librespot[1255]: main.(*App).withAppPlayer.func2() Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:261 +0x5c fp=0x224afec sp=0x224af8c pc=0x578f90 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x224afec sp=0x224afec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by main.(*App).withAppPlayer in goroutine 1 Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:258 +0x45c Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 42 gp=0x2085688 m=nil [IO wait]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f928, 0x74ebedd8, 0x2, 0x2, 0x5) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x213d5e8 sp=0x213d5d4 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.netpollblock(0x74ebedc8, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x213d600 sp=0x213d5e8 pc=0x540f0 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.runtime_pollWait(0x74ebedc8, 0x72) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x213d614 sp=0x213d600 pc=0x8eba8 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).wait(0x2136158, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x213d628 sp=0x213d614 pc=0x108108 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).waitRead(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*FD).Accept(0x2136140) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:611 +0x294 fp=0x213d670 sp=0x213d628 pc=0x10c974 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*netFD).accept(0x2136140) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_unix.go:172 +0x20 fp=0x213d6d8 sp=0x213d670 pc=0x2a2504 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*TCPListener).accept(0x24a80c0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock_posix.go:159 +0x20 fp=0x213d6f0 sp=0x213d6d8 pc=0x2ba604 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*TCPListener).Accept(0x24a80c0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock.go:327 +0x30 fp=0x213d70c sp=0x213d6f0 pc=0x2b9670 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*onceCloseListener).Accept(0x21b4060) Feb 16 11:59:19 volumio go-librespot[1255]: :1 +0x34 fp=0x213d724 sp=0x213d70c pc=0x45e914 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*Server).Serve(0x207e0a8, {0x72527c, 0x24a80c0}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3255 +0x314 fp=0x213d7bc sp=0x213d724 pc=0x43cfd0 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.Serve(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2794 Feb 16 11:59:19 volumio go-librespot[1255]: go-librespot/zeroconf.(*Zeroconf).Serve.func2() Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:265 +0x8c fp=0x213d7ec sp=0x213d7bc pc=0x528d7c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x213d7ec sp=0x213d7ec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:265 +0x160 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 48 gp=0x2206248 m=nil [IO wait]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f928, 0x74ebeb58, 0x2, 0x2, 0x5) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2076ad4 sp=0x2076ac0 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.netpollblock(0x74ebeb48, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2076aec sp=0x2076ad4 pc=0x540f0 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.runtime_pollWait(0x74ebeb48, 0x72) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2076b00 sp=0x2076aec pc=0x8eba8 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).wait(0x21541f8, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2076b14 sp=0x2076b00 pc=0x108108 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).waitRead(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*FD).Read(0x21541e0, {0x220c000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2076b5c sp=0x2076b14 pc=0x1092f4 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*netFD).Read(0x21541e0, {0x220c000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2076b88 sp=0x2076b5c pc=0x2a0610 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*conn).Read(0x202e2c0, {0x220c000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2076bb4 sp=0x2076b88 pc=0x2b07f0 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*TCPConn).Read(0x202e2c0, {0x220c000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: :1 +0x44 fp=0x2076bd4 sp=0x2076bb4 pc=0x2c3a44 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*atLeastReader).Read(0x223c4f0, {0x220c000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x2076c00 sp=0x2076bd4 pc=0x386018 Feb 16 11:59:19 volumio go-librespot[1255]: bytes.(*Buffer).ReadFrom(0x215684c, {0x723a98, 0x223c4f0}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x2076c3c sp=0x2076c00 pc=0x136d6c Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).readFromUntil(0x21566c8, {0x722604, 0x202e2c0}, 0x5) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x2076c64 sp=0x2076c3c pc=0x386274 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).readRecordOrCCS(0x21566c8, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x2076dc8 sp=0x2076c64 pc=0x383974 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).readRecord(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).Read(0x21566c8, {0x222a000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x2076df8 sp=0x2076dc8 pc=0x3897e4 Feb 16 11:59:19 volumio go-librespot[1255]: bufio.(*Reader).Read(0x2013c20, {0x207c3a4, 0x9, 0x9}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:241 +0x214 fp=0x2076e1c sp=0x2076df8 pc=0x15ab44 Feb 16 11:59:19 volumio go-librespot[1255]: io.ReadAtLeast({0x7227f8, 0x2013c20}, {0x207c3a4, 0x9, 0x9}, 0x9) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:335 +0x90 fp=0x2076e48 sp=0x2076e1c pc=0x1020b0 Feb 16 11:59:19 volumio go-librespot[1255]: io.ReadFull(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:354 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.http2readFrameHeader({0x207c3a4, 0x9, 0x9}, {0x7227f8, 0x2013c20}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1638 +0x54 fp=0x2076e70 sp=0x2076e48 pc=0x3f30fc Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*http2Framer).ReadFrame(0x207c380) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1902 +0x88 fp=0x2076eec sp=0x2076e70 pc=0x3f3894 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*http2clientConnReadLoop).run(0x2076fdc) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9334 +0x150 fp=0x2076fa4 sp=0x2076eec pc=0x4180b0 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*http2ClientConn).readLoop(0x2226008) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9229 +0x68 fp=0x2076fe4 sp=0x2076fa4 pc=0x4175a4 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*http2Transport).newClientConn.gowrap1() Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0x28 fp=0x2076fec sp=0x2076fe4 pc=0x4105f0 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2076fec sp=0x2076fec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by net/http.(*http2Transport).newClientConn in goroutine 47 Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0xcb0 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 141 gp=0x2536368 m=nil [select]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2059eb0 sp=0x2059e9c pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.selectgo(0x2059fac, 0x2059f78, 0x0, 0x0, 0x2, 0x1) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2059f50 sp=0x2059eb0 pc=0x705e4 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*persistConn).writeLoop(0x20ea4d8) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2444 +0xd0 fp=0x2059fe4 sp=0x2059f50 pc=0x4539dc Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*Transport).dialConn.gowrap3() Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1800 +0x28 fp=0x2059fec sp=0x2059fe4 pc=0x4507e8 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2059fec sp=0x2059fec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by net/http.(*Transport).dialConn in goroutine 95 Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1800 +0x1444 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 12 gp=0x2206fc8 m=nil [IO wait]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f928, 0x74ebea18, 0x2, 0x2, 0x5) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x224fad4 sp=0x224fac0 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.netpollblock(0x74ebea08, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x224faec sp=0x224fad4 pc=0x540f0 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.runtime_pollWait(0x74ebea08, 0x72) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x224fb00 sp=0x224faec pc=0x8eba8 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).wait(0x2266658, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x224fb14 sp=0x224fb00 pc=0x108108 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).waitRead(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*FD).Read(0x2266640, {0x2384000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x224fb5c sp=0x224fb14 pc=0x1092f4 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*netFD).Read(0x2266640, {0x2384000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x224fb88 sp=0x224fb5c pc=0x2a0610 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*conn).Read(0x2541508, {0x2384000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x224fbb4 sp=0x224fb88 pc=0x2b07f0 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*TCPConn).Read(0x2541508, {0x2384000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: :1 +0x44 fp=0x224fbd4 sp=0x224fbb4 pc=0x2c3a44 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*atLeastReader).Read(0x2272120, {0x2384000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x224fc00 sp=0x224fbd4 pc=0x386018 Feb 16 11:59:19 volumio go-librespot[1255]: bytes.(*Buffer).ReadFrom(0x232418c, {0x723a98, 0x2272120}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x224fc3c sp=0x224fc00 pc=0x136d6c Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).readFromUntil(0x2324008, {0x722604, 0x2541508}, 0x5) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x224fc64 sp=0x224fc3c pc=0x386274 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).readRecordOrCCS(0x2324008, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x224fdc8 sp=0x224fc64 pc=0x383974 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).readRecord(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).Read(0x2324008, {0x2388000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x224fdf8 sp=0x224fdc8 pc=0x3897e4 Feb 16 11:59:19 volumio go-librespot[1255]: bufio.(*Reader).Read(0x231f650, {0x2122424, 0x9, 0x9}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:241 +0x214 fp=0x224fe1c sp=0x224fdf8 pc=0x15ab44 Feb 16 11:59:19 volumio go-librespot[1255]: io.ReadAtLeast({0x7227f8, 0x231f650}, {0x2122424, 0x9, 0x9}, 0x9) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:335 +0x90 fp=0x224fe48 sp=0x224fe1c pc=0x1020b0 Feb 16 11:59:19 volumio go-librespot[1255]: io.ReadFull(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:354 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.http2readFrameHeader({0x2122424, 0x9, 0x9}, {0x7227f8, 0x231f650}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1638 +0x54 fp=0x224fe70 sp=0x224fe48 pc=0x3f30fc Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*http2Framer).ReadFrame(0x2122400) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1902 +0x88 fp=0x224feec sp=0x224fe70 pc=0x3f3894 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*http2clientConnReadLoop).run(0x224ffdc) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9334 +0x150 fp=0x224ffa4 sp=0x224feec pc=0x4180b0 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*http2ClientConn).readLoop(0x24ae108) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9229 +0x68 fp=0x224ffe4 sp=0x224ffa4 pc=0x4175a4 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*http2Transport).newClientConn.gowrap1() Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0x28 fp=0x224ffec sp=0x224ffe4 pc=0x4105f0 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x224ffec sp=0x224ffec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by net/http.(*http2Transport).newClientConn in goroutine 11 Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0xcb0 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 105 gp=0x2536908 m=nil [select]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x21396a8 sp=0x2139694 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.selectgo(0x21397a4, 0x213976c, 0x0, 0x0, 0x5, 0x1) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2139748 sp=0x21396a8 pc=0x705e4 Feb 16 11:59:19 volumio go-librespot[1255]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x2202c88) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x21397e4 sp=0x2139748 pc=0x47408c Feb 16 11:59:19 volumio go-librespot[1255]: nhooyr.io/websocket.newConn.gowrap1() Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x21397ec sp=0x21397e4 pc=0x473c80 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x21397ec sp=0x21397ec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by nhooyr.io/websocket.newConn in goroutine 65 Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 150 gp=0x2536a28 m=nil [IO wait]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f928, 0x74ebe6f8, 0x2, 0x2, 0x5) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x224ead0 sp=0x224eabc pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.netpollblock(0x74ebe6e8, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x224eae8 sp=0x224ead0 pc=0x540f0 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.runtime_pollWait(0x74ebe6e8, 0x72) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x224eafc sp=0x224eae8 pc=0x8eba8 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).wait(0x2338d88, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x224eb10 sp=0x224eafc pc=0x108108 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).waitRead(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*FD).Read(0x2338d70, {0x23a2b3c, 0x94c4, 0x94c4}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x224eb58 sp=0x224eb10 pc=0x1092f4 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*netFD).Read(0x2338d70, {0x23a2b3c, 0x94c4, 0x94c4}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x224eb84 sp=0x224eb58 pc=0x2a0610 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*conn).Read(0x2460e38, {0x23a2b3c, 0x94c4, 0x94c4}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x224ebb0 sp=0x224eb84 pc=0x2b07f0 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*TCPConn).Read(0x2460e38, {0x23a2b3c, 0x94c4, 0x94c4}) Feb 16 11:59:19 volumio go-librespot[1255]: :1 +0x44 fp=0x224ebd0 sp=0x224ebb0 pc=0x2c3a44 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*atLeastReader).Read(0x200e830, {0x23a2b3c, 0x94c4, 0x94c4}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x224ebfc sp=0x224ebd0 pc=0x386018 Feb 16 11:59:19 volumio go-librespot[1255]: bytes.(*Buffer).ReadFrom(0x20b0a8c, {0x723a98, 0x200e830}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x224ec38 sp=0x224ebfc pc=0x136d6c Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).readFromUntil(0x20b0908, {0x722604, 0x2460e38}, 0x4016) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x224ec60 sp=0x224ec38 pc=0x386274 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).readRecordOrCCS(0x20b0908, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:677 +0xa10 fp=0x224edc4 sp=0x224ec60 pc=0x384250 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).readRecord(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).Read(0x20b0908, {0x2806e60, 0x71a0, 0x71a0}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x224edf4 sp=0x224edc4 pc=0x3897e4 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*persistConn).Read(0x2208008, {0x2806e60, 0x71a0, 0x71a0}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1977 +0x16c fp=0x224ee28 sp=0x224edf4 pc=0x45138c Feb 16 11:59:19 volumio go-librespot[1255]: bufio.(*Reader).Read(0x20128a0, {0x2806e60, 0x71a0, 0x71a0}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:227 +0x12c fp=0x224ee4c sp=0x224ee28 pc=0x15aa5c Feb 16 11:59:19 volumio go-librespot[1255]: io.(*LimitedReader).Read(0x2090000, {0x2806e60, 0x71a0, 0x71a0}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:479 +0xc4 fp=0x224ee6c sp=0x224ee4c pc=0x102878 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*body).readLocked(0x20d2d80, {0x2806e60, 0x71a0, 0x71a0}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transfer.go:840 +0x4c fp=0x224ee98 sp=0x224ee6c pc=0x446748 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*body).Read(0x20d2d80, {0x2806e60, 0x71a0, 0x71a0}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transfer.go:832 +0xe8 fp=0x224eecc sp=0x224ee98 pc=0x446654 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*bodyEOFSignal).Read(0x20d2ff0, {0x2806e60, 0x71a0, 0x71a0}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2833 +0xc0 fp=0x224ef08 sp=0x224eecc pc=0x455570 Feb 16 11:59:19 volumio go-librespot[1255]: io.ReadAll({0x72276c, 0x20d2ff0}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:712 +0x84 fp=0x224ef44 sp=0x224ef08 pc=0x1030e8 Feb 16 11:59:19 volumio go-librespot[1255]: go-librespot/audio.(*HttpChunkedReader).fetchChunk(0x229cd80, 0x1) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/audio/chunked_reader.go:169 +0x140 fp=0x224efc4 sp=0x224ef44 pc=0x492724 Feb 16 11:59:19 volumio go-librespot[1255]: go-librespot/audio.(*HttpChunkedReader).prefetchChunks.func1(0x1) Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/audio/chunked_reader.go:198 +0x24 fp=0x224efe4 sp=0x224efc4 pc=0x492db4 Feb 16 11:59:19 volumio go-librespot[1255]: go-librespot/audio.(*HttpChunkedReader).prefetchChunks.gowrap1() Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/audio/chunked_reader.go:198 +0x30 fp=0x224efec sp=0x224efe4 pc=0x492d64 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x224efec sp=0x224efec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by go-librespot/audio.(*HttpChunkedReader).prefetchChunks in goroutine 78 Feb 16 11:59:19 volumio go-librespot[1255]: /home/runner/work/go-librespot/go-librespot/audio/chunked_reader.go:198 +0x34 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 99 gp=0x2002ea8 m=nil [IO wait]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f928, 0x74ebebf8, 0x2, 0x2, 0x5) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2055ad4 sp=0x2055ac0 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.netpollblock(0x74ebebe8, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2055aec sp=0x2055ad4 pc=0x540f0 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.runtime_pollWait(0x74ebebe8, 0x72) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2055b00 sp=0x2055aec pc=0x8eba8 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).wait(0x209c1f8, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2055b14 sp=0x2055b00 pc=0x108108 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).waitRead(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*FD).Read(0x209c1e0, {0x2363000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2055b5c sp=0x2055b14 pc=0x1092f4 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*netFD).Read(0x209c1e0, {0x2363000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2055b88 sp=0x2055b5c pc=0x2a0610 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*conn).Read(0x2460578, {0x2363000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2055bb4 sp=0x2055b88 pc=0x2b07f0 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*TCPConn).Read(0x2460578, {0x2363000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: :1 +0x44 fp=0x2055bd4 sp=0x2055bb4 pc=0x2c3a44 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*atLeastReader).Read(0x200e860, {0x2363000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x2055c00 sp=0x2055bd4 pc=0x386018 Feb 16 11:59:19 volumio go-librespot[1255]: bytes.(*Buffer).ReadFrom(0x232460c, {0x723a98, 0x200e860}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x2055c3c sp=0x2055c00 pc=0x136d6c Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).readFromUntil(0x2324488, {0x722604, 0x2460578}, 0x5) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x2055c64 sp=0x2055c3c pc=0x386274 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).readRecordOrCCS(0x2324488, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x2055dc8 sp=0x2055c64 pc=0x383974 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).readRecord(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Feb 16 11:59:19 volumio go-librespot[1255]: crypto/tls.(*Conn).Read(0x2324488, {0x222b000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x2055df8 sp=0x2055dc8 pc=0x3897e4 Feb 16 11:59:19 volumio go-librespot[1255]: bufio.(*Reader).Read(0x231f410, {0x20b46a4, 0x9, 0x9}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:241 +0x214 fp=0x2055e1c sp=0x2055df8 pc=0x15ab44 Feb 16 11:59:19 volumio go-librespot[1255]: io.ReadAtLeast({0x7227f8, 0x231f410}, {0x20b46a4, 0x9, 0x9}, 0x9) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:335 +0x90 fp=0x2055e48 sp=0x2055e1c pc=0x1020b0 Feb 16 11:59:19 volumio go-librespot[1255]: io.ReadFull(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:354 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.http2readFrameHeader({0x20b46a4, 0x9, 0x9}, {0x7227f8, 0x231f410}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1638 +0x54 fp=0x2055e70 sp=0x2055e48 pc=0x3f30fc Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*http2Framer).ReadFrame(0x20b4680) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1902 +0x88 fp=0x2055eec sp=0x2055e70 pc=0x3f3894 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*http2clientConnReadLoop).run(0x2055fdc) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9334 +0x150 fp=0x2055fa4 sp=0x2055eec pc=0x4180b0 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*http2ClientConn).readLoop(0x20d7308) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9229 +0x68 fp=0x2055fe4 sp=0x2055fa4 pc=0x4175a4 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*http2Transport).newClientConn.gowrap1() Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0x28 fp=0x2055fec sp=0x2055fe4 pc=0x4105f0 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2055fec sp=0x2055fec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by net/http.(*http2Transport).newClientConn in goroutine 98 Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0xcb0 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 133 gp=0x21087e8 m=nil [select]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x224beb0 sp=0x224be9c pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.selectgo(0x224bfac, 0x224bf78, 0x0, 0x0, 0x2, 0x1) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x224bf50 sp=0x224beb0 pc=0x705e4 Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*persistConn).writeLoop(0x23946e8) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2444 +0xd0 fp=0x224bfe4 sp=0x224bf50 pc=0x4539dc Feb 16 11:59:19 volumio go-librespot[1255]: net/http.(*Transport).dialConn.gowrap3() Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1800 +0x28 fp=0x224bfec sp=0x224bfe4 pc=0x4507e8 Feb 16 11:59:19 volumio go-librespot[1255]: runtime.goexit({}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x224bfec sp=0x224bfec pc=0x9346c Feb 16 11:59:19 volumio go-librespot[1255]: created by net/http.(*Transport).dialConn in goroutine 124 Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1800 +0x1444 Feb 16 11:59:19 volumio go-librespot[1255]: goroutine 65 gp=0x21098c8 m=nil [IO wait]: Feb 16 11:59:19 volumio go-librespot[1255]: runtime.gopark(0x68f928, 0x74ebe8d8, 0x2, 0x2, 0x5) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x20578a4 sp=0x2057890 pc=0x5c82c Feb 16 11:59:19 volumio go-librespot[1255]: runtime.netpollblock(0x74ebe8c8, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x20578bc sp=0x20578a4 pc=0x540f0 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.runtime_pollWait(0x74ebe8c8, 0x72) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x20578d0 sp=0x20578bc pc=0x8eba8 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).wait(0x22676e8, 0x72, 0x0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20578e4 sp=0x20578d0 pc=0x108108 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*pollDesc).waitRead(...) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Feb 16 11:59:19 volumio go-librespot[1255]: internal/poll.(*FD).Read(0x22676d0, {0x214d000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x205792c sp=0x20578e4 pc=0x1092f4 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*netFD).Read(0x22676d0, {0x214d000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2057958 sp=0x205792c pc=0x2a0610 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*conn).Read(0x2482ea8, {0x214d000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2057984 sp=0x2057958 pc=0x2b07f0 Feb 16 11:59:19 volumio go-librespot[1255]: net.(*TCPConn).Read(0x2482ea8, {0x214d000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: :1 +0x44 fp=0x20579a4 sp=0x2057984 pc=0x2c3a44 Feb 16 11:59:19 volumio go-librespot[1255]: io.(*multiReader).Read(0x2272600, {0x214d000, 0x1000, 0x1000}) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/multi.go:26 +0xb4 fp=0x20579d0 sp=0x20579a4 pc=0x103318 Feb 16 11:59:19 volumio go-librespot[1255]: bufio.(*Reader).fill(0x229d9e0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:110 +0x10c fp=0x20579f4 sp=0x20579d0 pc=0x15a4b8 Feb 16 11:59:19 volumio go-librespot[1255]: bufio.(*Reader).ReadByte(0x229d9e0) Feb 16 11:59:19 volumio go-librespot[1255]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:269 +0x28 fp=0x2057a00 sp=0x20579f4 pc=0x15ad3c Feb 16 11:59:19 volumio go-librespot[1255]: nhooyr.io/websocket.readFrameHeader(0x229d9e0, {0x2202cb8, 0x8, 0x8}) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"