-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-04-13 17:24:48 CEST. -- Apr 13 17:23:27 volumio systemd-timedated[1140]: Changed local time to Sun Apr 13 17:23:27 2025 Apr 13 17:23:27 volumio sudo[1138]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:27 volumio volumio-time-update[639]: volumio-time-update-util: System time updated successfully. Apr 13 17:23:27 volumio systemd[1]: Started Volumio Time Update Utility. Apr 13 17:23:27 volumio systemd[1]: Reached target Multi-User System. Apr 13 17:23:27 volumio systemd[1]: Reached target Graphical Interface. Apr 13 17:23:27 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 13 17:23:27 volumio volumio[1058]: info: Loading plugin "upnp_browser"... Apr 13 17:23:27 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 13 17:23:27 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 13 17:23:27 volumio systemd[1]: Startup finished in 12.128s (kernel) + 29.778s (userspace) = 41.907s. Apr 13 17:23:27 volumio volumio[1058]: info: Loading plugin "alarm-clock"... Apr 13 17:23:27 volumio volumio[1058]: info: Loading plugin "airplay_emulation"... Apr 13 17:23:27 volumio volumio[1058]: info: Starting Shairport Sync Apr 13 17:23:27 volumio volumio[1058]: info: Loading plugin "last_100"... Apr 13 17:23:27 volumio volumio[1058]: info: Loading plugin "webradio"... Apr 13 17:23:27 volumio volumio[1058]: info: Loading plugin "i2s_dacs"... Apr 13 17:23:27 volumio volumio[1058]: info: Loading plugin "volumiodiscovery"... Apr 13 17:23:27 volumio volumio[1058]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 13 17:23:27 volumio volumio[1058]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 17:23:27 volumio node[1058]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 13 17:23:27 volumio volumio[1058]: *** WARNING *** For more information see Apr 13 17:23:27 volumio volumio[1058]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 13 17:23:27 volumio volumio[1058]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 17:23:27 volumio volumio[1058]: *** WARNING *** For more information see Apr 13 17:23:27 volumio node[1058]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 17:23:27 volumio node[1058]: *** WARNING *** For more information see Apr 13 17:23:27 volumio node[1058]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 13 17:23:27 volumio node[1058]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 17:23:27 volumio node[1058]: *** WARNING *** For more information see Apr 13 17:23:27 volumio volumio[1058]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 13 17:23:27 volumio volumio[1058]: info: Discovery: Started advertising with name: Volumio Apr 13 17:23:27 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 13 17:23:27 volumio volumio[1058]: info: Loading plugin "spop"... Apr 13 17:23:28 volumio volumio[1058]: info: Loading plugin "ytmusic"... Apr 13 17:23:29 volumio sudo[1109]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:30 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 13 17:23:30 volumio volumio[1058]: info: Loading plugin "outputs"... Apr 13 17:23:30 volumio volumio[1058]: info: Loading plugin "albumart"... Apr 13 17:23:30 volumio volumio[1058]: info: Plugin example_plugin is not enabled Apr 13 17:23:30 volumio volumio[1058]: info: Loading plugin "inputs"... Apr 13 17:23:30 volumio volumio[1058]: info: Loading plugin "updater_comm"... Apr 13 17:23:30 volumio sh[568]: timed out Apr 13 17:23:30 volumio dhcpcd[602]: timed out Apr 13 17:23:30 volumio sh[568]: dhcpcd exited Apr 13 17:23:30 volumio dhcpcd[602]: dhcpcd exited Apr 13 17:23:30 volumio sh[568]: ifup: failed to bring up eth0 Apr 13 17:23:30 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 13 17:23:30 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 13 17:23:30 volumio volumio[1058]: info: Plugin mpdemulation is not enabled Apr 13 17:23:30 volumio volumio[1058]: info: Loading plugin "rest_api"... Apr 13 17:23:31 volumio volumio[1058]: info: Loading plugin "websocket"... Apr 13 17:23:31 volumio volumio[1058]: info: Starting Socket.io Server version 2.3.0 Apr 13 17:23:31 volumio volumio[1058]: info: Loading plugin "80s80s"... Apr 13 17:23:31 volumio volumio[1058]: Forking 3 albumart workers Apr 13 17:23:31 volumio volumio[1058]: info: Applying required configuration parameters for plugin 80s80s Apr 13 17:23:31 volumio volumio[1058]: info: [1744557811289] [80s80s] API delay: 30 Apr 13 17:23:31 volumio volumio[1058]: info: Loading plugin "ir_controller"... Apr 13 17:23:31 volumio volumio[1058]: info: Applying required configuration parameters for plugin ir_controller Apr 13 17:23:31 volumio volumio[1058]: info: Plugin peppymeterbasic is not enabled Apr 13 17:23:31 volumio volumio[1058]: info: Loading plugin "touch_display"... Apr 13 17:23:31 volumio volumio[1058]: Starting albumart workers Apr 13 17:23:31 volumio volumio[1058]: Starting albumart workers Apr 13 17:23:31 volumio volumio[1058]: Starting albumart workers Apr 13 17:23:31 volumio volumio[1058]: info: Applying required configuration parameters for plugin touch_display Apr 13 17:23:32 volumio volumio[1058]: info: Loading i18n strings for locale pl Apr 13 17:23:32 volumio volumio[1058]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Apr 13 17:23:32 volumio volumio[1058]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Apr 13 17:23:32 volumio volumio[1058]: Updating browse sources language Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::initPlayerControls Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: Express server listening on port 3000 Apr 13 17:23:32 volumio volumio[1058]: [Metrics] WebUI: 8s 197.00ms Apr 13 17:23:32 volumio volumio[1058]: info: CoreStateMachine::resetVolumioState Apr 13 17:23:32 volumio volumio[1058]: info: CoreStateMachine::getcurrentVolume Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::volumioRetrievevolume Apr 13 17:23:32 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 13 17:23:32 volumio volumio[1058]: info: Volumio Network Manager: Network status updated: 2 Apr 13 17:23:32 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 13 17:23:32 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 13 17:23:32 volumio volumio[1058]: info: CoreStateMachine::updateTrackBlock Apr 13 17:23:32 volumio volumio[1058]: info: CorePlayQueue::getTrackBlock Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::volumioRetrievevolume Apr 13 17:23:32 volumio volumio[1058]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: info: Reloading queue from file Apr 13 17:23:32 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 13 17:23:32 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 13 17:23:32 volumio volumio[1058]: info: CoreStateMachine::setRepeat null single undefined Apr 13 17:23:32 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 13 17:23:32 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 13 17:23:32 volumio volumio[1058]: info: CoreStateMachine::setRandom false Apr 13 17:23:32 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 13 17:23:32 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 13 17:23:32 volumio volumio[1058]: info: Setting Device type: Raspberry PI Apr 13 17:23:32 volumio volumio[1058]: info: Completed loading Core Plugins Apr 13 17:23:32 volumio volumio[1058]: info: Preparing to generate the ALSA configuration file Apr 13 17:23:32 volumio volumio[1058]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 13 17:23:32 volumio volumio[1058]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Apr 13 17:23:32 volumio volumio[1058]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 13 17:23:32 volumio volumio[1058]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 13 17:23:32 volumio volumio[1058]: info: Reading ALSA contributions from plugins. Apr 13 17:23:32 volumio volumio[1058]: info: Discovery: adding 7c92cd76-ddaf-4ab3-a685-83988dd18eaf Apr 13 17:23:32 volumio volumio[1058]: info: Discovery: Found device Volumio Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:23:32 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:23:32 volumio volumio[1058]: info: Asound.conf file unchanged, so no further update is needed Apr 13 17:23:32 volumio volumio[1058]: info: Output device has changed, restarting MPD Apr 13 17:23:32 volumio sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 13 17:23:32 volumio sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:32 volumio volumio[1058]: info: Output device has changed, restarting Shairport Sync Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 17:23:32 volumio sudo[1221]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:32 volumio sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 13 17:23:32 volumio sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:32 volumio systemd[1]: Stopping Music Player Daemon... Apr 13 17:23:32 volumio volumio[1058]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 13 17:23:32 volumio volumio[1058]: info: ___________ START PLUGINS ___________ Apr 13 17:23:32 volumio volumio[1058]: info: ControllerMpd::onStart: Initializing MPD Apr 13 17:23:32 volumio volumio[1058]: info: Creating MPD Configuration file Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 17:23:32 volumio volumio[1058]: info: [1744557812720] CoreMusicLibrary::Adding element Serwery Mediów Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 17:23:32 volumio sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 13 17:23:32 volumio sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:32 volumio sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 13 17:23:32 volumio sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:32 volumio sudo[1230]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 17:23:32 volumio systemd[1]: mpd.service: Succeeded. Apr 13 17:23:32 volumio systemd[1]: Stopped Music Player Daemon. Apr 13 17:23:32 volumio volumio[1058]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 17:23:32 volumio volumio[1058]: info: [1744557812810] CoreMusicLibrary::Adding element Last_100 Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 17:23:32 volumio volumio[1058]: info: [1744557812812] CoreMusicLibrary::Adding element Webradio Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 17:23:32 volumio volumio[1058]: info: Initializing BBC Radios Apr 13 17:23:32 volumio systemd[1]: Starting Music Player Daemon... Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 17:23:32 volumio volumio[1058]: info: Creating Spotify config file Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:32 volumio sudo[1238]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 13 17:23:32 volumio sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:32 volumio sudo[1238]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 17:23:32 volumio volumio[1058]: info: [1744557812938] CoreMusicLibrary::Adding element YouTube Music Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 17:23:32 volumio volumio[1058]: Cannot find translation for source YouTube Music Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 17:23:32 volumio volumio[1058]: info: [1744557812946] CoreMusicLibrary::Adding element 80s80s Radio Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 17:23:32 volumio volumio[1058]: Cannot find translation for source YouTube Music Apr 13 17:23:32 volumio volumio[1058]: Cannot find translation for source 80s80s Radio Apr 13 17:23:32 volumio volumio[1058]: info: Loading i18n strings for locale pl Apr 13 17:23:32 volumio volumio[1058]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Apr 13 17:23:32 volumio volumio[1058]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 17:23:32 volumio volumio[1058]: info: Loading i18n strings for locale pl Apr 13 17:23:32 volumio volumio[1058]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Apr 13 17:23:32 volumio volumio[1058]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Apr 13 17:23:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 17:23:32 volumio volumio[1058]: info: Volumio Calling Home Apr 13 17:23:33 volumio sudo[1260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Apr 13 17:23:33 volumio sudo[1260]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:33 volumio sudo[1260]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:33 volumio sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Apr 13 17:23:33 volumio sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:33 volumio sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Apr 13 17:23:33 volumio sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:33 volumio sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 13 17:23:33 volumio sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:33 volumio volumio[1058]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Apr 13 17:23:33 volumio volumio[1058]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Apr 13 17:23:33 volumio volumio[1058]: info: MPD Permissions set Apr 13 17:23:33 volumio volumio[1058]: info: MPD Permissions set Apr 13 17:23:33 volumio volumio[1058]: info: Volumio called home Apr 13 17:23:33 volumio volumio[1058]: info: Spotify config file written Apr 13 17:23:33 volumio systemd[1]: Reloading. Apr 13 17:23:33 volumio sudo[1263]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:33 volumio sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 13 17:23:33 volumio sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:33 volumio volumio[1058]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:23:33 volumio volumio[1058]: info: No need to fix Spotify hosts Apr 13 17:23:33 volumio volumio[1058]: info: touch_display: systemctl stop getty@tty1.service succeeded. Apr 13 17:23:33 volumio volumio[1058]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Apr 13 17:23:33 volumio sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Apr 13 17:23:33 volumio sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:33 volumio sudo[1296]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:33 volumio volumio[1058]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Apr 13 17:23:33 volumio volumio[1058]: info: Starting Shairport Sync Apr 13 17:23:33 volumio volumio[1058]: info: Starting Shairport Sync Apr 13 17:23:33 volumio sudo[1299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 13 17:23:33 volumio volumio[1058]: info: Starting Shairport Sync Apr 13 17:23:33 volumio sudo[1299]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:33 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 13 17:23:33 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:23:33 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:23:33 volumio sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 13 17:23:33 volumio sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:33 volumio sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 13 17:23:33 volumio sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:33 volumio volumio[1058]: info: ir_controller: LIRC correctly updated. Apr 13 17:23:33 volumio volumio[1058]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver! Apr 13 17:23:33 volumio volumio[1058]: info: ir_controller: Raspberry Pi revision code: d03115 Apr 13 17:23:33 volumio sudo[1312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r -1 Apr 13 17:23:33 volumio sudo[1312]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:33 volumio sudo[1312]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:33 volumio mpd[1253]: Apr 13 17:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 13 17:23:33 volumio sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-ir gpio_pin=25 gpio_pull=up Apr 13 17:23:33 volumio kernel: Registered IR keymap rc-rc6-mce Apr 13 17:23:33 volumio kernel: IR RC6 protocol handler initialized Apr 13 17:23:33 volumio volumio[1058]: info: ir_controller: Overlay gpio-ir gpio_pin=25 gpio_pull=up loaded. Apr 13 17:23:33 volumio sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:33 volumio sudo[1321]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:33 volumio sudo[1332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Apr 13 17:23:33 volumio sudo[1332]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:33 volumio kernel: rc rc0: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0 Apr 13 17:23:33 volumio kernel: rc rc0: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter Apr 13 17:23:33 volumio kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0/input2 Apr 13 17:23:33 volumio systemd[1]: Started Music Player Daemon. Apr 13 17:23:33 volumio sudo[1268]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:33 volumio sudo[1232]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:33 volumio systemd[1]: Reloading. Apr 13 17:23:33 volumio sudo[1223]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:33 volumio volumio[1058]: info: touch_display: systemctl daemon-reload succeeded. Apr 13 17:23:34 volumio sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Apr 13 17:23:34 volumio sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:34 volumio volumio[1058]: error: MPD error: The expression evaluated to a falsy value: Apr 13 17:23:34 volumio volumio[1058]: assert.ok(self.idling) Apr 13 17:23:34 volumio volumio[1058]: error: The expression evaluated to a falsy value: Apr 13 17:23:34 volumio volumio[1058]: assert.ok(self.idling) Apr 13 17:23:34 volumio volumio[1058]: error: updateQueue error: null Apr 13 17:23:34 volumio volumio[1058]: info: MPD running with PID1253 Apr 13 17:23:34 volumio volumio[1058]: ,establishing connection Apr 13 17:23:34 volumio volumio[1058]: error: updateQueue error: null Apr 13 17:23:34 volumio systemd-udevd[1335]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 13 17:23:34 volumio systemd-logind[640]: Watching system buttons on /dev/input/event1 (gpio_ir_recv) Apr 13 17:23:34 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 13 17:23:34 volumio volumio[1058]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 13 17:23:34 volumio volumio[1058]: SPOTIFY: BQAbKWGlpPKen2Gr21vj1egXvd2lG2MXhGErWtiNl_-K0UCGf1Ngq3sa8wnaNgh95AcT-EradzKlPeySHXzaMQ_Vv4htUqnp4OqeHpaM3kaN054QnH24PdEnp6rFUNsfwLWoQVe5kiqMn8_GPssgMcs5utsYeq5ZnXCob722n7c05jFFQ4hWERBx7Yf45jYb9bhtmrzziSl2DQG_PThYjweslymWTQC9h4K8PDJoQKSdcnkSFb_308l23hWAba4WtlKlr9YmvrD5x-ZGKE0 Apr 13 17:23:34 volumio volumio[1058]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 13 17:23:34 volumio volumio[1058]: info: New Spotify access token = BQAbKWGlpPKen2Gr21vj1egXvd2lG2MXhGErWtiNl_-K0UCGf1Ngq3sa8wnaNgh95AcT-EradzKlPeySHXzaMQ_Vv4htUqnp4OqeHpaM3kaN054QnH24PdEnp6rFUNsfwLWoQVe5kiqMn8_GPssgMcs5utsYeq5ZnXCob722n7c05jFFQ4hWERBx7Yf45jYb9bhtmrzziSl2DQG_PThYjweslymWTQC9h4K8PDJoQKSdcnkSFb_308l23hWAba4WtlKlr9YmvrD5x-ZGKE0 Apr 13 17:23:34 volumio volumio[1058]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 13 17:23:34 volumio sudo[1265]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:34 volumio volumio[1058]: info: touch_display: systemctl disable getty@tty1.service succeeded. Apr 13 17:23:34 volumio volumio[1058]: SPOTIFY: User informations: {"country":"PL","display_name":"fotofanart","email":"fotofanart@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/fotofanart"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/fotofanart","id":"fotofanart","images":[],"product":"free","type":"user","uri":"spotify:user:fotofanart"} Apr 13 17:23:34 volumio volumio[1058]: info: Spotify Successfully logged in Apr 13 17:23:34 volumio volumio[1058]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 17:23:34 volumio volumio[1058]: info: [1744557814608] CoreMusicLibrary::Adding element Spotify Apr 13 17:23:34 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 17:23:34 volumio volumio[1058]: Cannot find translation for source YouTube Music Apr 13 17:23:34 volumio volumio[1058]: Cannot find translation for source 80s80s Radio Apr 13 17:23:34 volumio volumio[1058]: Cannot find translation for source Spotify Apr 13 17:23:34 volumio sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Apr 13 17:23:34 volumio sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:34 volumio systemd[1]: Started Volumio Kiosk. Apr 13 17:23:34 volumio systemd[1]: Starting lircd(8) initialization helper tool... Apr 13 17:23:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 13 17:23:34 volumio systemd[1]: Started go-librespot Daemon. Apr 13 17:23:34 volumio sudo[1272]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:34 volumio go-librespot[1364]: go-librespot daemon starting... Apr 13 17:23:34 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 13 17:23:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 13 17:23:34 volumio sudo[1340]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 13 17:23:34 volumio volumio[1058]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Apr 13 17:23:34 volumio volumio[1058]: info: touch_display: Volumio Kiosk started. Apr 13 17:23:34 volumio sudo[1299]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:34 volumio sudo[1301]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:34 volumio sudo[1304]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:34 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:23:34 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:23:34 volumio volumio[1058]: info: Completed starting Core Plugins Apr 13 17:23:34 volumio volumio[1058]: info: ------------------------------------------- Apr 13 17:23:34 volumio volumio[1058]: info: ----- MyVolumio plugins startup ---- Apr 13 17:23:34 volumio volumio[1058]: info: ------------------------------------------- Apr 13 17:23:34 volumio volumio[1058]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 13 17:23:34 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:23:34 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:23:34 volumio volumio[1058]: info: Shairport-Sync Started Apr 13 17:23:34 volumio volumio[1058]: Error adding Membership: Error: addMembership EINVAL Apr 13 17:23:34 volumio volumio[1058]: info: Shairport-Sync Started Apr 13 17:23:34 volumio volumio[1058]: info: touch_display: X display number found: 0 Apr 13 17:23:34 volumio volumio[1058]: info: touch_display: X display number found: 0 Apr 13 17:23:34 volumio startx[1360]: X.Org X Server 1.20.4 Apr 13 17:23:34 volumio startx[1360]: X Protocol Version 11, Revision 0 Apr 13 17:23:34 volumio startx[1360]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Apr 13 17:23:34 volumio startx[1360]: Current Operating System: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Apr 13 17:23:34 volumio startx[1360]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=576 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:46:2F:DF 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=a0ee35d1-02bc-4547-b2c7-e6caa39d3454 imgfile=/volumio_current.sqsh bootpart=UUID=1BFB-AD60 datapart=UUID=a3c1e1ba-b424-4a77-8c81-c20d638e72d6 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Apr 13 17:23:34 volumio startx[1360]: Build Date: 04 April 2023 07:50:56AM Apr 13 17:23:34 volumio startx[1360]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Apr 13 17:23:34 volumio startx[1360]: Current version of pixman: 0.36.0 Apr 13 17:23:34 volumio startx[1360]: Before reporting problems, check http://wiki.x.org Apr 13 17:23:34 volumio startx[1360]: to make sure that you have the latest version. Apr 13 17:23:34 volumio startx[1360]: Markers: (--) probed, (**) from config file, (==) default setting, Apr 13 17:23:34 volumio startx[1360]: (++) from command line, (!!) notice, (II) informational, Apr 13 17:23:34 volumio startx[1360]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Apr 13 17:23:34 volumio startx[1360]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Apr 13 17:23:34 2025 Apr 13 17:23:35 volumio startx[1360]: (==) Using config directory: "/etc/X11/xorg.conf.d" Apr 13 17:23:35 volumio startx[1360]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Apr 13 17:23:35 volumio volumio[1058]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 13 17:23:35 volumio volumio[1058]: info: touch_display: X display number found: 0 Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=info msg="running go-librespot 0.2.0" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=debug msg="app state loaded" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 13 17:23:35 volumio lircd-setup[1362]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Apr 13 17:23:35 volumio lircd-setup[1362]: parser = configparser.SafeConfigParser() Apr 13 17:23:35 volumio systemd[1]: lircd-setup.service: Succeeded. Apr 13 17:23:35 volumio systemd[1]: Started lircd(8) initialization helper tool. Apr 13 17:23:35 volumio systemd[1]: Starting Flexible IR remote input/output application support... Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Info: lircd: Opening log, level: Info Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Version: lircd 0.10.1 Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: System info: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l GNU/Linux Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Info: Initial device: /dev/lirc0 Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Info: Initial device: /dev/lirc0 Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Info: [lirc] protocol is enabled Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: driver: default Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: output: /var/run/lirc/lircd Apr 13 17:23:35 volumio lircd[1403]: lircd-0.10.1[1403]: Info: lircd: Opening log, level: Info Apr 13 17:23:35 volumio lircd[1403]: lircd-0.10.1[1403]: Notice: Using systemd fd Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: nodaemon: 1 Apr 13 17:23:35 volumio lircd[1403]: lircd-0.10.1[1403]: Warning: Running as root Apr 13 17:23:35 volumio lircd[1403]: lircd-0.10.1[1403]: Info: Using remote: lircd.conf. Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Apr 13 17:23:35 volumio lircd[1403]: lircd-0.10.1[1403]: Notice: lircd(default) ready, using /var/run/lirc/lircd Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: logfile: syslog Apr 13 17:23:35 volumio systemd[1]: Started Flexible IR remote input/output application support. Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: immediate-init: 0 Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: permission: 666 Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: driver-options: Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: listen: 0 Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: connect: (null) Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: userelease: 0 Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: effective_user: (null) Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: release_suffix: _EVUP Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: allow_simulate: 0 Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: repeat_max: 600 Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: configfile: /etc/lirc/lircd.conf Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Options: dynamic_codes: (null) Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Current driver: default Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Driver API version: 3 Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Driver version: 0.10.0 Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Info: lircd: Opening log, level: Info Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: Using systemd fd Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Warning: Running as root Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Info: Using remote: lircd.conf. Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: lircd(default) ready, using /var/run/lirc/lircd Apr 13 17:23:35 volumio sudo[1359]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:35 volumio sudo[1332]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 13 17:23:35 volumio volumio[1058]: info: ir_controller: systemctl restart lircd.service succeeded. Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=info msg="zeroconf server listening on port 43341" Apr 13 17:23:35 volumio volumio[1058]: info: ir_controller: systemctl restart lircd.service succeeded. Apr 13 17:23:35 volumio sudo[1408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Apr 13 17:23:35 volumio sudo[1408]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:35 volumio sudo[1410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Apr 13 17:23:35 volumio sudo[1410]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=debug msg="obtained new client token: AACg/S7qd9p/bvMQ4NrukNnEVk5Ph19lhPwEVzVOTlrpaTc5+4oTaVENUrRNLdH6Cz5OJixkhXacKqgGKPZ53oVj2uXlZxYhRKnGFDIJQl7d8K/ju/z2ceWU4R/wo2bmeKbGZpQDcdE2QKthTVIlpINmnyUb+LR7iLPww9LiRJyyAoKhsNjhkVdnrOqj7DF4qpZCvZfNOfvnFzgrppJqRZ9Y7Sn0mYjiJkT7YKonPzwq0RMMc4QVtDaS2Q==" Apr 13 17:23:35 volumio systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Apr 13 17:23:35 volumio sudo[1408]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:35 volumio volumio[1058]: info: ir_controller: systemctl restart irexec.service succeeded. Apr 13 17:23:35 volumio sudo[1410]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:35 volumio volumio[1058]: info: ir_controller: systemctl restart irexec.service succeeded. Apr 13 17:23:35 volumio lircd[1403]: lircd-0.10.1[1403]: Notice: accepted new client on /var/run/lirc/lircd Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Notice: accepted new client on /var/run/lirc/lircd Apr 13 17:23:35 volumio lircd[1403]: lircd-0.10.1[1403]: Info: [lirc] protocol is enabled Apr 13 17:23:35 volumio lircd-0.10.1[1403]: Info: [lirc] protocol is enabled Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=debug msg="completed keyexchange" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=debug msg="completed challenge" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=info msg="authenticated AP as fotofanart" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=info msg="authenticated Login5 as fotofanart" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=debug msg="initializing zeroconf session, username: fotofanart" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=debug msg="dealer connection opened" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=trace msg="starting accesspoint recv loop" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=trace msg="starting dealer recv loop" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=trace msg="received accesspoint ping" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=debug msg="received connection id: ODRhYTJmYmEtOTk1YS00YmQ5LWIxYzItZDZjMTM0NDg1ZTdkK2RlYWxlcit0Y3A6Ly8wYWNhNThkZC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArRkRFNzU1MkY0QTUyNDY4MjFCOTgyRTcxMjFFNTdBNEM1QzRBMzZGMDA3QzA4NEFDRUY0RkJERUM0ODEyMDk3RA==" Apr 13 17:23:35 volumio go-librespot[1364]: time="2025-04-13T17:23:35+02:00" level=trace msg="received accesspoint pong ack" Apr 13 17:23:36 volumio go-librespot[1364]: time="2025-04-13T17:23:36+02:00" level=debug msg="put connect state because NEW_DEVICE" Apr 13 17:23:36 volumio volumio[1058]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 13 17:23:36 volumio sudo[1467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 13 17:23:36 volumio sudo[1467]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:36 volumio sudo[1467]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:36 volumio sudo[1470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 13 17:23:36 volumio sudo[1470]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:36 volumio sudo[1470]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:36 volumio sudo[1475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 13 17:23:36 volumio sudo[1475]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:36 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 13 17:23:36 volumio sudo[1475]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:36 volumio volumio[1058]: info: Upmpdcli Daemon Started Apr 13 17:23:36 volumio volumio[1482]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 13 17:23:37 volumio volumio[1058]: info: go-librespot daemon successfully initialized Apr 13 17:23:39 volumio volumio-remote-updater[646]: [2025-04-13 17:23:39] [connect] Successful connection Apr 13 17:23:39 volumio volumio-remote-updater[646]: [2025-04-13 17:23:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1744557819 101 Apr 13 17:23:39 volumio volumio[1058]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Apr 13 17:23:40 volumio volumio[1058]: info: Initializing connection to go-librespot Websocket Apr 13 17:23:40 volumio go-librespot[1364]: time="2025-04-13T17:23:40+02:00" level=debug msg="new websocket client" Apr 13 17:23:40 volumio volumio[1058]: info: Connection to go-librespot Websocket established Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 13 17:23:43 volumio volumio[1058]: info: Adding plugin bluetooth to MyMusic Plugins Apr 13 17:23:43 volumio volumio[1058]: info: Adding plugin multiroom to MyMusic Plugins Apr 13 17:23:43 volumio volumio[1058]: info: Adding plugin metavolumio to MyMusic Plugins Apr 13 17:23:43 volumio volumio[1058]: info: Adding plugin cd_controller to MyMusic Plugins Apr 13 17:23:43 volumio volumio[1058]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 13 17:23:43 volumio volumio[1058]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 13 17:23:43 volumio volumio[1058]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 13 17:23:44 volumio volumio[1058]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 13 17:23:44 volumio volumio[1058]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 13 17:23:44 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 17:23:44 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 17:23:44 volumio volumio[1058]: info: Starting MyVolumio Remote Streaming Endpoints Apr 13 17:23:44 volumio volumio[1058]: info: MyVolumio login type: Token Apr 13 17:23:44 volumio volumio[1058]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 13 17:23:44 volumio volumio[1058]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 13 17:23:44 volumio volumio[1058]: info: Starting Streaming Service Transparent Proxy Apr 13 17:23:44 volumio volumio[1058]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 13 17:23:44 volumio volumio[1058]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 13 17:23:44 volumio volumio[1058]: info: Streaming services startup Apr 13 17:23:44 volumio volumio[1058]: info: Starting Streaming Daemon Apr 13 17:23:45 volumio sudo[1654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 13 17:23:45 volumio sudo[1654]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:45 volumio sudo[1654]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:45 volumio volumio[1058]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 13 17:23:45 volumio volumio[1058]: info: Getting Spotify volume Apr 13 17:23:45 volumio volumio[1058]: error: Cannot start Volumio Streaming Daemon Apr 13 17:23:45 volumio volumio[1058]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 13 17:23:45 volumio volumio[1058]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 13 17:23:45 volumio volumio[1058]: info: Spotify volume: 100 Apr 13 17:23:45 volumio volumio[1058]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 13 17:23:45 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:23:45 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:23:45 volumio volumio[1058]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 13 17:23:45 volumio volumio[1058]: SPOTIFY: SPOTIFY VOLUME 100 Apr 13 17:23:45 volumio volumio[1058]: SPOTIFY: VOLUMIO VOLUME 20 Apr 13 17:23:45 volumio volumio[1058]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 13 17:23:45 volumio volumio[1058]: info: Setting Spotify Volume from Volumio: 20 Apr 13 17:23:45 volumio volumio[1058]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 13 17:23:45 volumio volumio[1058]: STREAMING PROXY: Starting server on port 3245 Apr 13 17:23:45 volumio volumio[1058]: Node JS runtime: 14 Apr 13 17:23:45 volumio volumio[1058]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 13 17:23:46 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 13 17:23:46 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 13 17:23:46 volumio volumio[1058]: SPOTIFY: SETTING SPOTIFY VOLUME 20 Apr 13 17:23:46 volumio volumio[1058]: info: Sending Spotify command with payload to local API: /player/volume Apr 13 17:23:46 volumio volumio[1058]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Apr 13 17:23:46 volumio volumio[1058]: info: MyVolumio token set successfully Apr 13 17:23:46 volumio volumio[1058]: info: MYVOLUMIO: Adding device Apr 13 17:23:46 volumio volumio[1058]: info: MYVOLUMIO: Evaluating Server Apr 13 17:23:47 volumio volumio[1058]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 13 17:23:47 volumio volumio[1058]: info: MyVolumio status changed Apr 13 17:23:47 volumio volumio[1058]: info: Streaming services startup Apr 13 17:23:47 volumio volumio[1058]: info: Starting Streaming Daemon Apr 13 17:23:47 volumio volumio[1058]: info: Removing browser output: myVolumio user plan is not superstar Apr 13 17:23:47 volumio volumio[1058]: info: Removing audio output: Apr 13 17:23:47 volumio volumio[1058]: info: Stoppping Tunnel 1 Apr 13 17:23:47 volumio sudo[1693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 13 17:23:47 volumio sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:47 volumio sudo[1695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 13 17:23:47 volumio sudo[1695]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:23:47 volumio sudo[1693]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:47 volumio volumio[1058]: error: Cannot start Volumio Streaming Daemon Apr 13 17:23:47 volumio volumio[1058]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 13 17:23:47 volumio volumio[1058]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 13 17:23:47 volumio sudo[1695]: pam_unix(sudo:session): session closed for user root Apr 13 17:23:47 volumio volumio[1058]: info: Remote SSH Stopped Apr 13 17:23:47 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 13 17:23:47 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 13 17:23:47 volumio volumio[1058]: info: Setting Geolocation for MyVolumio to eu7 Apr 13 17:23:47 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 17:23:47 volumio volumio[1058]: info: CoreCommandRouter::volumioGetVisibleSources Apr 13 17:23:47 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 17:23:47 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:23:47 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:23:47 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 13 17:23:47 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 13 17:23:47 volumio volumio[1058]: info: Received Get System Info Apr 13 17:23:47 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 17:23:47 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 17:23:47 volumio volumio[1058]: info: Discovery: Getting this device information Apr 13 17:23:47 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:23:47 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:23:47 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 17:23:47 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:23:47 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:23:47 volumio volumio[1058]: info: Listing playlists Apr 13 17:23:47 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 13 17:23:47 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 13 17:23:48 volumio volumio[1482]: .........................................................................................................................++++ Apr 13 17:23:48 volumio volumio[1058]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 13 17:23:48 volumio volumio[1058]: info: Updating MyVolumio device info Apr 13 17:23:48 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 17:23:49 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 17:23:49 volumio volumio[1058]: info: Received Get System Info Apr 13 17:23:49 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 17:23:49 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 17:23:49 volumio volumio[1058]: info: Discovery: Getting this device information Apr 13 17:23:49 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:23:49 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:23:49 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 17:23:49 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 13 17:23:49 volumio volumio[1058]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 13 17:23:49 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 13 17:23:51 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 17:23:51 volumio volumio[1058]: info: Received Get System Info Apr 13 17:23:51 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 17:23:51 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 17:23:51 volumio volumio[1058]: info: Discovery: Getting this device information Apr 13 17:23:51 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:23:51 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:23:51 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 17:23:52 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 17:23:52 volumio volumio[1058]: info: Received Get System Info Apr 13 17:23:52 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 17:23:52 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 17:23:52 volumio volumio[1058]: info: Discovery: Getting this device information Apr 13 17:23:52 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:23:52 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:23:52 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 17:23:52 volumio volumio[1058]: info: MYVOLUMIO: Adding device Apr 13 17:23:52 volumio volumio[1058]: info: MYVOLUMIO: Evaluating Server Apr 13 17:23:52 volumio volumio[1058]: info: Setting Geolocation for MyVolumio to eu6 Apr 13 17:23:52 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 17:23:53 volumio volumio[1058]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 13 17:23:53 volumio volumio[1058]: info: Updating MyVolumio device info Apr 13 17:23:53 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 17:23:54 volumio volumio[1058]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 13 17:23:54 volumio volumio[1482]: ............................................................................++++ Apr 13 17:23:55 volumio volumio[1482]: e is 65537 (0x010001) Apr 13 17:23:55 volumio volumio[1482]: writing RSA key Apr 13 17:23:57 volumio systemd[1]: systemd-timedated.service: Succeeded. Apr 13 17:23:57 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 13 17:23:59 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 13 17:23:59 volumio volumio[1058]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 13 17:23:59 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:23:59 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:02 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 17:24:02 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 17:24:02 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 13 17:24:03 volumio volumio-remote-updater[646]: No test mode Apr 13 17:24:03 volumio volumio-remote-updater[646]: No alpha test mode Apr 13 17:24:03 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 13 17:24:03 volumio volumio[1058]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 13 17:24:03 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 13 17:24:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:24:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:24:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 13 17:24:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 13 17:24:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 13 17:24:04 volumio volumio[1058]: info: CoreCommandRouter::volumioGetBrowseSources Apr 13 17:24:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 17:24:05 volumio volumio[1058]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 13 17:24:05 volumio volumio[1058]: info: Completed starting MyVolumio Plugin Apr 13 17:24:05 volumio volumio[1058]: [Metrics] CommandRouter: 40s 462.03ms Apr 13 17:24:05 volumio volumio[1058]: info: CoreCommandRouter::volumiosetStartupVolume Apr 13 17:24:05 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:24:05 volumio volumio[1058]: info: VolumeController:: Setting startup Volume 5 Apr 13 17:24:05 volumio volumio[1058]: info: VolumeController::SetAlsaVolume5 Apr 13 17:24:05 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 17:24:05 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Apr 13 17:24:05 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Apr 13 17:24:05 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 13 17:24:05 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:05 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 17:24:05 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 13 17:24:05 volumio volumio[1058]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Apr 13 17:24:05 volumio volumio[1058]: SPOTIFY: SPOTIFY VOLUME 20 Apr 13 17:24:05 volumio volumio[1058]: SPOTIFY: VOLUMIO VOLUME 5 Apr 13 17:24:05 volumio volumio[1058]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 13 17:24:05 volumio volumio[1058]: info: Setting Spotify Volume from Volumio: 5 Apr 13 17:24:05 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 13 17:24:05 volumio volumio[1058]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 13 17:24:05 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 13 17:24:05 volumio volumio[1058]: info: Received Get System Version Apr 13 17:24:05 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 17:24:05 volumio volumio[1058]: info: Received Get System Info Apr 13 17:24:05 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 17:24:05 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 17:24:05 volumio volumio[1058]: info: Discovery: Getting this device information Apr 13 17:24:05 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:05 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:05 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 17:24:05 volumio go-librespot[1364]: time="2025-04-13T17:24:05+02:00" level=trace msg="sent dealer ping" Apr 13 17:24:05 volumio go-librespot[1364]: time="2025-04-13T17:24:05+02:00" level=trace msg="received dealer pong" Apr 13 17:24:06 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 13 17:24:06 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 13 17:24:06 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 13 17:24:06 volumio volumio[1058]: info: Version has changed, forcing UI Reload Apr 13 17:24:06 volumio volumio[1058]: info: CoreCommandRouter::Reload Ui Apr 13 17:24:06 volumio volumio[1058]: SPOTIFY: SETTING SPOTIFY VOLUME 5 Apr 13 17:24:06 volumio volumio[1058]: info: Sending Spotify command with payload to local API: /player/volume Apr 13 17:24:06 volumio go-librespot[1364]: time="2025-04-13T17:24:06+02:00" level=debug msg="update volume to 3276/65535" Apr 13 17:24:06 volumio volumio[1058]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 13 17:24:07 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 13 17:24:07 volumio volumio[1058]: info: CoreCommandRouter::volumioGetVisibleSources Apr 13 17:24:07 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 17:24:07 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:07 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:07 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 13 17:24:07 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 13 17:24:07 volumio volumio[1058]: info: Received Get System Info Apr 13 17:24:07 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 17:24:07 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 17:24:07 volumio volumio[1058]: info: Discovery: Getting this device information Apr 13 17:24:07 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:07 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:07 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 17:24:07 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:07 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:07 volumio volumio[1058]: info: Listing playlists Apr 13 17:24:07 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 13 17:24:07 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 13 17:24:07 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 13 17:24:07 volumio go-librespot[1364]: time="2025-04-13T17:24:07+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 13 17:24:07 volumio go-librespot[1364]: time="2025-04-13T17:24:07+02:00" level=trace msg="emitting websocket event: volume" Apr 13 17:24:07 volumio volumio[1058]: SPOTIFY: received: {"type":"volume","data":{"value":5,"max":100}} Apr 13 17:24:07 volumio volumio[1058]: SPOTIFY: RECEIVED SPOTIFY VOLUME 5 Apr 13 17:24:08 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 17:24:08 volumio volumio[1058]: info: Received Get System Info Apr 13 17:24:08 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 17:24:08 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 17:24:08 volumio volumio[1058]: info: Discovery: Getting this device information Apr 13 17:24:08 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:08 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:08 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 17:24:08 volumio ntpd[825]: Soliciting pool server 185.80.247.36 Apr 13 17:24:08 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 13 17:24:09 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 17:24:09 volumio volumio[1058]: info: Received Get System Info Apr 13 17:24:09 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 17:24:09 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 17:24:09 volumio volumio[1058]: info: Discovery: Getting this device information Apr 13 17:24:09 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:09 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:09 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 17:24:09 volumio ntpd[825]: Soliciting pool server 178.239.19.60 Apr 13 17:24:09 volumio ntpd[825]: Soliciting pool server 193.70.94.182 Apr 13 17:24:09 volumio ntpd[825]: Soliciting pool server 136.244.111.41 Apr 13 17:24:10 volumio ntpd[825]: Soliciting pool server 45.90.187.8 Apr 13 17:24:10 volumio ntpd[825]: Soliciting pool server 162.159.200.1 Apr 13 17:24:10 volumio ntpd[825]: Soliciting pool server 185.51.192.62 Apr 13 17:24:10 volumio ntpd[825]: Soliciting pool server 213.222.217.11 Apr 13 17:24:11 volumio ntpd[825]: Soliciting pool server 185.157.13.96 Apr 13 17:24:11 volumio ntpd[825]: Soliciting pool server 81.172.248.188 Apr 13 17:24:11 volumio ntpd[825]: Soliciting pool server 54.37.233.160 Apr 13 17:24:11 volumio ntpd[825]: Soliciting pool server 94.198.159.15 Apr 13 17:24:12 volumio volumio[1058]: info: BOOT COMPLETED Apr 13 17:24:12 volumio volumio[1058]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.163 UA: Mozilla/5.0 (Linux; Android 15; 2312DRA50G Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.38 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 13 17:24:12 volumio ntpd[825]: Soliciting pool server 185.79.243.74 Apr 13 17:24:12 volumio ntpd[825]: Soliciting pool server 2a00:d5c0:10:10::136 Apr 13 17:24:12 volumio ntpd[825]: Soliciting pool server 91.212.242.21 Apr 13 17:24:12 volumio ntpd[825]: Soliciting pool server 94.198.159.11 Apr 13 17:24:12 volumio volumio[1058]: verbose: New Socket.io Connection to 192.168.0.12:3000 from 192.168.0.163 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 13 17:24:13 volumio ntpd[825]: Soliciting pool server 156.17.20.254 Apr 13 17:24:14 volumio volumio[1058]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.163 UA: Mozilla/5.0 (Linux; Android 15; 2312DRA50G Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.38 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 13 17:24:14 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 13 17:24:14 volumio volumio[1058]: info: CoreCommandRouter::volumioGetVisibleSources Apr 13 17:24:14 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 17:24:14 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:14 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:14 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 13 17:24:14 volumio volumio[1058]: info: CoreCommandRouter::volumioGetQueue Apr 13 17:24:14 volumio volumio[1058]: info: CoreStateMachine::getQueue Apr 13 17:24:14 volumio volumio[1058]: info: CorePlayQueue::getQueue Apr 13 17:24:14 volumio volumio[1058]: info: Listing playlists Apr 13 17:24:14 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 13 17:24:14 volumio volumio[1058]: info: Received Get System Info Apr 13 17:24:14 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 17:24:14 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 17:24:14 volumio volumio[1058]: info: Discovery: Getting this device information Apr 13 17:24:14 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:14 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:14 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 17:24:14 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:14 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:14 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 13 17:24:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 13 17:24:18 volumio ntpd[825]: receive: Unexpected origin timestamp 0xeba655a1.bca5a8bb does not match aorg 0000000000.00000000 from server@91.212.242.21 xmt 0xeba655a2.e5fec044 Apr 13 17:24:18 volumio ntpd[825]: receive: Unexpected origin timestamp 0xeba655a1.bca4cd70 does not match aorg 0000000000.00000000 from server@94.198.159.11 xmt 0xeba655a2.e8ae2e4d Apr 13 17:24:18 volumio ntpd[825]: receive: Unexpected origin timestamp 0xeba655a1.bca73619 does not match aorg 0000000000.00000000 from server@45.90.187.8 xmt 0xeba655a2.e98ebea3 Apr 13 17:24:18 volumio ntpd[825]: receive: Unexpected origin timestamp 0xeba655a1.bca8060d does not match aorg 0000000000.00000000 from server@136.244.111.41 xmt 0xeba655a2.e972e23d Apr 13 17:24:18 volumio ntpd[825]: receive: Unexpected origin timestamp 0xeba655a1.bc9f2938 does not match aorg 0000000000.00000000 from server@156.17.20.254 xmt 0xeba655a2.e771c932 Apr 13 17:24:18 volumio ntpd[825]: receive: Unexpected origin timestamp 0xeba655a1.bca99bc2 does not match aorg 0000000000.00000000 from server@178.239.19.60 xmt 0xeba655a2.ea06157c Apr 13 17:24:18 volumio ntpd[825]: receive: Unexpected origin timestamp 0xeba655a1.bca677e0 does not match aorg 0000000000.00000000 from server@94.198.159.15 xmt 0xeba655a2.ea2cb5d6 Apr 13 17:24:18 volumio ntpd[825]: receive: Unexpected origin timestamp 0xeba655a1.bcaa5a66 does not match aorg 0000000000.00000000 from server@185.80.247.36 xmt 0xeba655a2.ea14876c Apr 13 17:24:18 volumio ntpd[825]: receive: Unexpected origin timestamp 0xeba655a1.bca39afd does not match aorg 0000000000.00000000 from server@185.79.243.74 xmt 0xeba655a2.eae78183 Apr 13 17:24:25 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 13 17:24:25 volumio volumio[1058]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 13 17:24:25 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 13 17:24:25 volumio volumio[1058]: info: Received Get System Version Apr 13 17:24:25 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 17:24:25 volumio volumio[1058]: info: Received Get System Info Apr 13 17:24:25 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 17:24:25 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 17:24:25 volumio volumio[1058]: info: Discovery: Getting this device information Apr 13 17:24:25 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:25 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:25 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 17:24:26 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 17:24:26 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 13 17:24:31 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 17:24:31 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 17:24:31 volumio volumio[1058]: info: Discovery: Getting this device information Apr 13 17:24:31 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:31 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:31 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 17:24:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 17:24:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 17:24:33 volumio volumio[1058]: info: Discovery: Getting this device information Apr 13 17:24:33 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:33 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:33 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 17:24:37 volumio go-librespot[1364]: time="2025-04-13T17:24:37+02:00" level=trace msg="sent dealer ping" Apr 13 17:24:37 volumio go-librespot[1364]: time="2025-04-13T17:24:37+02:00" level=trace msg="received dealer pong" Apr 13 17:24:38 volumio sudo[2031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Apr 13 17:24:38 volumio sudo[2031]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:38 volumio sudo[2031]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:38 volumio sudo[2034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Apr 13 17:24:38 volumio sudo[2034]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:38 volumio sudo[2037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Apr 13 17:24:38 volumio sudo[2037]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:38 volumio systemd[1]: Created slice system-getty.slice. Apr 13 17:24:38 volumio volumio[1058]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Apr 13 17:24:38 volumio sudo[2040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Apr 13 17:24:38 volumio sudo[2040]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:38 volumio systemd[1]: Started Getty on tty1. Apr 13 17:24:38 volumio sudo[2034]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:38 volumio volumio[1058]: info: touch_display: systemctl start getty@tty1.service succeeded. Apr 13 17:24:38 volumio systemd[1]: Reloading. Apr 13 17:24:38 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 13 17:24:38 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Apr 13 17:24:38 volumio sudo[2037]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:38 volumio volumio[1058]: info: touch_display: systemctl enable getty@tty1.service succeeded. Apr 13 17:24:38 volumio startx[1360]: xinit: connection to X server lost Apr 13 17:24:38 volumio startx[1360]: [1B blob data] Apr 13 17:24:38 volumio systemd[1]: Stopping Volumio Kiosk... Apr 13 17:24:39 volumio startx[1360]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Apr 13 17:24:39 volumio startx[1360]: xinit: unexpected signal 15 Apr 13 17:24:39 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Apr 13 17:24:39 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Apr 13 17:24:39 volumio systemd[1]: Stopped Volumio Kiosk. Apr 13 17:24:39 volumio sudo[2040]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:39 volumio volumio[1058]: info: touch_display: systemctl stop volumio-kiosk.service succeeded. Apr 13 17:24:39 volumio volumio[1058]: info: Disabling plugin touch_display Apr 13 17:24:39 volumio volumio[1058]: info: Done. Apr 13 17:24:44 volumio volumio[1058]: info: Enabling plugin touch_display Apr 13 17:24:44 volumio volumio[1058]: info: Loading plugin "touch_display"... Apr 13 17:24:44 volumio volumio[1058]: info: Applying required configuration parameters for plugin touch_display Apr 13 17:24:44 volumio volumio[1058]: info: PLUGIN START: touch_display Apr 13 17:24:44 volumio volumio[1058]: info: Loading i18n strings for locale pl Apr 13 17:24:44 volumio volumio[1058]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Apr 13 17:24:44 volumio volumio[1058]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Apr 13 17:24:44 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 17:24:44 volumio sudo[2061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Apr 13 17:24:44 volumio sudo[2061]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:44 volumio sudo[2064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Apr 13 17:24:44 volumio systemd[1]: Stopping Getty on tty1... Apr 13 17:24:44 volumio sudo[2064]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:44 volumio systemd[1]: getty@tty1.service: Main process exited, code=killed, status=15/TERM Apr 13 17:24:44 volumio systemd[1]: getty@tty1.service: Succeeded. Apr 13 17:24:44 volumio systemd[1]: Stopped Getty on tty1. Apr 13 17:24:44 volumio volumio[1058]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Apr 13 17:24:44 volumio sudo[2061]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:44 volumio sudo[2067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 13 17:24:44 volumio sudo[2067]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:44 volumio systemd[1]: Reloading. Apr 13 17:24:44 volumio volumio[1058]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Apr 13 17:24:44 volumio sudo[2070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Apr 13 17:24:44 volumio sudo[2070]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:44 volumio sudo[2070]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:44 volumio volumio[1058]: info: touch_display: systemctl stop getty@tty1.service succeeded. Apr 13 17:24:44 volumio volumio[1058]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Apr 13 17:24:44 volumio sudo[2073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Apr 13 17:24:44 volumio sudo[2073]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:44 volumio sudo[2073]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:44 volumio volumio[1058]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Apr 13 17:24:44 volumio volumio[1058]: info: Enabling plugin touch_display Apr 13 17:24:44 volumio volumio[1058]: info: Loading plugin "touch_display"... Apr 13 17:24:44 volumio volumio[1058]: info: Applying required configuration parameters for plugin touch_display Apr 13 17:24:44 volumio volumio[1058]: info: PLUGIN START: touch_display Apr 13 17:24:44 volumio volumio[1058]: info: Loading i18n strings for locale pl Apr 13 17:24:44 volumio volumio[1058]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Apr 13 17:24:44 volumio volumio[1058]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Apr 13 17:24:44 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 17:24:44 volumio sudo[2089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Apr 13 17:24:44 volumio sudo[2089]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:44 volumio sudo[2092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Apr 13 17:24:44 volumio sudo[2092]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:44 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 13 17:24:44 volumio volumio[1058]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Apr 13 17:24:44 volumio sudo[2095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 13 17:24:44 volumio sudo[2095]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:44 volumio volumio[1058]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Apr 13 17:24:44 volumio sudo[2098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Apr 13 17:24:44 volumio sudo[2098]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:44 volumio sudo[2098]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:44 volumio volumio[1058]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Apr 13 17:24:44 volumio sudo[2101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Apr 13 17:24:44 volumio sudo[2101]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:44 volumio sudo[2101]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:44 volumio volumio[1058]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Apr 13 17:24:44 volumio sudo[2064]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:44 volumio volumio[1058]: info: touch_display: systemctl disable getty@tty1.service succeeded. Apr 13 17:24:45 volumio systemd[1]: Reloading. Apr 13 17:24:45 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 13 17:24:45 volumio sudo[2067]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:45 volumio volumio[1058]: info: touch_display: systemctl daemon-reload succeeded. Apr 13 17:24:45 volumio systemd[1]: Reloading. Apr 13 17:24:45 volumio sudo[2120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Apr 13 17:24:45 volumio sudo[2120]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:45 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 13 17:24:45 volumio volumio[1058]: info: Enabling plugin touch_display Apr 13 17:24:45 volumio volumio[1058]: info: Loading plugin "touch_display"... Apr 13 17:24:45 volumio volumio[1058]: info: Applying required configuration parameters for plugin touch_display Apr 13 17:24:45 volumio volumio[1058]: info: PLUGIN START: touch_display Apr 13 17:24:45 volumio volumio[1058]: info: Loading i18n strings for locale pl Apr 13 17:24:45 volumio volumio[1058]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Apr 13 17:24:45 volumio volumio[1058]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Apr 13 17:24:45 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 17:24:46 volumio sudo[2136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Apr 13 17:24:46 volumio sudo[2136]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:46 volumio sudo[2139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Apr 13 17:24:46 volumio sudo[2139]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:46 volumio systemd[1]: Reloading. Apr 13 17:24:46 volumio sudo[2095]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:46 volumio volumio[1058]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Apr 13 17:24:46 volumio volumio[1058]: info: touch_display: systemctl daemon-reload succeeded. Apr 13 17:24:46 volumio sudo[2142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 13 17:24:46 volumio sudo[2142]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:46 volumio sudo[2145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Apr 13 17:24:46 volumio sudo[2145]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:46 volumio volumio[1058]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Apr 13 17:24:46 volumio sudo[2161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Apr 13 17:24:46 volumio sudo[2161]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:46 volumio sudo[2161]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:46 volumio volumio[1058]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Apr 13 17:24:46 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 13 17:24:46 volumio sudo[2092]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:46 volumio volumio[1058]: info: touch_display: systemctl disable getty@tty1.service succeeded. Apr 13 17:24:46 volumio systemd[1]: Reloading. Apr 13 17:24:46 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 13 17:24:47 volumio sudo[2142]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:47 volumio volumio[1058]: info: touch_display: systemctl daemon-reload succeeded. Apr 13 17:24:47 volumio systemd[1]: Reloading. Apr 13 17:24:47 volumio sudo[2178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Apr 13 17:24:47 volumio sudo[2178]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:24:47 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 13 17:24:47 volumio sudo[2139]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:47 volumio volumio[1058]: info: touch_display: systemctl disable getty@tty1.service succeeded. Apr 13 17:24:47 volumio systemd[1]: Started Volumio Kiosk. Apr 13 17:24:47 volumio sudo[2145]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:47 volumio sudo[2120]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:47 volumio volumio[1058]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Apr 13 17:24:47 volumio volumio[1058]: info: touch_display: Volumio Kiosk started. Apr 13 17:24:47 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:47 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:47 volumio volumio[1058]: info: Done. Apr 13 17:24:47 volumio sudo[2089]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:47 volumio sudo[2178]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:47 volumio volumio[1058]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Apr 13 17:24:47 volumio volumio[1058]: info: touch_display: Volumio Kiosk started. Apr 13 17:24:47 volumio sudo[2136]: pam_unix(sudo:session): session closed for user root Apr 13 17:24:47 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:47 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:47 volumio volumio[1058]: info: Done. Apr 13 17:24:47 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:47 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:47 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:47 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:47 volumio volumio[1058]: info: touch_display: systemctl stop getty@tty1.service succeeded. Apr 13 17:24:47 volumio volumio[1058]: info: touch_display: systemctl stop getty@tty1.service succeeded. Apr 13 17:24:47 volumio volumio[1058]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Apr 13 17:24:47 volumio volumio[1058]: info: touch_display: Volumio Kiosk started. Apr 13 17:24:47 volumio startx[2193]: X.Org X Server 1.20.4 Apr 13 17:24:47 volumio startx[2193]: X Protocol Version 11, Revision 0 Apr 13 17:24:47 volumio startx[2193]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Apr 13 17:24:47 volumio startx[2193]: Current Operating System: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Apr 13 17:24:47 volumio startx[2193]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=576 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:46:2F:DF 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=a0ee35d1-02bc-4547-b2c7-e6caa39d3454 imgfile=/volumio_current.sqsh bootpart=UUID=1BFB-AD60 datapart=UUID=a3c1e1ba-b424-4a77-8c81-c20d638e72d6 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Apr 13 17:24:47 volumio startx[2193]: Build Date: 04 April 2023 07:50:56AM Apr 13 17:24:47 volumio startx[2193]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Apr 13 17:24:47 volumio startx[2193]: Current version of pixman: 0.36.0 Apr 13 17:24:47 volumio startx[2193]: Before reporting problems, check http://wiki.x.org Apr 13 17:24:47 volumio startx[2193]: to make sure that you have the latest version. Apr 13 17:24:47 volumio startx[2193]: Markers: (--) probed, (**) from config file, (==) default setting, Apr 13 17:24:47 volumio startx[2193]: (++) from command line, (!!) notice, (II) informational, Apr 13 17:24:47 volumio startx[2193]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Apr 13 17:24:47 volumio startx[2193]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Apr 13 17:24:47 2025 Apr 13 17:24:47 volumio startx[2193]: (==) Using config directory: "/etc/X11/xorg.conf.d" Apr 13 17:24:47 volumio startx[2193]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Apr 13 17:24:47 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:47 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:47 volumio volumio[1058]: info: Done. Apr 13 17:24:47 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 13 17:24:47 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 13 17:24:47 volumio volumio[1058]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 13 17:24:47 volumio volumio[1058]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 13 17:24:47 volumio volumio[1058]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 13 17:24:47 volumio volumio[1058]: info: touch_display: X display number found: Apr 13 17:24:47 volumio volumio[1058]: info: touch_display: X display number found: Apr 13 17:24:47 volumio volumio[1058]: error: touch_display: Error determining the video outputs: Error: Command failed: /usr/bin/xrandr -display : Apr 13 17:24:47 volumio volumio[1058]: Can't open display : Apr 13 17:24:47 volumio volumio[1058]: error: touch_display: Error determining the video outputs: Error: Command failed: /usr/bin/xrandr -display : Apr 13 17:24:47 volumio volumio[1058]: Can't open display : Apr 13 17:24:47 volumio volumio[1058]: info: touch_display: X display number found: 0 Apr 13 17:24:47 volumio volumio[1058]: error: touch_display: Error determining the video outputs: Error: Command failed: /usr/bin/xrandr -display : Apr 13 17:24:47 volumio volumio[1058]: Can't open display : Apr 13 17:24:47 volumio volumio[1058]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 17:24:47 volumio volumio[1058]: Error: connect EISCONN /tmp/.X11-unix/X0 Apr 13 17:24:47 volumio volumio[1058]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 13 17:24:47 volumio volumio[1058]: errno: -106, Apr 13 17:24:47 volumio volumio[1058]: code: 'EISCONN', Apr 13 17:24:47 volumio volumio[1058]: syscall: 'connect', Apr 13 17:24:47 volumio volumio[1058]: address: '/tmp/.X11-unix/X0' Apr 13 17:24:47 volumio volumio[1058]: } Apr 13 17:24:47 volumio volumio[1058]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 17:24:48 volumio sudo[2288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-13 17:23 Apr 13 17:24:48 volumio sudo[2288]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"