Mar 25 02:01:23 lenja-box ntpd[1071]: CLOCK: time stepped by 30.992269 Mar 25 02:01:23 lenja-box ntpd[1071]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Mar 25 02:01:24 lenja-box dhcpcd[775]: timed out Mar 25 02:01:24 lenja-box sh[762]: timed out Mar 25 02:01:24 lenja-box sh[679]: ifup: failed to bring up eth0 Mar 25 02:01:24 lenja-box dhcpcd[775]: dhcpcd exited Mar 25 02:01:24 lenja-box systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 25 02:01:24 lenja-box systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 25 02:01:25 lenja-box volumio[1245]: info: ------------------------------------------- Mar 25 02:01:25 lenja-box volumio[1245]: info: ----- Volumio3 ---- Mar 25 02:01:25 lenja-box volumio[1245]: info: ------------------------------------------- Mar 25 02:01:25 lenja-box volumio[1245]: info: ----- System startup ---- Mar 25 02:01:25 lenja-box volumio[1245]: info: ------------------------------------------- Mar 25 02:01:26 lenja-box volumio[1245]: info: MYVOLUMIO Environment detected Mar 25 02:01:27 lenja-box volumio[1245]: info: Plugin folders cleanup Mar 25 02:01:27 lenja-box volumio[1245]: info: Scanning into folder /volumio/app/plugins/ Mar 25 02:01:27 lenja-box volumio[1245]: info: Scanning category audio_interface Mar 25 02:01:27 lenja-box volumio[1245]: info: Scanning category miscellanea Mar 25 02:01:27 lenja-box volumio[1245]: info: Scanning category music_service Mar 25 02:01:27 lenja-box volumio[1245]: info: Scanning category plugins.json Mar 25 02:01:27 lenja-box volumio[1245]: info: Scanning category system_controller Mar 25 02:01:27 lenja-box volumio[1245]: info: Scanning category user_interface Mar 25 02:01:27 lenja-box volumio[1245]: info: Scanning into folder /data/plugins/ Mar 25 02:01:27 lenja-box volumio[1245]: info: Scanning category music_service Mar 25 02:01:27 lenja-box volumio[1245]: info: Scanning category system_controller Mar 25 02:01:27 lenja-box volumio[1245]: info: Scanning category user_interface Mar 25 02:01:27 lenja-box volumio[1245]: info: Plugin folders cleanup completed Mar 25 02:01:27 lenja-box volumio[1245]: info: ------------------------------------------- Mar 25 02:01:27 lenja-box volumio[1245]: info: ----- Core plugins startup ---- Mar 25 02:01:27 lenja-box volumio[1245]: info: ------------------------------------------- Mar 25 02:01:27 lenja-box volumio[1245]: info: Loading plugins from folder /volumio/app/plugins/ Mar 25 02:01:27 lenja-box volumio[1245]: info: Adding plugin upnp to MyMusic Plugins Mar 25 02:01:27 lenja-box volumio[1245]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 25 02:01:27 lenja-box volumio[1245]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 25 02:01:27 lenja-box volumio[1245]: info: Loading plugins from folder /data/plugins/ Mar 25 02:01:27 lenja-box volumio[1245]: info: Loading plugin "system"... Mar 25 02:01:27 lenja-box volumio[1245]: info: Loading plugin "appearance"... Mar 25 02:01:29 lenja-box volumio-remote-updater[847]: [2026-03-25 02:01:29] [connect] Successful connection Mar 25 02:01:30 lenja-box volumio[1245]: info: Loading plugin "network"... Mar 25 02:01:30 lenja-box volumio[1245]: info: Refreshing Cached IP Addresses Mar 25 02:01:30 lenja-box sudo[1422]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 25 02:01:30 lenja-box sudo[1422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:01:30 lenja-box sudo[1422]: pam_unix(sudo:session): session closed for user root Mar 25 02:01:30 lenja-box sudo[1424]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 02:01:30 lenja-box sudo[1424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:01:30 lenja-box sudo[1424]: pam_unix(sudo:session): session closed for user root Mar 25 02:01:30 lenja-box volumio[1245]: info: Loading plugin "services"... Mar 25 02:01:30 lenja-box sudo[1433]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 25 02:01:30 lenja-box sudo[1433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:01:30 lenja-box volumio[1245]: info: Loading plugin "volumio5onboarding"... Mar 25 02:01:30 lenja-box volumio[1245]: info: Loading plugin "alsa_controller"... Mar 25 02:01:30 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 02:01:30 lenja-box volumio[1245]: info: Loading plugin "wizard"... Mar 25 02:01:30 lenja-box volumio[1245]: info: Loading plugin "networkfs"... Mar 25 02:01:31 lenja-box volumio[1245]: info: Starting Udev Watcher for removable devices Mar 25 02:01:31 lenja-box volumio[1245]: info: Ignoring mount for partition: boot Mar 25 02:01:31 lenja-box volumio[1245]: info: Ignoring mount for partition: volumio Mar 25 02:01:31 lenja-box volumio[1245]: info: Ignoring mount for partition: volumio_data Mar 25 02:01:31 lenja-box sudo[1456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=media,password=4N50K0QBDa9f9A9ME41rqks,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.178.1/home.nas\\ssd_2TB\\Musik /mnt/NAS/Home_NAS Mar 25 02:01:31 lenja-box sudo[1456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:01:31 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 25 02:01:31 lenja-box volumio[1245]: info: Loading plugin "volumio_command_line_client"... Mar 25 02:01:31 lenja-box volumio[1245]: info: Loading plugin "upnp"... Mar 25 02:01:31 lenja-box volumio[1245]: info: [1774400491210] Starting Upmpd Daemon Mar 25 02:01:31 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 25 02:01:31 lenja-box volumio[1245]: info: Loading plugin "my_music"... Mar 25 02:01:31 lenja-box volumio[1245]: info: Loading plugin "mpd"... Mar 25 02:01:31 lenja-box kernel: netfs: FS-Cache loaded Mar 25 02:01:31 lenja-box systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 25 02:01:31 lenja-box systemd[1]: systemd-hostnamed.service: Deactivated successfully. Mar 25 02:01:31 lenja-box sudo[1433]: pam_unix(sudo:session): session closed for user root Mar 25 02:01:31 lenja-box kernel: Key type cifs.spnego registered Mar 25 02:01:31 lenja-box kernel: Key type cifs.idmap registered Mar 25 02:01:31 lenja-box kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Mar 25 02:01:31 lenja-box kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Mar 25 02:01:31 lenja-box kernel: CIFS: Attempting to mount //192.168.178.1/home.nas/ssd_2TB/Musik Mar 25 02:01:31 lenja-box sudo[1456]: pam_unix(sudo:session): session closed for user root Mar 25 02:01:31 lenja-box systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Mar 25 02:01:31 lenja-box systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 25 02:01:31 lenja-box systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 25 02:01:32 lenja-box upmpdcli[1495]: Could not open config: /tmp/upmpdcli.conf Mar 25 02:01:32 lenja-box systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 25 02:01:32 lenja-box systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 25 02:01:33 lenja-box volumio[1245]: info: Loading plugin "upnp_browser"... Mar 25 02:01:35 lenja-box volumio[1245]: info: Starting UPNP Browser Mar 25 02:01:35 lenja-box volumio[1245]: info: Loading plugin "alarm-clock"... Mar 25 02:01:35 lenja-box volumio[1245]: info: Alarm: Scheduling Playlist Mozart Radio at 5:0 Mar 25 02:01:35 lenja-box volumio[1245]: info: Loading plugin "airplay_emulation"... Mar 25 02:01:35 lenja-box volumio[1245]: info: Starting Shairport Sync Mar 25 02:01:35 lenja-box volumio[1245]: info: Loading plugin "last_100"... Mar 25 02:01:35 lenja-box volumio[1245]: info: Loading plugin "webradio"... Mar 25 02:01:36 lenja-box volumio[1245]: info: Loading plugin "i2s_dacs"... Mar 25 02:01:36 lenja-box volumio[1245]: info: I2S DAC not set, start Auto-detection Mar 25 02:01:36 lenja-box volumio[1245]: info: Loading plugin "volumiodiscovery"... Mar 25 02:01:36 lenja-box volumio[1245]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 25 02:01:36 lenja-box node[1245]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 25 02:01:36 lenja-box volumio[1245]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 25 02:01:36 lenja-box volumio[1245]: *** WARNING *** For more information see Mar 25 02:01:36 lenja-box volumio[1245]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 25 02:01:36 lenja-box volumio[1245]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 25 02:01:36 lenja-box volumio[1245]: *** WARNING *** For more information see Mar 25 02:01:36 lenja-box node[1245]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 25 02:01:36 lenja-box node[1245]: *** WARNING *** For more information see Mar 25 02:01:36 lenja-box node[1245]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 25 02:01:36 lenja-box node[1245]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 25 02:01:36 lenja-box node[1245]: *** WARNING *** For more information see Mar 25 02:01:36 lenja-box volumio[1245]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 25 02:01:36 lenja-box volumio[1245]: info: Discovery: Started advertising with name: Lenja-Box Mar 25 02:01:36 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 25 02:01:36 lenja-box volumio[1245]: info: Loading plugin "spop"... Mar 25 02:01:39 lenja-box systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 25 02:01:39 lenja-box systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 25 02:01:39 lenja-box systemd[1]: setdatetime-helper.service: Consumed 1.502s CPU time. Mar 25 02:01:40 lenja-box volumio[1245]: info: Loading plugin "outputs"... Mar 25 02:01:40 lenja-box volumio[1245]: info: Loading plugin "albumart"... Mar 25 02:01:40 lenja-box volumio[1245]: info: Plugin example_plugin is not enabled Mar 25 02:01:40 lenja-box volumio[1245]: info: Loading plugin "inputs"... Mar 25 02:01:40 lenja-box volumio[1245]: info: Loading plugin "updater_comm"... Mar 25 02:01:40 lenja-box volumio[1245]: info: Plugin mpdemulation is not enabled Mar 25 02:01:40 lenja-box volumio[1245]: info: Loading plugin "rest_api"... Mar 25 02:01:40 lenja-box volumio[1245]: info: Loading plugin "websocket"... Mar 25 02:01:40 lenja-box volumio[1245]: info: Starting Socket.io Server version 1.7.4 Mar 25 02:01:40 lenja-box volumio[1245]: info: Loading plugin "scheduledrestart"... Mar 25 02:01:41 lenja-box volumio[1245]: info: Applying required configuration parameters for plugin scheduledrestart Mar 25 02:01:41 lenja-box volumio[1245]: info: Loading plugin "touch_display"... Mar 25 02:01:42 lenja-box volumio[1509]: Forking 3 albumart workers Mar 25 02:01:42 lenja-box volumio[1245]: info: Applying required configuration parameters for plugin touch_display Mar 25 02:01:43 lenja-box volumio[1245]: info: Loading i18n strings for locale de Mar 25 02:01:43 lenja-box volumio[1245]: Updating browse sources language Mar 25 02:01:43 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 02:01:44 lenja-box volumio-remote-updater[847]: [2026-03-25 02:01:44] [connect] Successful connection Mar 25 02:01:45 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 02:01:45 lenja-box volumio[1245]: info: CoreCommandRouter::initPlayerControls Mar 25 02:01:45 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:01:45 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:01:45 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:01:45 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:01:45 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:01:45 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:01:45 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:01:45 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:01:45 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 02:01:45 lenja-box volumio[1245]: Express server listening on port 3000 Mar 25 02:01:45 lenja-box volumio[1245]: [Metrics] WebUI: 21s 745.62ms Mar 25 02:01:45 lenja-box volumio[1245]: info: CoreStateMachine::resetVolumioState Mar 25 02:01:45 lenja-box volumio[1245]: info: CoreStateMachine::getcurrentVolume Mar 25 02:01:45 lenja-box volumio[1245]: info: CoreCommandRouter::volumioRetrievevolume Mar 25 02:01:45 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 02:01:46 lenja-box sudo[1568]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 25 02:01:46 lenja-box sudo[1568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:01:46 lenja-box sudo[1568]: pam_unix(sudo:session): session closed for user root Mar 25 02:01:46 lenja-box sudo[1570]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 02:01:46 lenja-box sudo[1570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:01:46 lenja-box sudo[1570]: pam_unix(sudo:session): session closed for user root Mar 25 02:01:46 lenja-box volumio[1245]: info: Volumio Network Manager: Network status updated: 2 Mar 25 02:01:47 lenja-box systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Mar 25 02:01:47 lenja-box systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 25 02:01:47 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.61 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 25 02:01:47 lenja-box systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 25 02:01:47 lenja-box volumio[1245]: info: VolumeController:: Volume=83 Mute =false Mar 25 02:01:47 lenja-box upmpdcli[1575]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.178.32 port 49152 IPV6 port 49152 Mar 25 02:01:47 lenja-box upmpdcli[1575]: :3:../src/mediaserver/cdplugins/plgwithslave.cxx:248::PlgWithSlave: starting streaming proxy on port 49149 Mar 25 02:01:47 lenja-box upmpdcli[1575]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Mar 25 02:01:47 lenja-box upmpdcli[1575]: :2:../src/main.cxx:732::MPD connection failed Mar 25 02:01:47 lenja-box volumio[1245]: info: CoreStateMachine::pushState Mar 25 02:01:47 lenja-box volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 02:01:47 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 02:01:47 lenja-box volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 02:01:47 lenja-box volumio[1245]: info: CoreStateMachine::updateTrackBlock Mar 25 02:01:47 lenja-box volumio[1245]: info: CorePlayQueue::getTrackBlock Mar 25 02:01:47 lenja-box volumio[1245]: info: CoreCommandRouter::volumioRetrievevolume Mar 25 02:01:47 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.58 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Mar 25 02:01:48 lenja-box volumio[1245]: info: Reloading queue from file Mar 25 02:01:48 lenja-box volumio[1245]: info: CoreStateMachine::setRepeat null single undefined Mar 25 02:01:48 lenja-box volumio[1245]: info: CoreStateMachine::pushState Mar 25 02:01:48 lenja-box volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 02:01:48 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 02:01:48 lenja-box volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 02:01:48 lenja-box volumio[1245]: info: CoreStateMachine::setRandom false Mar 25 02:01:48 lenja-box volumio[1245]: info: CoreStateMachine::pushState Mar 25 02:01:48 lenja-box volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 02:01:48 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 02:01:48 lenja-box volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 02:01:49 lenja-box volumio[1245]: info: Setting Device type: Raspberry PI Mar 25 02:01:49 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.61 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 25 02:01:49 lenja-box volumio[1245]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 25 02:01:49 lenja-box volumio[1245]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Mar 25 02:01:49 lenja-box volumio[1245]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 25 02:01:49 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.58 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 25 02:01:49 lenja-box upmpdcli[1575]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Mar 25 02:01:49 lenja-box upmpdcli[1575]: :2:../src/main.cxx:732::MPD connection failed Mar 25 02:01:49 lenja-box volumio[1245]: info: VolumeController:: Volume=83 Mute =false Mar 25 02:01:49 lenja-box volumio[1245]: info: CoreStateMachine::pushState Mar 25 02:01:49 lenja-box volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 02:01:49 lenja-box volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 02:01:49 lenja-box sudo[1598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 25 02:01:49 lenja-box sudo[1598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:01:49 lenja-box volumio[1245]: info: Discovery: adding 7a929136-793d-4356-9d10-fd9fb9818b09 Mar 25 02:01:49 lenja-box volumio[1245]: info: Discovery: Found device Lenja-Box Mar 25 02:01:49 lenja-box volumio[1245]: info: CoreCommandRouter::volumioGetState Mar 25 02:01:49 lenja-box volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 02:01:49 lenja-box sudo[1598]: pam_unix(sudo:session): session closed for user root Mar 25 02:01:49 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.61 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 25 02:01:50 lenja-box volumio[1245]: info: Discovery: this is already registered, 7a929136-793d-4356-9d10-fd9fb9818b09 Mar 25 02:01:50 lenja-box volumio[1245]: info: Discovery: Found device Lenja-Box Mar 25 02:01:50 lenja-box volumio[1245]: info: CoreCommandRouter::volumioGetState Mar 25 02:01:50 lenja-box volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 02:01:50 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.61 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 25 02:01:50 lenja-box volumio[1245]: info: Upmpdcli Daemon Started Mar 25 02:01:50 lenja-box volumio[1245]: info: Discovery: adding f261a9fe-2a85-49d5-a2d8-932cdb58467e Mar 25 02:01:50 lenja-box volumio[1245]: info: Discovery: Found device liam-box Mar 25 02:01:50 lenja-box volumio[1245]: info: Discovery: Connecting to remote: 192.168.178.61 Mar 25 02:01:50 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.61 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 25 02:01:50 lenja-box volumio[1245]: info: Discovery: adding 13175b4d-dad8-47b7-a698-c8429869b314 Mar 25 02:01:50 lenja-box volumio[1245]: info: Discovery: Found device Stuben-Box Mar 25 02:01:50 lenja-box volumio[1245]: info: Discovery: Connecting to remote: 192.168.178.58 Mar 25 02:01:50 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.61 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 25 02:01:50 lenja-box volumio[1245]: info: Completed loading Core Plugins Mar 25 02:01:50 lenja-box volumio[1245]: info: Preparing to generate the ALSA configuration file Mar 25 02:01:51 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.61 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 25 02:01:51 lenja-box volumio[1534]: Starting albumart workers Mar 25 02:01:51 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.58 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 25 02:01:51 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.61 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 25 02:01:51 lenja-box volumio[1245]: info: Discovery: Connected to remote: 192.168.178.61 Mar 25 02:01:51 lenja-box volumio[1533]: Starting albumart workers Mar 25 02:01:52 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.58 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Mar 25 02:01:52 lenja-box volumio[1245]: info: Asound.conf file unchanged, so no further update is needed Mar 25 02:01:52 lenja-box volumio[1245]: info: Output device has changed, restarting MPD Mar 25 02:01:52 lenja-box volumio[1245]: info: Output device has changed, restarting Shairport Sync Mar 25 02:01:52 lenja-box volumio[1535]: Starting albumart workers Mar 25 02:01:52 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:01:52 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 02:01:52 lenja-box sudo[1618]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 02:01:52 lenja-box sudo[1618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:01:52 lenja-box sudo[1616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 02:01:52 lenja-box sudo[1616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:01:52 lenja-box sudo[1616]: pam_unix(sudo:session): session closed for user root Mar 25 02:01:52 lenja-box volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 02:01:52 lenja-box volumio[1245]: info: ___________ START PLUGINS ___________ Mar 25 02:01:52 lenja-box systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 25 02:01:52 lenja-box systemd[1]: Starting mpd.service - Music Player Daemon... Mar 25 02:01:52 lenja-box volumio[1245]: info: ControllerMpd::onStart: Initializing MPD Mar 25 02:01:52 lenja-box volumio[1245]: info: Creating MPD Configuration file Mar 25 02:01:53 lenja-box sudo[1628]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 25 02:01:53 lenja-box sudo[1628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 25 02:01:53 lenja-box sudo[1633]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 25 02:01:53 lenja-box sudo[1628]: pam_unix(sudo:session): session closed for user root Mar 25 02:01:53 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 02:01:53 lenja-box volumio[1245]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 25 02:01:53 lenja-box volumio[1245]: info: [1774400513161] CoreMusicLibrary::Adding element Medienserver Mar 25 02:01:53 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 02:01:53 lenja-box sudo[1627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 25 02:01:53 lenja-box sudo[1627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:01:53 lenja-box systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 25 02:01:53 lenja-box sudo[1627]: pam_unix(sudo:session): session closed for user root Mar 25 02:01:53 lenja-box volumio[1245]: info: UPNP Browser: Client initialized successfully Mar 25 02:01:53 lenja-box sudo[1632]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 02:01:53 lenja-box sudo[1630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 02:01:53 lenja-box sudo[1630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:01:53 lenja-box sudo[1630]: pam_unix(sudo:session): session closed for user root Mar 25 02:01:53 lenja-box sudo[1632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:01:53 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:01:53 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 02:01:55 lenja-box upmpdcli[1575]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Mar 25 02:01:55 lenja-box upmpdcli[1575]: :2:../src/main.cxx:732::MPD connection failed Mar 25 02:01:56 lenja-box systemd[1]: mpd.service: Deactivated successfully. Mar 25 02:01:56 lenja-box systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 25 02:01:56 lenja-box systemd[1]: mpd.socket: Deactivated successfully. Mar 25 02:01:56 lenja-box systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 25 02:01:56 lenja-box systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 25 02:01:56 lenja-box systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 25 02:01:56 lenja-box systemd[1]: Starting mpd.service - Music Player Daemon... Mar 25 02:01:56 lenja-box sudo[1651]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 25 02:01:56 lenja-box sudo[1651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 25 02:01:56 lenja-box sudo[1653]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 25 02:01:56 lenja-box sudo[1651]: pam_unix(sudo:session): session closed for user root Mar 25 02:01:56 lenja-box volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 02:01:56 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:01:56 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 02:01:56 lenja-box volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 02:01:56 lenja-box volumio[1245]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 25 02:01:56 lenja-box volumio[1245]: info: [1774400516969] CoreMusicLibrary::Adding element Last_100 Mar 25 02:01:56 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 02:01:56 lenja-box volumio[1245]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 25 02:01:56 lenja-box volumio[1245]: info: [1774400516986] CoreMusicLibrary::Adding element Webradio Mar 25 02:01:56 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 02:01:57 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 02:01:57 lenja-box volumio[1245]: info: Initializing BBC Radios Mar 25 02:01:59 lenja-box volumio-remote-updater[847]: [2026-03-25 02:01:59] [connect] Successful connection Mar 25 02:01:59 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 02:01:59 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 02:01:59 lenja-box volumio[1245]: info: Creating Spotify config file Mar 25 02:01:59 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:00 lenja-box volumio5-onboarding[1636]: time=2026-03-25T02:02:00.438+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 25 02:02:02 lenja-box volumio[1245]: info: Scheduling reboots with hours: 2, minutes: 0 in timezone: Europe/Berlin Mar 25 02:02:02 lenja-box volumio[1245]: info: Scheduling job with cron expression: 0 0 2 * * 0 Mar 25 02:02:02 lenja-box volumio[1245]: info: Scheduling job with cron expression: 0 0 2 * * 1 Mar 25 02:02:02 lenja-box volumio[1245]: info: Scheduling job with cron expression: 0 0 2 * * 2 Mar 25 02:02:02 lenja-box volumio[1245]: info: Scheduling job with cron expression: 0 0 2 * * 3 Mar 25 02:02:02 lenja-box volumio[1245]: info: Scheduling job with cron expression: 0 0 2 * * 4 Mar 25 02:02:02 lenja-box volumio[1245]: info: Scheduling job with cron expression: 0 0 2 * * 5 Mar 25 02:02:02 lenja-box volumio[1245]: info: Scheduling job with cron expression: 0 0 2 * * 6 Mar 25 02:02:02 lenja-box volumio[1245]: info: Loading i18n strings for locale de Mar 25 02:02:02 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 02:02:03 lenja-box volumio[1245]: info: Volumio Calling Home Mar 25 02:02:03 lenja-box sudo[1707]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Mar 25 02:02:03 lenja-box sudo[1707]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:02:03 lenja-box sudo[1707]: pam_unix(sudo:session): session closed for user root Mar 25 02:02:03 lenja-box sudo[1713]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 25 02:02:03 lenja-box sudo[1713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:02:03 lenja-box sudo[1715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 25 02:02:03 lenja-box sudo[1715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:02:04 lenja-box sudo[1717]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 25 02:02:04 lenja-box sudo[1717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:02:04 lenja-box sudo[1713]: pam_unix(sudo:session): session closed for user root Mar 25 02:02:04 lenja-box systemd[1]: Reloading. Mar 25 02:02:05 lenja-box upmpdcli[1575]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Mar 25 02:02:05 lenja-box upmpdcli[1575]: :2:../src/main.cxx:732::MPD connection failed Mar 25 02:02:06 lenja-box volumio[1245]: info: Discovery: Connected to remote: 192.168.178.58 Mar 25 02:02:06 lenja-box sudo[1717]: pam_unix(sudo:session): session closed for user root Mar 25 02:02:06 lenja-box systemd[1]: Reloading. Mar 25 02:02:06 lenja-box volumio[1245]: info: Discovery: Disconnected from remote: 192.168.178.58 Mar 25 02:02:06 lenja-box volumio[1245]: info: touch_display: Backlight interface detected. Mar 25 02:02:06 lenja-box volumio[1245]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Mar 25 02:02:07 lenja-box volumio[1245]: info: touch_display: systemctl daemon-reload succeeded. Mar 25 02:02:07 lenja-box volumio[1245]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 25 02:02:07 lenja-box volumio[1245]: info: MPD Permissions set Mar 25 02:02:07 lenja-box volumio[1245]: info: MPD Permissions set Mar 25 02:02:07 lenja-box volumio[1245]: info: Discovery: Disconnected from remote: 192.168.178.61 Mar 25 02:02:07 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.61 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Mar 25 02:02:07 lenja-box volumio[1245]: info: Volumio called home Mar 25 02:02:07 lenja-box sudo[1758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 25 02:02:07 lenja-box sudo[1758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:02:07 lenja-box volumio[1245]: info: Spotify config file written Mar 25 02:02:07 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.58 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 02:02:07 lenja-box sudo[1768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:07 lenja-box sudo[1768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:07 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:08 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 02:02:08 lenja-box mpd[1654]: 2026-03-25T02:02:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 25 02:02:08 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 02:02:08 lenja-box volumio[1245]: info: No need to fix Spotify hosts Mar 25 02:02:08 lenja-box sudo[1761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Mar 25 02:02:08 lenja-box sudo[1761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:02:08 lenja-box systemd[1]: Started mpd.service - Music Player Daemon. Mar 25 02:02:08 lenja-box sudo[1715]: pam_unix(sudo:session): session closed for user root Mar 25 02:02:08 lenja-box sudo[1618]: pam_unix(sudo:session): session closed for user root Mar 25 02:02:08 lenja-box sudo[1632]: pam_unix(sudo:session): session closed for user root Mar 25 02:02:09 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.58 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Mar 25 02:02:09 lenja-box systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 25 02:02:09 lenja-box sudo[1761]: pam_unix(sudo:session): session closed for user root Mar 25 02:02:09 lenja-box systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 25 02:02:09 lenja-box systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 02:02:09 lenja-box go-librespot[1783]: go-librespot daemon starting... Mar 25 02:02:09 lenja-box sudo[1768]: pam_unix(sudo:session): session closed for user root Mar 25 02:02:09 lenja-box systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Mar 25 02:02:09 lenja-box sudo[1758]: pam_unix(sudo:session): session closed for user root Mar 25 02:02:10 lenja-box startx[1808]: X.Org X Server 1.21.1.7 Mar 25 02:02:10 lenja-box startx[1808]: X Protocol Version 11, Revision 0 Mar 25 02:02:10 lenja-box startx[1808]: Current Operating System: Linux lenja-box 6.12.47-v7+ #1904 SMP Mon Sep 15 13:05:52 BST 2025 armv7l Mar 25 02:02:10 lenja-box startx[1808]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_hdmi=0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=7e9efb31-d81e-40e6-b369-17d6be2a2543 imgfile=/volumio_current.sqsh bootpart=UUID=41F4-3F60 datapart=UUID=d86abd49-9ab6-4e7d-8df3-6405c27b7066 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 25 02:02:10 lenja-box startx[1808]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Mar 25 02:02:10 lenja-box startx[1808]: Current version of pixman: 0.44.0 Mar 25 02:02:10 lenja-box startx[1808]: Before reporting problems, check http://wiki.x.org Mar 25 02:02:10 lenja-box startx[1808]: to make sure that you have the latest version. Mar 25 02:02:10 lenja-box startx[1808]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 25 02:02:10 lenja-box startx[1808]: (++) from command line, (!!) notice, (II) informational, Mar 25 02:02:10 lenja-box startx[1808]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 25 02:02:10 lenja-box startx[1808]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Mar 25 02:02:10 2026 Mar 25 02:02:10 lenja-box startx[1808]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 25 02:02:10 lenja-box startx[1808]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 25 02:02:10 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.61 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Mar 25 02:02:10 lenja-box volumio[1245]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Mar 25 02:02:10 lenja-box volumio[1245]: info: touch_display: Volumio Kiosk started. Mar 25 02:02:10 lenja-box volumio5-onboarding[1636]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:35856->127.0.0.1:3000: i/o timeout Mar 25 02:02:10 lenja-box systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 25 02:02:10 lenja-box systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 25 02:02:10 lenja-box volumio[1245]: info: CoreCommandRouter::volumioGetState Mar 25 02:02:10 lenja-box volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 02:02:10 lenja-box volumio[1245]: info: Completed starting Core Plugins Mar 25 02:02:10 lenja-box volumio[1245]: info: ------------------------------------------- Mar 25 02:02:10 lenja-box volumio[1245]: info: ----- MyVolumio plugins startup ---- Mar 25 02:02:10 lenja-box volumio[1245]: info: ------------------------------------------- Mar 25 02:02:10 lenja-box volumio[1245]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 25 02:02:10 lenja-box systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Mar 25 02:02:10 lenja-box systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 25 02:02:10 lenja-box volumio[1245]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 25 02:02:10 lenja-box volumio[1245]: info: touch_display: IframeKeyboardBridge extension installed successfully Mar 25 02:02:10 lenja-box systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 25 02:02:10 lenja-box volumio5-onboarding[1818]: time=2026-03-25T02:02:10.867+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 25 02:02:11 lenja-box sudo[1827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Mar 25 02:02:11 lenja-box sudo[1827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:02:11 lenja-box sudo[1827]: pam_unix(sudo:session): session closed for user root Mar 25 02:02:11 lenja-box go-librespot[1785]: time="2026-03-25T02:02:11+01:00" level=info msg="running go-librespot 0.6.2" Mar 25 02:02:11 lenja-box go-librespot[1785]: time="2026-03-25T02:02:11+01:00" level=debug msg="app state loaded" Mar 25 02:02:11 lenja-box go-librespot[1785]: time="2026-03-25T02:02:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 02:02:11 lenja-box volumio[1245]: info: MPD running with PID1654 Mar 25 02:02:11 lenja-box volumio[1245]: ,establishing connection Mar 25 02:02:11 lenja-box volumio[1245]: info: Starting Shairport Sync Mar 25 02:02:11 lenja-box volumio[1245]: info: Starting Shairport Sync Mar 25 02:02:11 lenja-box volumio[1245]: info: Starting Shairport Sync Mar 25 02:02:11 lenja-box volumio[1245]: info: touch_display: Raspberry Pi Foundation touch screen detected. Mar 25 02:02:11 lenja-box sudo[1854]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Mar 25 02:02:11 lenja-box sudo[1854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:02:11 lenja-box sudo[1854]: pam_unix(sudo:session): session closed for user root Mar 25 02:02:11 lenja-box sudo[1836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 02:02:11 lenja-box sudo[1836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:02:11 lenja-box go-librespot[1785]: unexpected fault address 0xff2ffcd4 Mar 25 02:02:11 lenja-box go-librespot[1785]: fatal error: fault Mar 25 02:02:11 lenja-box go-librespot[1785]: [signal SIGSEGV: segmentation violation code=0x1 addr=0xff2ffcd4 pc=0xff2ffcd4] Mar 25 02:02:11 lenja-box go-librespot[1785]: goroutine 39 gp=0x2dd45a8 m=7 mp=0x307e008 [running]: Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.throw({0x9046af, 0x5}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/panic.go:1094 +0x34 fp=0x2c5baa0 sp=0x2c5ba8c pc=0xa9680 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.sigpanic() Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/signal_unix.go:939 +0x2a8 fp=0x2c5bad0 sp=0x2c5baa0 pc=0xabbc8 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*http2ClientConn).writeHeader(0x2e4c008, {0x906ea9, 0xa}, {0x917afc, 0x15}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:9348 +0x118 fp=0x2c5bb34 sp=0x2c5bad4 pc=0x31b858 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*http2clientStream).encodeAndWriteHeaders.func1({0x906ea9, 0xa}, {0x917afc, 0x15}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:8893 +0x3c fp=0x2c5bb4c sp=0x2c5bb34 pc=0x319b34 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http/internal/httpcommon.EncodeHeaders.func3({0x906ea9, 0xa}, {0x917afc, 0x15}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/internal/httpcommon/httpcommon.go:405 +0x74 fp=0x2c5bb74 sp=0x2c5bb4c pc=0x2e568c Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http/internal/httpcommon.EncodeHeaders.func1(0x2c5bca4) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/internal/httpcommon/httpcommon.go:289 +0x80 fp=0x2c5bc0c sp=0x2c5bb74 pc=0x2e57e4 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http/internal/httpcommon.EncodeHeaders({0xa2611c, 0x2dd62d0}, {{0x2dd6280, {0x903a7b, 0x3}, {0x0, 0x0}, 0x2c768a0, 0x0, 0x0}, ...}, ...) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/internal/httpcommon/httpcommon.go:397 +0x5ac fp=0x2c5bd54 sp=0x2c5bc0c pc=0x2e5310 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.http2encodeRequestHeaders(0x2f002c8, 0x1, 0xffffffffffffffff, 0x2ea1664) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:8909 +0x168 fp=0x2c5bde8 sp=0x2c5bd54 pc=0x319d04 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*http2clientStream).encodeAndWriteHeaders(0x2cfc968, 0x2f002c8) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:8892 +0x214 fp=0x2c5be70 sp=0x2c5bde8 pc=0x319964 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*http2clientStream).writeRequest(0x2cfc968, 0x2f002c8, 0x0) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:8794 +0x67c fp=0x2c5bfc4 sp=0x2c5be70 pc=0x318ff0 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*http2clientStream).doRequest(0x2cfc968, 0x2f002c8, 0x0) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:8711 +0x60 fp=0x2c5bfdc sp=0x2c5bfc4 pc=0x318948 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*http2ClientConn).roundTrip.gowrap1() Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:8616 +0x38 fp=0x2c5bfec sp=0x2c5bfdc pc=0x3188bc Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goexit({}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2c5bfec sp=0x2c5bfec pc=0xb0874 Mar 25 02:02:11 lenja-box go-librespot[1785]: created by net/http.(*http2ClientConn).roundTrip in goroutine 1 Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:8616 +0x4a4 Mar 25 02:02:11 lenja-box go-librespot[1785]: goroutine 1 gp=0x2c02128 m=nil [select]: Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2e53600 sp=0x2e535ec pc=0xa97c0 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.selectgo(0x2e53760, 0x2e536e0, 0x0, 0x0, 0x4, 0x1) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2e536b0 sp=0x2e53600 pc=0x85000 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*http2ClientConn).roundTrip(0x2e4c008, 0x2f002c8, 0x0) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:8680 +0x538 fp=0x2e537c4 sp=0x2e536b0 pc=0x318068 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*http2ClientConn).RoundTrip(...) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:8595 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*http2Transport).RoundTripOpt(0x2c7ee00, 0x2f002c8, {0x0, 0x0}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:7921 +0x24c fp=0x2e53864 sp=0x2e537c4 pc=0x314060 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*http2Transport).RoundTrip(0x2c7ee00, 0x2f002c8) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:7872 +0x30 fp=0x2e53880 sp=0x2e53864 pc=0x313ae0 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*Transport).roundTrip(0xf812a8, 0x2f002c8) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/transport.go:694 +0x9b4 fp=0x2e5396c sp=0x2e53880 pc=0x34d564 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*Transport).RoundTrip(0xf812a8, 0x2f002c8) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/roundtrip.go:33 +0x2c fp=0x2e53984 sp=0x2e5396c pc=0x35ba84 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.send(0x2f00168, {0xa21b28, 0xf812a8}, {0xc268eaec51cf7b72, 0x73456e675, 0xfd1e68}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/client.go:259 +0x53c fp=0x2e53ab0 sp=0x2e53984 pc=0x2ed3ec Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*Client).send(0x2ca1820, 0x2f00168, {0xc268eaec51cf7b72, 0x73456e675, 0xfd1e68}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/client.go:180 +0x98 fp=0x2e53af4 sp=0x2e53ab0 pc=0x2eccd8 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*Client).do(0x2ca1820, 0x2f00168) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/client.go:729 +0x9a8 fp=0x2e53c04 sp=0x2e53af4 pc=0x2ef340 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*Client).Do(...) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/client.go:587 Mar 25 02:02:11 lenja-box go-librespot[1785]: github.com/devgianlu/go-librespot/apresolve.(*ApResolver).fetchUrls(0x2c00060, {0xa260ac, 0xfdcff0}, {0x2e53eb0, 0x3, 0x3}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /src/apresolve/resolve.go:83 +0x4d0 fp=0x2e53e3c sp=0x2e53c04 pc=0x597990 Mar 25 02:02:11 lenja-box go-librespot[1785]: github.com/devgianlu/go-librespot/apresolve.(*ApResolver).FetchAll(...) Mar 25 02:02:11 lenja-box go-librespot[1785]: /src/apresolve/resolve.go:122 Mar 25 02:02:11 lenja-box go-librespot[1785]: main.(*App).withAppPlayer(0x2cda400, {0xa260ac, 0xfdcff0}, 0x2c10340) Mar 25 02:02:11 lenja-box go-librespot[1785]: /src/cmd/daemon/main.go:251 +0x94 fp=0x2e53ed0 sp=0x2e53e3c pc=0x6aab9c Mar 25 02:02:11 lenja-box go-librespot[1785]: main.(*App).withCredentials(...) Mar 25 02:02:11 lenja-box go-librespot[1785]: /src/cmd/daemon/main.go:209 Mar 25 02:02:11 lenja-box go-librespot[1785]: main.(*App).SpotifyToken(0x2cda400, {0xa260ac, 0xfdcff0}, {0x2cae820, 0x1c}, {0x2c9a3c0, 0x13e}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x2e53f00 sp=0x2e53ed0 pc=0x6aa4b0 Mar 25 02:02:11 lenja-box go-librespot[1785]: main.main() Mar 25 02:02:11 lenja-box go-librespot[1785]: /src/cmd/daemon/main.go:593 +0x660 fp=0x2e53fa8 sp=0x2e53f00 pc=0x6ad58c Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.main() Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x2e53fec sp=0x2e53fa8 pc=0x6f390 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goexit({}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2e53fec sp=0x2e53fec pc=0xb0874 Mar 25 02:02:11 lenja-box go-librespot[1785]: goroutine 2 gp=0x2c027e8 m=nil [force gc (idle)]: Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gopark(0x960edc, 0xfd0f08, 0x11, 0xa, 0x1) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2c48fd4 sp=0x2c48fc0 pc=0xa97c0 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goparkunlock(...) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.forcegchelper() Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x2c48fec sp=0x2c48fd4 pc=0x6f7f4 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goexit({}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2c48fec sp=0x2c48fec pc=0xb0874 Mar 25 02:02:11 lenja-box go-librespot[1785]: created by runtime.init.6 in goroutine 1 Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:361 +0x1c Mar 25 02:02:11 lenja-box go-librespot[1785]: goroutine 3 gp=0x2c02d88 m=nil [GC sweep wait]: Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gopark(0x960edc, 0xfd17b0, 0xc, 0x9, 0x1) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2c497c4 sp=0x2c497b0 pc=0xa97c0 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goparkunlock(...) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.bgsweep(0x2c2e050) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x2c497e4 sp=0x2c497c4 pc=0x5768c Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gcenable.gowrap1() Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x2c497ec sp=0x2c497e4 pc=0x46c3c Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goexit({}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2c497ec sp=0x2c497ec pc=0xb0874 Mar 25 02:02:11 lenja-box go-librespot[1785]: created by runtime.gcenable in goroutine 1 Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgc.go:212 +0x74 Mar 25 02:02:11 lenja-box go-librespot[1785]: goroutine 4 gp=0x2c02ea8 m=nil [GC scavenge wait]: Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gopark(0x960edc, 0xfd20d0, 0xd, 0xa, 0x2) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2c49fb4 sp=0x2c49fa0 pc=0xa97c0 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goparkunlock(...) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.(*scavengerState).park(0xfd20d0) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2c49fc8 sp=0x2c49fb4 pc=0x54a28 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.bgscavenge(0x2c2e050) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2c49fe4 sp=0x2c49fc8 pc=0x5516c Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gcenable.gowrap2() Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x2c49fec sp=0x2c49fe4 pc=0x46be8 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goexit({}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2c49fec sp=0x2c49fec pc=0xb0874 Mar 25 02:02:11 lenja-box go-librespot[1785]: created by runtime.gcenable in goroutine 1 Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgc.go:213 +0xbc Mar 25 02:02:11 lenja-box go-librespot[1785]: goroutine 18 gp=0x2c82248 m=nil [GOMAXPROCS updater (idle)]: Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gopark(0x960edc, 0xfd1548, 0x12, 0xa, 0x1) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2c447a4 sp=0x2c44790 pc=0xa97c0 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goparkunlock(...) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.updateMaxProcsGoroutine() Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x2c447ec sp=0x2c447a4 pc=0x7f174 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goexit({}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2c447ec sp=0x2c447ec pc=0xb0874 Mar 25 02:02:11 lenja-box go-librespot[1785]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:6708 +0x40 Mar 25 02:02:11 lenja-box go-librespot[1785]: goroutine 5 gp=0x2c02fc8 m=nil [finalizer wait]: Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gopark(0x960d84, 0xfdd0e0, 0x10, 0xa, 0x1) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2c4a788 sp=0x2c4a774 pc=0xa97c0 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.runFinalizers() Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x2c4a7ec sp=0x2c4a788 pc=0x45888 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goexit({}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2c4a7ec sp=0x2c4a7ec pc=0xb0874 Mar 25 02:02:11 lenja-box go-librespot[1785]: created by runtime.createfing in goroutine 1 Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c Mar 25 02:02:11 lenja-box go-librespot[1785]: goroutine 34 gp=0x2d38ea8 m=nil [cleanup wait]: Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gopark(0x960edc, 0xfd22a8, 0x2e, 0xa, 0x1) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2e9e7a4 sp=0x2e9e790 pc=0xa97c0 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goparkunlock(...) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.(*cleanupQueue).dequeue(0xfd2248) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x2e9e7c4 sp=0x2e9e7a4 pc=0x41cb0 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.runCleanups() Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x2e9e7ec sp=0x2e9e7c4 pc=0x4284c Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goexit({}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2e9e7ec sp=0x2e9e7ec pc=0xb0874 Mar 25 02:02:11 lenja-box go-librespot[1785]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 Mar 25 02:02:11 lenja-box go-librespot[1785]: goroutine 8 gp=0x2dd4488 m=nil [IO wait]: Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gopark(0x960ecc, 0x74f83010, 0x2, 0x2, 0x5) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2c4b4f0 sp=0x2c4b4dc pc=0xa97c0 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.netpollblock(0x74f83000, 0x72, 0x0) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2c4b508 sp=0x2c4b4f0 pc=0x675a0 Mar 25 02:02:11 lenja-box go-librespot[1785]: internal/poll.runtime_pollWait(0x74f83000, 0x72) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2c4b51c sp=0x2c4b508 pc=0xa8864 Mar 25 02:02:11 lenja-box go-librespot[1785]: internal/poll.(*pollDesc).wait(0x2dd6248, 0x72, 0x0) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2c4b530 sp=0x2c4b51c pc=0x134df4 Mar 25 02:02:11 lenja-box go-librespot[1785]: internal/poll.(*pollDesc).waitRead(...) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 25 02:02:11 lenja-box go-librespot[1785]: internal/poll.(*FD).Accept(0x2dd6230) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x2c4b578 sp=0x2c4b530 pc=0x1395b0 Mar 25 02:02:11 lenja-box go-librespot[1785]: net.(*netFD).accept(0x2dd6230) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x2c4b5e0 sp=0x2c4b578 pc=0x1aa9d8 Mar 25 02:02:11 lenja-box sudo[1842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 02:02:11 lenja-box volumio[1245]: info: New Spotify access tokenBQDNoudGSp... Mar 25 02:02:11 lenja-box volumio[1245]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 25 02:02:11 lenja-box go-librespot[1785]: net.(*TCPListener).accept(0x2c001e0) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x2c4b634 sp=0x2c4b5e0 pc=0x1c2304 Mar 25 02:02:11 lenja-box go-librespot[1785]: net.(*TCPListener).Accept(0x2c001e0) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x2c4b650 sp=0x2c4b634 pc=0x1c134c Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*onceCloseListener).Accept(0x2ca18c0) Mar 25 02:02:11 lenja-box go-librespot[1785]: :1 +0x34 fp=0x2c4b668 sp=0x2c4b650 pc=0x367710 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*Server).Serve(0x2cd4638, {0xa258a8, 0x2c001e0}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x2c4b700 sp=0x2c4b668 pc=0x3408c8 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.Serve(...) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/server.go:2971 Mar 25 02:02:11 lenja-box go-librespot[1785]: main.(*ConcreteApiServer).serve(0x2c2a2a0) Mar 25 02:02:11 lenja-box go-librespot[1785]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x2c4b7e4 sp=0x2c4b700 pc=0x69fdac Mar 25 02:02:11 lenja-box go-librespot[1785]: main.NewApiServer.gowrap1() Mar 25 02:02:11 lenja-box go-librespot[1785]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x2c4b7ec sp=0x2c4b7e4 pc=0x69eae4 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goexit({}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2c4b7ec sp=0x2c4b7ec pc=0xb0874 Mar 25 02:02:11 lenja-box go-librespot[1785]: created by main.NewApiServer in goroutine 1 Mar 25 02:02:11 lenja-box go-librespot[1785]: /src/cmd/daemon/api_server.go:332 +0x310 Mar 25 02:02:11 lenja-box go-librespot[1785]: goroutine 19 gp=0x2dd4908 m=nil [GC worker (idle)]: Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gopark(0x960d90, 0x2e48a00, 0x1c, 0xa, 0x0) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2c4af88 sp=0x2c4af74 pc=0xa97c0 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gcBgMarkWorker(0x2c90c80) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x2c4afe4 sp=0x2c4af88 pc=0x49f34 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x2c4afec sp=0x2c4afe4 pc=0x49e14 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goexit({}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2c4afec sp=0x2c4afec pc=0xb0874 Mar 25 02:02:11 lenja-box go-librespot[1785]: created by runtime.gcBgMarkStartWorkers in goroutine 35 Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 25 02:02:11 lenja-box go-librespot[1785]: goroutine 38 gp=0x2dd4a28 m=nil [sync.Mutex.Lock]: Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gopark(0x960edc, 0xfd71c0, 0x16, 0x5, 0x6) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x30a5e40 sp=0x30a5e2c pc=0xa97c0 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goparkunlock(...) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.semacquire1(0x2e4c0f0, 0x0, 0x3, 0x2, 0x16) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/sema.go:192 +0x270 fp=0x30a5e68 sp=0x30a5e40 pc=0x85c6c Mar 25 02:02:11 lenja-box systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Mar 25 02:02:11 lenja-box sudo[1842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:02:11 lenja-box go-librespot[1785]: internal/sync.runtime_SemacquireMutex(0x2e4c0f0, 0x0, 0x2) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/sema.go:95 +0x3c fp=0x30a5e80 sp=0x30a5e68 pc=0xaaf88 Mar 25 02:02:11 lenja-box go-librespot[1785]: internal/sync.(*Mutex).lockSlow(0x2e4c0ec) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/internal/sync/mutex.go:149 +0x280 fp=0x30a5eb4 sp=0x30a5e80 pc=0xba0c4 Mar 25 02:02:11 lenja-box go-librespot[1785]: internal/sync.(*Mutex).Lock(0x2e4c0ec) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/internal/sync/mutex.go:70 +0x4c fp=0x30a5ec8 sp=0x30a5eb4 pc=0xb9dc4 Mar 25 02:02:11 lenja-box go-librespot[1785]: sync.(*Mutex).Lock(...) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/sync/mutex.go:46 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*http2clientConnReadLoop).processSettings(0x30a5fdc, 0x300b0e0) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:10114 +0x54 fp=0x30a5ef4 sp=0x30a5ec8 pc=0x3202b4 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*http2clientConnReadLoop).run(0x30a5fdc) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:9579 +0x624 fp=0x30a5fa4 sp=0x30a5ef4 pc=0x31cfcc Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*http2ClientConn).readLoop(0x2e4c008) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x30a5fe4 sp=0x30a5fa4 pc=0x31bd78 Mar 25 02:02:11 lenja-box go-librespot[1785]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x30a5fec sp=0x30a5fe4 pc=0x3160d4 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goexit({}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x30a5fec sp=0x30a5fec pc=0xb0874 Mar 25 02:02:11 lenja-box go-librespot[1785]: created by net/http.(*http2Transport).newClientConn in goroutine 37 Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Mar 25 02:02:11 lenja-box go-librespot[1785]: goroutine 20 gp=0x2e4c7e8 m=nil [GC worker (idle)]: Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gopark(0x960d90, 0x2e48c00, 0x1c, 0xa, 0x0) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2c48788 sp=0x2c48774 pc=0xa97c0 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gcBgMarkWorker(0x2c90c80) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x2c487e4 sp=0x2c48788 pc=0x49f34 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x2c487ec sp=0x2c487e4 pc=0x49e14 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goexit({}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2c487ec sp=0x2c487ec pc=0xb0874 Mar 25 02:02:11 lenja-box go-librespot[1785]: created by runtime.gcBgMarkStartWorkers in goroutine 35 Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 25 02:02:11 lenja-box go-librespot[1785]: goroutine 21 gp=0x2e4c908 m=nil [GC worker (idle)]: Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gopark(0x960d90, 0x2e48e00, 0x1c, 0xa, 0x0) Mar 25 02:02:11 lenja-box systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 02:02:11 lenja-box sudo[1846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2c44f88 sp=0x2c44f74 pc=0xa97c0 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gcBgMarkWorker(0x2c90c80) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x2c44fe4 sp=0x2c44f88 pc=0x49f34 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x2c44fec sp=0x2c44fe4 pc=0x49e14 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goexit({}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2c44fec sp=0x2c44fec pc=0xb0874 Mar 25 02:02:11 lenja-box go-librespot[1785]: created by runtime.gcBgMarkStartWorkers in goroutine 35 Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 25 02:02:11 lenja-box go-librespot[1785]: goroutine 22 gp=0x2e4ca28 m=nil [GC worker (idle)]: Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gopark(0x960d90, 0x2e49000, 0x1c, 0xa, 0x0) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x30a7f88 sp=0x30a7f74 pc=0xa97c0 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gcBgMarkWorker(0x2c90c80) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x30a7fe4 sp=0x30a7f88 pc=0x49f34 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x30a7fec sp=0x30a7fe4 pc=0x49e14 Mar 25 02:02:11 lenja-box go-librespot[1785]: runtime.goexit({}) Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x30a7fec sp=0x30a7fec pc=0xb0874 Mar 25 02:02:11 lenja-box go-librespot[1785]: created by runtime.gcBgMarkStartWorkers in goroutine 35 Mar 25 02:02:11 lenja-box go-librespot[1785]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 25 02:02:11 lenja-box sudo[1846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:02:11 lenja-box go-librespot[1783]: Aborted Mar 25 02:02:11 lenja-box sudo[1844]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 02:02:11 lenja-box sudo[1844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 02:02:11 lenja-box sudo[1846]: pam_unix(sudo:session): session closed for user root Mar 25 02:02:12 lenja-box systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 25 02:02:12 lenja-box systemd[1]: shairport-sync.service: Deactivated successfully. Mar 25 02:02:12 lenja-box systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 02:02:12 lenja-box systemd[1]: shairport-sync.service: Consumed 1.690s CPU time. Mar 25 02:02:12 lenja-box systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 02:02:12 lenja-box sudo[1836]: pam_unix(sudo:session): session closed for user root Mar 25 02:02:12 lenja-box systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 25 02:02:12 lenja-box systemd[1]: shairport-sync.service: Deactivated successfully. Mar 25 02:02:12 lenja-box systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 02:02:12 lenja-box systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 02:02:12 lenja-box sudo[1842]: pam_unix(sudo:session): session closed for user root Mar 25 02:02:12 lenja-box sudo[1844]: pam_unix(sudo:session): session closed for user root Mar 25 02:02:12 lenja-box volumio[1245]: error: MPD error: The expression evaluated to a falsy value: Mar 25 02:02:12 lenja-box volumio[1245]: assert.ok(self.idling) Mar 25 02:02:13 lenja-box volumio[1245]: error: The expression evaluated to a falsy value: Mar 25 02:02:13 lenja-box volumio[1245]: assert.ok(self.idling) Mar 25 02:02:13 lenja-box volumio[1245]: error: MPD error: The expression evaluated to a falsy value: Mar 25 02:02:13 lenja-box volumio[1245]: assert.ok(self.idling) Mar 25 02:02:13 lenja-box volumio[1245]: error: The expression evaluated to a falsy value: Mar 25 02:02:13 lenja-box volumio[1245]: assert.ok(self.idling) Mar 25 02:02:13 lenja-box volumio[1245]: info: touch_display: File permissions for backlight brightness control set. Mar 25 02:02:13 lenja-box volumio[1245]: info: touch_display: X display number found: 0 Mar 25 02:02:13 lenja-box volumio[1245]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Mar 25 02:02:13 lenja-box volumio[1245]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 25 02:02:13 lenja-box volumio[1245]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Mar 25 02:02:13 lenja-box volumio[1245]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 25 02:02:13 lenja-box volumio[1245]: info: Shairport-Sync Started Mar 25 02:02:13 lenja-box volumio[1245]: Error adding Membership: Error: addMembership EINVAL Mar 25 02:02:13 lenja-box volumio[1245]: info: Shairport-Sync Started Mar 25 02:02:13 lenja-box volumio[1245]: info: Shairport-Sync Started Mar 25 02:02:13 lenja-box volumio[1245]: info: go-librespot daemon successfully initialized Mar 25 02:02:13 lenja-box volumio[1245]: error: updateQueue error: null Mar 25 02:02:13 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.61 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Mar 25 02:02:13 lenja-box volumio[1245]: info: Discovery: Connected to remote: 192.168.178.58 Mar 25 02:02:13 lenja-box volumio[1245]: info: Discovery: Connected to remote: 192.168.178.61 Mar 25 02:02:14 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.58 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Mar 25 02:02:14 lenja-box volumio[1245]: info: touch_display: X display number found: 0 Mar 25 02:02:14 lenja-box volumio[1245]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 Mar 25 02:02:14 lenja-box volumio-remote-updater[847]: [2026-03-25 02:02:14] [connect] Successful connection Mar 25 02:02:15 lenja-box systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Mar 25 02:02:15 lenja-box systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 02:02:15 lenja-box volumio[1245]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 22 Mar 25 02:02:15 lenja-box systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 02:02:15 lenja-box go-librespot[1967]: go-librespot daemon starting... Mar 25 02:02:15 lenja-box go-librespot[1968]: time="2026-03-25T02:02:15+01:00" level=info msg="running go-librespot 0.6.2" Mar 25 02:02:15 lenja-box go-librespot[1968]: time="2026-03-25T02:02:15+01:00" level=debug msg="app state loaded" Mar 25 02:02:15 lenja-box volumio[1245]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 25 02:02:15 lenja-box go-librespot[1968]: time="2026-03-25T02:02:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 02:02:15 lenja-box volumio[1245]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 22 Mar 25 02:02:15 lenja-box volumio[1245]: info: Received Get System Info Mar 25 02:02:15 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 02:02:15 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 02:02:15 lenja-box volumio[1245]: info: Discovery: Getting this device information Mar 25 02:02:15 lenja-box volumio[1245]: info: CoreCommandRouter::volumioGetState Mar 25 02:02:15 lenja-box volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 02:02:15 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 02:02:15 lenja-box volumio5-onboarding[1818]: time=2026-03-25T02:02:15.699+01:00 level=INFO msg="system info for b5a067b6ecc659e24c6d5cf675295d0a" deviceName=Lenja-Box deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 25 02:02:15 lenja-box volumio5-onboarding[1818]: time=2026-03-25T02:02:15.738+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 25 02:02:15 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.61 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 23 Mar 25 02:02:15 lenja-box volumio[1245]: info: Received Get System Info Mar 25 02:02:15 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 02:02:15 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 02:02:15 lenja-box volumio[1245]: info: Discovery: Getting this device information Mar 25 02:02:15 lenja-box volumio[1245]: info: CoreCommandRouter::volumioGetState Mar 25 02:02:15 lenja-box volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 02:02:15 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 02:02:16 lenja-box volumio[1245]: info: Initializing connection to go-librespot Websocket Mar 25 02:02:16 lenja-box volumio-remote-updater[847]: [2026-03-25 02:02:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774400534 101 Mar 25 02:02:16 lenja-box volumio[1245]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 24 Mar 25 02:02:16 lenja-box go-librespot[1968]: time="2026-03-25T02:02:16+01:00" level=debug msg="new websocket client" Mar 25 02:02:16 lenja-box volumio[1245]: info: Connection to go-librespot Websocket established Mar 25 02:02:17 lenja-box volumio[1245]: info: CoreCommandRouter::volumioGetState Mar 25 02:02:17 lenja-box volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 02:02:17 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 25 02:02:17 lenja-box volumio[1245]: info: CoreCommandRouter::volumioGetState Mar 25 02:02:17 lenja-box volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 02:02:17 lenja-box volumio[1245]: info: CoreCommandRouter::volumioGetState Mar 25 02:02:17 lenja-box volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 02:02:17 lenja-box volumio[1245]: SPOTIFY: User informations: {"country":"DE","display_name":"LJ","email":"lenja@schriftli.ch","explicit_content":{"filter_enabled":true,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31ptitpuhs63fgmcj6jjgt6dxzzq"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31ptitpuhs63fgmcj6jjgt6dxzzq","id":"31ptitpuhs63fgmcj6jjgt6dxzzq","images":[],"product":"premium","type":"user","uri":"spotify:user:31ptitpuhs63fgmcj6jjgt6dxzzq"} Mar 25 02:02:17 lenja-box volumio[1245]: info: Spotify Successfully logged in Mar 25 02:02:17 lenja-box volumio[1245]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 25 02:02:17 lenja-box volumio[1245]: info: [1774400537627] CoreMusicLibrary::Adding element Spotify Mar 25 02:02:17 lenja-box volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 02:02:17 lenja-box volumio[1245]: Cannot find translation for source Spotify Mar 25 02:02:18 lenja-box volumio[1245]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 25 02:02:24 lenja-box upmpdcli[2015]: writing RSA key Mar 25 02:02:24 lenja-box go-librespot[1968]: unexpected fault address 0xff2ffcd4 Mar 25 02:02:24 lenja-box go-librespot[1968]: fatal error: fault Mar 25 02:02:24 lenja-box go-librespot[1968]: [signal SIGSEGV: segmentation violation code=0x1 addr=0xff2ffcd4 pc=0xff2ffcd4] Mar 25 02:02:24 lenja-box go-librespot[1968]: goroutine 33 gp=0x31d4248 m=4 mp=0x304d808 [running]: Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.throw({0x9046af, 0x5}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/panic.go:1094 +0x34 fp=0x3056aa0 sp=0x3056a8c pc=0xa9680 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.sigpanic() Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/signal_unix.go:939 +0x2a8 fp=0x3056ad0 sp=0x3056aa0 pc=0xabbc8 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*http2ClientConn).writeHeader(0x315ea28, {0x906ea9, 0xa}, {0x917afc, 0x15}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:9348 +0x118 fp=0x3056b34 sp=0x3056ad4 pc=0x31b858 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*http2clientStream).encodeAndWriteHeaders.func1({0x906ea9, 0xa}, {0x917afc, 0x15}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:8893 +0x3c fp=0x3056b4c sp=0x3056b34 pc=0x319b34 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http/internal/httpcommon.EncodeHeaders.func3({0x906ea9, 0xa}, {0x917afc, 0x15}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/internal/httpcommon/httpcommon.go:405 +0x74 fp=0x3056b74 sp=0x3056b4c pc=0x2e568c Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http/internal/httpcommon.EncodeHeaders.func1(0x3056ca4) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/internal/httpcommon/httpcommon.go:289 +0x80 fp=0x3056c0c sp=0x3056b74 pc=0x2e57e4 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http/internal/httpcommon.EncodeHeaders({0xa2611c, 0x31aa0a0}, {{0x31aa050, {0x903a7b, 0x3}, {0x0, 0x0}, 0x31ce060, 0x0, 0x0}, ...}, ...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/internal/httpcommon/httpcommon.go:397 +0x5ac fp=0x3056d54 sp=0x3056c0c pc=0x2e5310 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.http2encodeRequestHeaders(0x31d6218, 0x1, 0xffffffffffffffff, 0x3286664) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:8909 +0x168 fp=0x3056de8 sp=0x3056d54 pc=0x319d04 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*http2clientStream).encodeAndWriteHeaders(0x318e2d8, 0x31d6218) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:8892 +0x214 fp=0x3056e70 sp=0x3056de8 pc=0x319964 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*http2clientStream).writeRequest(0x318e2d8, 0x31d6218, 0x0) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:8794 +0x67c fp=0x3056fc4 sp=0x3056e70 pc=0x318ff0 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*http2clientStream).doRequest(0x318e2d8, 0x31d6218, 0x0) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:8711 +0x60 fp=0x3056fdc sp=0x3056fc4 pc=0x318948 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*http2ClientConn).roundTrip.gowrap1() Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:8616 +0x38 fp=0x3056fec sp=0x3056fdc pc=0x3188bc Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goexit({}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3056fec sp=0x3056fec pc=0xb0874 Mar 25 02:02:24 lenja-box go-librespot[1968]: created by net/http.(*http2ClientConn).roundTrip in goroutine 1 Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:8616 +0x4a4 Mar 25 02:02:24 lenja-box go-librespot[1968]: goroutine 1 gp=0x3002128 m=nil [select]: Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3185600 sp=0x31855ec pc=0xa97c0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.selectgo(0x3185760, 0x31856e0, 0x0, 0x0, 0x4, 0x1) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x31856b0 sp=0x3185600 pc=0x85000 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*http2ClientConn).roundTrip(0x315ea28, 0x31d6218, 0x0) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:8680 +0x538 fp=0x31857c4 sp=0x31856b0 pc=0x318068 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*http2ClientConn).RoundTrip(...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:8595 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*http2Transport).RoundTripOpt(0x31da000, 0x31d6218, {0x0, 0x0}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:7921 +0x24c fp=0x3185864 sp=0x31857c4 pc=0x314060 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*http2Transport).RoundTrip(0x31da000, 0x31d6218) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:7872 +0x30 fp=0x3185880 sp=0x3185864 pc=0x313ae0 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*Transport).roundTrip(0xf812a8, 0x31d6218) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/transport.go:694 +0x9b4 fp=0x318596c sp=0x3185880 pc=0x34d564 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*Transport).RoundTrip(0xf812a8, 0x31d6218) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/roundtrip.go:33 +0x2c fp=0x3185984 sp=0x318596c pc=0x35ba84 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.send(0x31d60b8, {0xa21b28, 0xf812a8}, {0xc268eaed4b676849, 0x700e3e382, 0xfd1e68}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/client.go:259 +0x53c fp=0x3185ab0 sp=0x3185984 pc=0x2ed3ec Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*Client).send(0x316e940, 0x31d60b8, {0xc268eaed4b676849, 0x700e3e382, 0xfd1e68}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/client.go:180 +0x98 fp=0x3185af4 sp=0x3185ab0 pc=0x2eccd8 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*Client).do(0x316e940, 0x31d60b8) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/client.go:729 +0x9a8 fp=0x3185c04 sp=0x3185af4 pc=0x2ef340 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*Client).Do(...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/client.go:587 Mar 25 02:02:24 lenja-box go-librespot[1968]: github.com/devgianlu/go-librespot/apresolve.(*ApResolver).fetchUrls(0x30004b0, {0xa260ac, 0xfdcff0}, {0x3185eb0, 0x3, 0x3}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/apresolve/resolve.go:83 +0x4d0 fp=0x3185e3c sp=0x3185c04 pc=0x597990 Mar 25 02:02:24 lenja-box go-librespot[1968]: github.com/devgianlu/go-librespot/apresolve.(*ApResolver).FetchAll(...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/apresolve/resolve.go:122 Mar 25 02:02:24 lenja-box go-librespot[1968]: main.(*App).withAppPlayer(0x30a6800, {0xa260ac, 0xfdcff0}, 0x31a8020) Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/cmd/daemon/main.go:251 +0x94 fp=0x3185ed0 sp=0x3185e3c pc=0x6aab9c Mar 25 02:02:24 lenja-box go-librespot[1968]: main.(*App).withCredentials(...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/cmd/daemon/main.go:209 Mar 25 02:02:24 lenja-box go-librespot[1968]: main.(*App).SpotifyToken(0x30a6800, {0xa260ac, 0xfdcff0}, {0x30a2980, 0x1c}, {0x307e3c0, 0x13e}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x3185f00 sp=0x3185ed0 pc=0x6aa4b0 Mar 25 02:02:24 lenja-box go-librespot[1968]: main.main() Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/cmd/daemon/main.go:593 +0x660 fp=0x3185fa8 sp=0x3185f00 pc=0x6ad58c Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.main() Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x3185fec sp=0x3185fa8 pc=0x6f390 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goexit({}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3185fec sp=0x3185fec pc=0xb0874 Mar 25 02:02:24 lenja-box go-librespot[1968]: goroutine 2 gp=0x30027e8 m=nil [force gc (idle)]: Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gopark(0x960edc, 0xfd0f08, 0x11, 0xa, 0x1) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3048fd4 sp=0x3048fc0 pc=0xa97c0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goparkunlock(...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.forcegchelper() Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x3048fec sp=0x3048fd4 pc=0x6f7f4 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goexit({}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3048fec sp=0x3048fec pc=0xb0874 Mar 25 02:02:24 lenja-box go-librespot[1968]: created by runtime.init.6 in goroutine 1 Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:361 +0x1c Mar 25 02:02:24 lenja-box go-librespot[1968]: goroutine 3 gp=0x3002b48 m=nil [GC sweep wait]: Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gopark(0x960edc, 0xfd17b0, 0xc, 0x9, 0x1) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x30497c4 sp=0x30497b0 pc=0xa97c0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goparkunlock(...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.bgsweep(0x302e050) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x30497e4 sp=0x30497c4 pc=0x5768c Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gcenable.gowrap1() Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x30497ec sp=0x30497e4 pc=0x46c3c Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goexit({}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x30497ec sp=0x30497ec pc=0xb0874 Mar 25 02:02:24 lenja-box go-librespot[1968]: created by runtime.gcenable in goroutine 1 Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgc.go:212 +0x74 Mar 25 02:02:24 lenja-box go-librespot[1968]: goroutine 4 gp=0x3002c68 m=nil [GC scavenge wait]: Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gopark(0x960edc, 0xfd20d0, 0xd, 0xa, 0x2) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3049fb4 sp=0x3049fa0 pc=0xa97c0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goparkunlock(...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.(*scavengerState).park(0xfd20d0) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x3049fc8 sp=0x3049fb4 pc=0x54a28 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.bgscavenge(0x302e050) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x3049fe4 sp=0x3049fc8 pc=0x5516c Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gcenable.gowrap2() Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x3049fec sp=0x3049fe4 pc=0x46be8 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goexit({}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3049fec sp=0x3049fec pc=0xb0874 Mar 25 02:02:24 lenja-box go-librespot[1968]: created by runtime.gcenable in goroutine 1 Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgc.go:213 +0xbc Mar 25 02:02:24 lenja-box go-librespot[1968]: goroutine 5 gp=0x3002fc8 m=nil [GOMAXPROCS updater (idle)]: Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gopark(0x960edc, 0xfd1548, 0x12, 0xa, 0x1) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x304a7a4 sp=0x304a790 pc=0xa97c0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goparkunlock(...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.updateMaxProcsGoroutine() Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x304a7ec sp=0x304a7a4 pc=0x7f174 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goexit({}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x304a7ec sp=0x304a7ec pc=0xb0874 Mar 25 02:02:24 lenja-box go-librespot[1968]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:6708 +0x40 Mar 25 02:02:24 lenja-box go-librespot[1968]: goroutine 6 gp=0x3003328 m=nil [finalizer wait]: Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gopark(0x960d84, 0xfdd0e0, 0x10, 0xa, 0x1) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x304af88 sp=0x304af74 pc=0xa97c0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.runFinalizers() Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x304afec sp=0x304af88 pc=0x45888 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goexit({}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x304afec sp=0x304afec pc=0xb0874 Mar 25 02:02:24 lenja-box go-librespot[1968]: created by runtime.createfing in goroutine 1 Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c Mar 25 02:02:24 lenja-box go-librespot[1968]: goroutine 7 gp=0x315e908 m=nil [cleanup wait]: Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gopark(0x960edc, 0xfd22a8, 0x2e, 0xa, 0x1) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x30487a4 sp=0x3048790 pc=0xa97c0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goparkunlock(...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.(*cleanupQueue).dequeue(0xfd2248) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x30487c4 sp=0x30487a4 pc=0x41cb0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.runCleanups() Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x30487ec sp=0x30487c4 pc=0x4284c Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goexit({}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x30487ec sp=0x30487ec pc=0xb0874 Mar 25 02:02:24 lenja-box go-librespot[1968]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 Mar 25 02:02:24 lenja-box go-librespot[1968]: goroutine 18 gp=0x31d4008 m=nil [IO wait]: Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gopark(0x960ecc, 0x7515be10, 0x2, 0x2, 0x5) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3222cf0 sp=0x3222cdc pc=0xa97c0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.netpollblock(0x7515be00, 0x72, 0x0) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x3222d08 sp=0x3222cf0 pc=0x675a0 Mar 25 02:02:24 lenja-box go-librespot[1968]: internal/poll.runtime_pollWait(0x7515be00, 0x72) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x3222d1c sp=0x3222d08 pc=0xa8864 Mar 25 02:02:24 lenja-box go-librespot[1968]: internal/poll.(*pollDesc).wait(0x31aa018, 0x72, 0x0) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x3222d30 sp=0x3222d1c pc=0x134df4 Mar 25 02:02:24 lenja-box go-librespot[1968]: internal/poll.(*pollDesc).waitRead(...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 25 02:02:24 lenja-box go-librespot[1968]: internal/poll.(*FD).Accept(0x31aa000) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x3222d78 sp=0x3222d30 pc=0x1395b0 Mar 25 02:02:24 lenja-box go-librespot[1968]: net.(*netFD).accept(0x31aa000) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x3222de0 sp=0x3222d78 pc=0x1aa9d8 Mar 25 02:02:24 lenja-box go-librespot[1968]: net.(*TCPListener).accept(0x31c8000) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x3222e34 sp=0x3222de0 pc=0x1c2304 Mar 25 02:02:24 lenja-box go-librespot[1968]: net.(*TCPListener).Accept(0x31c8000) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x3222e50 sp=0x3222e34 pc=0x1c134c Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*onceCloseListener).Accept(0x322c080) Mar 25 02:02:24 lenja-box go-librespot[1968]: :1 +0x34 fp=0x3222e68 sp=0x3222e50 pc=0x367710 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*Server).Serve(0x323c008, {0xa258a8, 0x31c8000}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x3222f00 sp=0x3222e68 pc=0x3408c8 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.Serve(...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/server.go:2971 Mar 25 02:02:24 lenja-box go-librespot[1968]: main.(*ConcreteApiServer).serve(0x302a900) Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x3222fe4 sp=0x3222f00 pc=0x69fdac Mar 25 02:02:24 lenja-box go-librespot[1968]: main.NewApiServer.gowrap1() Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x3222fec sp=0x3222fe4 pc=0x69eae4 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goexit({}) Mar 25 02:02:24 lenja-box systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3222fec sp=0x3222fec pc=0xb0874 Mar 25 02:02:24 lenja-box go-librespot[1968]: created by main.NewApiServer in goroutine 1 Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/cmd/daemon/api_server.go:332 +0x310 Mar 25 02:02:24 lenja-box go-librespot[1968]: goroutine 32 gp=0x31d4488 m=nil [IO wait]: Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gopark(0x960ecc, 0x7515ba10, 0x2, 0x2, 0x5) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3224af4 sp=0x3224ae0 pc=0xa97c0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.netpollblock(0x7515ba00, 0x72, 0x0) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x3224b0c sp=0x3224af4 pc=0x675a0 Mar 25 02:02:24 lenja-box go-librespot[1968]: internal/poll.runtime_pollWait(0x7515ba00, 0x72) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x3224b20 sp=0x3224b0c pc=0xa8864 Mar 25 02:02:24 lenja-box go-librespot[1968]: internal/poll.(*pollDesc).wait(0x308b378, 0x72, 0x0) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x3224b34 sp=0x3224b20 pc=0x134df4 Mar 25 02:02:24 lenja-box go-librespot[1968]: internal/poll.(*pollDesc).waitRead(...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 25 02:02:24 lenja-box go-librespot[1968]: internal/poll.(*FD).Read(0x308b360, {0x3296000, 0x1000, 0x1000}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x3224b7c sp=0x3224b34 pc=0x135f74 Mar 25 02:02:24 lenja-box go-librespot[1968]: net.(*netFD).Read(0x308b360, {0x3296000, 0x1000, 0x1000}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x3224ba8 sp=0x3224b7c pc=0x1a8c28 Mar 25 02:02:24 lenja-box go-librespot[1968]: net.(*conn).Read(0x31ae200, {0x3296000, 0x1000, 0x1000}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x3224bd4 sp=0x3224ba8 pc=0x1b842c Mar 25 02:02:24 lenja-box go-librespot[1968]: crypto/tls.(*atLeastReader).Read(0x31a96b0, {0x3296000, 0x1000, 0x1000}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x3224c00 sp=0x3224bd4 pc=0x266c34 Mar 25 02:02:24 lenja-box go-librespot[1968]: bytes.(*Buffer).ReadFrom(0x31ecf0c, {0xa22858, 0x31a96b0}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x3224c3c sp=0x3224c00 pc=0x160e08 Mar 25 02:02:24 lenja-box go-librespot[1968]: crypto/tls.(*Conn).readFromUntil(0x31ecd88, {0xa21798, 0x31ae200}, 0x5) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x3224c64 sp=0x3224c3c pc=0x266e90 Mar 25 02:02:24 lenja-box go-librespot[1968]: crypto/tls.(*Conn).readRecordOrCCS(0x31ecd88, 0x0) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x3224dc4 sp=0x3224c64 pc=0x2645b4 Mar 25 02:02:24 lenja-box go-librespot[1968]: crypto/tls.(*Conn).readRecord(...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/crypto/tls/conn.go:589 Mar 25 02:02:24 lenja-box go-librespot[1968]: crypto/tls.(*Conn).Read(0x31ecd88, {0x3297000, 0x1000, 0x1000}) Mar 25 02:02:24 lenja-box systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x3224df4 sp=0x3224dc4 pc=0x26a378 Mar 25 02:02:24 lenja-box go-librespot[1968]: bufio.(*Reader).Read(0x31c03c0, {0x30a6424, 0x9, 0x9}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x3224e18 sp=0x3224df4 pc=0x2b6a14 Mar 25 02:02:24 lenja-box go-librespot[1968]: io.ReadAtLeast({0xa219e8, 0x31c03c0}, {0x30a6424, 0x9, 0x9}, 0x9) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x3224e44 sp=0x3224e18 pc=0xee130 Mar 25 02:02:24 lenja-box go-librespot[1968]: io.ReadFull(...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/io/io.go:354 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.http2readFrameHeader({0x30a6424, 0x9, 0x9}, {0xa219e8, 0x31c03c0}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x3224e6c sp=0x3224e44 pc=0x2f7e20 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*http2Framer).ReadFrame(0x30a6400) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x3224ef4 sp=0x3224e6c pc=0x2f8578 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*http2clientConnReadLoop).run(0x3224fdc) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x3224fa4 sp=0x3224ef4 pc=0x31cab0 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*http2ClientConn).readLoop(0x315ea28) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x3224fe4 sp=0x3224fa4 pc=0x31bd78 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x3224fec sp=0x3224fe4 pc=0x3160d4 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goexit({}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3224fec sp=0x3224fec pc=0xb0874 Mar 25 02:02:24 lenja-box go-librespot[1968]: created by net/http.(*http2Transport).newClientConn in goroutine 31 Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Mar 25 02:02:24 lenja-box go-librespot[1968]: goroutine 22 gp=0x31d47e8 m=nil [IO wait]: Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gopark(0x960ecc, 0x7515bc10, 0x2, 0x2, 0x5) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x31818d4 sp=0x31818c0 pc=0xa97c0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.netpollblock(0x7515bc00, 0x72, 0x0) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x31818ec sp=0x31818d4 pc=0x675a0 Mar 25 02:02:24 lenja-box go-librespot[1968]: internal/poll.runtime_pollWait(0x7515bc00, 0x72) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x3181900 sp=0x31818ec pc=0xa8864 Mar 25 02:02:24 lenja-box go-librespot[1968]: internal/poll.(*pollDesc).wait(0x31aa248, 0x72, 0x0) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x3181914 sp=0x3181900 pc=0x134df4 Mar 25 02:02:24 lenja-box go-librespot[1968]: internal/poll.(*pollDesc).waitRead(...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 25 02:02:24 lenja-box go-librespot[1968]: internal/poll.(*FD).Read(0x31aa230, {0x31ee000, 0x1000, 0x1000}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x318195c sp=0x3181914 pc=0x135f74 Mar 25 02:02:24 lenja-box go-librespot[1968]: net.(*netFD).Read(0x31aa230, {0x31ee000, 0x1000, 0x1000}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x3181988 sp=0x318195c pc=0x1a8c28 Mar 25 02:02:24 lenja-box go-librespot[1968]: net.(*conn).Read(0x31ae120, {0x31ee000, 0x1000, 0x1000}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x31819b4 sp=0x3181988 pc=0x1b842c Mar 25 02:02:24 lenja-box go-librespot[1968]: io.(*multiReader).Read(0x31a8170, {0x31ee000, 0x1000, 0x1000}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x31819e0 sp=0x31819b4 pc=0xef364 Mar 25 02:02:24 lenja-box go-librespot[1968]: bufio.(*Reader).fill(0x31c8180) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x3181a04 sp=0x31819e0 pc=0x2b6390 Mar 25 02:02:24 lenja-box go-librespot[1968]: bufio.(*Reader).ReadByte(0x31c8180) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x3181a10 sp=0x3181a04 pc=0x2b6c0c Mar 25 02:02:24 lenja-box go-librespot[1968]: nhooyr.io/websocket.readFrameHeader(0x31c8180, {0x31f603c, 0x8, 0x8}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/frame.go:55 +0x68 fp=0x3181a50 sp=0x3181a10 pc=0x498164 Mar 25 02:02:24 lenja-box go-librespot[1968]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x31f6008, {0xa26090, 0xfdcff0}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:235 +0xb4 fp=0x3181b10 sp=0x3181a50 pc=0x49b01c Mar 25 02:02:24 lenja-box go-librespot[1968]: nhooyr.io/websocket.(*Conn).readLoop(0x31f6008, {0xa26090, 0xfdcff0}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:193 +0x3c fp=0x3181bd4 sp=0x3181b10 pc=0x49ab08 Mar 25 02:02:24 lenja-box go-librespot[1968]: nhooyr.io/websocket.(*Conn).reader(0x31f6008, {0xa26090, 0xfdcff0}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:355 +0xec fp=0x3181c58 sp=0x3181bd4 pc=0x49bc20 Mar 25 02:02:24 lenja-box go-librespot[1968]: nhooyr.io/websocket.(*Conn).Reader(...) Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:39 Mar 25 02:02:24 lenja-box go-librespot[1968]: nhooyr.io/websocket.(*Conn).Read(0x31f6008, {0xa26090, 0xfdcff0}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:47 +0x30 fp=0x3181c80 sp=0x3181c58 pc=0x49a0a0 Mar 25 02:02:24 lenja-box go-librespot[1968]: main.(*ConcreteApiServer).serve.func17({0xa25968, 0x31d64d8}, 0x31d6378) Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/cmd/daemon/api_server.go:636 +0x3b0 fp=0x3181cfc sp=0x3181c80 pc=0x6a062c Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.HandlerFunc.ServeHTTP(0x3218188, {0xa25968, 0x31d64d8}, 0x31d6378) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x3181d0c sp=0x3181cfc pc=0x33cf4c Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*ServeMux).ServeHTTP(0x3210000, {0xa25968, 0x31d64d8}, 0x31d6378) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x3181d4c sp=0x3181d0c pc=0x33ee3c Mar 25 02:02:24 lenja-box go-librespot[1968]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa25968, 0x31d64d8}, 0x31d6378) Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x3181d74 sp=0x3181d4c pc=0x69ffa8 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.HandlerFunc.ServeHTTP(0x3214140, {0xa25968, 0x31d64d8}, 0x31d6378) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x3181d84 sp=0x3181d74 pc=0x33cf4c Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.serverHandler.ServeHTTP({0x323c008}, {0xa25968, 0x31d64d8}, 0x31d6378) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x3181da0 sp=0x3181d84 pc=0x35c2b8 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*conn).serve(0x31d80c0, {0xa260c8, 0x322a1e0}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x3181fdc sp=0x3181da0 pc=0x33b008 Mar 25 02:02:24 lenja-box go-librespot[1968]: net/http.(*Server).Serve.gowrap3() Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x3181fec sp=0x3181fdc pc=0x340d40 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goexit({}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3181fec sp=0x3181fec pc=0xb0874 Mar 25 02:02:24 lenja-box go-librespot[1968]: created by net/http.(*Server).Serve in goroutine 18 Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/net/http/server.go:3493 +0x42c Mar 25 02:02:24 lenja-box go-librespot[1968]: goroutine 24 gp=0x31d4a28 m=nil [select]: Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3045694 sp=0x3045680 pc=0xa97c0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.selectgo(0x30457a8, 0x304576c, 0x0, 0x0, 0x5, 0x1) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x3045744 sp=0x3045694 pc=0x85000 Mar 25 02:02:24 lenja-box go-librespot[1968]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x31f6008) Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:180 +0x144 fp=0x30457e4 sp=0x3045744 pc=0x494d20 Mar 25 02:02:24 lenja-box go-librespot[1968]: nhooyr.io/websocket.newConn.gowrap1() Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x28 fp=0x30457ec sp=0x30457e4 pc=0x4949b0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goexit({}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x30457ec sp=0x30457ec pc=0xb0874 Mar 25 02:02:24 lenja-box go-librespot[1968]: created by nhooyr.io/websocket.newConn in goroutine 22 Mar 25 02:02:24 lenja-box go-librespot[1968]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x578 Mar 25 02:02:24 lenja-box go-librespot[1968]: goroutine 27 gp=0x31d4c68 m=nil [GC worker (idle)]: Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gopark(0x960d90, 0x32d0600, 0x1c, 0xa, 0x0) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3045f88 sp=0x3045f74 pc=0xa97c0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gcBgMarkWorker(0x341db40) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x3045fe4 sp=0x3045f88 pc=0x49f34 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x3045fec sp=0x3045fe4 pc=0x49e14 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goexit({}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3045fec sp=0x3045fec pc=0xb0874 Mar 25 02:02:24 lenja-box go-librespot[1968]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 25 02:02:24 lenja-box go-librespot[1968]: goroutine 28 gp=0x31d4d88 m=nil [GC worker (idle)]: Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gopark(0x960d90, 0x32d0800, 0x1c, 0xa, 0x0) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3046788 sp=0x3046774 pc=0xa97c0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gcBgMarkWorker(0x341db40) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x30467e4 sp=0x3046788 pc=0x49f34 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x30467ec sp=0x30467e4 pc=0x49e14 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goexit({}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x30467ec sp=0x30467ec pc=0xb0874 Mar 25 02:02:24 lenja-box go-librespot[1968]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 25 02:02:24 lenja-box go-librespot[1968]: goroutine 29 gp=0x31d4ea8 m=nil [GC worker (idle)]: Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gopark(0x960d90, 0x32d0a00, 0x1c, 0xa, 0x0) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3046f88 sp=0x3046f74 pc=0xa97c0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gcBgMarkWorker(0x341db40) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x3046fe4 sp=0x3046f88 pc=0x49f34 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x3046fec sp=0x3046fe4 pc=0x49e14 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goexit({}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3046fec sp=0x3046fec pc=0xb0874 Mar 25 02:02:24 lenja-box go-librespot[1968]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 25 02:02:24 lenja-box go-librespot[1968]: goroutine 30 gp=0x31d4fc8 m=nil [GC worker (idle)]: Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gopark(0x960d90, 0x32d0c00, 0x1c, 0xa, 0x0) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3221f88 sp=0x3221f74 pc=0xa97c0 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gcBgMarkWorker(0x341db40) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x3221fe4 sp=0x3221f88 pc=0x49f34 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x3221fec sp=0x3221fe4 pc=0x49e14 Mar 25 02:02:24 lenja-box go-librespot[1968]: runtime.goexit({}) Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3221fec sp=0x3221fec pc=0xb0874 Mar 25 02:02:24 lenja-box go-librespot[1968]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Mar 25 02:02:24 lenja-box go-librespot[1968]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 25 02:02:24 lenja-box go-librespot[1967]: Aborted Mar 25 02:02:24 lenja-box volumio[1245]: info: Getting Spotify volume Mar 25 02:02:25 lenja-box volumio[1245]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.58 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Mar 25 02:02:25 lenja-box volumio[1245]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 02:02:25 lenja-box volumio[1245]: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 02:02:25 lenja-box volumio[1245]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 25 02:02:25 lenja-box volumio[1245]: errno: -111, Mar 25 02:02:25 lenja-box volumio[1245]: code: 'ECONNREFUSED', Mar 25 02:02:25 lenja-box volumio[1245]: syscall: 'connect', Mar 25 02:02:25 lenja-box volumio[1245]: address: '127.0.0.1', Mar 25 02:02:25 lenja-box volumio[1245]: port: 9879, Mar 25 02:02:25 lenja-box volumio[1245]: response: undefined Mar 25 02:02:25 lenja-box volumio[1245]: } Mar 25 02:02:25 lenja-box volumio[1245]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 02:02:27 lenja-box systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Mar 25 02:02:27 lenja-box systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 02:02:27 lenja-box systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 02:02:27 lenja-box go-librespot[2066]: go-librespot daemon starting... Mar 25 02:02:27 lenja-box go-librespot[2067]: time="2026-03-25T02:02:27+01:00" level=info msg="running go-librespot 0.6.2" Mar 25 02:02:27 lenja-box go-librespot[2067]: time="2026-03-25T02:02:27+01:00" level=debug msg="app state loaded" Mar 25 02:02:27 lenja-box go-librespot[2067]: time="2026-03-25T02:02:27+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 02:02:28 lenja-box go-librespot[2067]: unexpected fault address 0xff2ffcd4 Mar 25 02:02:28 lenja-box go-librespot[2067]: fatal error: fault Mar 25 02:02:28 lenja-box go-librespot[2067]: [signal SIGSEGV: segmentation violation code=0x1 addr=0xff2ffcd4 pc=0xff2ffcd4] Mar 25 02:02:28 lenja-box go-librespot[2067]: goroutine 36 gp=0x165fb08 m=4 mp=0x144d808 [running]: Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.throw({0x9046af, 0x5}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/panic.go:1094 +0x34 fp=0x145baa0 sp=0x145ba8c pc=0xa9680 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.sigpanic() Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/signal_unix.go:939 +0x2a8 fp=0x145bad0 sp=0x145baa0 pc=0xabbc8 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*http2ClientConn).writeHeader(0x1482d88, {0x906ea9, 0xa}, {0x917afc, 0x15}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:9348 +0x118 fp=0x145bb34 sp=0x145bad4 pc=0x31b858 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*http2clientStream).encodeAndWriteHeaders.func1({0x906ea9, 0xa}, {0x917afc, 0x15}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:8893 +0x3c fp=0x145bb4c sp=0x145bb34 pc=0x319b34 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http/internal/httpcommon.EncodeHeaders.func3({0x906ea9, 0xa}, {0x917afc, 0x15}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/internal/httpcommon/httpcommon.go:405 +0x74 fp=0x145bb74 sp=0x145bb4c pc=0x2e568c Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http/internal/httpcommon.EncodeHeaders.func1(0x145bca4) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/internal/httpcommon/httpcommon.go:289 +0x80 fp=0x145bc0c sp=0x145bb74 pc=0x2e57e4 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http/internal/httpcommon.EncodeHeaders({0xa2611c, 0x16445f0}, {{0x16445a0, {0x903a7b, 0x3}, {0x0, 0x0}, 0x146fac0, 0x0, 0x0}, ...}, ...) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/internal/httpcommon/httpcommon.go:397 +0x5ac fp=0x145bd54 sp=0x145bc0c pc=0x2e5310 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.http2encodeRequestHeaders(0x1640588, 0x1, 0xffffffffffffffff, 0x1748e64) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:8909 +0x168 fp=0x145bde8 sp=0x145bd54 pc=0x319d04 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*http2clientStream).encodeAndWriteHeaders(0x157cff8, 0x1640588) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:8892 +0x214 fp=0x145be70 sp=0x145bde8 pc=0x319964 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*http2clientStream).writeRequest(0x157cff8, 0x1640588, 0x0) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:8794 +0x67c fp=0x145bfc4 sp=0x145be70 pc=0x318ff0 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*http2clientStream).doRequest(0x157cff8, 0x1640588, 0x0) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:8711 +0x60 fp=0x145bfdc sp=0x145bfc4 pc=0x318948 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*http2ClientConn).roundTrip.gowrap1() Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:8616 +0x38 fp=0x145bfec sp=0x145bfdc pc=0x3188bc Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goexit({}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x145bfec sp=0x145bfec pc=0xb0874 Mar 25 02:02:28 lenja-box go-librespot[2067]: created by net/http.(*http2ClientConn).roundTrip in goroutine 1 Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:8616 +0x4a4 Mar 25 02:02:28 lenja-box go-librespot[2067]: goroutine 1 gp=0x1402128 m=nil [select]: Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x15af600 sp=0x15af5ec pc=0xa97c0 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.selectgo(0x15af760, 0x15af6e0, 0x0, 0x0, 0x4, 0x1) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x15af6b0 sp=0x15af600 pc=0x85000 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*http2ClientConn).roundTrip(0x1482d88, 0x1640588, 0x0) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:8680 +0x538 fp=0x15af7c4 sp=0x15af6b0 pc=0x318068 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*http2ClientConn).RoundTrip(...) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:8595 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*http2Transport).RoundTripOpt(0x168aa80, 0x1640588, {0x0, 0x0}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:7921 +0x24c fp=0x15af864 sp=0x15af7c4 pc=0x314060 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*http2Transport).RoundTrip(0x168aa80, 0x1640588) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:7872 +0x30 fp=0x15af880 sp=0x15af864 pc=0x313ae0 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*Transport).roundTrip(0xf812a8, 0x1640588) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/transport.go:694 +0x9b4 fp=0x15af96c sp=0x15af880 pc=0x34d564 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*Transport).RoundTrip(0xf812a8, 0x1640588) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/roundtrip.go:33 +0x2c fp=0x15af984 sp=0x15af96c pc=0x35ba84 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.send(0x1640428, {0xa21b28, 0xf812a8}, {0xc268eaf074469444, 0x70808b15b, 0xfd1e68}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/client.go:259 +0x53c fp=0x15afab0 sp=0x15af984 pc=0x2ed3ec Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*Client).send(0x1502f20, 0x1640428, {0xc268eaf074469444, 0x70808b15b, 0xfd1e68}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/client.go:180 +0x98 fp=0x15afaf4 sp=0x15afab0 pc=0x2eccd8 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*Client).do(0x1502f20, 0x1640428) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/client.go:729 +0x9a8 fp=0x15afc04 sp=0x15afaf4 pc=0x2ef340 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*Client).Do(...) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/client.go:587 Mar 25 02:02:28 lenja-box go-librespot[2067]: github.com/devgianlu/go-librespot/apresolve.(*ApResolver).fetchUrls(0x14001e0, {0xa260ac, 0xfdcff0}, {0x15afeb0, 0x3, 0x3}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /src/apresolve/resolve.go:83 +0x4d0 fp=0x15afe3c sp=0x15afc04 pc=0x597990 Mar 25 02:02:28 lenja-box go-librespot[2067]: github.com/devgianlu/go-librespot/apresolve.(*ApResolver).FetchAll(...) Mar 25 02:02:28 lenja-box go-librespot[2067]: /src/apresolve/resolve.go:122 Mar 25 02:02:28 lenja-box go-librespot[2067]: main.(*App).withAppPlayer(0x1518500, {0xa260ac, 0xfdcff0}, 0x1410a40) Mar 25 02:02:28 lenja-box go-librespot[2067]: /src/cmd/daemon/main.go:251 +0x94 fp=0x15afed0 sp=0x15afe3c pc=0x6aab9c Mar 25 02:02:28 lenja-box go-librespot[2067]: main.(*App).withCredentials(...) Mar 25 02:02:28 lenja-box go-librespot[2067]: /src/cmd/daemon/main.go:209 Mar 25 02:02:28 lenja-box go-librespot[2067]: main.(*App).SpotifyToken(0x1518500, {0xa260ac, 0xfdcff0}, {0x15125e0, 0x1c}, {0x15a6000, 0x13e}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x15aff00 sp=0x15afed0 pc=0x6aa4b0 Mar 25 02:02:28 lenja-box go-librespot[2067]: main.main() Mar 25 02:02:28 lenja-box go-librespot[2067]: /src/cmd/daemon/main.go:593 +0x660 fp=0x15affa8 sp=0x15aff00 pc=0x6ad58c Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.main() Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x15affec sp=0x15affa8 pc=0x6f390 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goexit({}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x15affec sp=0x15affec pc=0xb0874 Mar 25 02:02:28 lenja-box go-librespot[2067]: goroutine 2 gp=0x14027e8 m=nil [force gc (idle)]: Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gopark(0x960edc, 0xfd0f08, 0x11, 0xa, 0x1) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1448fd4 sp=0x1448fc0 pc=0xa97c0 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goparkunlock(...) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.forcegchelper() Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x1448fec sp=0x1448fd4 pc=0x6f7f4 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goexit({}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1448fec sp=0x1448fec pc=0xb0874 Mar 25 02:02:28 lenja-box go-librespot[2067]: created by runtime.init.6 in goroutine 1 Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:361 +0x1c Mar 25 02:02:28 lenja-box go-librespot[2067]: goroutine 3 gp=0x1402b48 m=nil [GC sweep wait]: Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gopark(0x960edc, 0xfd17b0, 0xc, 0x9, 0x1) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x14497c4 sp=0x14497b0 pc=0xa97c0 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goparkunlock(...) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.bgsweep(0x142e050) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x14497e4 sp=0x14497c4 pc=0x5768c Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gcenable.gowrap1() Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x14497ec sp=0x14497e4 pc=0x46c3c Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goexit({}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x14497ec sp=0x14497ec pc=0xb0874 Mar 25 02:02:28 lenja-box go-librespot[2067]: created by runtime.gcenable in goroutine 1 Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgc.go:212 +0x74 Mar 25 02:02:28 lenja-box go-librespot[2067]: goroutine 4 gp=0x1402c68 m=nil [GC scavenge wait]: Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gopark(0x960edc, 0xfd20d0, 0xd, 0xa, 0x2) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1449fb4 sp=0x1449fa0 pc=0xa97c0 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goparkunlock(...) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.(*scavengerState).park(0xfd20d0) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x1449fc8 sp=0x1449fb4 pc=0x54a28 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.bgscavenge(0x142e050) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x1449fe4 sp=0x1449fc8 pc=0x5516c Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gcenable.gowrap2() Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x1449fec sp=0x1449fe4 pc=0x46be8 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goexit({}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1449fec sp=0x1449fec pc=0xb0874 Mar 25 02:02:28 lenja-box go-librespot[2067]: created by runtime.gcenable in goroutine 1 Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgc.go:213 +0xbc Mar 25 02:02:28 lenja-box go-librespot[2067]: goroutine 18 gp=0x1482248 m=nil [GOMAXPROCS updater (idle)]: Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gopark(0x960edc, 0xfd1548, 0x12, 0xa, 0x1) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x14447a4 sp=0x1444790 pc=0xa97c0 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goparkunlock(...) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.updateMaxProcsGoroutine() Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x14447ec sp=0x14447a4 pc=0x7f174 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goexit({}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x14447ec sp=0x14447ec pc=0xb0874 Mar 25 02:02:28 lenja-box go-librespot[2067]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:6708 +0x40 Mar 25 02:02:28 lenja-box go-librespot[2067]: goroutine 19 gp=0x14825a8 m=nil [finalizer wait]: Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gopark(0x960d84, 0xfdd0e0, 0x10, 0xa, 0x1) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1444f88 sp=0x1444f74 pc=0xa97c0 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.runFinalizers() Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x1444fec sp=0x1444f88 pc=0x45888 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goexit({}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1444fec sp=0x1444fec pc=0xb0874 Mar 25 02:02:28 lenja-box go-librespot[2067]: created by runtime.createfing in goroutine 1 Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c Mar 25 02:02:28 lenja-box go-librespot[2067]: goroutine 5 gp=0x165f328 m=nil [cleanup wait]: Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gopark(0x960edc, 0xfd22a8, 0x2e, 0xa, 0x1) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x144a7a4 sp=0x144a790 pc=0xa97c0 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goparkunlock(...) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.(*cleanupQueue).dequeue(0xfd2248) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x144a7c4 sp=0x144a7a4 pc=0x41cb0 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.runCleanups() Mar 25 02:02:28 lenja-box systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x144a7ec sp=0x144a7c4 pc=0x4284c Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goexit({}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x144a7ec sp=0x144a7ec pc=0xb0874 Mar 25 02:02:28 lenja-box go-librespot[2067]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 Mar 25 02:02:28 lenja-box go-librespot[2067]: goroutine 8 gp=0x165f9e8 m=nil [IO wait]: Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gopark(0x960ecc, 0x7582c010, 0x2, 0x2, 0x5) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x144b4f0 sp=0x144b4dc pc=0xa97c0 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.netpollblock(0x7582c000, 0x72, 0x0) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x144b508 sp=0x144b4f0 pc=0x675a0 Mar 25 02:02:28 lenja-box go-librespot[2067]: internal/poll.runtime_pollWait(0x7582c000, 0x72) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x144b51c sp=0x144b508 pc=0xa8864 Mar 25 02:02:28 lenja-box go-librespot[2067]: internal/poll.(*pollDesc).wait(0x1644568, 0x72, 0x0) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x144b530 sp=0x144b51c pc=0x134df4 Mar 25 02:02:28 lenja-box go-librespot[2067]: internal/poll.(*pollDesc).waitRead(...) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 25 02:02:28 lenja-box go-librespot[2067]: internal/poll.(*FD).Accept(0x1644550) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x144b578 sp=0x144b530 pc=0x1395b0 Mar 25 02:02:28 lenja-box go-librespot[2067]: net.(*netFD).accept(0x1644550) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x144b5e0 sp=0x144b578 pc=0x1aa9d8 Mar 25 02:02:28 lenja-box go-librespot[2067]: net.(*TCPListener).accept(0x1400360) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x144b634 sp=0x144b5e0 pc=0x1c2304 Mar 25 02:02:28 lenja-box go-librespot[2067]: net.(*TCPListener).Accept(0x1400360) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x144b650 sp=0x144b634 pc=0x1c134c Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*onceCloseListener).Accept(0x14a6260) Mar 25 02:02:28 lenja-box go-librespot[2067]: :1 +0x34 fp=0x144b668 sp=0x144b650 pc=0x367710 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*Server).Serve(0x14d8008, {0xa258a8, 0x1400360}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x144b700 sp=0x144b668 pc=0x3408c8 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.Serve(...) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/server.go:2971 Mar 25 02:02:28 lenja-box go-librespot[2067]: main.(*ConcreteApiServer).serve(0x142a600) Mar 25 02:02:28 lenja-box go-librespot[2067]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x144b7e4 sp=0x144b700 pc=0x69fdac Mar 25 02:02:28 lenja-box go-librespot[2067]: main.NewApiServer.gowrap1() Mar 25 02:02:28 lenja-box go-librespot[2067]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x144b7ec sp=0x144b7e4 pc=0x69eae4 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goexit({}) Mar 25 02:02:28 lenja-box systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x144b7ec sp=0x144b7ec pc=0xb0874 Mar 25 02:02:28 lenja-box go-librespot[2067]: created by main.NewApiServer in goroutine 1 Mar 25 02:02:28 lenja-box go-librespot[2067]: /src/cmd/daemon/api_server.go:332 +0x310 Mar 25 02:02:28 lenja-box go-librespot[2067]: goroutine 35 gp=0x165fe68 m=nil [IO wait]: Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gopark(0x960ecc, 0x7582be10, 0x2, 0x2, 0x5) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x190daf4 sp=0x190dae0 pc=0xa97c0 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.netpollblock(0x7582be00, 0x72, 0x0) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x190db0c sp=0x190daf4 pc=0x675a0 Mar 25 02:02:28 lenja-box go-librespot[2067]: internal/poll.runtime_pollWait(0x7582be00, 0x72) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x190db20 sp=0x190db0c pc=0xa8864 Mar 25 02:02:28 lenja-box go-librespot[2067]: internal/poll.(*pollDesc).wait(0x16447e8, 0x72, 0x0) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x190db34 sp=0x190db20 pc=0x134df4 Mar 25 02:02:28 lenja-box go-librespot[2067]: internal/poll.(*pollDesc).waitRead(...) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 25 02:02:28 lenja-box go-librespot[2067]: internal/poll.(*FD).Read(0x16447d0, {0x14fe000, 0x1000, 0x1000}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x190db7c sp=0x190db34 pc=0x135f74 Mar 25 02:02:28 lenja-box go-librespot[2067]: net.(*netFD).Read(0x16447d0, {0x14fe000, 0x1000, 0x1000}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x190dba8 sp=0x190db7c pc=0x1a8c28 Mar 25 02:02:28 lenja-box go-librespot[2067]: net.(*conn).Read(0x149c268, {0x14fe000, 0x1000, 0x1000}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x190dbd4 sp=0x190dba8 pc=0x1b842c Mar 25 02:02:28 lenja-box go-librespot[2067]: crypto/tls.(*atLeastReader).Read(0x174b740, {0x14fe000, 0x1000, 0x1000}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x190dc00 sp=0x190dbd4 pc=0x266c34 Mar 25 02:02:28 lenja-box go-librespot[2067]: bytes.(*Buffer).ReadFrom(0x14da18c, {0xa22858, 0x174b740}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x190dc3c sp=0x190dc00 pc=0x160e08 Mar 25 02:02:28 lenja-box go-librespot[2067]: crypto/tls.(*Conn).readFromUntil(0x14da008, {0xa21798, 0x149c268}, 0x5) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x190dc64 sp=0x190dc3c pc=0x266e90 Mar 25 02:02:28 lenja-box go-librespot[2067]: crypto/tls.(*Conn).readRecordOrCCS(0x14da008, 0x0) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x190ddc4 sp=0x190dc64 pc=0x2645b4 Mar 25 02:02:28 lenja-box go-librespot[2067]: crypto/tls.(*Conn).readRecord(...) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/crypto/tls/conn.go:589 Mar 25 02:02:28 lenja-box go-librespot[2067]: crypto/tls.(*Conn).Read(0x14da008, {0x1571000, 0x1000, 0x1000}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x190ddf4 sp=0x190ddc4 pc=0x26a378 Mar 25 02:02:28 lenja-box go-librespot[2067]: bufio.(*Reader).Read(0x18530b0, {0x14ce0a4, 0x9, 0x9}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x190de18 sp=0x190ddf4 pc=0x2b6a14 Mar 25 02:02:28 lenja-box go-librespot[2067]: io.ReadAtLeast({0xa219e8, 0x18530b0}, {0x14ce0a4, 0x9, 0x9}, 0x9) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x190de44 sp=0x190de18 pc=0xee130 Mar 25 02:02:28 lenja-box go-librespot[2067]: io.ReadFull(...) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/io/io.go:354 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.http2readFrameHeader({0x14ce0a4, 0x9, 0x9}, {0xa219e8, 0x18530b0}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x190de6c sp=0x190de44 pc=0x2f7e20 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*http2Framer).ReadFrame(0x14ce080) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x190def4 sp=0x190de6c pc=0x2f8578 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*http2clientConnReadLoop).run(0x190dfdc) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x190dfa4 sp=0x190def4 pc=0x31cab0 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*http2ClientConn).readLoop(0x1482d88) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x190dfe4 sp=0x190dfa4 pc=0x31bd78 Mar 25 02:02:28 lenja-box go-librespot[2067]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x190dfec sp=0x190dfe4 pc=0x3160d4 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goexit({}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x190dfec sp=0x190dfec pc=0xb0874 Mar 25 02:02:28 lenja-box go-librespot[2067]: created by net/http.(*http2Transport).newClientConn in goroutine 34 Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Mar 25 02:02:28 lenja-box go-librespot[2067]: goroutine 22 gp=0x1482908 m=nil [GC worker (idle)]: Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gopark(0x960d90, 0x14ca600, 0x1c, 0xa, 0x0) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1445788 sp=0x1445774 pc=0xa97c0 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gcBgMarkWorker(0x1766040) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x14457e4 sp=0x1445788 pc=0x49f34 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x14457ec sp=0x14457e4 pc=0x49e14 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goexit({}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x14457ec sp=0x14457ec pc=0xb0874 Mar 25 02:02:28 lenja-box go-librespot[2067]: created by runtime.gcBgMarkStartWorkers in goroutine 20 Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 25 02:02:28 lenja-box go-librespot[2067]: goroutine 23 gp=0x1482a28 m=nil [GC worker (idle)]: Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gopark(0x960d90, 0x14ca800, 0x1c, 0xa, 0x0) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1445f88 sp=0x1445f74 pc=0xa97c0 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gcBgMarkWorker(0x1766040) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x1445fe4 sp=0x1445f88 pc=0x49f34 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x1445fec sp=0x1445fe4 pc=0x49e14 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goexit({}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1445fec sp=0x1445fec pc=0xb0874 Mar 25 02:02:28 lenja-box go-librespot[2067]: created by runtime.gcBgMarkStartWorkers in goroutine 20 Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 25 02:02:28 lenja-box go-librespot[2067]: goroutine 24 gp=0x1482b48 m=nil [GC worker (idle)]: Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gopark(0x960d90, 0x14caa00, 0x1c, 0xa, 0x0) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x190ff88 sp=0x190ff74 pc=0xa97c0 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gcBgMarkWorker(0x1766040) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x190ffe4 sp=0x190ff88 pc=0x49f34 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x190ffec sp=0x190ffe4 pc=0x49e14 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goexit({}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x190ffec sp=0x190ffec pc=0xb0874 Mar 25 02:02:28 lenja-box go-librespot[2067]: created by runtime.gcBgMarkStartWorkers in goroutine 20 Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 25 02:02:28 lenja-box go-librespot[2067]: goroutine 25 gp=0x1482c68 m=nil [GC worker (idle)]: Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gopark(0x960d90, 0x14cac00, 0x1c, 0xa, 0x0) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1446f88 sp=0x1446f74 pc=0xa97c0 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gcBgMarkWorker(0x1766040) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x1446fe4 sp=0x1446f88 pc=0x49f34 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x1446fec sp=0x1446fe4 pc=0x49e14 Mar 25 02:02:28 lenja-box go-librespot[2067]: runtime.goexit({}) Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1446fec sp=0x1446fec pc=0xb0874 Mar 25 02:02:28 lenja-box go-librespot[2067]: created by runtime.gcBgMarkStartWorkers in goroutine 20 Mar 25 02:02:28 lenja-box go-librespot[2067]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 25 02:02:28 lenja-box go-librespot[2066]: Aborted Mar 25 02:02:31 lenja-box systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Mar 25 02:02:31 lenja-box systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 02:02:31 lenja-box systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 02:02:31 lenja-box go-librespot[2111]: go-librespot daemon starting... Mar 25 02:02:31 lenja-box go-librespot[2113]: time="2026-03-25T02:02:31+01:00" level=info msg="running go-librespot 0.6.2" Mar 25 02:02:31 lenja-box go-librespot[2113]: time="2026-03-25T02:02:31+01:00" level=debug msg="app state loaded" Mar 25 02:02:32 lenja-box go-librespot[2113]: time="2026-03-25T02:02:32+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 02:02:32 lenja-box go-librespot[2113]: unexpected fault address 0xff2ffcd4 Mar 25 02:02:32 lenja-box go-librespot[2113]: fatal error: fault Mar 25 02:02:32 lenja-box go-librespot[2113]: [signal SIGSEGV: segmentation violation code=0x1 addr=0xff2ffcd4 pc=0xff2ffcd4] Mar 25 02:02:32 lenja-box go-librespot[2113]: goroutine 27 gp=0x2483c28 m=3 mp=0x244d008 [running]: Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.throw({0x9046af, 0x5}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/panic.go:1094 +0x34 fp=0x269baa0 sp=0x269ba8c pc=0xa9680 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.sigpanic() Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/signal_unix.go:939 +0x2a8 fp=0x269bad0 sp=0x269baa0 pc=0xabbc8 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*http2ClientConn).writeHeader(0x26d2248, {0x906ea9, 0xa}, {0x917afc, 0x15}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:9348 +0x118 fp=0x269bb34 sp=0x269bad4 pc=0x31b858 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*http2clientStream).encodeAndWriteHeaders.func1({0x906ea9, 0xa}, {0x917afc, 0x15}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:8893 +0x3c fp=0x269bb4c sp=0x269bb34 pc=0x319b34 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http/internal/httpcommon.EncodeHeaders.func3({0x906ea9, 0xa}, {0x917afc, 0x15}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/internal/httpcommon/httpcommon.go:405 +0x74 fp=0x269bb74 sp=0x269bb4c pc=0x2e568c Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http/internal/httpcommon.EncodeHeaders.func1(0x269bca4) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/internal/httpcommon/httpcommon.go:289 +0x80 fp=0x269bc0c sp=0x269bb74 pc=0x2e57e4 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http/internal/httpcommon.EncodeHeaders({0xa2611c, 0x24a4140}, {{0x24a40f0, {0x903a7b, 0x3}, {0x0, 0x0}, 0x24a6de0, 0x0, 0x0}, ...}, ...) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/internal/httpcommon/httpcommon.go:397 +0x5ac fp=0x269bd54 sp=0x269bc0c pc=0x2e5310 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.http2encodeRequestHeaders(0x24fe638, 0x1, 0xffffffffffffffff, 0x2445664) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:8909 +0x168 fp=0x269bde8 sp=0x269bd54 pc=0x319d04 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*http2clientStream).encodeAndWriteHeaders(0x2704008, 0x24fe638) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:8892 +0x214 fp=0x269be70 sp=0x269bde8 pc=0x319964 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*http2clientStream).writeRequest(0x2704008, 0x24fe638, 0x0) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:8794 +0x67c fp=0x269bfc4 sp=0x269be70 pc=0x318ff0 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*http2clientStream).doRequest(0x2704008, 0x24fe638, 0x0) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:8711 +0x60 fp=0x269bfdc sp=0x269bfc4 pc=0x318948 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*http2ClientConn).roundTrip.gowrap1() Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:8616 +0x38 fp=0x269bfec sp=0x269bfdc pc=0x3188bc Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goexit({}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x269bfec sp=0x269bfec pc=0xb0874 Mar 25 02:02:32 lenja-box go-librespot[2113]: created by net/http.(*http2ClientConn).roundTrip in goroutine 1 Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:8616 +0x4a4 Mar 25 02:02:32 lenja-box go-librespot[2113]: goroutine 1 gp=0x2402128 m=nil [select]: Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x25c5600 sp=0x25c55ec pc=0xa97c0 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.selectgo(0x25c5760, 0x25c56e0, 0x0, 0x0, 0x4, 0x1) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x25c56b0 sp=0x25c5600 pc=0x85000 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*http2ClientConn).roundTrip(0x26d2248, 0x24fe638, 0x0) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:8680 +0x538 fp=0x25c57c4 sp=0x25c56b0 pc=0x318068 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*http2ClientConn).RoundTrip(...) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:8595 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*http2Transport).RoundTripOpt(0x252c150, 0x24fe638, {0x0, 0x0}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:7921 +0x24c fp=0x25c5864 sp=0x25c57c4 pc=0x314060 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*http2Transport).RoundTrip(0x252c150, 0x24fe638) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:7872 +0x30 fp=0x25c5880 sp=0x25c5864 pc=0x313ae0 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*Transport).roundTrip(0xf812a8, 0x24fe638) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/transport.go:694 +0x9b4 fp=0x25c596c sp=0x25c5880 pc=0x34d564 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*Transport).RoundTrip(0xf812a8, 0x24fe638) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/roundtrip.go:33 +0x2c fp=0x25c5984 sp=0x25c596c pc=0x35ba84 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.send(0x24fe4d8, {0xa21b28, 0xf812a8}, {0xc268eaf180670311, 0x709ad206e, 0xfd1e68}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/client.go:259 +0x53c fp=0x25c5ab0 sp=0x25c5984 pc=0x2ed3ec Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*Client).send(0x246e5c0, 0x24fe4d8, {0xc268eaf180670311, 0x709ad206e, 0xfd1e68}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/client.go:180 +0x98 fp=0x25c5af4 sp=0x25c5ab0 pc=0x2eccd8 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*Client).do(0x246e5c0, 0x24fe4d8) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/client.go:729 +0x9a8 fp=0x25c5c04 sp=0x25c5af4 pc=0x2ef340 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*Client).Do(...) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/client.go:587 Mar 25 02:02:32 lenja-box go-librespot[2113]: github.com/devgianlu/go-librespot/apresolve.(*ApResolver).fetchUrls(0x2400090, {0xa260ac, 0xfdcff0}, {0x25c5eb0, 0x3, 0x3}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /src/apresolve/resolve.go:83 +0x4d0 fp=0x25c5e3c sp=0x25c5c04 pc=0x597990 Mar 25 02:02:32 lenja-box go-librespot[2113]: github.com/devgianlu/go-librespot/apresolve.(*ApResolver).FetchAll(...) Mar 25 02:02:32 lenja-box go-librespot[2113]: /src/apresolve/resolve.go:122 Mar 25 02:02:32 lenja-box go-librespot[2113]: main.(*App).withAppPlayer(0x25ac300, {0xa260ac, 0xfdcff0}, 0x24ac200) Mar 25 02:02:32 lenja-box go-librespot[2113]: /src/cmd/daemon/main.go:251 +0x94 fp=0x25c5ed0 sp=0x25c5e3c pc=0x6aab9c Mar 25 02:02:32 lenja-box go-librespot[2113]: main.(*App).withCredentials(...) Mar 25 02:02:32 lenja-box go-librespot[2113]: /src/cmd/daemon/main.go:209 Mar 25 02:02:32 lenja-box go-librespot[2113]: main.(*App).SpotifyToken(0x25ac300, {0xa260ac, 0xfdcff0}, {0x265a7e0, 0x1c}, {0x2680140, 0x13e}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x25c5f00 sp=0x25c5ed0 pc=0x6aa4b0 Mar 25 02:02:32 lenja-box go-librespot[2113]: main.main() Mar 25 02:02:32 lenja-box go-librespot[2113]: /src/cmd/daemon/main.go:593 +0x660 fp=0x25c5fa8 sp=0x25c5f00 pc=0x6ad58c Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.main() Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x25c5fec sp=0x25c5fa8 pc=0x6f390 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goexit({}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x25c5fec sp=0x25c5fec pc=0xb0874 Mar 25 02:02:32 lenja-box go-librespot[2113]: goroutine 2 gp=0x24027e8 m=nil [force gc (idle)]: Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gopark(0x960edc, 0xfd0f08, 0x11, 0xa, 0x1) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2448fd4 sp=0x2448fc0 pc=0xa97c0 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goparkunlock(...) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.forcegchelper() Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x2448fec sp=0x2448fd4 pc=0x6f7f4 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goexit({}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2448fec sp=0x2448fec pc=0xb0874 Mar 25 02:02:32 lenja-box go-librespot[2113]: created by runtime.init.6 in goroutine 1 Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:361 +0x1c Mar 25 02:02:32 lenja-box go-librespot[2113]: goroutine 3 gp=0x2402b48 m=nil [GC sweep wait]: Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gopark(0x960edc, 0xfd17b0, 0xc, 0x9, 0x1) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24497c4 sp=0x24497b0 pc=0xa97c0 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goparkunlock(...) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.bgsweep(0x242e050) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x24497e4 sp=0x24497c4 pc=0x5768c Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gcenable.gowrap1() Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x24497ec sp=0x24497e4 pc=0x46c3c Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goexit({}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24497ec sp=0x24497ec pc=0xb0874 Mar 25 02:02:32 lenja-box go-librespot[2113]: created by runtime.gcenable in goroutine 1 Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgc.go:212 +0x74 Mar 25 02:02:32 lenja-box go-librespot[2113]: goroutine 4 gp=0x2402c68 m=nil [GC scavenge wait]: Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gopark(0x960edc, 0xfd20d0, 0xd, 0xa, 0x2) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2449fb4 sp=0x2449fa0 pc=0xa97c0 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goparkunlock(...) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.(*scavengerState).park(0xfd20d0) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2449fc8 sp=0x2449fb4 pc=0x54a28 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.bgscavenge(0x242e050) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2449fe4 sp=0x2449fc8 pc=0x5516c Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gcenable.gowrap2() Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x2449fec sp=0x2449fe4 pc=0x46be8 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goexit({}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2449fec sp=0x2449fec pc=0xb0874 Mar 25 02:02:32 lenja-box go-librespot[2113]: created by runtime.gcenable in goroutine 1 Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgc.go:213 +0xbc Mar 25 02:02:32 lenja-box go-librespot[2113]: goroutine 18 gp=0x2482248 m=nil [GOMAXPROCS updater (idle)]: Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gopark(0x960edc, 0xfd1548, 0x12, 0xa, 0x1) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24447a4 sp=0x2444790 pc=0xa97c0 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goparkunlock(...) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.updateMaxProcsGoroutine() Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x24447ec sp=0x24447a4 pc=0x7f174 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goexit({}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24447ec sp=0x24447ec pc=0xb0874 Mar 25 02:02:32 lenja-box go-librespot[2113]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:6708 +0x40 Mar 25 02:02:32 lenja-box go-librespot[2113]: goroutine 19 gp=0x24825a8 m=nil [finalizer wait]: Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gopark(0x960d84, 0xfdd0e0, 0x10, 0xa, 0x1) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2444f88 sp=0x2444f74 pc=0xa97c0 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.runFinalizers() Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x2444fec sp=0x2444f88 pc=0x45888 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goexit({}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2444fec sp=0x2444fec pc=0xb0874 Mar 25 02:02:32 lenja-box go-librespot[2113]: created by runtime.createfing in goroutine 1 Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c Mar 25 02:02:32 lenja-box go-librespot[2113]: goroutine 34 gp=0x2609328 m=nil [cleanup wait]: Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gopark(0x960edc, 0xfd22a8, 0x2e, 0xa, 0x1) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26767a4 sp=0x2676790 pc=0xa97c0 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goparkunlock(...) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:466 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.(*cleanupQueue).dequeue(0xfd2248) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x26767c4 sp=0x26767a4 pc=0x41cb0 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.runCleanups() Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x26767ec sp=0x26767c4 pc=0x4284c Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goexit({}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26767ec sp=0x26767ec pc=0xb0874 Mar 25 02:02:32 lenja-box go-librespot[2113]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 Mar 25 02:02:32 lenja-box go-librespot[2113]: goroutine 7 gp=0x25c8488 m=nil [GC worker (idle)]: Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gopark(0x960d90, 0x25dc800, 0x1c, 0xa, 0x0) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x244af88 sp=0x244af74 pc=0xa97c0 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gcBgMarkWorker(0x2830d40) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x244afe4 sp=0x244af88 pc=0x49f34 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 25 02:02:32 lenja-box systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x244afec sp=0x244afe4 pc=0x49e14 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goexit({}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x244afec sp=0x244afec pc=0xb0874 Mar 25 02:02:32 lenja-box go-librespot[2113]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 25 02:02:32 lenja-box go-librespot[2113]: goroutine 8 gp=0x25c85a8 m=nil [GC worker (idle)]: Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gopark(0x960d90, 0x25dca00, 0x1c, 0xa, 0x0) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x244a788 sp=0x244a774 pc=0xa97c0 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gcBgMarkWorker(0x2830d40) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x244a7e4 sp=0x244a788 pc=0x49f34 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x244a7ec sp=0x244a7e4 pc=0x49e14 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goexit({}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x244a7ec sp=0x244a7ec pc=0xb0874 Mar 25 02:02:32 lenja-box go-librespot[2113]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 25 02:02:32 lenja-box go-librespot[2113]: goroutine 20 gp=0x24837a8 m=nil [IO wait]: Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gopark(0x960ecc, 0x760ad010, 0x2, 0x2, 0x5) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24484f0 sp=0x24484dc pc=0xa97c0 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.netpollblock(0x760ad000, 0x72, 0x0) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2448508 sp=0x24484f0 pc=0x675a0 Mar 25 02:02:32 lenja-box go-librespot[2113]: internal/poll.runtime_pollWait(0x760ad000, 0x72) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x244851c sp=0x2448508 pc=0xa8864 Mar 25 02:02:32 lenja-box go-librespot[2113]: internal/poll.(*pollDesc).wait(0x24a40b8, 0x72, 0x0) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2448530 sp=0x244851c pc=0x134df4 Mar 25 02:02:32 lenja-box go-librespot[2113]: internal/poll.(*pollDesc).waitRead(...) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 25 02:02:32 lenja-box go-librespot[2113]: internal/poll.(*FD).Accept(0x24a40a0) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x2448578 sp=0x2448530 pc=0x1395b0 Mar 25 02:02:32 lenja-box go-librespot[2113]: net.(*netFD).accept(0x24a40a0) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x24485e0 sp=0x2448578 pc=0x1aa9d8 Mar 25 02:02:32 lenja-box go-librespot[2113]: net.(*TCPListener).accept(0x24b01b0) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x2448634 sp=0x24485e0 pc=0x1c2304 Mar 25 02:02:32 lenja-box go-librespot[2113]: net.(*TCPListener).Accept(0x24b01b0) Mar 25 02:02:32 lenja-box systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x2448650 sp=0x2448634 pc=0x1c134c Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*onceCloseListener).Accept(0x246e980) Mar 25 02:02:32 lenja-box go-librespot[2113]: :1 +0x34 fp=0x2448668 sp=0x2448650 pc=0x367710 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*Server).Serve(0x25aa218, {0xa258a8, 0x24b01b0}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x2448700 sp=0x2448668 pc=0x3408c8 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.Serve(...) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/server.go:2971 Mar 25 02:02:32 lenja-box go-librespot[2113]: main.(*ConcreteApiServer).serve(0x242a180) Mar 25 02:02:32 lenja-box go-librespot[2113]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x24487e4 sp=0x2448700 pc=0x69fdac Mar 25 02:02:32 lenja-box go-librespot[2113]: main.NewApiServer.gowrap1() Mar 25 02:02:32 lenja-box go-librespot[2113]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x24487ec sp=0x24487e4 pc=0x69eae4 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goexit({}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24487ec sp=0x24487ec pc=0xb0874 Mar 25 02:02:32 lenja-box go-librespot[2113]: created by main.NewApiServer in goroutine 1 Mar 25 02:02:32 lenja-box go-librespot[2113]: /src/cmd/daemon/api_server.go:332 +0x310 Mar 25 02:02:32 lenja-box go-librespot[2113]: goroutine 21 gp=0x24839e8 m=nil [runnable]: Mar 25 02:02:32 lenja-box go-librespot[2113]: internal/runtime/maps.rand() Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/rand.go:183 +0x30 fp=0x2457e74 sp=0x2457e74 pc=0xaa178 Mar 25 02:02:32 lenja-box go-librespot[2113]: internal/runtime/maps.NewEmptyMap(...) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/internal/runtime/maps/map.go:329 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.makemap_small() Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/map_swiss.go:48 +0x28 fp=0x2457e88 sp=0x2457e74 pc=0xa72a4 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*Transport).tryPutIdleConn(0xf812a8, 0x25aa8f8) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/transport.go:1112 +0x1f4 fp=0x2457f4c sp=0x2457e88 pc=0x34f060 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*Transport).putOrCloseIdleConn(0xf812a8, 0x25aa8f8) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/transport.go:1035 +0x24 fp=0x2457f60 sp=0x2457f4c pc=0x34ee38 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*Transport).dialConnFor(0xf812a8, 0x249e300) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/transport.go:1624 +0xfc fp=0x2457fc4 sp=0x2457f60 pc=0x351efc Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*Transport).startDialConnForLocked.func1() Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/transport.go:1600 +0x3c fp=0x2457fec sp=0x2457fc4 pc=0x351d08 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goexit({}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2457fec sp=0x2457fec pc=0xb0874 Mar 25 02:02:32 lenja-box go-librespot[2113]: created by net/http.(*Transport).startDialConnForLocked in goroutine 1 Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/transport.go:1599 +0x134 Mar 25 02:02:32 lenja-box go-librespot[2113]: goroutine 12 gp=0x2609e68 m=nil [runnable]: Mar 25 02:02:32 lenja-box go-librespot[2113]: internal/poll.(*FD).Read(0x2647040, {0x2513000, 0x1000, 0x1000}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/internal/poll/fd_unix.go:141 +0x32c fp=0x26a1b7c sp=0x26a1b7c pc=0x136074 Mar 25 02:02:32 lenja-box go-librespot[2113]: net.(*netFD).Read(0x2647040, {0x2513000, 0x1000, 0x1000}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x26a1ba8 sp=0x26a1b7c pc=0x1a8c28 Mar 25 02:02:32 lenja-box go-librespot[2113]: net.(*conn).Read(0x249c328, {0x2513000, 0x1000, 0x1000}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x26a1bd4 sp=0x26a1ba8 pc=0x1b842c Mar 25 02:02:32 lenja-box go-librespot[2113]: crypto/tls.(*atLeastReader).Read(0x27e70d0, {0x2513000, 0x1000, 0x1000}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x26a1c00 sp=0x26a1bd4 pc=0x266c34 Mar 25 02:02:32 lenja-box go-librespot[2113]: bytes.(*Buffer).ReadFrom(0x24c6a8c, {0xa22858, 0x27e70d0}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x26a1c3c sp=0x26a1c00 pc=0x160e08 Mar 25 02:02:32 lenja-box go-librespot[2113]: crypto/tls.(*Conn).readFromUntil(0x24c6908, {0xa21798, 0x249c328}, 0x5) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x26a1c64 sp=0x26a1c3c pc=0x266e90 Mar 25 02:02:32 lenja-box go-librespot[2113]: crypto/tls.(*Conn).readRecordOrCCS(0x24c6908, 0x0) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x26a1dc4 sp=0x26a1c64 pc=0x2645b4 Mar 25 02:02:32 lenja-box go-librespot[2113]: crypto/tls.(*Conn).readRecord(...) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/crypto/tls/conn.go:589 Mar 25 02:02:32 lenja-box go-librespot[2113]: crypto/tls.(*Conn).Read(0x24c6908, {0x25af000, 0x1000, 0x1000}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x26a1df4 sp=0x26a1dc4 pc=0x26a378 Mar 25 02:02:32 lenja-box go-librespot[2113]: bufio.(*Reader).Read(0x25f6600, {0x26120a4, 0x9, 0x9}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x26a1e18 sp=0x26a1df4 pc=0x2b6a14 Mar 25 02:02:32 lenja-box go-librespot[2113]: io.ReadAtLeast({0xa219e8, 0x25f6600}, {0x26120a4, 0x9, 0x9}, 0x9) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x26a1e44 sp=0x26a1e18 pc=0xee130 Mar 25 02:02:32 lenja-box go-librespot[2113]: io.ReadFull(...) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/io/io.go:354 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.http2readFrameHeader({0x26120a4, 0x9, 0x9}, {0xa219e8, 0x25f6600}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x26a1e6c sp=0x26a1e44 pc=0x2f7e20 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*http2Framer).ReadFrame(0x2612080) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x26a1ef4 sp=0x26a1e6c pc=0x2f8578 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*http2clientConnReadLoop).run(0x26a1fdc) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x26a1fa4 sp=0x26a1ef4 pc=0x31cab0 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*http2ClientConn).readLoop(0x26d2248) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x26a1fe4 sp=0x26a1fa4 pc=0x31bd78 Mar 25 02:02:32 lenja-box go-librespot[2113]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x26a1fec sp=0x26a1fe4 pc=0x3160d4 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goexit({}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26a1fec sp=0x26a1fec pc=0xb0874 Mar 25 02:02:32 lenja-box go-librespot[2113]: created by net/http.(*http2Transport).newClientConn in goroutine 11 Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Mar 25 02:02:32 lenja-box go-librespot[2113]: goroutine 9 gp=0x25c8908 m=nil [GC worker (idle)]: Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gopark(0x960d90, 0x25dcc00, 0x1c, 0xa, 0x0) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x269df88 sp=0x269df74 pc=0xa97c0 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gcBgMarkWorker(0x2830d40) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x269dfe4 sp=0x269df88 pc=0x49f34 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x269dfec sp=0x269dfe4 pc=0x49e14 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goexit({}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x269dfec sp=0x269dfec pc=0xb0874 Mar 25 02:02:32 lenja-box go-librespot[2113]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 25 02:02:32 lenja-box go-librespot[2113]: goroutine 10 gp=0x25c8a28 m=nil [GC worker (idle)]: Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gopark(0x960d90, 0x25dce00, 0x1c, 0xa, 0x0) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x244bf88 sp=0x244bf74 pc=0xa97c0 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gcBgMarkWorker(0x2830d40) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x244bfe4 sp=0x244bf88 pc=0x49f34 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x244bfec sp=0x244bfe4 pc=0x49e14 Mar 25 02:02:32 lenja-box go-librespot[2113]: runtime.goexit({}) Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x244bfec sp=0x244bfec pc=0xb0874 Mar 25 02:02:32 lenja-box go-librespot[2113]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Mar 25 02:02:32 lenja-box go-librespot[2113]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 25 02:02:32 lenja-box go-librespot[2111]: Aborted Mar 25 02:02:32 lenja-box sudo[2112]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-25 02:01' Mar 25 02:02:32 lenja-box sudo[2112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"