-- Logs begin at Tue 2025-02-25 16:29:37 CET, end at Tue 2025-02-25 16:31:20 CET. -- Feb 25 16:30:00 volumio wireless.js[861]: trying... Feb 25 16:30:00 volumio sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 16:30:00 volumio sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:00 volumio sudo[1162]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:01 volumio wireless.js[861]: trying... Feb 25 16:30:01 volumio sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 16:30:01 volumio sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:01 volumio sudo[1168]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:01 volumio volumio-remote-updater[842]: [2025-02-25 16:30:01] [info] asio async_connect error: system:111 (Connection refused) Feb 25 16:30:01 volumio volumio-remote-updater[842]: [2025-02-25 16:30:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 25 16:30:01 volumio volumio-remote-updater[842]: [2025-02-25 16:30:01] [error] handle_connect error: Underlying Transport Error Feb 25 16:30:02 volumio wireless.js[861]: trying... Feb 25 16:30:02 volumio sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 16:30:02 volumio sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:02 volumio sudo[1171]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:03 volumio wireless.js[861]: trying... Feb 25 16:30:03 volumio sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 16:30:03 volumio sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:03 volumio sudo[1174]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:03 volumio dhcpcd[1069]: wlan0: using IPv4LL address 169.254.214.152 Feb 25 16:30:03 volumio avahi-daemon[864]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.214.152. Feb 25 16:30:03 volumio avahi-daemon[864]: New relevant interface wlan0.IPv4 for mDNS. Feb 25 16:30:03 volumio dhcpcd[1069]: wlan0: adding route to 169.254.0.0/16 Feb 25 16:30:03 volumio avahi-daemon[864]: Registering new address record for 169.254.214.152 on wlan0.IPv4. Feb 25 16:30:03 volumio dhcpcd[1069]: wlan0: adding default route Feb 25 16:30:03 volumio dhcpcd[1069]: wlan0: probing address 192.168.178.126/24 Feb 25 16:30:04 volumio wireless.js[861]: trying... Feb 25 16:30:04 volumio sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 16:30:04 volumio sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:04 volumio sudo[1188]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:04 volumio wireless.js[861]: Connected to: ----FRITZ!Box 7530 YD_EXT Feb 25 16:30:04 volumio wireless.js[861]: ---- Feb 25 16:30:04 volumio sudo[1191]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 16:30:04 volumio sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:04 volumio sudo[1191]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:04 volumio wireless.js[861]: ... joined AP, wlan0 IPv4 is 169.254.214.152, ipV6 is undefined Feb 25 16:30:04 volumio wireless.js[861]: It's done! AP Feb 25 16:30:04 volumio systemd[1]: Started Wireless Services. Feb 25 16:30:04 volumio systemd[1]: Started Volumio Backend Module. Feb 25 16:30:04 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 25 16:30:04 volumio systemd[1]: Started Volumio Cpu Tweaker. Feb 25 16:30:04 volumio volumio-cpu-tweak[1197]: Setting RT Priority for mpd Feb 25 16:30:04 volumio volumio-cpu-tweak[1197]: Setting MPD Affinity Feb 25 16:30:04 volumio volumio-cpu-tweak[1197]: pid 982's current affinity mask: f Feb 25 16:30:04 volumio volumio-cpu-tweak[1197]: pid 982's new affinity mask: 3 Feb 25 16:30:04 volumio volumio-cpu-tweak[1197]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 25 16:30:04 volumio volumio-cpu-tweak[1197]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Feb 25 16:30:04 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Feb 25 16:30:04 volumio volumio-time-update[848]: volumio-time-update-util: Fetching time from Volumio... Feb 25 16:30:04 volumio ntpd[960]: Listen normally on 3 wlan0 169.254.214.152:123 Feb 25 16:30:04 volumio ntpd[960]: new interface(s) found: waking up resolver Feb 25 16:30:04 volumio volumio[1196]: info: ------------------------------------------- Feb 25 16:30:04 volumio volumio[1196]: info: ----- Volumio3 ---- Feb 25 16:30:04 volumio volumio[1196]: info: ------------------------------------------- Feb 25 16:30:04 volumio volumio[1196]: info: ----- System startup ---- Feb 25 16:30:04 volumio volumio[1196]: info: ------------------------------------------- Feb 25 16:30:05 volumio volumio[1196]: info: MYVOLUMIO Environment detected Feb 25 16:30:05 volumio nmbd[940]: [2025/02/25 16:30:05.111007, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 25 16:30:05 volumio nmbd[940]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 25 16:30:05 volumio systemd[1]: Started Samba NMB Daemon. Feb 25 16:30:05 volumio volumio[1196]: info: Plugin folders cleanup Feb 25 16:30:05 volumio volumio[1196]: info: Scanning into folder /volumio/app/plugins/ Feb 25 16:30:05 volumio volumio[1196]: info: Scanning category audio_interface Feb 25 16:30:05 volumio volumio[1196]: info: Scanning category miscellanea Feb 25 16:30:05 volumio volumio[1196]: info: Scanning category music_service Feb 25 16:30:05 volumio volumio[1196]: info: Scanning category plugins.json Feb 25 16:30:05 volumio volumio[1196]: info: Scanning category system_controller Feb 25 16:30:05 volumio volumio[1196]: info: Scanning category user_interface Feb 25 16:30:05 volumio volumio[1196]: info: Scanning into folder /data/plugins/ Feb 25 16:30:05 volumio volumio[1196]: info: Scanning category music_service Feb 25 16:30:05 volumio volumio[1196]: info: Scanning category system_controller Feb 25 16:30:05 volumio volumio[1196]: info: Scanning category user_interface Feb 25 16:30:05 volumio volumio[1196]: info: Plugin folders cleanup completed Feb 25 16:30:05 volumio volumio[1196]: info: ------------------------------------------- Feb 25 16:30:05 volumio volumio[1196]: info: ----- Core plugins startup ---- Feb 25 16:30:05 volumio volumio[1196]: info: ------------------------------------------- Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugins from folder /volumio/app/plugins/ Feb 25 16:30:05 volumio volumio[1196]: info: Adding plugin upnp to MyMusic Plugins Feb 25 16:30:05 volumio volumio[1196]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 25 16:30:05 volumio volumio[1196]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugins from folder /data/plugins/ Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "system"... Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "appearance"... Feb 25 16:30:05 volumio systemd[1]: Starting Samba Winbind Daemon... Feb 25 16:30:05 volumio winbindd[1227]: [2025/02/25 16:30:05.214019, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 25 16:30:05 volumio winbindd[1227]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 25 16:30:05 volumio winbindd[1227]: [2025/02/25 16:30:05.221119, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 25 16:30:05 volumio systemd[1]: Started Samba Winbind Daemon. Feb 25 16:30:05 volumio winbindd[1227]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 25 16:30:05 volumio systemd[1]: Starting Samba SMB Daemon... Feb 25 16:30:05 volumio smbd[1235]: [2025/02/25 16:30:05.396703, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 25 16:30:05 volumio systemd[1]: Started Samba SMB Daemon. Feb 25 16:30:05 volumio smbd[1235]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "network"... Feb 25 16:30:05 volumio volumio[1196]: info: Refreshing Cached IP Addresses Feb 25 16:30:05 volumio sudo[1244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 16:30:05 volumio sudo[1244]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:05 volumio sudo[1244]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:05 volumio sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 16:30:05 volumio sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:05 volumio sudo[1246]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "services"... Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "alsa_controller"... Feb 25 16:30:05 volumio sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 25 16:30:05 volumio sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "wizard"... Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "networkfs"... Feb 25 16:30:05 volumio volumio[1196]: info: Starting Udev Watcher for removable devices Feb 25 16:30:05 volumio volumio[1196]: info: Ignoring mount for partition: boot Feb 25 16:30:05 volumio volumio[1196]: info: Ignoring mount for partition: volumio Feb 25 16:30:05 volumio volumio[1196]: info: Ignoring mount for partition: volumio_data Feb 25 16:30:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "volumio_command_line_client"... Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "upnp"... Feb 25 16:30:05 volumio volumio[1196]: info: [1740497405646] Starting Upmpd Daemon Feb 25 16:30:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "my_music"... Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "mpd"... Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "upnp_browser"... Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "alarm-clock"... Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "airplay_emulation"... Feb 25 16:30:05 volumio volumio[1196]: info: Starting Shairport Sync Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "last_100"... Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "webradio"... Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "i2s_dacs"... Feb 25 16:30:05 volumio volumio[1196]: info: I2S DAC not set, start Auto-detection Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "volumiodiscovery"... Feb 25 16:30:05 volumio volumio[1196]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 25 16:30:05 volumio node[1196]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 25 16:30:05 volumio node[1196]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 16:30:05 volumio volumio[1196]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 16:30:05 volumio volumio[1196]: *** WARNING *** For more information see Feb 25 16:30:05 volumio volumio[1196]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 25 16:30:05 volumio volumio[1196]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 16:30:05 volumio volumio[1196]: *** WARNING *** For more information see Feb 25 16:30:05 volumio node[1196]: *** WARNING *** For more information see Feb 25 16:30:05 volumio node[1196]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 25 16:30:05 volumio node[1196]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 16:30:05 volumio node[1196]: *** WARNING *** For more information see Feb 25 16:30:05 volumio volumio[1196]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 25 16:30:05 volumio volumio[1196]: info: Discovery: Started advertising with name: Volumio Feb 25 16:30:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 16:30:05 volumio volumio[1196]: info: Loading plugin "autostart"... Feb 25 16:30:06 volumio volumio[1196]: info: Applying required configuration parameters for plugin autostart Feb 25 16:30:06 volumio volumio[1196]: info: AutoStart - onVolumioStart - read config.json Feb 25 16:30:06 volumio volumio[1196]: info: Loading plugin "now_playing"... Feb 25 16:30:06 volumio volumio[1196]: info: Loading plugin "peppy_screensaver"... Feb 25 16:30:06 volumio volumio[1196]: info: Applying required configuration parameters for plugin peppy_screensaver Feb 25 16:30:06 volumio volumio[1196]: info: Loading plugin "outputs"... Feb 25 16:30:06 volumio volumio[1196]: info: Loading plugin "albumart"... Feb 25 16:30:06 volumio volumio[1196]: info: Plugin example_plugin is not enabled Feb 25 16:30:06 volumio volumio[1196]: info: Loading plugin "inputs"... Feb 25 16:30:06 volumio volumio[1196]: info: Loading plugin "updater_comm"... Feb 25 16:30:06 volumio volumio[1196]: info: Plugin mpdemulation is not enabled Feb 25 16:30:06 volumio volumio[1196]: info: Loading plugin "rest_api"... Feb 25 16:30:06 volumio volumio[1196]: Forking 3 albumart workers Feb 25 16:30:06 volumio volumio[1196]: info: Loading plugin "websocket"... Feb 25 16:30:06 volumio volumio[1196]: info: Starting Socket.io Server version 2.3.0 Feb 25 16:30:06 volumio volumio[1196]: info: Loading plugin "RoonBridge"... Feb 25 16:30:06 volumio volumio[1196]: info: Applying required configuration parameters for plugin RoonBridge Feb 25 16:30:06 volumio volumio[1196]: info: Plugin lms is not enabled Feb 25 16:30:06 volumio volumio[1196]: info: Loading plugin "minidlna"... Feb 25 16:30:06 volumio volumio[1196]: info: Applying required configuration parameters for plugin minidlna Feb 25 16:30:06 volumio volumio[1196]: Starting albumart workers Feb 25 16:30:06 volumio volumio[1196]: Starting albumart workers Feb 25 16:30:06 volumio volumio[1196]: Starting albumart workers Feb 25 16:30:06 volumio volumio[1196]: info: Loading plugin "Systeminfo"... Feb 25 16:30:07 volumio volumio[1196]: info: Loading plugin "touch_display"... Feb 25 16:30:07 volumio volumio[1196]: info: Applying required configuration parameters for plugin touch_display Feb 25 16:30:07 volumio volumio[1196]: info: Loading i18n strings for locale de Feb 25 16:30:07 volumio volumio[1196]: Updating browse sources language Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::initPlayerControls Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: Express server listening on port 3000 Feb 25 16:30:07 volumio volumio[1196]: [Metrics] WebUI: 2s 828.67ms Feb 25 16:30:07 volumio volumio[1196]: info: CoreStateMachine::resetVolumioState Feb 25 16:30:07 volumio volumio[1196]: info: CoreStateMachine::getcurrentVolume Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: Volumio Network Manager: Network status updated: 2 Feb 25 16:30:07 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 25 16:30:07 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 25 16:30:07 volumio volumio[1196]: info: CoreStateMachine::updateTrackBlock Feb 25 16:30:07 volumio volumio[1196]: info: CorePlayQueue::getTrackBlock Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 16:30:07 volumio volumio[1196]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 25 16:30:07 volumio volumio[1196]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 25 16:30:07 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 25 16:30:07 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 25 16:30:07 volumio volumio[1196]: info: CoreStateMachine::setRepeat null single undefined Feb 25 16:30:07 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 25 16:30:07 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 25 16:30:07 volumio volumio[1196]: info: CoreStateMachine::setRandom true Feb 25 16:30:07 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 25 16:30:07 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 25 16:30:07 volumio volumio[1196]: info: Setting Device type: Raspberry PI Feb 25 16:30:07 volumio volumio[1196]: info: Completed loading Core Plugins Feb 25 16:30:07 volumio volumio[1196]: info: Preparing to generate the ALSA configuration file Feb 25 16:30:07 volumio volumio[1196]: info: Reloading queue from file Feb 25 16:30:07 volumio volumio[1196]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 25 16:30:07 volumio volumio[1196]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Feb 25 16:30:07 volumio volumio[1196]: info: Reading ALSA contributions from plugins. Feb 25 16:30:07 volumio volumio[1196]: info: Asound.conf file unchanged, so no further update is needed Feb 25 16:30:07 volumio volumio[1196]: info: Output device has changed, restarting MPD Feb 25 16:30:07 volumio sudo[1346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 16:30:07 volumio sudo[1346]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:07 volumio volumio[1196]: info: Output device has changed, restarting Shairport Sync Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:30:07 volumio sudo[1346]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:07 volumio sudo[1349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 16:30:07 volumio sudo[1349]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:07 volumio systemd[1]: Stopping Music Player Daemon... Feb 25 16:30:07 volumio volumio[1196]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 16:30:07 volumio volumio[1196]: info: ___________ START PLUGINS ___________ Feb 25 16:30:07 volumio volumio[1196]: info: ControllerMpd::onStart: Initializing MPD Feb 25 16:30:07 volumio volumio[1196]: info: Creating MPD Configuration file Feb 25 16:30:07 volumio sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 16:30:07 volumio sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:07 volumio sudo[1355]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 16:30:07 volumio volumio[1196]: info: [1740497407600] CoreMusicLibrary::Adding element Medienserver Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 16:30:07 volumio sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 16:30:07 volumio sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:30:07 volumio systemd[1]: mpd.service: Succeeded. Feb 25 16:30:07 volumio systemd[1]: Stopped Music Player Daemon. Feb 25 16:30:07 volumio volumio[1196]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:30:07 volumio systemd[1]: Starting Music Player Daemon... Feb 25 16:30:07 volumio volumio[1196]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 16:30:07 volumio volumio[1196]: info: [1740497407639] CoreMusicLibrary::Adding element Last_100 Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 16:30:07 volumio volumio[1196]: info: [1740497407640] CoreMusicLibrary::Adding element Webradio Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 16:30:07 volumio volumio[1196]: info: Initializing BBC Radios Feb 25 16:30:07 volumio sudo[1363]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 16:30:07 volumio sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:07 volumio sudo[1363]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: AutoStart - onStart Feb 25 16:30:07 volumio volumio[1196]: info: [now-playing] ConfigUpdater: config is up to date. Feb 25 16:30:07 volumio volumio[1196]: info: Loading i18n strings for locale de Feb 25 16:30:07 volumio volumio[1196]: info: /tmp/myfifo created Feb 25 16:30:07 volumio volumio[1196]: info: /tmp/myfifosa created Feb 25 16:30:07 volumio sudo[1384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Feb 25 16:30:07 volumio sudo[1384]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:07 volumio sudo[1384]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:07 volumio volumio[1196]: info: snd-dummy loaded Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:30:07 volumio volumio[1196]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 16:30:07 volumio volumio[1196]: info: Loading i18n strings for locale de Feb 25 16:30:07 volumio sudo[1404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 25 16:30:07 volumio sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:07 volumio volumio[1196]: info: Loading i18n strings for locale de Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 16:30:07 volumio volumio[1196]: info: Volumio Calling Home Feb 25 16:30:07 volumio systemd[1]: Started RoonBridge. Feb 25 16:30:07 volumio sudo[1404]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:07 volumio sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 25 16:30:07 volumio sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:07 volumio sudo[1423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 25 16:30:07 volumio sudo[1423]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:07 volumio systemd[1]: Reloading. Feb 25 16:30:07 volumio sudo[1426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 25 16:30:07 volumio sudo[1426]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:07 volumio volumio[1196]: info: [now-playing] App is listening on port 4004. Feb 25 16:30:07 volumio volumio[1196]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Feb 25 16:30:07 volumio volumio[1196]: info: touch_display: No backlight interface detected. Feb 25 16:30:07 volumio volumio[1196]: info: minidlna: Found miniDLNA version 1.2.1 Feb 25 16:30:07 volumio volumio[1196]: info: minidlna: Starting minidlna.service Feb 25 16:30:07 volumio mpd[1374]: Feb 25 16:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 16:30:07 volumio sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Feb 25 16:30:07 volumio sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:07 volumio volumio[1196]: info: MPD Permissions set Feb 25 16:30:07 volumio volumio[1196]: info: MPD Permissions set Feb 25 16:30:07 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Feb 25 16:30:07 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 25 16:30:08 volumio volumio[1196]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:30:08 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. Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:08 volumio volumio[1196]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 25 16:30:08 volumio sudo[1457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 25 16:30:08 volumio sudo[1457]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:08 volumio sudo[1460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 25 16:30:08 volumio sudo[1460]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:08 volumio volumio[1196]: info: Starting Shairport Sync Feb 25 16:30:08 volumio sudo[1466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Feb 25 16:30:08 volumio sudo[1466]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:08 volumio sudo[1460]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:08 volumio volumio[1196]: info: Starting Shairport Sync Feb 25 16:30:08 volumio sudo[1468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 16:30:08 volumio sudo[1468]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:08 volumio sudo[1457]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:08 volumio sudo[1466]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:08 volumio volumio[1196]: info: Starting Shairport Sync Feb 25 16:30:08 volumio systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Feb 25 16:30:08 volumio systemd[1]: serial-getty@ttyAMA10.service: Current command vanished from the unit file, execution of the command list won't be resumed. Feb 25 16:30:08 volumio volumio[1196]: info: Starting Shairport Sync Feb 25 16:30:08 volumio sudo[1474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 16:30:08 volumio sudo[1472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Feb 25 16:30:08 volumio volumio[1196]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 25 16:30:08 volumio volumio[1196]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:08 volumio sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:08 volumio sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:08 volumio sudo[1476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 16:30:08 volumio sudo[1476]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:08 volumio sudo[1481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 16:30:08 volumio sudo[1481]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:08 volumio sudo[1481]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:30:08 volumio volumio[1196]: info: MPD Permissions set Feb 25 16:30:08 volumio sudo[1423]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:08 volumio volumio[1196]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 25 16:30:08 volumio systemd[1]: Started Music Player Daemon. Feb 25 16:30:08 volumio sudo[1358]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:08 volumio sudo[1349]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:08 volumio systemd[1]: Reloading. Feb 25 16:30:08 volumio volumio[1196]: error: MPD error: The expression evaluated to a falsy value: Feb 25 16:30:08 volumio volumio[1196]: assert.ok(self.idling) Feb 25 16:30:08 volumio volumio[1196]: error: The expression evaluated to a falsy value: Feb 25 16:30:08 volumio volumio[1196]: assert.ok(self.idling) Feb 25 16:30:08 volumio volumio[1196]: info: MPD running with PID1374 Feb 25 16:30:08 volumio volumio[1196]: ,establishing connection Feb 25 16:30:08 volumio volumio[1196]: error: updateQueue error: null Feb 25 16:30:08 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. Feb 25 16:30:08 volumio RoonBridge[1408]: 00:00:00.003 Warn: get lock file path: /tmp/.rnbgem0- Feb 25 16:30:08 volumio sudo[1426]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:08 volumio volumio[1196]: info: touch_display: systemctl daemon-reload succeeded. Feb 25 16:30:08 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Feb 25 16:30:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 25 16:30:08 volumio sudo[1445]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:08 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 25 16:30:08 volumio volumio[1196]: info: minidlna: systemctl start minidlna.service succeeded. Feb 25 16:30:08 volumio sudo[1503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 25 16:30:08 volumio sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 25 16:30:08 volumio sudo[1416]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 25 16:30:08 volumio volumio[1196]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 25 16:30:08 volumio sudo[1474]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:08 volumio sudo[1476]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:08 volumio sudo[1468]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:08 volumio volumio[1196]: info: Shairport-Sync Started Feb 25 16:30:08 volumio volumio[1196]: Error adding Membership: Error: addMembership EINVAL Feb 25 16:30:08 volumio sudo[1472]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:08 volumio systemd[1]: Started Volumio Kiosk. Feb 25 16:30:08 volumio sudo[1503]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:08 volumio volumio[1196]: info: Shairport-Sync Started Feb 25 16:30:08 volumio volumio[1196]: info: Shairport-Sync Started Feb 25 16:30:08 volumio volumio[1196]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 25 16:30:08 volumio volumio[1196]: info: touch_display: Volumio Kiosk started. Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Feb 25 16:30:08 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 25 16:30:08 volumio volumio[1196]: info: Completed starting Core Plugins Feb 25 16:30:08 volumio volumio[1196]: info: ------------------------------------------- Feb 25 16:30:08 volumio volumio[1196]: info: ----- MyVolumio plugins startup ---- Feb 25 16:30:08 volumio volumio[1196]: info: ------------------------------------------- Feb 25 16:30:08 volumio volumio[1196]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 25 16:30:08 volumio volumio[1196]: info: Shairport-Sync Started Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Feb 25 16:30:08 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 25 16:30:08 volumio volumio[1196]: info: touch_display: X display number found: Feb 25 16:30:08 volumio RoonBridge[1408]: 00:00:00.181 Trace: [childprocess] using unix child process Feb 25 16:30:08 volumio RoonBridge[1408]: 00:00:00.202 Debug: PathForResource, filename: ../.update Feb 25 16:30:08 volumio startx[1507]: X.Org X Server 1.20.4 Feb 25 16:30:08 volumio startx[1507]: X Protocol Version 11, Revision 0 Feb 25 16:30:08 volumio startx[1507]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Feb 25 16:30:08 volumio startx[1507]: Current Operating System: Linux volumio 6.6.62-v8+ #1816 SMP PREEMPT Mon Nov 18 15:35:52 GMT 2024 aarch64 Feb 25 16:30:08 volumio startx[1507]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave smsc95xx.macaddr=2C:CF:67:D0:AD:88 vc_mem.mem_base=0x3fc00000 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=ttyAMA10,115200 console=tty1 imgpart=UUID=fb80cb44-b4d3-4b36-be35-613cba0a09fb imgfile=/volumio_current.sqsh bootpart=UUID=51B1-040D datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a 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 Feb 25 16:30:08 volumio startx[1507]: Build Date: 04 April 2023 07:50:56AM Feb 25 16:30:08 volumio startx[1507]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Feb 25 16:30:08 volumio startx[1507]: Current version of pixman: 0.36.0 Feb 25 16:30:08 volumio startx[1507]: Before reporting problems, check http://wiki.x.org Feb 25 16:30:08 volumio startx[1507]: to make sure that you have the latest version. Feb 25 16:30:08 volumio startx[1507]: Markers: (--) probed, (**) from config file, (==) default setting, Feb 25 16:30:08 volumio startx[1507]: (++) from command line, (!!) notice, (II) informational, Feb 25 16:30:08 volumio startx[1507]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Feb 25 16:30:08 volumio startx[1507]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue Feb 25 16:30:08 2025 Feb 25 16:30:08 volumio startx[1507]: (==) Using config directory: "/etc/X11/xorg.conf.d" Feb 25 16:30:08 volumio startx[1507]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Feb 25 16:30:08 volumio RoonBridge[1408]: 00:00:00.241 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Feb 25 16:30:08 volumio RoonBridge[1408]: 00:00:00.243 Debug: PathForResource, filename: RAATServer Feb 25 16:30:08 volumio RoonBridge[1408]: 00:00:00.243 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Feb 25 16:30:08 volumio RoonBridge[1408]: 00:00:00.243 Debug: PathForResource, filename: RoonBridgeHelper Feb 25 16:30:08 volumio RoonBridge[1408]: 00:00:00.243 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Feb 25 16:30:08 volumio RoonBridge[1408]: Initializing Feb 25 16:30:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Feb 25 16:30:08 volumio RoonBridge[1408]: 00:00:00.262 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Feb 25 16:30:08 volumio volumio[1196]: info: touch_display: X display number found: 0 Feb 25 16:30:08 volumio sudo[1548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 16:30:08 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:08 volumio RoonBridge[1408]: 00:00:00.280 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Feb 25 16:30:08 volumio systemd[1]: Stopping Music Player Daemon... Feb 25 16:30:08 volumio systemd[1]: mpd.service: Succeeded. Feb 25 16:30:08 volumio systemd[1]: Stopped Music Player Daemon. Feb 25 16:30:08 volumio RoonBridge[1408]: Not Running (.o) Feb 25 16:30:08 volumio systemd[1]: Starting Music Player Daemon... Feb 25 16:30:08 volumio systemd[1]: systemd-fsckd.service: Succeeded. Feb 25 16:30:08 volumio sudo[1554]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 16:30:08 volumio sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:08 volumio sudo[1554]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:08 volumio volumio[1196]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 25 16:30:08 volumio volumio[1196]: info: touch_display: X display number found: 0 Feb 25 16:30:08 volumio RoonBridge[1408]: Running Feb 25 16:30:08 volumio dhcpcd[1069]: wlan0: leased 192.168.178.126 for 864000 seconds Feb 25 16:30:08 volumio avahi-daemon[864]: Registering new address record for 192.168.178.126 on wlan0.IPv4. Feb 25 16:30:08 volumio dhcpcd[1069]: wlan0: adding route to 192.168.178.0/24 Feb 25 16:30:08 volumio dhcpcd[1069]: wlan0: changing default route via 192.168.178.1 Feb 25 16:30:08 volumio sh[653]: timed out Feb 25 16:30:08 volumio dhcpcd[739]: timed out Feb 25 16:30:08 volumio sh[653]: dhcpcd exited Feb 25 16:30:08 volumio dhcpcd[739]: dhcpcd exited Feb 25 16:30:09 volumio sh[653]: ifup: failed to bring up eth0 Feb 25 16:30:09 volumio mpd[1560]: Feb 25 16:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 16:30:09 volumio avahi-daemon[864]: Withdrawing address record for 169.254.214.152 on wlan0. Feb 25 16:30:09 volumio avahi-daemon[864]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.214.152. Feb 25 16:30:09 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Feb 25 16:30:09 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Feb 25 16:30:09 volumio systemd[1]: Started Music Player Daemon. Feb 25 16:30:09 volumio avahi-daemon[864]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.126. Feb 25 16:30:09 volumio dhcpcd[1069]: wlan0: deleting route to 169.254.0.0/16 Feb 25 16:30:09 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:09 volumio volumio[1196]: error: updateQueue error: null Feb 25 16:30:09 volumio dhcpcd[1069]: wlan0: pid 1069 deleted default route via 192.168.178.1 Feb 25 16:30:09 volumio RoonBridge[1408]: 00:00:00.012 Warn: get lock file path: /tmp/.rnbhgem0- Feb 25 16:30:09 volumio nmbd[940]: [2025/02/25 16:30:09.121319, 0] ../source3/libsmb/nmblib.c:917(send_udp) Feb 25 16:30:09 volumio nmbd[940]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Feb 25 16:30:09 volumio nmbd[940]: [2025/02/25 16:30:09.121414, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 25 16:30:09 volumio nmbd[940]: retransmit_or_expire_response_records: Failed to resend packet id 12707 to IP 169.254.255.255 on subnet 169.254.214.152 Feb 25 16:30:09 volumio nmbd[940]: [2025/02/25 16:30:09.121438, 0] ../source3/libsmb/nmblib.c:917(send_udp) Feb 25 16:30:09 volumio nmbd[940]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Feb 25 16:30:09 volumio nmbd[940]: [2025/02/25 16:30:09.121454, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 25 16:30:09 volumio nmbd[940]: retransmit_or_expire_response_records: Failed to resend packet id 12708 to IP 169.254.255.255 on subnet 169.254.214.152 Feb 25 16:30:09 volumio nmbd[940]: [2025/02/25 16:30:09.121471, 0] ../source3/libsmb/nmblib.c:917(send_udp) Feb 25 16:30:09 volumio nmbd[940]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Feb 25 16:30:09 volumio nmbd[940]: [2025/02/25 16:30:09.121485, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 25 16:30:09 volumio nmbd[940]: retransmit_or_expire_response_records: Failed to resend packet id 12709 to IP 169.254.255.255 on subnet 169.254.214.152 Feb 25 16:30:09 volumio nmbd[940]: [2025/02/25 16:30:09.121502, 0] ../source3/libsmb/nmblib.c:917(send_udp) Feb 25 16:30:09 volumio nmbd[940]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Feb 25 16:30:09 volumio nmbd[940]: [2025/02/25 16:30:09.121517, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 25 16:30:09 volumio nmbd[940]: retransmit_or_expire_response_records: Failed to resend packet id 12710 to IP 169.254.255.255 on subnet 169.254.214.152 Feb 25 16:30:09 volumio nmbd[940]: [2025/02/25 16:30:09.121533, 0] ../source3/libsmb/nmblib.c:917(send_udp) Feb 25 16:30:09 volumio nmbd[940]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Feb 25 16:30:09 volumio nmbd[940]: [2025/02/25 16:30:09.121548, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 25 16:30:09 volumio nmbd[940]: retransmit_or_expire_response_records: Failed to resend packet id 12711 to IP 169.254.255.255 on subnet 169.254.214.152 Feb 25 16:30:09 volumio sudo[1255]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:09 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Feb 25 16:30:10 volumio nmbd[940]: [2025/02/25 16:30:10.122741, 0] ../source3/libsmb/nmblib.c:917(send_udp) Feb 25 16:30:10 volumio nmbd[940]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Feb 25 16:30:10 volumio volumio-time-update[848]: volumio-time-update-util: Setting system time to: 2025-02-25 16:30:10 Feb 25 16:30:10 volumio ntpd[960]: Listen normally on 4 wlan0 192.168.178.126:123 Feb 25 16:30:10 volumio ntpd[960]: Deleting interface #3 wlan0, 169.254.214.152#123, interface stats: received=0, sent=0, dropped=0, active_time=6 secs Feb 25 16:30:10 volumio ntpd[960]: new interface(s) found: waking up resolver Feb 25 16:30:11 volumio volumio-remote-updater[842]: [2025-02-25 16:30:11] [connect] Successful connection Feb 25 16:30:11 volumio volumio-remote-updater[842]: [2025-02-25 16:30:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740497411 101 Feb 25 16:30:11 volumio volumio[1196]: 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: 4 Feb 25 16:30:12 volumio volumio[1196]: info: Discovery: adding f9b13b56-a937-4855-9d3d-a60ef3660aef Feb 25 16:30:12 volumio volumio[1196]: info: Discovery: Found device Volumio Feb 25 16:30:12 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Feb 25 16:30:12 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 25 16:30:12 volumio systemd[1]: Stopping Music Player Daemon... Feb 25 16:30:13 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Feb 25 16:30:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 25 16:30:41 volumio volumio-remote-updater[842]: [2025-02-25 16:30:41] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Feb 25 16:30:44 volumio ntpd[960]: Soliciting pool server 213.172.105.106 Feb 25 16:30:45 volumio ntpd[960]: Soliciting pool server 144.76.138.23 Feb 25 16:30:46 volumio ntpd[960]: Soliciting pool server 185.248.188.98 Feb 25 16:30:46 volumio ntpd[960]: Soliciting pool server 185.194.239.35 Feb 25 16:30:46 volumio volumio-remote-updater[842]: [2025-02-25 16:30:46] [connect] Successful connection Feb 25 16:30:59 volumio volumio-remote-updater[842]: [2025-02-25 16:30:59] [connect] Successful connection Feb 25 16:31:11 volumio kernel: mmc0: cqhci: timeout for tag 0, qcnt 8 Feb 25 16:31:11 volumio kernel: mmc0: cqhci: ============ CQHCI REGISTER DUMP =========== Feb 25 16:31:11 volumio kernel: mmc0: cqhci: Caps: 0x000030c8 | Version: 0x00000510 Feb 25 16:31:11 volumio kernel: mmc0: cqhci: Config: 0x00000101 | Control: 0x00000000 Feb 25 16:31:11 volumio kernel: mmc0: cqhci: Int stat: 0x00000000 | Int enab: 0x00000016 Feb 25 16:31:11 volumio kernel: mmc0: cqhci: Int sig: 0x00000016 | Int Coal: 0x00000000 Feb 25 16:31:11 volumio kernel: mmc0: cqhci: TDL base: 0x048b1000 | TDL up32: 0x00000001 Feb 25 16:31:11 volumio kernel: mmc0: cqhci: Doorbell: 0x3090000f | TCN: 0x00000000 Feb 25 16:31:11 volumio kernel: mmc0: cqhci: Dev queue: 0x00800000 | Dev Pend: 0x00800000 Feb 25 16:31:11 volumio kernel: mmc0: cqhci: Task clr: 0x00000000 | SSC1: 0x00004000 Feb 25 16:31:11 volumio kernel: mmc0: cqhci: SSC2: 0x000059b4 | DCMD rsp: 0x00000000 Feb 25 16:31:11 volumio kernel: mmc0: cqhci: RED mask: 0xfdf9a080 | TERRI: 0x00000000 Feb 25 16:31:11 volumio kernel: mmc0: cqhci: Resp idx: 0x0000002e | Resp arg: 0x00000900 Feb 25 16:31:11 volumio kernel: mmc0: running CQE recovery Feb 25 16:31:12 volumio kernel: mmc0: cqhci: Failed to halt Feb 25 16:31:12 volumio nmbd[940]: [2025/02/25 16:31:12.117205, 0] ../source3/libsmb/nmblib.c:917(send_udp) Feb 25 16:31:12 volumio nmbd[940]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Feb 25 16:31:12 volumio startx[1507]: V3D 7.1 not supported by this version of Mesa. Feb 25 16:31:12 volumio sudo[1636]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-02-25 16:30:10 Feb 25 16:31:12 volumio sudo[1636]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:31:12 volumio systemd[1]: mpd.service: Succeeded. Feb 25 16:31:12 volumio sudo[1783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 16:31:12 volumio volumio[1196]: info: AutoStart - Plugin is starting Feb 25 16:31:12 volumio volumio[1196]: info: CoreCommandRouter::volumioGetQueue Feb 25 16:31:12 volumio volumio[1196]: info: CoreStateMachine::getQueue Feb 25 16:31:12 volumio volumio[1196]: info: CorePlayQueue::getQueue Feb 25 16:31:12 volumio volumio[1196]: info: AutoStart - start playing Feb 25 16:31:12 volumio volumio[1196]: info: AutoStart - start playing with no specific position Feb 25 16:31:12 volumio volumio[1196]: info: CoreCommandRouter::volumioPlay Feb 25 16:31:12 volumio volumio[1196]: info: CoreStateMachine::play index 0 Feb 25 16:31:12 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 16:31:12 volumio volumio[1196]: info: CoreStateMachine::stop Feb 25 16:31:12 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 16:31:12 volumio volumio[1196]: info: CoreStateMachine::play index undefined Feb 25 16:31:12 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 16:31:12 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 25 16:31:12 volumio volumio[1196]: info: CoreStateMachine::startPlaybackTimer Feb 25 16:31:12 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 25 16:31:12 volumio volumio[1196]: verbose: ControllerMpd::clearAddPlayTracks USB/0EF8157C0EF8157C/FLAC ORDNER/01. Down dirty Dogs - Pelvis Pusher.wav Feb 25 16:31:12 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand stop Feb 25 16:31:12 volumio volumio[1196]: error: MPD error: Error: write EPIPE Feb 25 16:31:12 volumio volumio[1196]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Feb 25 16:31:12 volumio volumio[1196]: error: MyVolumio Plugin failed to start in a timely fashion Feb 25 16:31:12 volumio volumio[1196]: [Metrics] CommandRouter: 67s 458.55ms Feb 25 16:31:12 volumio volumio[1196]: info: CoreCommandRouter::volumiosetStartupVolume Feb 25 16:31:12 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:31:12 volumio volumio[1196]: info: VolumeController:: Setting startup Volume 20 Feb 25 16:31:12 volumio volumio[1196]: info: VolumeController::SetAlsaVolume20 Feb 25 16:31:12 volumio systemd[1]: Stopped Music Player Daemon. Feb 25 16:31:12 volumio sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:31:12 volumio systemd[1]: Starting Music Player Daemon... Feb 25 16:31:12 volumio dbus-daemon[850]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.18' (uid=0 pid=1782 comm="timedatectl set-time 2025-02-25 16:30:10 ") Feb 25 16:31:12 volumio sudo[1785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 16:31:12 volumio sudo[1785]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:31:12 volumio sudo[1785]: pam_unix(sudo:session): session closed for user root Feb 25 16:31:12 volumio sudo[1783]: pam_unix(sudo:session): session closed for user root Feb 25 16:31:12 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:31:12 volumio volumio[1196]: info: CoreCommandRouter::Close All Modals sent Feb 25 16:31:12 volumio volumio[1196]: info: CoreCommandRouter::Close All Modals sent Feb 25 16:31:12 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 25 16:31:12 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 25 16:31:12 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 16:31:12 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 25 16:31:12 volumio systemd[1]: Starting Time & Date Service... Feb 25 16:31:12 volumio sudo[1786]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 16:31:12 volumio sudo[1786]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:31:12 volumio sudo[1786]: pam_unix(sudo:session): session closed for user root Feb 25 16:31:12 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 16:31:12 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:31:12 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 16:31:12 volumio dbus-daemon[850]: [system] Successfully activated service 'org.freedesktop.timedate1' Feb 25 16:31:12 volumio systemd[1]: Started Time & Date Service. Feb 25 16:30:10 volumio systemd-timedated[1795]: Changed local time to Tue Feb 25 16:30:10 2025 Feb 25 16:30:10 volumio sudo[1636]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:10 volumio volumio-time-update[848]: volumio-time-update-util: System time updated successfully. Feb 25 16:30:10 volumio systemd[1]: Started Volumio Time Update Utility. Feb 25 16:30:10 volumio systemd[1]: Reached target Multi-User System. Feb 25 16:30:10 volumio systemd[1]: Reached target Graphical Interface. Feb 25 16:30:10 volumio ntpd[960]: Soliciting pool server 195.201.19.162 Feb 25 16:30:10 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 25 16:30:10 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 25 16:30:10 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 25 16:30:10 volumio kernel: broken atomic modeset userspace detected, disabling atomic Feb 25 16:30:10 volumio volumio[1196]: info: Volumio called home Feb 25 16:30:10 volumio sudo[1813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 25 16:30:10 volumio sudo[1813]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:10 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 25 16:30:10 volumio sudo[1813]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:10 volumio volumio[1196]: info: Upmpdcli Daemon Started Feb 25 16:30:10 volumio ntpd[960]: Soliciting pool server 207.180.217.145 Feb 25 16:30:10 volumio ntpd[960]: Soliciting pool server 176.9.44.212 Feb 25 16:30:10 volumio ntpd[960]: Soliciting pool server 144.91.126.59 Feb 25 16:30:10 volumio mpd[1806]: Feb 25 16:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 16:30:10 volumio systemd[1]: Started Music Player Daemon. Feb 25 16:30:10 volumio mpd_monitor.sh[849]: MPD restarted due to no mpc output. Feb 25 16:30:10 volumio systemd[1]: Startup finished in 11.447s (kernel) + 1min 35.105s (userspace) = 1min 46.553s. Feb 25 16:30:10 volumio volumio[1815]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 25 16:30:10 volumio startx[1507]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Feb 25 16:30:10 volumio volumio[1815]: .++++ Feb 25 16:30:10 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Feb 25 16:30:10 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 25 16:30:10 volumio volumio[1196]: info: touch_display: Setting screensaver timeout to 480 seconds. Feb 25 16:30:10 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 25 16:30:10 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 16:30:10 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 25 16:30:10 volumio volumio[1196]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 25 16:30:11 volumio volumio[1196]: info: Adding plugin bluetooth to MyMusic Plugins Feb 25 16:30:11 volumio volumio[1196]: info: Adding plugin multiroom to MyMusic Plugins Feb 25 16:30:11 volumio volumio[1196]: info: Adding plugin metavolumio to MyMusic Plugins Feb 25 16:30:11 volumio volumio[1196]: info: Adding plugin cd_controller to MyMusic Plugins Feb 25 16:30:11 volumio volumio[1196]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 25 16:30:11 volumio volumio[1196]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 25 16:30:11 volumio ntpd[960]: Soliciting pool server 185.11.138.90 Feb 25 16:30:11 volumio ntpd[960]: Soliciting pool server 5.189.151.39 Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 25 16:30:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:30:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:30:11 volumio volumio[1196]: info: Starting MyVolumio Remote Streaming Endpoints Feb 25 16:30:11 volumio volumio[1196]: info: MyVolumio login type: Token Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 25 16:30:11 volumio volumio[1196]: info: Starting Streaming Service Transparent Proxy Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 25 16:30:11 volumio volumio[1196]: info: Streaming services startup Feb 25 16:30:11 volumio volumio[1196]: info: Starting Streaming Daemon Feb 25 16:30:11 volumio sudo[1935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 16:30:11 volumio sudo[1935]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:30:11 volumio sudo[1935]: pam_unix(sudo:session): session closed for user root Feb 25 16:30:11 volumio volumio[1196]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 25 16:30:11 volumio volumio[1196]: error: Cannot start Volumio Streaming Daemon Feb 25 16:30:11 volumio volumio[1196]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 16:30:11 volumio volumio[1196]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 16:30:12 volumio volumio[1196]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 25 16:30:12 volumio volumio[1196]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 25 16:30:12 volumio volumio[1196]: STREAMING PROXY: Starting server on port 3245 Feb 25 16:30:12 volumio volumio[1196]: Node JS runtime: 14 Feb 25 16:30:12 volumio ntpd[960]: Soliciting pool server 5.45.104.115 Feb 25 16:30:12 volumio ntpd[960]: Soliciting pool server 46.4.54.78 Feb 25 16:30:13 volumio volumio[1196]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 25 16:30:13 volumio ntpd[960]: Soliciting pool server 2001:4ba0:babe:1753::1 Feb 25 16:30:13 volumio volumio[1815]: ..........................................................++++ Feb 25 16:30:13 volumio volumio[1815]: e is 65537 (0x010001) Feb 25 16:30:13 volumio volumio[1815]: writing RSA key Feb 25 16:30:14 volumio volumio[1196]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 25 16:30:14 volumio volumio[1196]: info: CoreCommandRouter::volumioGetVisibleSources Feb 25 16:30:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 16:30:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 16:30:14 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Feb 25 16:30:14 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 25 16:30:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 25 16:30:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 25 16:30:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 25 16:30:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 16:30:15 volumio volumio[1196]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 25 16:30:15 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Feb 25 16:30:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 25 16:30:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 16:30:15 volumio volumio[1196]: info: CoreCommandRouter::volumioGetVisibleSources Feb 25 16:30:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 16:30:15 volumio volumio[1196]: info: Listing playlists Feb 25 16:30:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 25 16:30:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 25 16:30:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 25 16:30:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 16:30:16 volumio ntpd[960]: receive: Unexpected origin timestamp 0xeb686086.509738b3 does not match aorg 0xeb686088.507ed39a from server@207.180.217.145 xmt 0xeb6860c4.f3fe9e38 Feb 25 16:30:16 volumio ntpd[960]: receive: Unexpected origin timestamp 0xeb686086.5091c595 does not match aorg 0xeb686088.507bd9a2 from server@5.189.151.39 xmt 0xeb6860c4.f2f67ce2 Feb 25 16:30:16 volumio ntpd[960]: receive: Unexpected origin timestamp 0xeb686086.509865a4 does not match aorg 0xeb686088.507f6607 from server@144.91.126.59 xmt 0xeb6860c4.f5126af4 Feb 25 16:30:16 volumio ntpd[960]: receive: Unexpected origin timestamp 0xeb686086.509e6d39 does not match aorg 0xeb686088.507fe434 from server@195.201.19.162 xmt 0xeb6860c4.f6a26374 Feb 25 16:30:16 volumio ntpd[960]: receive: Unexpected origin timestamp 0xeb686086.50a0a37a does not match aorg 0xeb686088.5080d8e6 from server@144.76.138.23 xmt 0xeb6860c4.f5042417 Feb 25 16:30:16 volumio ntpd[960]: receive: Unexpected origin timestamp 0xeb686086.509faf97 does not match aorg 0xeb686088.508058d6 from server@185.248.188.98 xmt 0xeb6860c4.f51bd13d Feb 25 16:30:16 volumio ntpd[960]: Soliciting pool server 144.76.43.40 Feb 25 16:30:16 volumio ntpd[960]: Soliciting pool server 202.61.251.102 Feb 25 16:30:16 volumio volumio[1196]: info: MyVolumio token set successfully Feb 25 16:30:16 volumio volumio[1196]: info: MYVOLUMIO: Adding device Feb 25 16:30:16 volumio volumio[1196]: info: MYVOLUMIO: Evaluating Server Feb 25 16:30:17 volumio volumio[1196]: info: MyVolumio Plan changed: superstar Feb 25 16:30:17 volumio volumio[1196]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Feb 25 16:30:17 volumio volumio[1196]: info: Adding audio output: Feb 25 16:30:17 volumio volumio[1196]: info: MYVOLUMIO: Adding device Feb 25 16:30:17 volumio volumio[1196]: info: MYVOLUMIO: Evaluating Server Feb 25 16:30:17 volumio volumio[1196]: info: BOOT COMPLETED Feb 25 16:30:17 volumio volumio[1196]: info: Remote config written successfully Feb 25 16:30:17 volumio volumio[1196]: info: Starting Tunnel 1 Feb 25 16:30:17 volumio volumio[1196]: info: Starting Tunnel Connection Checker Feb 25 16:30:17 volumio ntpd[960]: Soliciting pool server 217.79.189.239 Feb 25 16:30:17 volumio ntpd[960]: Soliciting pool server 178.63.52.50 Feb 25 16:31:20 volumio ntpd[960]: receive: Unexpected origin timestamp 0xeb686089.5084644c does not match aorg 0000000000.00000000 from server@185.194.239.35 xmt 0xeb6860c7.f369806a Feb 25 16:31:20 volumio ntpd[960]: receive: Unexpected origin timestamp 0xeb686089.50852f13 does not match aorg 0000000000.00000000 from server@213.172.105.106 xmt 0xeb6860c7.f3e80078 Feb 25 16:31:20 volumio ntpd[960]: receive: Unexpected origin timestamp 0xeb686089.507d5698 does not match aorg 0000000000.00000000 from server@46.4.54.78 xmt 0xeb6860c7.f3b4590a Feb 25 16:31:20 volumio ntpd[960]: receive: Unexpected origin timestamp 0xeb686089.50829b55 does not match aorg 0000000000.00000000 from server@185.11.138.90 xmt 0xeb6860c7.f4285901 Feb 25 16:31:20 volumio volumio[1196]: info: MYVolumio Device enabled Feb 25 16:31:20 volumio volumio[1196]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 25 16:31:20 volumio volumio[1196]: info: MyVolumio status changed Feb 25 16:31:20 volumio volumio[1196]: info: Streaming services startup Feb 25 16:31:20 volumio volumio[1196]: info: Starting Streaming Daemon Feb 25 16:31:20 volumio sudo[2126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 16:31:20 volumio sudo[2126]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:31:20 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 25 16:31:20 volumio sudo[2126]: pam_unix(sudo:session): session closed for user root Feb 25 16:31:20 volumio volumio[1196]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 25 16:31:20 volumio volumio[1196]: info: [1740497480128] Starting BluetoothController Feb 25 16:31:20 volumio volumio[1196]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 25 16:31:20 volumio volumio[1196]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Feb 25 16:31:20 volumio sudo[2129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 25 16:31:20 volumio sudo[2129]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:31:20 volumio sudo[2129]: pam_unix(sudo:session): session closed for user root Feb 25 16:31:20 volumio volumio[1196]: info: MRS: MultiRoom plugin initialized Feb 25 16:31:20 volumio volumio[1196]: info: MRS: STOPPING SNAPCLIENT Feb 25 16:31:20 volumio volumio[1196]: info: MRS: Snap server stop Feb 25 16:31:20 volumio sudo[2145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 25 16:31:20 volumio sudo[2145]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:31:20 volumio volumio[1196]: info: MRS: STOPPING volumioStreaming Feb 25 16:31:20 volumio sudo[2148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 25 16:31:20 volumio sudo[2148]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:31:20 volumio sudo[2145]: pam_unix(sudo:session): session closed for user root Feb 25 16:31:20 volumio sudo[2151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 25 16:31:20 volumio sudo[2151]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:31:20 volumio volumio[1196]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 25 16:31:20 volumio sudo[2154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 25 16:31:20 volumio sudo[2154]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:31:20 volumio sudo[2154]: pam_unix(sudo:session): session closed for user root Feb 25 16:31:20 volumio sudo[2148]: pam_unix(sudo:session): session closed for user root Feb 25 16:31:20 volumio volumio[1196]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 25 16:31:20 volumio sudo[2151]: pam_unix(sudo:session): session closed for user root Feb 25 16:31:20 volumio volumio[1196]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 25 16:31:20 volumio volumio[1196]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 25 16:31:20 volumio volumio[1196]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 25 16:31:20 volumio volumio[1196]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Feb 25 16:31:20 volumio volumio[1196]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 25 16:31:20 volumio volumio[1196]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 25 16:31:20 volumio volumio[1196]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 25 16:31:20 volumio volumio[1196]: info: Preparing to generate the ALSA configuration file Feb 25 16:31:20 volumio volumio[1196]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 25 16:31:20 volumio volumio[1196]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 25 16:31:20 volumio volumio[1196]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Feb 25 16:31:20 volumio volumio[1196]: info: Reading ALSA contributions from plugins. Feb 25 16:31:20 volumio volumio[1196]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 16:31:20 volumio volumio[1196]: Error: Unable to resolve or reject the same promise twice Feb 25 16:31:20 volumio volumio[1196]: at Promise.reject (/volumio/node_modules/kew/kew.js:189:43) Feb 25 16:31:20 volumio volumio[1196]: at /volumio/app/plugins/miscellanea/albumart/albumart.js:158:15 Feb 25 16:31:20 volumio volumio[1196]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:667:14) Feb 25 16:31:20 volumio volumio[1196]: at ClientRequest.emit (events.js:315:20) Feb 25 16:31:20 volumio volumio[1196]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Feb 25 16:31:20 volumio volumio[1196]: at TLSSocket.emit (events.js:315:20) Feb 25 16:31:20 volumio volumio[1196]: at TLSSocket._emitTLSError (_tls_wrap.js:888:10) Feb 25 16:31:20 volumio volumio[1196]: at TLSWrap.onerror (_tls_wrap.js:416:11) Feb 25 16:31:20 volumio volumio[1196]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 16:31:20 volumio sudo[2165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-25 16:30 Feb 25 16:31:20 volumio sudo[2165]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"