-- Logs begin at Sat 2025-11-01 07:06:55 CET, end at Sun 2025-11-02 18:42:37 CET. -- Nov 02 18:41:00 volumio-wz volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:41:00 volumio-wz volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:41:00 volumio-wz volumio[946]: info: Discovery: Getting this device information Nov 02 18:41:00 volumio-wz volumio[946]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:00 volumio-wz volumio[946]: info: CorePlayQueue::getTrack 5 Nov 02 18:41:00 volumio-wz volumio[946]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:41:02 volumio-wz volumio[946]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:02 volumio-wz volumio[946]: info: CorePlayQueue::getTrack 5 Nov 02 18:41:12 volumio-wz volumio[946]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:12 volumio-wz volumio[946]: info: CorePlayQueue::getTrack 5 Nov 02 18:41:12 volumio-wz volumio[946]: info: Listing playlists Nov 02 18:41:18 volumio-wz volumio[946]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 18:41:18 volumio-wz volumio[946]: Error: connect EHOSTUNREACH 199.232.190.53:443 Nov 02 18:41:18 volumio-wz volumio[946]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Nov 02 18:41:18 volumio-wz volumio[946]: errno: -113, Nov 02 18:41:18 volumio-wz volumio[946]: code: 'EHOSTUNREACH', Nov 02 18:41:18 volumio-wz volumio[946]: syscall: 'connect', Nov 02 18:41:18 volumio-wz volumio[946]: address: '199.232.190.53', Nov 02 18:41:18 volumio-wz volumio[946]: port: 443 Nov 02 18:41:18 volumio-wz volumio[946]: } Nov 02 18:41:18 volumio-wz volumio[946]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 18:41:19 volumio-wz sudo[12764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-02 18:40 Nov 02 18:41:19 volumio-wz sudo[12764]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:19 volumio-wz sudo[12764]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:19 volumio-wz volumio-remote-updater[681]: [2025-11-02 18:41:19] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 02 18:41:19 volumio-wz volumio-remote-updater[681]: [2025-11-02 18:41:19] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 02 18:41:19 volumio-wz vtcs[28563]: [2025-11-02 18:41:19.374] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Nov 02 18:41:19 volumio-wz systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 18:41:19 volumio-wz systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 02 18:41:19 volumio-wz systemd[1]: Started dynamicswap service. Nov 02 18:41:19 volumio-wz systemd[1]: dynamicswap.service: Succeeded. Nov 02 18:41:19 volumio-wz systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 18:41:19 volumio-wz systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Nov 02 18:41:19 volumio-wz systemd[1]: Started dynamicswap service. Nov 02 18:41:19 volumio-wz systemd[1]: Stopped Volumio Backend Module. Nov 02 18:41:19 volumio-wz systemd[1]: Started Volumio Backend Module. Nov 02 18:41:19 volumio-wz systemd[1]: dynamicswap.service: Succeeded. Nov 02 18:41:19 volumio-wz volumio[12779]: info: ------------------------------------------- Nov 02 18:41:19 volumio-wz volumio[12779]: info: ----- Volumio3 ---- Nov 02 18:41:19 volumio-wz volumio[12779]: info: ------------------------------------------- Nov 02 18:41:19 volumio-wz volumio[12779]: info: ----- System startup ---- Nov 02 18:41:19 volumio-wz volumio[12779]: info: ------------------------------------------- Nov 02 18:41:19 volumio-wz volumio[12779]: info: MYVOLUMIO Environment detected Nov 02 18:41:19 volumio-wz volumio[12779]: info: Plugin folders cleanup Nov 02 18:41:19 volumio-wz volumio[12779]: info: Scanning into folder /volumio/app/plugins/ Nov 02 18:41:19 volumio-wz volumio[12779]: info: Scanning category audio_interface Nov 02 18:41:19 volumio-wz volumio[12779]: info: Scanning category miscellanea Nov 02 18:41:19 volumio-wz volumio[12779]: info: Scanning category music_service Nov 02 18:41:19 volumio-wz volumio[12779]: info: Scanning category plugins.json Nov 02 18:41:19 volumio-wz volumio[12779]: info: Scanning category system_controller Nov 02 18:41:19 volumio-wz volumio[12779]: info: Scanning category user_interface Nov 02 18:41:19 volumio-wz volumio[12779]: info: Scanning into folder /data/plugins/ Nov 02 18:41:19 volumio-wz volumio[12779]: info: Scanning category audio_interface Nov 02 18:41:19 volumio-wz volumio[12779]: info: Scanning category music_service Nov 02 18:41:19 volumio-wz volumio[12779]: info: Scanning category system_controller Nov 02 18:41:19 volumio-wz volumio[12779]: info: Scanning category system_hardware Nov 02 18:41:19 volumio-wz volumio[12779]: info: Scanning category user_interface Nov 02 18:41:19 volumio-wz volumio[12779]: info: Plugin folders cleanup completed Nov 02 18:41:19 volumio-wz volumio[12779]: info: ------------------------------------------- Nov 02 18:41:19 volumio-wz volumio[12779]: info: ----- Core plugins startup ---- Nov 02 18:41:19 volumio-wz volumio[12779]: info: ------------------------------------------- Nov 02 18:41:19 volumio-wz volumio[12779]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 18:41:19 volumio-wz volumio[12779]: info: Adding plugin upnp to MyMusic Plugins Nov 02 18:41:19 volumio-wz volumio[12779]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 18:41:19 volumio-wz volumio[12779]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 18:41:19 volumio-wz volumio[12779]: info: Loading plugins from folder /data/plugins/ Nov 02 18:41:19 volumio-wz volumio[12779]: info: Loading plugin "system"... Nov 02 18:41:19 volumio-wz volumio[12779]: info: Loading plugin "appearance"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "network"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Refreshing Cached IP Addresses Nov 02 18:41:20 volumio-wz sudo[12806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 18:41:20 volumio-wz sudo[12806]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:20 volumio-wz sudo[12806]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:20 volumio-wz sudo[12808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 18:41:20 volumio-wz sudo[12808]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "services"... Nov 02 18:41:20 volumio-wz sudo[12808]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "alsa_controller"... Nov 02 18:41:20 volumio-wz sudo[12818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 02 18:41:20 volumio-wz sudo[12818]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:20 volumio-wz sudo[12818]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:20 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "wizard"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "networkfs"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Cannot mount NAS Music_on_MediaPC at system boot, trial number 1 ,retrying in 5 seconds Nov 02 18:41:20 volumio-wz volumio[12779]: info: Cannot mount NAS music at system boot, trial number 1 ,retrying in 5 seconds Nov 02 18:41:20 volumio-wz volumio[12779]: info: Starting Udev Watcher for removable devices Nov 02 18:41:20 volumio-wz volumio[12779]: info: Ignoring mount for partition: boot Nov 02 18:41:20 volumio-wz volumio[12779]: info: Ignoring mount for partition: volumio Nov 02 18:41:20 volumio-wz volumio[12779]: info: Ignoring mount for partition: volumio_data Nov 02 18:41:20 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "volumio_command_line_client"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "upnp"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: [1762105280184] Starting Upmpd Daemon Nov 02 18:41:20 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "my_music"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "mpd"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "upnp_browser"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "alarm-clock"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "airplay_emulation"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Starting Shairport Sync Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "last_100"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "webradio"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "i2s_dacs"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: I2S DAC not set, start Auto-detection Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "volumiodiscovery"... Nov 02 18:41:20 volumio-wz volumio[12779]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 18:41:20 volumio-wz volumio[12779]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 18:41:20 volumio-wz volumio[12779]: *** WARNING *** For more information see Nov 02 18:41:20 volumio-wz volumio[12779]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 18:41:20 volumio-wz volumio[12779]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 18:41:20 volumio-wz node[12779]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 18:41:20 volumio-wz volumio[12779]: *** WARNING *** For more information see Nov 02 18:41:20 volumio-wz node[12779]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 18:41:20 volumio-wz node[12779]: *** WARNING *** For more information see Nov 02 18:41:20 volumio-wz node[12779]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 18:41:20 volumio-wz node[12779]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 18:41:20 volumio-wz node[12779]: *** WARNING *** For more information see Nov 02 18:41:20 volumio-wz volumio[12779]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 18:41:20 volumio-wz volumio[12779]: info: Discovery: Started advertising with name: Volumio-WZ Nov 02 18:41:20 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "bandcamp"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "calmradio"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "mixcloud"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "soundcloud"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "now_playing"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "outputs"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "albumart"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Plugin example_plugin is not enabled Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "inputs"... Nov 02 18:41:20 volumio-wz volumio[12779]: info: Loading plugin "updater_comm"... Nov 02 18:41:21 volumio-wz volumio[12779]: Forking 3 albumart workers Nov 02 18:41:21 volumio-wz volumio[12779]: info: Plugin mpdemulation is not enabled Nov 02 18:41:21 volumio-wz volumio[12779]: info: Loading plugin "rest_api"... Nov 02 18:41:21 volumio-wz volumio[12779]: info: Loading plugin "websocket"... Nov 02 18:41:21 volumio-wz volumio[12779]: info: Starting Socket.io Server version 2.3.0 Nov 02 18:41:21 volumio-wz volumio[12779]: info: Loading plugin "mpdoutput"... Nov 02 18:41:21 volumio-wz volumio[12779]: info: Loading plugin "80s80s"... Nov 02 18:41:21 volumio-wz volumio[12779]: Starting albumart workers Nov 02 18:41:21 volumio-wz volumio[12779]: Starting albumart workers Nov 02 18:41:21 volumio-wz volumio[12779]: Starting albumart workers Nov 02 18:41:21 volumio-wz volumio[12779]: info: Applying required configuration parameters for plugin 80s80s Nov 02 18:41:21 volumio-wz volumio[12779]: info: [1762105281202] [80s80s] API delay: 30 Nov 02 18:41:21 volumio-wz volumio[12779]: info: Loading plugin "RoonBridge"... Nov 02 18:41:21 volumio-wz volumio[12779]: info: Applying required configuration parameters for plugin RoonBridge Nov 02 18:41:21 volumio-wz volumio[12779]: info: Loading plugin "minidlna"... Nov 02 18:41:21 volumio-wz volumio[12779]: info: Applying required configuration parameters for plugin minidlna Nov 02 18:41:21 volumio-wz volumio[12779]: info: Loading plugin "motherearthradio"... Nov 02 18:41:21 volumio-wz volumio[12779]: info: Applying required configuration parameters for plugin motherearthradio Nov 02 18:41:21 volumio-wz volumio[12779]: info: [1762105281354] [MotherEarth] API delay: 5 Nov 02 18:41:21 volumio-wz volumio[12779]: info: Loading plugin "nowyswiat"... Nov 02 18:41:21 volumio-wz volumio[12779]: info: Applying required configuration parameters for plugin nowyswiat Nov 02 18:41:21 volumio-wz volumio[12779]: info: [1762105281385] [RadioNowySwiat] API delay: 5 Nov 02 18:41:21 volumio-wz volumio[12779]: info: [1762105281385] [RadioNowySwiat] Stream 256Kb: http://stream.rcs.revma.com/ypqt40u0x1zuv Nov 02 18:41:21 volumio-wz volumio[12779]: info: Loading plugin "podcast"... Nov 02 18:41:21 volumio-wz volumio[12779]: info: ControllerPodcast::constructor Nov 02 18:41:21 volumio-wz volumio[12779]: info: Loading plugin "backup_restore"... Nov 02 18:41:21 volumio-wz volumio[12779]: info: Applying required configuration parameters for plugin backup_restore Nov 02 18:41:21 volumio-wz volumio[12779]: info: Loading plugin "music_services_shield"... Nov 02 18:41:21 volumio-wz volumio[12779]: info: Applying required configuration parameters for plugin music_services_shield Nov 02 18:41:21 volumio-wz volumio[12779]: info: Loading plugin "Systeminfo"... Nov 02 18:41:21 volumio-wz volumio[12779]: info: Loading plugin "peppyspectrum"... Nov 02 18:41:21 volumio-wz volumio[12779]: info: Loading plugin "randomizer"... Nov 02 18:41:21 volumio-wz volumio[12779]: info: Applying required configuration parameters for plugin randomizer Nov 02 18:41:21 volumio-wz volumio[12779]: info: Loading i18n strings for locale de Nov 02 18:41:21 volumio-wz volumio[12779]: Updating browse sources language Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::initPlayerControls Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: Express server listening on port 3000 Nov 02 18:41:21 volumio-wz volumio[12779]: [Metrics] WebUI: 1s 925.31ms Nov 02 18:41:21 volumio-wz volumio[12779]: info: Setting Device type: x86 Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreStateMachine::resetVolumioState Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreStateMachine::getcurrentVolume Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreStateMachine::pushState Nov 02 18:41:21 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioPushState Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 02 18:41:21 volumio-wz volumio[12779]: info: Completed loading Core Plugins Nov 02 18:41:21 volumio-wz volumio[12779]: info: Preparing to generate the ALSA configuration file Nov 02 18:41:21 volumio-wz volumio[12779]: info: Volumio Network Manager: Network status updated: 1 Nov 02 18:41:21 volumio-wz volumio[12779]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 02 18:41:21 volumio-wz volumio[12779]: wlan0 Interface doesn't support scanning. Nov 02 18:41:21 volumio-wz volumio[12779]: info: Cannot use regular scanning, forcing with ap-force Nov 02 18:41:21 volumio-wz sudo[12891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 02 18:41:21 volumio-wz sudo[12891]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:21 volumio-wz sudo[12891]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:21 volumio-wz volumio[12779]: command failed: No such device (-19) Nov 02 18:41:21 volumio-wz volumio[12779]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 02 18:41:21 volumio-wz volumio[12779]: command failed: No such device (-19) Nov 02 18:41:21 volumio-wz volumio[12779]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Nov 02 18:41:21 volumio-wz volumio[12779]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 02 18:41:21 volumio-wz volumio[12779]: info: Reading ALSA contributions from plugins. Nov 02 18:41:21 volumio-wz volumio[12779]: verbose: New Socket.io Connection to 192.168.22.13 from 192.168.22.92 UA: Mozilla/5.0 (Linux; Android 15; 25010PN30G Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 02 18:41:21 volumio-wz volumio[12779]: verbose: New Socket.io Connection to 192.168.22.13:3000 from 192.168.22.92 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Nov 02 18:41:21 volumio-wz volumio[12779]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 02 18:41:21 volumio-wz volumio[12779]: info: Reloading queue from file Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreStateMachine::setRepeat null single undefined Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreStateMachine::pushState Nov 02 18:41:21 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioPushState Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreStateMachine::setRandom null Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreStateMachine::pushState Nov 02 18:41:21 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioPushState Nov 02 18:41:21 volumio-wz volumio[12779]: info: Asound.conf file unchanged, so no further update is needed Nov 02 18:41:21 volumio-wz volumio[12779]: info: Output device has changed, restarting MPD Nov 02 18:41:21 volumio-wz sudo[12901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 18:41:21 volumio-wz sudo[12901]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:21 volumio-wz sudo[12901]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:21 volumio-wz volumio[12779]: info: Output device has changed, restarting Shairport Sync Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:21 volumio-wz sudo[12904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 18:41:21 volumio-wz sudo[12904]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:21 volumio-wz systemd[1]: musicservicesshield.service: Succeeded. Nov 02 18:41:21 volumio-wz systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 02 18:41:21 volumio-wz systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 02 18:41:21 volumio-wz systemd[1]: Stopping Music Player Daemon... Nov 02 18:41:21 volumio-wz volumio[12779]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 18:41:21 volumio-wz volumio[12779]: info: ___________ START PLUGINS ___________ Nov 02 18:41:21 volumio-wz volumio[12779]: info: ControllerMpd::onStart: Initializing MPD Nov 02 18:41:21 volumio-wz volumio[12779]: info: Creating MPD Configuration file Nov 02 18:41:21 volumio-wz sudo[12910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 18:41:21 volumio-wz sudo[12910]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:21 volumio-wz sudo[12910]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:41:21 volumio-wz volumio[12779]: info: [1762105281785] CoreMusicLibrary::Adding element Medienserver Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:41:21 volumio-wz sudo[12914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 18:41:21 volumio-wz sudo[12914]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:41:21 volumio-wz volumio[12779]: info: [1762105281814] CoreMusicLibrary::Adding element Last_100 Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:41:21 volumio-wz volumio[12779]: info: [1762105281815] CoreMusicLibrary::Adding element Webradio Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 18:41:21 volumio-wz volumio[12779]: info: Initializing BBC Radios Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:41:21 volumio-wz volumio[12779]: info: [1762105281842] CoreMusicLibrary::Adding element Bandcamp Discover Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Bandcamp Discover Nov 02 18:41:21 volumio-wz volumio[12779]: info: Adding Calm Radio to Browse Sources Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:41:21 volumio-wz volumio[12779]: info: [1762105281843] CoreMusicLibrary::Adding element Calm Radio Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Bandcamp Discover Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Calm Radio Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:41:21 volumio-wz volumio[12779]: info: [1762105281845] CoreMusicLibrary::Adding element Mixcloud Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Bandcamp Discover Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Calm Radio Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Mixcloud Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:41:21 volumio-wz volumio[12779]: info: [1762105281847] CoreMusicLibrary::Adding element SoundCloud Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Bandcamp Discover Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Calm Radio Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Mixcloud Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source SoundCloud Nov 02 18:41:21 volumio-wz volumio[12779]: info: [now-playing] ConfigUpdater: config is up to date. Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:41:21 volumio-wz volumio[12779]: info: [1762105281853] CoreMusicLibrary::Adding element 80s80s Radio Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Bandcamp Discover Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Calm Radio Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Mixcloud Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source SoundCloud Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source 80s80s Radio Nov 02 18:41:21 volumio-wz volumio[12779]: info: Loading i18n strings for locale de Nov 02 18:41:21 volumio-wz sudo[12933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Nov 02 18:41:21 volumio-wz sudo[12933]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:21 volumio-wz sudo[12933]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:41:21 volumio-wz volumio[12779]: info: [1762105281871] CoreMusicLibrary::Adding element Mother Earth Radio Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Bandcamp Discover Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Calm Radio Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Mixcloud Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source SoundCloud Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source 80s80s Radio Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Mother Earth Radio Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:41:21 volumio-wz volumio[12779]: info: [1762105281872] CoreMusicLibrary::Adding element Radio Nowy Swiat Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Bandcamp Discover Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Calm Radio Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Mixcloud Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source SoundCloud Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source 80s80s Radio Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Mother Earth Radio Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Radio Nowy Swiat Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:41:21 volumio-wz volumio[12779]: info: [1762105281874] CoreMusicLibrary::Adding element Podcast Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Bandcamp Discover Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Calm Radio Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Mixcloud Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source SoundCloud Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source 80s80s Radio Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Mother Earth Radio Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Radio Nowy Swiat Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Podcast Nov 02 18:41:21 volumio-wz systemd[1]: mpd.service: Succeeded. Nov 02 18:41:21 volumio-wz systemd[1]: Stopped Music Player Daemon. Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 02 18:41:21 volumio-wz sudo[12964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Nov 02 18:41:21 volumio-wz sudo[12964]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:21 volumio-wz systemd[1]: Starting Music Player Daemon... Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:41:21 volumio-wz volumio[12779]: info: [1762105281947] CoreMusicLibrary::Adding element Randomizer Nov 02 18:41:21 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Bandcamp Discover Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Calm Radio Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Mixcloud Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source SoundCloud Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source 80s80s Radio Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Mother Earth Radio Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Radio Nowy Swiat Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Podcast Nov 02 18:41:21 volumio-wz volumio[12779]: Cannot find translation for source Randomizer Nov 02 18:41:21 volumio-wz volumio[12779]: info: Volumio Calling Home Nov 02 18:41:21 volumio-wz volumio[12779]: info: Preparing to generate the ALSA configuration file Nov 02 18:41:21 volumio-wz sudo[12962]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 18:41:21 volumio-wz sudo[12962]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:21 volumio-wz sudo[12962]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:21 volumio-wz volumio[12779]: info: [now-playing] App is listening on port 4004. Nov 02 18:41:21 volumio-wz volumio[12779]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Nov 02 18:41:21 volumio-wz volumio[12779]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 02 18:41:21 volumio-wz volumio[12779]: info: Reading ALSA contributions from plugins. Nov 02 18:41:22 volumio-wz volumio[12779]: info: minidlna: Found miniDLNA version 1.2.1 Nov 02 18:41:22 volumio-wz volumio[12779]: info: minidlna: Starting minidlna.service Nov 02 18:41:22 volumio-wz volumio[12779]: info: MPD Permissions set Nov 02 18:41:22 volumio-wz volumio[12779]: info: MPD Permissions set Nov 02 18:41:22 volumio-wz volumio[12779]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 02 18:41:22 volumio-wz sudo[12964]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:22 volumio-wz volumio[12779]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Nov 02 18:41:22 volumio-wz sudo[12985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Nov 02 18:41:22 volumio-wz volumio[12779]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz sudo[12985]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 02 18:41:22 volumio-wz sudo[12985]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz systemd[1]: minidlna.service: Succeeded. Nov 02 18:41:22 volumio-wz sudo[13003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Nov 02 18:41:22 volumio-wz sudo[13003]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:22 volumio-wz volumio[12779]: info: minidlna: systemctl start minidlna.service succeeded. Nov 02 18:41:22 volumio-wz volumio[12779]: info: Discovery: adding 8195056c-cf5d-43ad-a43c-da28822f12c4 Nov 02 18:41:22 volumio-wz volumio[12779]: info: Discovery: Found device Volumio-WZ Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:22 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:22 volumio-wz volumio[12779]: info: Starting Shairport Sync Nov 02 18:41:22 volumio-wz volumio[12779]: info: Starting Shairport Sync Nov 02 18:41:22 volumio-wz sudo[13008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 18:41:22 volumio-wz sudo[13008]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:22 volumio-wz volumio[12779]: info: Starting Shairport Sync Nov 02 18:41:22 volumio-wz sudo[13010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 18:41:22 volumio-wz sudo[13010]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:22 volumio-wz systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:22 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:22 volumio-wz volumio[12779]: info: Asound.conf file unchanged, so no further update is needed Nov 02 18:41:22 volumio-wz volumio[12779]: info: Output device has changed, restarting MPD Nov 02 18:41:22 volumio-wz sudo[13013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 18:41:22 volumio-wz sudo[13013]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:22 volumio-wz mpd[12982]: Nov 02 18:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 18:41:22 volumio-wz systemd[1]: shairport-sync.service: Succeeded. Nov 02 18:41:22 volumio-wz systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 18:41:22 volumio-wz sudo[13017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 18:41:22 volumio-wz sudo[13017]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:22 volumio-wz sudo[13017]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:22 volumio-wz volumio[12779]: info: Output device has changed, restarting Shairport Sync Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:22 volumio-wz sudo[13021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 18:41:22 volumio-wz sudo[13021]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:22 volumio-wz volumio[12779]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 18:41:22 volumio-wz systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 18:41:22 volumio-wz systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 18:41:22 volumio-wz sudo[13010]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:22 volumio-wz sudo[13008]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:22 volumio-wz systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 02 18:41:22 volumio-wz systemd[1]: shairport-sync.service: Succeeded. Nov 02 18:41:22 volumio-wz systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 18:41:22 volumio-wz systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 18:41:22 volumio-wz systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 02 18:41:22 volumio-wz systemd[1]: mpd.service: Succeeded. Nov 02 18:41:22 volumio-wz systemd[1]: Stopped Music Player Daemon. Nov 02 18:41:22 volumio-wz sudo[13013]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:22 volumio-wz systemd[1]: Starting Music Player Daemon... Nov 02 18:41:22 volumio-wz volumio[12779]: info: MPD Permissions set Nov 02 18:41:22 volumio-wz volumio[12779]: info: Shairport-Sync Started Nov 02 18:41:22 volumio-wz volumio[12779]: Error adding Membership: Error: addMembership EINVAL Nov 02 18:41:22 volumio-wz volumio[12779]: info: Shairport-Sync Started Nov 02 18:41:22 volumio-wz volumio[12779]: info: Shairport-Sync Started Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz sudo[13030]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 18:41:22 volumio-wz sudo[13030]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 02 18:41:22 volumio-wz sudo[13030]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:22 volumio-wz volumio[12779]: info: Starting Shairport Sync Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:22 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioPause Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreStateMachine::pause Nov 02 18:41:22 volumio-wz volumio[12779]: info: mpdhttpout --- Volumio set on pause Nov 02 18:41:22 volumio-wz volumio[12779]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Nov 02 18:41:22 volumio-wz sudo[13042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 18:41:22 volumio-wz sudo[13042]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:22 volumio-wz systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 18:41:22 volumio-wz systemd[1]: shairport-sync.service: Succeeded. Nov 02 18:41:22 volumio-wz systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 18:41:22 volumio-wz systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 18:41:22 volumio-wz sudo[13042]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:22 volumio-wz volumio[12779]: info: Shairport-Sync Started Nov 02 18:41:22 volumio-wz mpd[13038]: Nov 02 18:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 18:41:22 volumio-wz volumio[12779]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:22 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:22 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 18:41:22 volumio-wz volumio[12779]: info: Received Get System Info Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:41:22 volumio-wz volumio[12779]: info: Discovery: Getting this device information Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:22 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:22 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:22 volumio-wz volumio[12779]: info: Listing playlists Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:41:22 volumio-wz volumio[12779]: info: Discovery: Getting this device information Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:22 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:41:22 volumio-wz volumio[12779]: verbose: New Socket.io Connection to 192.168.22.13:3000 from 192.168.22.92 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:22 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 02 18:41:22 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:22 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:23 volumio-wz systemd[1]: Started Music Player Daemon. Nov 02 18:41:23 volumio-wz sudo[12914]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:23 volumio-wz sudo[13021]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:23 volumio-wz sudo[12904]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:23 volumio-wz volumio[12779]: info: Completed starting Core Plugins Nov 02 18:41:23 volumio-wz volumio[12779]: info: ------------------------------------------- Nov 02 18:41:23 volumio-wz volumio[12779]: info: ----- MyVolumio plugins startup ---- Nov 02 18:41:23 volumio-wz volumio[12779]: info: ------------------------------------------- Nov 02 18:41:23 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 02 18:41:23 volumio-wz volumio[12779]: error: MPD error: The expression evaluated to a falsy value: Nov 02 18:41:23 volumio-wz volumio[12779]: assert.ok(self.idling) Nov 02 18:41:23 volumio-wz volumio[12779]: error: The expression evaluated to a falsy value: Nov 02 18:41:23 volumio-wz volumio[12779]: assert.ok(self.idling) Nov 02 18:41:23 volumio-wz volumio[12779]: error: MPD error: The expression evaluated to a falsy value: Nov 02 18:41:23 volumio-wz volumio[12779]: assert.ok(self.idling) Nov 02 18:41:23 volumio-wz volumio[12779]: error: The expression evaluated to a falsy value: Nov 02 18:41:23 volumio-wz volumio[12779]: assert.ok(self.idling) Nov 02 18:41:23 volumio-wz volumio[12779]: info: MPD running with PID13038 Nov 02 18:41:23 volumio-wz volumio[12779]: ,establishing connection Nov 02 18:41:23 volumio-wz volumio[12779]: error: MPD error: The expression evaluated to a falsy value: Nov 02 18:41:23 volumio-wz volumio[12779]: assert.ok(self.idling) Nov 02 18:41:23 volumio-wz volumio[12779]: error: The expression evaluated to a falsy value: Nov 02 18:41:23 volumio-wz volumio[12779]: assert.ok(self.idling) Nov 02 18:41:23 volumio-wz systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 02 18:41:23 volumio-wz volumio[12779]: error: updateQueue error: null Nov 02 18:41:23 volumio-wz volumio[13109]: cset: --> shielding system active with Nov 02 18:41:23 volumio-wz volumio[13109]: cset: "system" cpuset of CPUSPEC(1-3) with 270 tasks running Nov 02 18:41:23 volumio-wz volumio[13109]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 02 18:41:23 volumio-wz systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 02 18:41:23 volumio-wz systemd[1]: Reloading. Nov 02 18:41:23 volumio-wz 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. Nov 02 18:41:23 volumio-wz sudo[13003]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:23 volumio-wz volumio[12779]: info: succeeded Nov 02 18:41:24 volumio-wz volumio[12779]: verbose: New Socket.io Connection to 192.168.22.13 from 192.168.22.92 UA: Mozilla/5.0 (Linux; Android 15; 25010PN30G Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Nov 02 18:41:24 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 18:41:24 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 18:41:24 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:41:24 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:24 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:24 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 18:41:24 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 18:41:24 volumio-wz volumio[12779]: info: Received Get System Info Nov 02 18:41:24 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:41:24 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:41:24 volumio-wz volumio[12779]: info: Discovery: Getting this device information Nov 02 18:41:24 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:24 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:24 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:41:24 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:24 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:24 volumio-wz volumio[12779]: info: Listing playlists Nov 02 18:41:24 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetQueue Nov 02 18:41:24 volumio-wz volumio[12779]: info: CoreStateMachine::getQueue Nov 02 18:41:24 volumio-wz volumio[12779]: info: CorePlayQueue::getQueue Nov 02 18:41:24 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Nov 02 18:41:24 volumio-wz sudo[13145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 18:41:24 volumio-wz sudo[13145]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:24 volumio-wz systemd[1]: musicservicesshield.service: Succeeded. Nov 02 18:41:24 volumio-wz systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 02 18:41:24 volumio-wz systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 02 18:41:24 volumio-wz systemd[1]: Stopping Music Player Daemon... Nov 02 18:41:24 volumio-wz volumio-remote-updater[681]: [2025-11-02 18:41:24] [connect] Successful connection Nov 02 18:41:24 volumio-wz volumio-remote-updater[681]: [2025-11-02 18:41:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1762105284 101 Nov 02 18:41:24 volumio-wz volumio[12779]: 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: 10 Nov 02 18:41:24 volumio-wz systemd[1]: mpd.service: Succeeded. Nov 02 18:41:24 volumio-wz systemd[1]: Stopped Music Player Daemon. Nov 02 18:41:24 volumio-wz systemd[1]: Starting Music Player Daemon... Nov 02 18:41:24 volumio-wz sudo[13147]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 18:41:24 volumio-wz sudo[13147]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:24 volumio-wz sudo[13147]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:24 volumio-wz mpd[13149]: Nov 02 18:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 18:41:25 volumio-wz volumio[12779]: info: Cannot mount NAS Music_on_MediaPC at system boot, trial number 2 ,retrying in 5 seconds Nov 02 18:41:25 volumio-wz volumio[12779]: info: Cannot mount NAS music at system boot, trial number 2 ,retrying in 5 seconds Nov 02 18:41:25 volumio-wz systemd[1]: Started Music Player Daemon. Nov 02 18:41:25 volumio-wz sudo[13145]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:25 volumio-wz volumio[12779]: error: updateQueue error: null Nov 02 18:41:25 volumio-wz systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 02 18:41:25 volumio-wz volumio[13152]: cset: --> shielding system active with Nov 02 18:41:25 volumio-wz volumio[13152]: cset: "system" cpuset of CPUSPEC(1-3) with 266 tasks running Nov 02 18:41:25 volumio-wz volumio[13152]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 02 18:41:25 volumio-wz systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 02 18:41:28 volumio-wz volumio[12779]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 02 18:41:30 volumio-wz volumio[12779]: info: Cannot mount NAS Music_on_MediaPC at system boot, trial number 3 ,retrying in 5 seconds Nov 02 18:41:30 volumio-wz volumio[12779]: info: Cannot mount NAS music at system boot, trial number 3 ,retrying in 5 seconds Nov 02 18:41:30 volumio-wz sudo[13183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 18:41:30 volumio-wz sudo[13183]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:30 volumio-wz sudo[13185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 18:41:30 volumio-wz sudo[13185]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:30 volumio-wz sudo[13183]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:30 volumio-wz sudo[13185]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:31 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:41:31 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:41:31 volumio-wz volumio[12779]: info: Discovery: Getting this device information Nov 02 18:41:31 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:31 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:31 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:41:31 volumio-wz sudo[13192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 02 18:41:31 volumio-wz sudo[13192]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:31 volumio-wz volumio[12779]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 18:41:31 volumio-wz sudo[13192]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:31 volumio-wz volumio[12779]: info: Upmpdcli Daemon Started Nov 02 18:41:32 volumio-wz volumio[12779]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.832&uuid=519a4756a75491288cc3d463c3459476" http://updates.volumio.org/downloader-v1/track-device Nov 02 18:41:32 volumio-wz volumio[12779]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 02 18:41:32 volumio-wz volumio[12779]: Dload Upload Total Spent Left Speed Nov 02 18:41:32 volumio-wz volumio[12779]: [158B blob data] Nov 02 18:41:32 volumio-wz volumio[12779]: curl: (7) Failed to connect to updates.volumio.org port 80: No route to host Nov 02 18:41:32 volumio-wz volumio[12779]: retrying in 5 seconds, trial 0 Nov 02 18:41:32 volumio-wz volumio[12779]: info: Volumio Calling Home Nov 02 18:41:32 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 02 18:41:32 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:32 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 18:41:32 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:32 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:32 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:32 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:32 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:32 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:32 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:32 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 02 18:41:32 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 02 18:41:33 volumio-wz volumio[12779]: info: Volumio called home Nov 02 18:41:33 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:33 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 02 18:41:33 volumio-wz volumio[12779]: info: Adding plugin bluetooth to MyMusic Plugins Nov 02 18:41:33 volumio-wz volumio[12779]: info: Adding plugin multiroom to MyMusic Plugins Nov 02 18:41:33 volumio-wz volumio[12779]: info: Adding plugin metavolumio to MyMusic Plugins Nov 02 18:41:33 volumio-wz volumio[12779]: info: Adding plugin cd_controller to MyMusic Plugins Nov 02 18:41:33 volumio-wz volumio[12779]: info: Adding plugin qobuzconnect to MyMusic Plugins Nov 02 18:41:33 volumio-wz volumio[12779]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 02 18:41:33 volumio-wz volumio[12779]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 02 18:41:33 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:33 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:33 volumio-wz volumio[12779]: info: Starting MyVolumio Remote Streaming Endpoints Nov 02 18:41:33 volumio-wz volumio[12779]: info: MyVolumio login type: Token Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 02 18:41:33 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 02 18:41:34 volumio-wz volumio[12779]: info: Starting Streaming Service Transparent Proxy Nov 02 18:41:34 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 02 18:41:34 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 02 18:41:34 volumio-wz volumio[12779]: info: Streaming services startup Nov 02 18:41:34 volumio-wz volumio[12779]: info: Starting Streaming Daemon Nov 02 18:41:34 volumio-wz sudo[13220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 18:41:34 volumio-wz sudo[13220]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:34 volumio-wz sudo[13220]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:34 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 02 18:41:34 volumio-wz volumio[12779]: STREAMING PROXY: Starting server on port 3245 Nov 02 18:41:34 volumio-wz volumio[12779]: Node JS runtime: 14 Nov 02 18:41:34 volumio-wz volumio[12779]: info: Listing playlists Nov 02 18:41:34 volumio-wz volumio[12779]: info: Listing playlists Nov 02 18:41:34 volumio-wz volumio[12779]: error: Cannot start Volumio Streaming Daemon Nov 02 18:41:34 volumio-wz volumio[12779]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 18:41:34 volumio-wz volumio[12779]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 18:41:35 volumio-wz volumio[12779]: info: Cannot mount NAS Music_on_MediaPC at system boot, trial number 4 ,retrying in 5 seconds Nov 02 18:41:35 volumio-wz volumio[12779]: info: Cannot mount NAS music at system boot, trial number 4 ,retrying in 5 seconds Nov 02 18:41:36 volumio-wz volumio[12779]: error: MyVolumio Custom Token format not valid, refreshing it Nov 02 18:41:39 volumio-wz volumio[12779]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Nov 02 18:41:39 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Nov 02 18:41:39 volumio-wz volumio[12779]: info: Preparing to save Alsa Options, stopping services first Nov 02 18:41:39 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:39 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:39 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioPause Nov 02 18:41:39 volumio-wz volumio[12779]: info: CoreStateMachine::pause Nov 02 18:41:39 volumio-wz volumio[12779]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"HIFI USB AUDIO"}} Nov 02 18:41:39 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 18:41:39 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 18:41:39 volumio-wz volumio[12779]: info: Device 5 does not have any Mixer Control Available Nov 02 18:41:39 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 02 18:41:39 volumio-wz volumio[12779]: info: Updating Volume Controller Parameters: Device: 5 Name: HIFI USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 02 18:41:39 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 02 18:41:39 volumio-wz volumio[12779]: info: Disabling external Volume Control Nov 02 18:41:39 volumio-wz volumio[12779]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 18:41:39 volumio-wz volumio[12779]: info: CoreStateMachine::pushState Nov 02 18:41:39 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:39 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioPushState Nov 02 18:41:39 volumio-wz volumio[12779]: info: Preparing to generate the ALSA configuration file Nov 02 18:41:39 volumio-wz volumio[12779]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 02 18:41:39 volumio-wz volumio[12779]: info: Reading ALSA contributions from plugins. Nov 02 18:41:39 volumio-wz volumio[12779]: info: PeppySpectrum ---peppyspectrum status stop Nov 02 18:41:39 volumio-wz sudo[13236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 02 18:41:39 volumio-wz sudo[13236]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:39 volumio-wz sudo[13236]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:40 volumio-wz volumio[12779]: info: peppyspectrum Daemon Stop Nov 02 18:41:40 volumio-wz volumio[12779]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 02 18:41:40 volumio-wz volumio[12779]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 02 18:41:40 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 02 18:41:40 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:40 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 18:41:40 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:40 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:40 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:40 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:40 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:40 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:40 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:41:40 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 02 18:41:40 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 02 18:41:42 volumio-wz volumio[12779]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Nov 02 18:41:42 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:42 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:42 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioPause Nov 02 18:41:42 volumio-wz volumio[12779]: info: CoreStateMachine::pause Nov 02 18:41:42 volumio-wz volumio[12779]: info: mpdhttpout --- Volumio set on pause Nov 02 18:41:43 volumio-wz volumio[12779]: info: Asound.conf file unchanged, so no further update is needed Nov 02 18:41:43 volumio-wz volumio[12779]: info: Output device has changed, restarting MPD Nov 02 18:41:43 volumio-wz sudo[13268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 18:41:43 volumio-wz sudo[13268]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:43 volumio-wz sudo[13268]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:43 volumio-wz volumio[12779]: info: Output device has changed, restarting Shairport Sync Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:43 volumio-wz sudo[13271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 18:41:43 volumio-wz sudo[13271]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:43 volumio-wz systemd[1]: musicservicesshield.service: Succeeded. Nov 02 18:41:43 volumio-wz systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 02 18:41:43 volumio-wz systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 02 18:41:43 volumio-wz systemd[1]: Stopping Music Player Daemon... Nov 02 18:41:43 volumio-wz volumio[12779]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 18:41:43 volumio-wz systemd[1]: mpd.service: Succeeded. Nov 02 18:41:43 volumio-wz systemd[1]: Stopped Music Player Daemon. Nov 02 18:41:43 volumio-wz systemd[1]: Starting Music Player Daemon... Nov 02 18:41:43 volumio-wz sudo[13276]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 18:41:43 volumio-wz sudo[13276]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:43 volumio-wz sudo[13276]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:43 volumio-wz volumio[12779]: info: MyVolumio login type: Token Nov 02 18:41:43 volumio-wz volumio[12779]: info: MPD Permissions set Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreStateMachine::getcurrentVolume Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreStateMachine::pushState Nov 02 18:41:43 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioPushState Nov 02 18:41:43 volumio-wz volumio[12779]: info: PeppySpectrum ---peppyspectrum status stop Nov 02 18:41:43 volumio-wz sudo[13280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 02 18:41:43 volumio-wz sudo[13280]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:43 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:43 volumio-wz sudo[13280]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:43 volumio-wz volumio[12779]: info: peppyspectrum Daemon Stop Nov 02 18:41:43 volumio-wz volumio[12779]: info: Starting Shairport Sync Nov 02 18:41:43 volumio-wz volumio[12779]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:43 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioPause Nov 02 18:41:43 volumio-wz volumio[12779]: info: CoreStateMachine::pause Nov 02 18:41:43 volumio-wz volumio[12779]: info: mpdhttpout --- Volumio set on pause Nov 02 18:41:43 volumio-wz sudo[13289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 18:41:43 volumio-wz sudo[13289]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:43 volumio-wz systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 18:41:43 volumio-wz systemd[1]: shairport-sync.service: Succeeded. Nov 02 18:41:43 volumio-wz systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 18:41:43 volumio-wz systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 18:41:43 volumio-wz sudo[13289]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:43 volumio-wz volumio[12779]: info: Shairport-Sync Started Nov 02 18:41:43 volumio-wz mpd[13278]: Nov 02 18:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 18:41:44 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Nov 02 18:41:44 volumio-wz sudo[13297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 18:41:44 volumio-wz sudo[13297]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:44 volumio-wz systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 02 18:41:44 volumio-wz systemd[1]: mpd.service: Succeeded. Nov 02 18:41:44 volumio-wz systemd[1]: Stopped Music Player Daemon. Nov 02 18:41:44 volumio-wz systemd[1]: Starting Music Player Daemon... Nov 02 18:41:44 volumio-wz sudo[13299]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 18:41:44 volumio-wz sudo[13299]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:44 volumio-wz sudo[13299]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:44 volumio-wz mpd[13301]: Nov 02 18:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 18:41:44 volumio-wz volumio[12779]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 02 18:41:45 volumio-wz systemd[1]: Started Music Player Daemon. Nov 02 18:41:45 volumio-wz sudo[13297]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:45 volumio-wz sudo[13271]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:45 volumio-wz volumio[12779]: error: MPD error: The expression evaluated to a falsy value: Nov 02 18:41:45 volumio-wz volumio[12779]: assert.ok(self.idling) Nov 02 18:41:45 volumio-wz volumio[12779]: error: The expression evaluated to a falsy value: Nov 02 18:41:45 volumio-wz volumio[12779]: assert.ok(self.idling) Nov 02 18:41:45 volumio-wz volumio[12779]: error: updateQueue error: null Nov 02 18:41:45 volumio-wz systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 02 18:41:45 volumio-wz volumio[12779]: info: MyVolumio token set successfully Nov 02 18:41:45 volumio-wz volumio[12779]: info: MYVOLUMIO: Adding device Nov 02 18:41:45 volumio-wz volumio[12779]: info: MYVOLUMIO: Evaluating Server Nov 02 18:41:45 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Nov 02 18:41:45 volumio-wz sudo[13323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 18:41:45 volumio-wz sudo[13323]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:45 volumio-wz volumio[13305]: cset: --> shielding system active with Nov 02 18:41:45 volumio-wz volumio[13305]: cset: "system" cpuset of CPUSPEC(1-3) with 266 tasks running Nov 02 18:41:45 volumio-wz volumio[13305]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 02 18:41:45 volumio-wz systemd[1]: Stopping Music Player Daemon... Nov 02 18:41:45 volumio-wz systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 02 18:41:45 volumio-wz systemd[1]: mpd.service: Succeeded. Nov 02 18:41:45 volumio-wz systemd[1]: Stopped Music Player Daemon. Nov 02 18:41:45 volumio-wz systemd[1]: Starting Music Player Daemon... Nov 02 18:41:45 volumio-wz sudo[13333]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 18:41:45 volumio-wz sudo[13333]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:45 volumio-wz sudo[13333]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:45 volumio-wz volumio[12779]: info: MyVolumio Plan changed: premium Nov 02 18:41:45 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Nov 02 18:41:45 volumio-wz volumio[12779]: info: Removing browser output: myVolumio user plan is not superstar Nov 02 18:41:45 volumio-wz volumio[12779]: info: Removing audio output: Nov 02 18:41:45 volumio-wz volumio[12779]: info: MYVOLUMIO: Adding device Nov 02 18:41:45 volumio-wz volumio[12779]: info: MYVOLUMIO: Evaluating Server Nov 02 18:41:45 volumio-wz volumio[12779]: info: Remote config written successfully Nov 02 18:41:45 volumio-wz volumio[12779]: info: Starting Tunnel 1 Nov 02 18:41:45 volumio-wz volumio[12779]: info: Starting Tunnel Connection Checker Nov 02 18:41:45 volumio-wz volumio[12779]: info: Completed starting MyVolumio Plugin Nov 02 18:41:46 volumio-wz mpd[13338]: Nov 02 18:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 18:41:46 volumio-wz volumio[12779]: info: MYVolumio Device enabled Nov 02 18:41:46 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Nov 02 18:41:46 volumio-wz volumio[12779]: info: MyVolumio status changed Nov 02 18:41:46 volumio-wz volumio[12779]: info: Streaming services startup Nov 02 18:41:46 volumio-wz volumio[12779]: info: Starting Streaming Daemon Nov 02 18:41:46 volumio-wz sudo[13360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 18:41:46 volumio-wz sudo[13360]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:46 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 02 18:41:46 volumio-wz sudo[13360]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:46 volumio-wz volumio[12779]: error: [MyVolumio PluginManager] Cache data is invalid! Nov 02 18:41:46 volumio-wz volumio[12779]: error: Cannot start Volumio Streaming Daemon Nov 02 18:41:46 volumio-wz volumio[12779]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 18:41:46 volumio-wz volumio[12779]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 18:41:47 volumio-wz volumio[12779]: info: Setting Geolocation for MyVolumio to eu10 Nov 02 18:41:47 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:47 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:47 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:47 volumio-wz volumio[12779]: info: Setting Geolocation for MyVolumio to eu4 Nov 02 18:41:47 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:47 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:47 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:47 volumio-wz systemd[1]: Started Music Player Daemon. Nov 02 18:41:47 volumio-wz sudo[13323]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:47 volumio-wz volumio[12779]: error: updateQueue error: null Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Nov 02 18:41:47 volumio-wz volumio[12779]: info: [1762105307376] Starting BluetoothController Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Nov 02 18:41:47 volumio-wz sudo[13366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Nov 02 18:41:47 volumio-wz sudo[13366]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:47 volumio-wz sudo[13366]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:47 volumio-wz volumio[12779]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Nov 02 18:41:47 volumio-wz volumio[12779]: info: MRS: MultiRoom plugin initialized Nov 02 18:41:47 volumio-wz volumio[12779]: info: MRS: STOPPING SNAPCLIENT Nov 02 18:41:47 volumio-wz volumio[12779]: info: MRS: Snap server stop Nov 02 18:41:47 volumio-wz sudo[13382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Nov 02 18:41:47 volumio-wz sudo[13382]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:47 volumio-wz sudo[13382]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:47 volumio-wz volumio[12779]: info: MRS: STOPPING volumioStreaming Nov 02 18:41:47 volumio-wz sudo[13385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Nov 02 18:41:47 volumio-wz sudo[13385]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:47 volumio-wz sudo[13385]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:47 volumio-wz sudo[13388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Nov 02 18:41:47 volumio-wz sudo[13388]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:47 volumio-wz sudo[13388]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Nov 02 18:41:47 volumio-wz sudo[13391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 02 18:41:47 volumio-wz sudo[13391]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:47 volumio-wz sudo[13391]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Nov 02 18:41:47 volumio-wz volumio[12779]: info: Preparing to generate the ALSA configuration file Nov 02 18:41:47 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 02 18:41:47 volumio-wz volumio[12779]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 02 18:41:47 volumio-wz volumio[12779]: info: Reading ALSA contributions from plugins. Nov 02 18:41:47 volumio-wz volumio[12779]: info: Successfully Added MyVolumio device Nov 02 18:41:47 volumio-wz volumio[12779]: info: MRS: Removed streaming files Nov 02 18:41:47 volumio-wz volumio[12779]: info: MRS: volumioStreaming STOPPED Nov 02 18:41:47 volumio-wz volumio[12779]: info: MRS: SNAPSERVER STOPPED Nov 02 18:41:47 volumio-wz volumio[12779]: info: MRS: SNAPCLIENT STOPPED Nov 02 18:41:47 volumio-wz volumio[12779]: info: Asound.conf file unchanged, so no further update is needed Nov 02 18:41:47 volumio-wz volumio[12779]: info: Output device has changed, restarting MPD Nov 02 18:41:47 volumio-wz sudo[13394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 18:41:47 volumio-wz sudo[13394]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:47 volumio-wz sudo[13394]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:47 volumio-wz volumio[12779]: info: Output device has changed, restarting Shairport Sync Nov 02 18:41:47 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:47 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:47 volumio-wz sudo[13397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 18:41:47 volumio-wz sudo[13397]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:47 volumio-wz systemd[1]: musicservicesshield.service: Succeeded. Nov 02 18:41:47 volumio-wz systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 02 18:41:47 volumio-wz systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 02 18:41:47 volumio-wz systemd[1]: Stopping Music Player Daemon... Nov 02 18:41:47 volumio-wz volumio[12779]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 18:41:47 volumio-wz volumio[12779]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 02 18:41:47 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Nov 02 18:41:47 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Nov 02 18:41:47 volumio-wz volumio[12779]: info: Adding METAVOLUMIO REST API Endpoints Nov 02 18:41:47 volumio-wz volumio[12779]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 02 18:41:47 volumio-wz volumio[12779]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Nov 02 18:41:47 volumio-wz volumio[12779]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Nov 02 18:41:47 volumio-wz volumio[12779]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Nov 02 18:41:47 volumio-wz volumio[12779]: info: Preparing CD Folders Nov 02 18:41:47 volumio-wz sudo[13405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio-WZ Nov 02 18:41:47 volumio-wz sudo[13405]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:47 volumio-wz volumio[12779]: info: Adding CD REST API Endpoints Nov 02 18:41:47 volumio-wz volumio[12779]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Nov 02 18:41:47 volumio-wz volumio[12779]: info: Starting UDEV Watcher for CD Nov 02 18:41:47 volumio-wz volumio[12779]: info: Detecting CD presence with UDEV Nov 02 18:41:47 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Nov 02 18:41:47 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Nov 02 18:41:47 volumio-wz sudo[13405]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:47 volumio-wz volumio[12779]: info: Adding inputs REST Endpoints Nov 02 18:41:47 volumio-wz volumio[12779]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Nov 02 18:41:47 volumio-wz volumio[12779]: info: Scanning Audio Inputs Nov 02 18:41:48 volumio-wz volumio[12779]: info: Checking against Known Cards name Nov 02 18:41:48 volumio-wz volumio[12779]: info: Checking against Known Cards name Nov 02 18:41:48 volumio-wz volumio[12779]: info: Adding Server instance for streaming Nov 02 18:41:48 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Nov 02 18:41:48 volumio-wz volumio[12779]: error: Hi Res Audio Failed Login: Missing Login Data Nov 02 18:41:48 volumio-wz volumio[12779]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 02 18:41:48 volumio-wz volumio[12779]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 02 18:41:48 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Nov 02 18:41:48 volumio-wz volumio[12779]: info: Refreshing TIDAL token Nov 02 18:41:48 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Nov 02 18:41:48 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Nov 02 18:41:48 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Nov 02 18:41:48 volumio-wz volumio[12779]: info: [MyVolumio PluginManager] Plugin qobuzconnect disabled by user. Not starting Nov 02 18:41:48 volumio-wz volumio[12779]: info: Stopping AccessToken refresher cron for QOBUZ Nov 02 18:41:48 volumio-wz sudo[13410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 02 18:41:48 volumio-wz sudo[13410]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:48 volumio-wz systemd[1]: Stopping Volumio Tidal Connect Service... Nov 02 18:41:48 volumio-wz systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 02 18:41:48 volumio-wz volumio[12779]: info: AccessToken refresher cron started for QOBUZ Nov 02 18:41:48 volumio-wz volumio[12779]: info: Adding QOBUZ REST API Endpoints Nov 02 18:41:48 volumio-wz volumio[12779]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 02 18:41:48 volumio-wz systemd[1]: vtcs.service: Succeeded. Nov 02 18:41:48 volumio-wz systemd[1]: Stopped Volumio Tidal Connect Service. Nov 02 18:41:48 volumio-wz sudo[13410]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:48 volumio-wz volumio[12779]: info: Successfully Added MyVolumio device Nov 02 18:41:48 volumio-wz volumio[12779]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Volumio-WZ' Nov 02 18:41:48 volumio-wz volumio[12779]: Can't get device info: No such device Nov 02 18:41:48 volumio-wz volumio[12779]: info: MPD Permissions set Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:48 volumio-wz systemd[1]: mpd.service: Succeeded. Nov 02 18:41:48 volumio-wz systemd[1]: Stopped Music Player Daemon. Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:48 volumio-wz volumio[12779]: info: Starting Shairport Sync Nov 02 18:41:48 volumio-wz systemd[1]: Starting Music Player Daemon... Nov 02 18:41:48 volumio-wz volumio[12779]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:48 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioPause Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreStateMachine::pause Nov 02 18:41:48 volumio-wz volumio[12779]: info: mpdhttpout --- Volumio set on pause Nov 02 18:41:48 volumio-wz sudo[13414]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 18:41:48 volumio-wz sudo[13414]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:48 volumio-wz sudo[13421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 18:41:48 volumio-wz sudo[13421]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:48 volumio-wz sudo[13414]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:48 volumio-wz systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 18:41:48 volumio-wz systemd[1]: shairport-sync.service: Succeeded. Nov 02 18:41:48 volumio-wz systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 18:41:48 volumio-wz systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 18:41:48 volumio-wz sudo[13421]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:48 volumio-wz volumio[12779]: info: Shairport-Sync Started Nov 02 18:41:48 volumio-wz volumio[12779]: info: Updating MyVolumio device info Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:48 volumio-wz volumio[12779]: info: Updating MyVolumio device info Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:48 volumio-wz mpd[13424]: Nov 02 18:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 18:41:48 volumio-wz volumio[12779]: info: Access Token successfully retrieved Nov 02 18:41:48 volumio-wz volumio[12779]: error: Failed to retrieve User Session from TIDAL: undefined Error: connect EHOSTUNREACH 99.84.91.22:443 Nov 02 18:41:48 volumio-wz volumio[12779]: error: Could not start login into TIDAL at startup: Error: An error occurred while getting subscription info from Tidal. Nov 02 18:41:48 volumio-wz volumio[12779]: info: Stopping AccessToken refresher cron Nov 02 18:41:48 volumio-wz volumio[12779]: info: AccessToken refresher cron started Nov 02 18:41:48 volumio-wz volumio[12779]: info: Adding TIDAL REST API Endpoints Nov 02 18:41:48 volumio-wz volumio[12779]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 02 18:41:48 volumio-wz volumio[12779]: info: Successfully Updated MyVolumio device Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 18:41:48 volumio-wz volumio[12779]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Nov 02 18:41:48 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:48 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:49 volumio-wz volumio[12779]: info: Successfully Updated MyVolumio device Nov 02 18:41:49 volumio-wz systemd[1]: Started Music Player Daemon. Nov 02 18:41:49 volumio-wz sudo[13397]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:49 volumio-wz volumio[12779]: error: updateQueue error: null Nov 02 18:41:49 volumio-wz systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 02 18:41:49 volumio-wz volumio[13430]: cset: --> shielding system active with Nov 02 18:41:49 volumio-wz volumio[13430]: cset: "system" cpuset of CPUSPEC(1-3) with 248 tasks running Nov 02 18:41:49 volumio-wz volumio[13430]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 02 18:41:49 volumio-wz systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 02 18:41:50 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Nov 02 18:41:50 volumio-wz sudo[13460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 18:41:50 volumio-wz sudo[13460]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:50 volumio-wz systemd[1]: musicservicesshield.service: Succeeded. Nov 02 18:41:50 volumio-wz systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 02 18:41:50 volumio-wz systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 02 18:41:50 volumio-wz systemd[1]: Stopping Music Player Daemon... Nov 02 18:41:50 volumio-wz systemd[1]: mpd.service: Succeeded. Nov 02 18:41:50 volumio-wz systemd[1]: Stopped Music Player Daemon. Nov 02 18:41:50 volumio-wz systemd[1]: Starting Music Player Daemon... Nov 02 18:41:50 volumio-wz sudo[13462]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 18:41:50 volumio-wz sudo[13462]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:50 volumio-wz sudo[13462]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:50 volumio-wz mpd[13464]: Nov 02 18:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 18:41:50 volumio-wz sudo[13466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Nov 02 18:41:50 volumio-wz sudo[13466]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:51 volumio-wz sudo[13466]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:51 volumio-wz volumio[12779]: info: Volumio BT Module successfully started Nov 02 18:41:51 volumio-wz volumio[12779]: info: TidalConnect service stoped! Nov 02 18:41:51 volumio-wz sudo[13477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 02 18:41:51 volumio-wz sudo[13477]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:51 volumio-wz systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 02 18:41:51 volumio-wz systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 02 18:41:51 volumio-wz autossh[11982]: received signal to exit (15) Nov 02 18:41:51 volumio-wz systemd[1]: sshtunnel.service: Succeeded. Nov 02 18:41:51 volumio-wz systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 02 18:41:51 volumio-wz volumio[12779]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 18:41:51 volumio-wz volumio[12779]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 18:41:51 volumio-wz volumio[12779]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 02 18:41:51 volumio-wz volumio[12779]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 02 18:41:51 volumio-wz systemd[1]: Started MyVolumio SSH Tunnel. Nov 02 18:41:51 volumio-wz sudo[13477]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:51 volumio-wz volumio[12779]: info: Remote SSH Started Nov 02 18:41:51 volumio-wz sudo[13488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 02 18:41:51 volumio-wz sudo[13488]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:51 volumio-wz autossh[13485]: port set to 0, monitoring disabled Nov 02 18:41:51 volumio-wz autossh[13485]: starting ssh (count 1) Nov 02 18:41:51 volumio-wz autossh[13485]: ssh child pid is 13490 Nov 02 18:41:51 volumio-wz systemd[1]: Started Volumio Tidal Connect Service. Nov 02 18:41:51 volumio-wz sudo[13488]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:51 volumio-wz volumio[12779]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Nov 02 18:41:51 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:51 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:51 volumio-wz volumio[12779]: info: Executing endpoint tc_getconfig Nov 02 18:41:51 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 02 18:41:51 volumio-wz vtcs[13491]: STARTING TidalConnect services, version: 1.5.1-beta1 Nov 02 18:41:51 volumio-wz vtcs[13491]: STARTED TidalConnect services. Nov 02 18:41:51 volumio-wz volumio[12779]: info: Executing endpoint tc_connect Nov 02 18:41:51 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 02 18:41:51 volumio-wz volumio[12779]: info: Connecting to TidalConnect Nov 02 18:41:51 volumio-wz volumio[12779]: info: CoreCommandRouter::servicePushState Nov 02 18:41:51 volumio-wz volumio[12779]: info: CoreStateMachine::pushState Nov 02 18:41:51 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:51 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioPushState Nov 02 18:41:51 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:51 volumio-wz volumio[12779]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 02 18:41:51 volumio-wz volumio[12779]: info: CoreCommandRouter::servicePushState Nov 02 18:41:51 volumio-wz volumio[12779]: info: CoreStateMachine::pushState Nov 02 18:41:51 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:51 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioPushState Nov 02 18:41:51 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:51 volumio-wz volumio[12779]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 02 18:41:51 volumio-wz volumio[12779]: info: PeppySpectrum ---peppyspectrum status stop Nov 02 18:41:51 volumio-wz volumio[12779]: info: PeppySpectrum ---peppyspectrum status stop Nov 02 18:41:51 volumio-wz sudo[13511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 02 18:41:51 volumio-wz sudo[13511]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:51 volumio-wz sudo[13511]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:51 volumio-wz sudo[13514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 02 18:41:51 volumio-wz volumio[12779]: info: peppyspectrum Daemon Stop Nov 02 18:41:51 volumio-wz sudo[13514]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:41:51 volumio-wz sudo[13514]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:51 volumio-wz volumio[12779]: info: peppyspectrum Daemon Stop Nov 02 18:41:51 volumio-wz volumiossh-tunnel[13479]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Nov 02 18:41:51 volumio-wz systemd[1]: Started Music Player Daemon. Nov 02 18:41:51 volumio-wz sudo[13460]: pam_unix(sudo:session): session closed for user root Nov 02 18:41:51 volumio-wz volumio[12779]: error: updateQueue error: null Nov 02 18:41:51 volumio-wz systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 02 18:41:51 volumio-wz volumio[12779]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 18:41:51 volumio-wz volumio[13518]: cset: --> shielding system active with Nov 02 18:41:51 volumio-wz volumio[13518]: cset: "system" cpuset of CPUSPEC(1-3) with 264 tasks running Nov 02 18:41:51 volumio-wz volumio[13518]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 02 18:41:51 volumio-wz systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 02 18:41:51 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 18:41:51 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:51 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 18:41:52 volumio-wz volumio-remote-updater[681]: No test mode Nov 02 18:41:52 volumio-wz volumio-remote-updater[681]: No alpha test mode Nov 02 18:41:52 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 02 18:41:52 volumio-wz volumio-remote-updater[681]: curl_easy_perform() failed: Couldn't connect to server Nov 02 18:41:52 volumio-wz volumio-remote-updater[681]: terminate called after throwing an instance of 'std::invalid_argument' Nov 02 18:41:52 volumio-wz volumio-remote-updater[681]: what(): parse error - unexpected end of input Nov 02 18:41:52 volumio-wz systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT Nov 02 18:41:52 volumio-wz systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. Nov 02 18:41:52 volumio-wz systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 18:41:52 volumio-wz systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Nov 02 18:41:52 volumio-wz systemd[1]: Stopped volumio-remote-updater.service. Nov 02 18:41:52 volumio-wz systemd[1]: Started volumio-remote-updater.service. Nov 02 18:41:52 volumio-wz volumio-remote-updater[13536]: Error: No active session Nov 02 18:41:52 volumio-wz volumio-remote-updater[13536]: [2025-11-02 18:41:52] [connect] Successful connection Nov 02 18:41:52 volumio-wz volumio-remote-updater[13536]: [2025-11-02 18:41:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1762105312 101 Nov 02 18:41:52 volumio-wz volumio[12779]: 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: 10 Nov 02 18:41:52 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:41:52 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:41:52 volumio-wz volumio[12779]: info: Listing playlists Nov 02 18:41:52 volumio-wz volumio[12779]: info: Listing playlists Nov 02 18:41:53 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:53 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:53 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 02 18:41:53 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 02 18:41:53 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 02 18:41:53 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 18:41:53 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 18:41:54 volumio-wz volumio[12779]: info: TidalConnect service started! Nov 02 18:41:54 volumio-wz volumio[12779]: [Metrics] CommandRouter: 34s 320.00ms Nov 02 18:41:54 volumio-wz volumio[12779]: info: CoreCommandRouter::volumiosetStartupVolume Nov 02 18:41:54 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:41:54 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:41:54 volumio-wz volumio[12779]: info: CoreCommandRouter::Close All Modals sent Nov 02 18:41:54 volumio-wz volumio[12779]: info: CoreCommandRouter::Close All Modals sent Nov 02 18:41:55 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 02 18:41:55 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 18:41:55 volumio-wz volumio[12779]: No protocol specified Nov 02 18:41:55 volumio-wz volumio[12779]: xcb_connection_has_error() returned true Nov 02 18:41:55 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 02 18:42:00 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:42:00 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:42:00 volumio-wz volumio[12779]: info: Discovery: Getting this device information Nov 02 18:42:00 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:00 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:00 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:42:01 volumio-wz volumio[12779]: info: BOOT COMPLETED Nov 02 18:42:01 volumio-wz volumio[12779]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Nov 02 18:42:01 volumio-wz volumio[12779]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Nov 02 18:42:01 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:01 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:01 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioPause Nov 02 18:42:01 volumio-wz volumio[12779]: info: CoreStateMachine::pause Nov 02 18:42:01 volumio-wz volumio[12779]: info: mpdhttpout --- Volumio set on pause Nov 02 18:42:02 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:02 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:03 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Nov 02 18:42:03 volumio-wz sudo[13581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 18:42:03 volumio-wz sudo[13581]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:03 volumio-wz systemd[1]: musicservicesshield.service: Succeeded. Nov 02 18:42:03 volumio-wz systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 02 18:42:03 volumio-wz systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 02 18:42:03 volumio-wz systemd[1]: Stopping Music Player Daemon... Nov 02 18:42:03 volumio-wz volumio[12779]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 18:42:03 volumio-wz volumio[12779]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 18:42:03 volumio-wz systemd[1]: mpd.service: Succeeded. Nov 02 18:42:03 volumio-wz systemd[1]: Stopped Music Player Daemon. Nov 02 18:42:03 volumio-wz volumio[12779]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 18:42:03 volumio-wz systemd[1]: Starting Music Player Daemon... Nov 02 18:42:03 volumio-wz volumio[12779]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 18:42:03 volumio-wz sudo[13583]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 18:42:03 volumio-wz sudo[13583]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:03 volumio-wz sudo[13583]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:03 volumio-wz mpd[13585]: Nov 02 18:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 18:42:04 volumio-wz systemd[1]: Started Music Player Daemon. Nov 02 18:42:04 volumio-wz sudo[13581]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:04 volumio-wz volumio[12779]: error: updateQueue error: null Nov 02 18:42:04 volumio-wz systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 02 18:42:04 volumio-wz volumio[12779]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 18:42:04 volumio-wz volumio[13589]: cset: --> shielding system active with Nov 02 18:42:04 volumio-wz volumio[13589]: cset: "system" cpuset of CPUSPEC(1-3) with 259 tasks running Nov 02 18:42:04 volumio-wz volumio[13589]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 02 18:42:04 volumio-wz systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 02 18:42:07 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:07 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:08 volumio-wz sudo[13608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 18:42:08 volumio-wz sudo[13608]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:08 volumio-wz sudo[13608]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:08 volumio-wz sudo[13611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 18:42:08 volumio-wz sudo[13611]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:08 volumio-wz sudo[13611]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:08 volumio-wz volumio[12779]: verbose: New Socket.io Connection to 192.168.22.13 from 192.168.22.92 UA: Mozilla/5.0 (Linux; Android 15; 25010PN30G Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Nov 02 18:42:08 volumio-wz sudo[13616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 18:42:08 volumio-wz sudo[13616]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:08 volumio-wz sudo[13616]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:08 volumio-wz sudo[13619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 18:42:08 volumio-wz sudo[13619]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:08 volumio-wz sudo[13619]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:08 volumio-wz volumio[12779]: verbose: New Socket.io Connection to 192.168.22.13 from 192.168.22.92 UA: Mozilla/5.0 (Linux; Android 15; 25010PN30G Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Nov 02 18:42:08 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 18:42:08 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 18:42:08 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:08 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:08 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:08 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 18:42:08 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 18:42:08 volumio-wz volumio[12779]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 02 18:42:08 volumio-wz volumio[12779]: info: Received Get System Info Nov 02 18:42:08 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:42:08 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:42:08 volumio-wz volumio[12779]: info: Discovery: Getting this device information Nov 02 18:42:08 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:08 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:08 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:42:08 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:08 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:08 volumio-wz volumio[12779]: info: Listing playlists Nov 02 18:42:08 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 02 18:42:08 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 18:42:08 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 02 18:42:10 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 02 18:42:10 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 02 18:42:10 volumio-wz volumio[12779]: info: Received Get System Info Nov 02 18:42:10 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:42:10 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:42:10 volumio-wz volumio[12779]: info: Discovery: Getting this device information Nov 02 18:42:10 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:10 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:10 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:42:11 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetQueue Nov 02 18:42:11 volumio-wz volumio[12779]: info: CoreStateMachine::getQueue Nov 02 18:42:11 volumio-wz volumio[12779]: info: CorePlayQueue::getQueue Nov 02 18:42:12 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 02 18:42:12 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:12 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:12 volumio-wz volumio[12779]: info: Listing playlists Nov 02 18:42:12 volumio-wz volumio[12779]: info: Listing playlists Nov 02 18:42:13 volumio-wz volumio[12779]: info: Received Get System Info Nov 02 18:42:13 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:42:13 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:42:13 volumio-wz volumio[12779]: info: Discovery: Getting this device information Nov 02 18:42:13 volumio-wz volumio[12779]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:13 volumio-wz volumio[12779]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:13 volumio-wz volumio[12779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:42:14 volumio-wz volumio[12779]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 18:42:14 volumio-wz volumio[12779]: Error: connect EHOSTUNREACH 199.232.190.53:443 Nov 02 18:42:14 volumio-wz volumio[12779]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Nov 02 18:42:14 volumio-wz volumio[12779]: errno: -113, Nov 02 18:42:14 volumio-wz volumio[12779]: code: 'EHOSTUNREACH', Nov 02 18:42:14 volumio-wz volumio[12779]: syscall: 'connect', Nov 02 18:42:14 volumio-wz volumio[12779]: address: '199.232.190.53', Nov 02 18:42:14 volumio-wz volumio[12779]: port: 443 Nov 02 18:42:14 volumio-wz volumio[12779]: } Nov 02 18:42:14 volumio-wz volumio[12779]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 18:42:15 volumio-wz sudo[13659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-02 18:41 Nov 02 18:42:15 volumio-wz sudo[13659]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:15 volumio-wz sudo[13659]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:15 volumio-wz vtcs[13491]: [2025-11-02 18:42:15.077] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Nov 02 18:42:15 volumio-wz volumio-remote-updater[13536]: [2025-11-02 18:42:15] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 02 18:42:15 volumio-wz volumio-remote-updater[13536]: [2025-11-02 18:42:15] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 02 18:42:15 volumio-wz systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 18:42:15 volumio-wz systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 02 18:42:15 volumio-wz systemd[1]: Started dynamicswap service. Nov 02 18:42:15 volumio-wz systemd[1]: dynamicswap.service: Succeeded. Nov 02 18:42:15 volumio-wz systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 18:42:15 volumio-wz systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Nov 02 18:42:15 volumio-wz systemd[1]: Started dynamicswap service. Nov 02 18:42:15 volumio-wz systemd[1]: Stopped Volumio Backend Module. Nov 02 18:42:15 volumio-wz systemd[1]: Started Volumio Backend Module. Nov 02 18:42:15 volumio-wz systemd[1]: dynamicswap.service: Succeeded. Nov 02 18:42:15 volumio-wz volumio[13670]: info: ------------------------------------------- Nov 02 18:42:15 volumio-wz volumio[13670]: info: ----- Volumio3 ---- Nov 02 18:42:15 volumio-wz volumio[13670]: info: ------------------------------------------- Nov 02 18:42:15 volumio-wz volumio[13670]: info: ----- System startup ---- Nov 02 18:42:15 volumio-wz volumio[13670]: info: ------------------------------------------- Nov 02 18:42:15 volumio-wz volumio[13670]: info: MYVOLUMIO Environment detected Nov 02 18:42:15 volumio-wz volumio[13670]: info: Plugin folders cleanup Nov 02 18:42:15 volumio-wz volumio[13670]: info: Scanning into folder /volumio/app/plugins/ Nov 02 18:42:15 volumio-wz volumio[13670]: info: Scanning category audio_interface Nov 02 18:42:15 volumio-wz volumio[13670]: info: Scanning category miscellanea Nov 02 18:42:15 volumio-wz volumio[13670]: info: Scanning category music_service Nov 02 18:42:15 volumio-wz volumio[13670]: info: Scanning category plugins.json Nov 02 18:42:15 volumio-wz volumio[13670]: info: Scanning category system_controller Nov 02 18:42:15 volumio-wz volumio[13670]: info: Scanning category user_interface Nov 02 18:42:15 volumio-wz volumio[13670]: info: Scanning into folder /data/plugins/ Nov 02 18:42:15 volumio-wz volumio[13670]: info: Scanning category audio_interface Nov 02 18:42:15 volumio-wz volumio[13670]: info: Scanning category music_service Nov 02 18:42:15 volumio-wz volumio[13670]: info: Scanning category system_controller Nov 02 18:42:15 volumio-wz volumio[13670]: info: Scanning category system_hardware Nov 02 18:42:15 volumio-wz volumio[13670]: info: Scanning category user_interface Nov 02 18:42:15 volumio-wz volumio[13670]: info: Plugin folders cleanup completed Nov 02 18:42:15 volumio-wz volumio[13670]: info: ------------------------------------------- Nov 02 18:42:15 volumio-wz volumio[13670]: info: ----- Core plugins startup ---- Nov 02 18:42:15 volumio-wz volumio[13670]: info: ------------------------------------------- Nov 02 18:42:15 volumio-wz volumio[13670]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 18:42:15 volumio-wz volumio[13670]: info: Adding plugin upnp to MyMusic Plugins Nov 02 18:42:15 volumio-wz volumio[13670]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 18:42:15 volumio-wz volumio[13670]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 18:42:15 volumio-wz volumio[13670]: info: Loading plugins from folder /data/plugins/ Nov 02 18:42:15 volumio-wz volumio[13670]: info: Loading plugin "system"... Nov 02 18:42:15 volumio-wz volumio[13670]: info: Loading plugin "appearance"... Nov 02 18:42:15 volumio-wz volumio[13670]: info: Loading plugin "network"... Nov 02 18:42:15 volumio-wz volumio[13670]: info: Refreshing Cached IP Addresses Nov 02 18:42:15 volumio-wz sudo[13696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 18:42:15 volumio-wz sudo[13696]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:15 volumio-wz sudo[13696]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:15 volumio-wz sudo[13698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 18:42:15 volumio-wz sudo[13698]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:15 volumio-wz sudo[13698]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:15 volumio-wz volumio[13670]: info: Loading plugin "services"... Nov 02 18:42:15 volumio-wz volumio[13670]: info: Loading plugin "alsa_controller"... Nov 02 18:42:15 volumio-wz sudo[13710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 02 18:42:15 volumio-wz sudo[13710]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:15 volumio-wz sudo[13710]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:15 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 18:42:15 volumio-wz volumio[13670]: info: Loading plugin "wizard"... Nov 02 18:42:15 volumio-wz volumio[13670]: info: Loading plugin "networkfs"... Nov 02 18:42:15 volumio-wz volumio[13670]: info: Cannot mount NAS Music_on_MediaPC at system boot, trial number 1 ,retrying in 5 seconds Nov 02 18:42:15 volumio-wz volumio[13670]: info: Cannot mount NAS music at system boot, trial number 1 ,retrying in 5 seconds Nov 02 18:42:15 volumio-wz volumio[13670]: info: Starting Udev Watcher for removable devices Nov 02 18:42:15 volumio-wz volumio[13670]: info: Ignoring mount for partition: boot Nov 02 18:42:15 volumio-wz volumio[13670]: info: Ignoring mount for partition: volumio Nov 02 18:42:15 volumio-wz volumio[13670]: info: Ignoring mount for partition: volumio_data Nov 02 18:42:15 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 18:42:15 volumio-wz volumio[13670]: info: Loading plugin "volumio_command_line_client"... Nov 02 18:42:15 volumio-wz volumio[13670]: info: Loading plugin "upnp"... Nov 02 18:42:15 volumio-wz volumio[13670]: info: [1762105335921] Starting Upmpd Daemon Nov 02 18:42:15 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 18:42:15 volumio-wz volumio[13670]: info: Loading plugin "my_music"... Nov 02 18:42:15 volumio-wz volumio[13670]: info: Loading plugin "mpd"... Nov 02 18:42:15 volumio-wz volumio[13670]: info: Loading plugin "upnp_browser"... Nov 02 18:42:15 volumio-wz volumio[13670]: info: Loading plugin "alarm-clock"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "airplay_emulation"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Starting Shairport Sync Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "last_100"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "webradio"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "i2s_dacs"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: I2S DAC not set, start Auto-detection Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "volumiodiscovery"... Nov 02 18:42:16 volumio-wz volumio[13670]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 18:42:16 volumio-wz volumio[13670]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 18:42:16 volumio-wz volumio[13670]: *** WARNING *** For more information see Nov 02 18:42:16 volumio-wz volumio[13670]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 18:42:16 volumio-wz node[13670]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 18:42:16 volumio-wz volumio[13670]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 18:42:16 volumio-wz volumio[13670]: *** WARNING *** For more information see Nov 02 18:42:16 volumio-wz node[13670]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 18:42:16 volumio-wz node[13670]: *** WARNING *** For more information see Nov 02 18:42:16 volumio-wz node[13670]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 18:42:16 volumio-wz node[13670]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 18:42:16 volumio-wz node[13670]: *** WARNING *** For more information see Nov 02 18:42:16 volumio-wz volumio[13670]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 18:42:16 volumio-wz volumio[13670]: info: Discovery: Started advertising with name: Volumio-WZ Nov 02 18:42:16 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "bandcamp"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "calmradio"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "mixcloud"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "soundcloud"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "now_playing"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "outputs"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "albumart"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Plugin example_plugin is not enabled Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "inputs"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "updater_comm"... Nov 02 18:42:16 volumio-wz volumio[13670]: Forking 3 albumart workers Nov 02 18:42:16 volumio-wz volumio[13670]: info: Plugin mpdemulation is not enabled Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "rest_api"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "websocket"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Starting Socket.io Server version 2.3.0 Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "mpdoutput"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "80s80s"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Applying required configuration parameters for plugin 80s80s Nov 02 18:42:16 volumio-wz volumio[13670]: info: [1762105336616] [80s80s] API delay: 30 Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "RoonBridge"... Nov 02 18:42:16 volumio-wz volumio[13670]: Starting albumart workers Nov 02 18:42:16 volumio-wz volumio[13670]: Starting albumart workers Nov 02 18:42:16 volumio-wz volumio[13670]: info: Applying required configuration parameters for plugin RoonBridge Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "minidlna"... Nov 02 18:42:16 volumio-wz volumio[13670]: Starting albumart workers Nov 02 18:42:16 volumio-wz volumio[13670]: info: Applying required configuration parameters for plugin minidlna Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "motherearthradio"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Applying required configuration parameters for plugin motherearthradio Nov 02 18:42:16 volumio-wz volumio[13670]: info: [1762105336804] [MotherEarth] API delay: 5 Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "nowyswiat"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Applying required configuration parameters for plugin nowyswiat Nov 02 18:42:16 volumio-wz volumio[13670]: info: [1762105336829] [RadioNowySwiat] API delay: 5 Nov 02 18:42:16 volumio-wz volumio[13670]: info: [1762105336829] [RadioNowySwiat] Stream 256Kb: http://stream.rcs.revma.com/ypqt40u0x1zuv Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "podcast"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: ControllerPodcast::constructor Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "backup_restore"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Applying required configuration parameters for plugin backup_restore Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "music_services_shield"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Applying required configuration parameters for plugin music_services_shield Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "Systeminfo"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "peppyspectrum"... Nov 02 18:42:16 volumio-wz volumio[13670]: info: Loading plugin "randomizer"... Nov 02 18:42:17 volumio-wz volumio[13670]: info: Applying required configuration parameters for plugin randomizer Nov 02 18:42:17 volumio-wz volumio[13670]: info: Loading i18n strings for locale de Nov 02 18:42:17 volumio-wz volumio[13670]: Updating browse sources language Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::initPlayerControls Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: Express server listening on port 3000 Nov 02 18:42:17 volumio-wz volumio[13670]: [Metrics] WebUI: 1s 519.52ms Nov 02 18:42:17 volumio-wz volumio[13670]: info: Setting Device type: x86 Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreStateMachine::resetVolumioState Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreStateMachine::getcurrentVolume Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreStateMachine::pushState Nov 02 18:42:17 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 02 18:42:17 volumio-wz volumio[13670]: info: Completed loading Core Plugins Nov 02 18:42:17 volumio-wz volumio[13670]: info: Preparing to generate the ALSA configuration file Nov 02 18:42:17 volumio-wz volumio[13670]: info: Volumio Network Manager: Network status updated: 1 Nov 02 18:42:17 volumio-wz volumio[13670]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 02 18:42:17 volumio-wz volumio[13670]: wlan0 Interface doesn't support scanning. Nov 02 18:42:17 volumio-wz volumio[13670]: info: Cannot use regular scanning, forcing with ap-force Nov 02 18:42:17 volumio-wz sudo[13780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 02 18:42:17 volumio-wz sudo[13780]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz sudo[13780]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:17 volumio-wz volumio[13670]: command failed: No such device (-19) Nov 02 18:42:17 volumio-wz volumio[13670]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 02 18:42:17 volumio-wz volumio[13670]: command failed: No such device (-19) Nov 02 18:42:17 volumio-wz volumio[13670]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Nov 02 18:42:17 volumio-wz volumio[13670]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 02 18:42:17 volumio-wz volumio[13670]: info: Reading ALSA contributions from plugins. Nov 02 18:42:17 volumio-wz volumio[13670]: verbose: New Socket.io Connection to 192.168.22.13:3000 from 192.168.22.92 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Nov 02 18:42:17 volumio-wz volumio[13670]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Nov 02 18:42:17 volumio-wz volumio[13670]: verbose: New Socket.io Connection to 192.168.22.13 from 192.168.22.92 UA: Mozilla/5.0 (Linux; Android 15; 25010PN30G Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 02 18:42:17 volumio-wz volumio[13670]: verbose: New Socket.io Connection to 192.168.22.13 from 192.168.22.92 UA: Mozilla/5.0 (Linux; Android 15; 25010PN30G Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 02 18:42:17 volumio-wz volumio[13670]: info: Reloading queue from file Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreStateMachine::setRepeat null single undefined Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreStateMachine::pushState Nov 02 18:42:17 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreStateMachine::setRandom null Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreStateMachine::pushState Nov 02 18:42:17 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioPushState Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 18:42:17 volumio-wz volumio[13670]: info: Discovery: A device disappeared from network Nov 02 18:42:17 volumio-wz volumio[13670]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Nov 02 18:42:17 volumio-wz volumio[13670]: info: Asound.conf file unchanged, so no further update is needed Nov 02 18:42:17 volumio-wz volumio[13670]: info: Output device has changed, restarting MPD Nov 02 18:42:17 volumio-wz sudo[13791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 18:42:17 volumio-wz sudo[13791]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz sudo[13791]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:17 volumio-wz volumio[13670]: info: Output device has changed, restarting Shairport Sync Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:17 volumio-wz sudo[13794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 18:42:17 volumio-wz sudo[13794]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz systemd[1]: musicservicesshield.service: Succeeded. Nov 02 18:42:17 volumio-wz systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 02 18:42:17 volumio-wz systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 02 18:42:17 volumio-wz systemd[1]: Stopping Music Player Daemon... Nov 02 18:42:17 volumio-wz volumio[13670]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 18:42:17 volumio-wz volumio[13670]: info: ___________ START PLUGINS ___________ Nov 02 18:42:17 volumio-wz volumio[13670]: info: ControllerMpd::onStart: Initializing MPD Nov 02 18:42:17 volumio-wz volumio[13670]: info: Creating MPD Configuration file Nov 02 18:42:17 volumio-wz sudo[13800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 18:42:17 volumio-wz sudo[13800]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz sudo[13800]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:42:17 volumio-wz volumio[13670]: info: [1762105337147] CoreMusicLibrary::Adding element Medienserver Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:17 volumio-wz sudo[13803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 18:42:17 volumio-wz sudo[13803]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:42:17 volumio-wz volumio[13670]: info: [1762105337179] CoreMusicLibrary::Adding element Last_100 Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:42:17 volumio-wz volumio[13670]: info: [1762105337180] CoreMusicLibrary::Adding element Webradio Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 18:42:17 volumio-wz volumio[13670]: info: Initializing BBC Radios Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:42:17 volumio-wz volumio[13670]: info: [1762105337207] CoreMusicLibrary::Adding element Bandcamp Discover Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Bandcamp Discover Nov 02 18:42:17 volumio-wz volumio[13670]: info: Adding Calm Radio to Browse Sources Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:42:17 volumio-wz volumio[13670]: info: [1762105337208] CoreMusicLibrary::Adding element Calm Radio Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Bandcamp Discover Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Calm Radio Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:42:17 volumio-wz volumio[13670]: info: [1762105337210] CoreMusicLibrary::Adding element Mixcloud Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Bandcamp Discover Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Calm Radio Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Mixcloud Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:42:17 volumio-wz volumio[13670]: info: [1762105337212] CoreMusicLibrary::Adding element SoundCloud Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Bandcamp Discover Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Calm Radio Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Mixcloud Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source SoundCloud Nov 02 18:42:17 volumio-wz volumio[13670]: info: [now-playing] ConfigUpdater: config is up to date. Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:42:17 volumio-wz volumio[13670]: info: [1762105337217] CoreMusicLibrary::Adding element 80s80s Radio Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Bandcamp Discover Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Calm Radio Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Mixcloud Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source SoundCloud Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source 80s80s Radio Nov 02 18:42:17 volumio-wz volumio[13670]: info: Loading i18n strings for locale de Nov 02 18:42:17 volumio-wz sudo[13822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Nov 02 18:42:17 volumio-wz sudo[13822]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz sudo[13822]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:42:17 volumio-wz volumio[13670]: info: [1762105337237] CoreMusicLibrary::Adding element Mother Earth Radio Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Bandcamp Discover Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Calm Radio Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Mixcloud Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source SoundCloud Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source 80s80s Radio Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Mother Earth Radio Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:42:17 volumio-wz volumio[13670]: info: [1762105337239] CoreMusicLibrary::Adding element Radio Nowy Swiat Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Bandcamp Discover Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Calm Radio Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Mixcloud Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source SoundCloud Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source 80s80s Radio Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Mother Earth Radio Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Radio Nowy Swiat Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:42:17 volumio-wz volumio[13670]: info: [1762105337240] CoreMusicLibrary::Adding element Podcast Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Bandcamp Discover Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Calm Radio Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Mixcloud Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source SoundCloud Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source 80s80s Radio Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Mother Earth Radio Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Radio Nowy Swiat Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Podcast Nov 02 18:42:17 volumio-wz systemd[1]: mpd.service: Succeeded. Nov 02 18:42:17 volumio-wz systemd[1]: Stopped Music Player Daemon. Nov 02 18:42:17 volumio-wz systemd[1]: Starting Music Player Daemon... Nov 02 18:42:17 volumio-wz sudo[13853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Nov 02 18:42:17 volumio-wz sudo[13853]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 18:42:17 volumio-wz volumio[13670]: info: [1762105337321] CoreMusicLibrary::Adding element Randomizer Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Bandcamp Discover Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Calm Radio Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Mixcloud Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source SoundCloud Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source 80s80s Radio Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Mother Earth Radio Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Radio Nowy Swiat Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Podcast Nov 02 18:42:17 volumio-wz volumio[13670]: Cannot find translation for source Randomizer Nov 02 18:42:17 volumio-wz volumio[13670]: info: Volumio Calling Home Nov 02 18:42:17 volumio-wz sudo[13849]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 18:42:17 volumio-wz sudo[13849]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz sudo[13849]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:17 volumio-wz volumio[13670]: info: Preparing to generate the ALSA configuration file Nov 02 18:42:17 volumio-wz volumio[13670]: info: [now-playing] App is listening on port 4004. Nov 02 18:42:17 volumio-wz volumio[13670]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Nov 02 18:42:17 volumio-wz volumio[13670]: info: Discovery: adding 8195056c-cf5d-43ad-a43c-da28822f12c4 Nov 02 18:42:17 volumio-wz volumio[13670]: info: Discovery: Found device Volumio-WZ Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:17 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:17 volumio-wz volumio[13670]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 02 18:42:17 volumio-wz volumio[13670]: info: Reading ALSA contributions from plugins. Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:17 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:17 volumio-wz sudo[13853]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 18:42:17 volumio-wz volumio[13670]: info: Received Get System Info Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:42:17 volumio-wz volumio[13670]: info: Discovery: Getting this device information Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:17 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:17 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:17 volumio-wz volumio[13670]: info: Listing playlists Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetQueue Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreStateMachine::getQueue Nov 02 18:42:17 volumio-wz volumio[13670]: info: CorePlayQueue::getQueue Nov 02 18:42:17 volumio-wz volumio[13670]: info: minidlna: Found miniDLNA version 1.2.1 Nov 02 18:42:17 volumio-wz volumio[13670]: info: minidlna: Starting minidlna.service Nov 02 18:42:17 volumio-wz volumio[13670]: info: MPD Permissions set Nov 02 18:42:17 volumio-wz volumio[13670]: info: MPD Permissions set Nov 02 18:42:17 volumio-wz volumio[13670]: info: Discovery: this is already registered, 8195056c-cf5d-43ad-a43c-da28822f12c4 Nov 02 18:42:17 volumio-wz volumio[13670]: info: Discovery: Found device Volumio-WZ Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:17 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:17 volumio-wz sudo[13874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Nov 02 18:42:17 volumio-wz sudo[13874]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:42:17 volumio-wz volumio[13670]: info: Discovery: Getting this device information Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:17 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:42:17 volumio-wz sudo[13880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Nov 02 18:42:17 volumio-wz sudo[13880]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Nov 02 18:42:17 volumio-wz volumio[13670]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Nov 02 18:42:17 volumio-wz sudo[13874]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:17 volumio-wz volumio[13670]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 02 18:42:17 volumio-wz volumio[13670]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz systemd[1]: minidlna.service: Succeeded. Nov 02 18:42:17 volumio-wz mpd[13866]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 18:42:17 volumio-wz mpd[13866]: output: No 'audio_output' defined in config file Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: minidlna: systemctl start minidlna.service succeeded. Nov 02 18:42:17 volumio-wz mpd[13866]: output: Successfully detected a sndio audio device Nov 02 18:42:17 volumio-wz mpd[13866]: zeroconf: No global port, disabling zeroconf Nov 02 18:42:17 volumio-wz systemd[1]: Started Music Player Daemon. Nov 02 18:42:17 volumio-wz sudo[13794]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:17 volumio-wz sudo[13803]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:17 volumio-wz volumio[13670]: verbose: New Socket.io Connection to 192.168.22.13:3000 from 192.168.22.92 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Nov 02 18:42:17 volumio-wz volumio[13670]: info: Volumio called home Nov 02 18:42:17 volumio-wz volumio[13670]: info: Completed starting Core Plugins Nov 02 18:42:17 volumio-wz volumio[13670]: info: ------------------------------------------- Nov 02 18:42:17 volumio-wz volumio[13670]: info: ----- MyVolumio plugins startup ---- Nov 02 18:42:17 volumio-wz volumio[13670]: info: ------------------------------------------- Nov 02 18:42:17 volumio-wz volumio[13670]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 02 18:42:17 volumio-wz volumio[13670]: info: Starting Shairport Sync Nov 02 18:42:17 volumio-wz systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 02 18:42:17 volumio-wz volumio[13670]: info: Starting Shairport Sync Nov 02 18:42:17 volumio-wz sudo[13908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 18:42:17 volumio-wz sudo[13908]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 18:42:17 volumio-wz systemd[1]: shairport-sync.service: Succeeded. Nov 02 18:42:17 volumio-wz volumio[13670]: info: Starting Shairport Sync Nov 02 18:42:17 volumio-wz systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 18:42:17 volumio-wz systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 18:42:17 volumio-wz sudo[13908]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:17 volumio-wz sudo[13915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 18:42:17 volumio-wz sudo[13915]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 18:42:17 volumio-wz systemd[1]: shairport-sync.service: Succeeded. Nov 02 18:42:17 volumio-wz systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 18:42:17 volumio-wz systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 18:42:17 volumio-wz sudo[13920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 18:42:17 volumio-wz sudo[13920]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz sudo[13915]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:17 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 02 18:42:17 volumio-wz systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:17 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:17 volumio-wz systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 02 18:42:17 volumio-wz systemd[1]: shairport-sync.service: Succeeded. Nov 02 18:42:17 volumio-wz systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 18:42:17 volumio-wz systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 18:42:17 volumio-wz sudo[13920]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:17 volumio-wz volumio[13670]: error: MPD error: The expression evaluated to a falsy value: Nov 02 18:42:17 volumio-wz volumio[13670]: assert.ok(self.idling) Nov 02 18:42:17 volumio-wz volumio[13670]: error: The expression evaluated to a falsy value: Nov 02 18:42:17 volumio-wz volumio[13670]: assert.ok(self.idling) Nov 02 18:42:17 volumio-wz volumio[13670]: info: Shairport-Sync Started Nov 02 18:42:17 volumio-wz volumio[13670]: Error adding Membership: Error: addMembership EINVAL Nov 02 18:42:17 volumio-wz volumio[13670]: info: Shairport-Sync Started Nov 02 18:42:17 volumio-wz volumio[13670]: info: MPD running with PID13866 Nov 02 18:42:17 volumio-wz volumio[13670]: ,establishing connection Nov 02 18:42:17 volumio-wz volumio[13670]: info: Asound.conf file unchanged, so no further update is needed Nov 02 18:42:17 volumio-wz volumio[13670]: info: Output device has changed, restarting MPD Nov 02 18:42:17 volumio-wz sudo[13933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 18:42:17 volumio-wz sudo[13933]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz sudo[13933]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:17 volumio-wz volumio[13670]: info: Output device has changed, restarting Shairport Sync Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:17 volumio-wz sudo[13936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 18:42:17 volumio-wz sudo[13936]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz systemd[1]: Stopping Music Player Daemon... Nov 02 18:42:17 volumio-wz volumio[13899]: cset: --> shielding system active with Nov 02 18:42:17 volumio-wz volumio[13899]: cset: "system" cpuset of CPUSPEC(1-3) with 261 tasks running Nov 02 18:42:17 volumio-wz volumio[13899]: cset: "user" cpuset of CPUSPEC(0) with 1 task running Nov 02 18:42:17 volumio-wz systemd[1]: mpd.service: Succeeded. Nov 02 18:42:17 volumio-wz systemd[1]: Stopped Music Player Daemon. Nov 02 18:42:17 volumio-wz systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 02 18:42:17 volumio-wz volumio[13670]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 18:42:17 volumio-wz volumio[13670]: error: updateQueue error: null Nov 02 18:42:17 volumio-wz volumio[13670]: info: Shairport-Sync Started Nov 02 18:42:17 volumio-wz volumio[13670]: error: MPD error: Error: write EPIPE Nov 02 18:42:17 volumio-wz volumio[13670]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Nov 02 18:42:17 volumio-wz volumio[13670]: info: MPD Permissions set Nov 02 18:42:17 volumio-wz systemd[1]: Starting Music Player Daemon... Nov 02 18:42:17 volumio-wz systemd[1]: Reloading. Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 02 18:42:17 volumio-wz sudo[13942]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 18:42:17 volumio-wz sudo[13942]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz sudo[13942]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 18:42:17 volumio-wz volumio[13670]: info: Starting Shairport Sync Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:17 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioPause Nov 02 18:42:17 volumio-wz volumio[13670]: info: CoreStateMachine::pause Nov 02 18:42:17 volumio-wz volumio[13670]: info: mpdhttpout --- Volumio set on pause Nov 02 18:42:17 volumio-wz volumio[13670]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Nov 02 18:42:17 volumio-wz sudo[13954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 18:42:17 volumio-wz sudo[13954]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:17 volumio-wz 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. Nov 02 18:42:17 volumio-wz sudo[13880]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:17 volumio-wz volumio[13670]: info: succeeded Nov 02 18:42:17 volumio-wz systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 18:42:17 volumio-wz systemd[1]: shairport-sync.service: Succeeded. Nov 02 18:42:17 volumio-wz systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 18:42:17 volumio-wz systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 18:42:17 volumio-wz volumio[13670]: info: Shairport-Sync Started Nov 02 18:42:17 volumio-wz sudo[13954]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:17 volumio-wz mpd[13969]: Nov 02 18:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 18:42:18 volumio-wz volumio[13670]: verbose: New Socket.io Connection to 192.168.22.13 from 192.168.22.92 UA: Mozilla/5.0 (Linux; Android 15; 25010PN30G Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 02 18:42:18 volumio-wz systemd[1]: Started Music Player Daemon. Nov 02 18:42:18 volumio-wz sudo[13936]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:18 volumio-wz volumio[13670]: error: updateQueue error: null Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:18 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 18:42:18 volumio-wz volumio[13670]: info: Received Get System Info Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:42:18 volumio-wz volumio[13670]: info: Discovery: Getting this device information Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:18 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:18 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:18 volumio-wz volumio[13670]: info: Listing playlists Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetQueue Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreStateMachine::getQueue Nov 02 18:42:18 volumio-wz volumio[13670]: info: CorePlayQueue::getQueue Nov 02 18:42:18 volumio-wz volumio[13670]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:18 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:18 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 18:42:18 volumio-wz volumio[13670]: info: Received Get System Info Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:42:18 volumio-wz volumio[13670]: info: Discovery: Getting this device information Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:18 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:42:18 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:18 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:18 volumio-wz volumio[13670]: info: Listing playlists Nov 02 18:42:19 volumio-wz volumio[13670]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Nov 02 18:42:19 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Nov 02 18:42:19 volumio-wz sudo[13977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 18:42:19 volumio-wz sudo[13977]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:19 volumio-wz systemd[1]: musicservicesshield.service: Succeeded. Nov 02 18:42:19 volumio-wz systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 02 18:42:19 volumio-wz systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 02 18:42:19 volumio-wz systemd[1]: Stopping Music Player Daemon... Nov 02 18:42:19 volumio-wz systemd[1]: mpd.service: Succeeded. Nov 02 18:42:19 volumio-wz systemd[1]: Stopped Music Player Daemon. Nov 02 18:42:20 volumio-wz systemd[1]: Starting Music Player Daemon... Nov 02 18:42:20 volumio-wz sudo[13979]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 18:42:20 volumio-wz sudo[13979]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:20 volumio-wz sudo[13979]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:20 volumio-wz volumio-remote-updater[13536]: [2025-11-02 18:42:20] [connect] Successful connection Nov 02 18:42:20 volumio-wz volumio-remote-updater[13536]: [2025-11-02 18:42:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1762105340 101 Nov 02 18:42:20 volumio-wz volumio[13670]: 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: 11 Nov 02 18:42:20 volumio-wz mpd[13981]: Nov 02 18:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 18:42:20 volumio-wz volumio[13670]: info: Cannot mount NAS Music_on_MediaPC at system boot, trial number 2 ,retrying in 5 seconds Nov 02 18:42:20 volumio-wz volumio[13670]: info: Cannot mount NAS music at system boot, trial number 2 ,retrying in 5 seconds Nov 02 18:42:20 volumio-wz systemd[1]: Started Music Player Daemon. Nov 02 18:42:20 volumio-wz sudo[13977]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:20 volumio-wz volumio[13670]: error: updateQueue error: null Nov 02 18:42:20 volumio-wz systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 02 18:42:21 volumio-wz volumio[13988]: cset: --> shielding system active with Nov 02 18:42:21 volumio-wz volumio[13988]: cset: "system" cpuset of CPUSPEC(1-3) with 255 tasks running Nov 02 18:42:21 volumio-wz volumio[13988]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 02 18:42:21 volumio-wz systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 02 18:42:22 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:22 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:23 volumio-wz volumio[13670]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 02 18:42:26 volumio-wz volumio[13670]: info: Cannot mount NAS Music_on_MediaPC at system boot, trial number 3 ,retrying in 5 seconds Nov 02 18:42:26 volumio-wz volumio[13670]: info: Cannot mount NAS music at system boot, trial number 3 ,retrying in 5 seconds Nov 02 18:42:26 volumio-wz sudo[14076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 18:42:26 volumio-wz sudo[14076]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:26 volumio-wz sudo[14076]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:26 volumio-wz sudo[14079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 18:42:26 volumio-wz sudo[14079]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:26 volumio-wz sudo[14079]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:26 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 18:42:26 volumio-wz volumio[13670]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 02 18:42:26 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 02 18:42:26 volumio-wz volumio[13670]: info: Received Get System Version Nov 02 18:42:26 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 18:42:26 volumio-wz volumio[13670]: info: Received Get System Info Nov 02 18:42:26 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:42:26 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:42:26 volumio-wz volumio[13670]: info: Discovery: Getting this device information Nov 02 18:42:26 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:26 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:26 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:42:27 volumio-wz sudo[14085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 02 18:42:27 volumio-wz sudo[14085]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 18:42:27 volumio-wz sudo[14085]: pam_unix(sudo:session): session closed for user root Nov 02 18:42:28 volumio-wz volumio[13670]: info: Upmpdcli Daemon Started Nov 02 18:42:31 volumio-wz volumio[13670]: info: Cannot mount NAS Music_on_MediaPC at system boot, trial number 4 ,retrying in 5 seconds Nov 02 18:42:31 volumio-wz volumio[13670]: info: Cannot mount NAS music at system boot, trial number 4 ,retrying in 5 seconds Nov 02 18:42:31 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 18:42:31 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 18:42:31 volumio-wz volumio[13670]: info: Discovery: Getting this device information Nov 02 18:42:31 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:31 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:31 volumio-wz volumio[13670]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 18:42:34 volumio-wz volumio[13670]: info: CoreCommandRouter::volumioGetState Nov 02 18:42:34 volumio-wz volumio[13670]: info: CorePlayQueue::getTrack 0 Nov 02 18:42:35 volumio-wz volumio[13670]: info: Listing playlists Nov 02 18:42:35 volumio-wz volumio[13670]: info: Listing playlists Nov 02 18:42:36 volumio-wz volumio[13670]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 02 18:42:36 volumio-wz volumio[13670]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 02 18:42:37 volumio-wz volumio[13670]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 18:42:37 volumio-wz volumio[13670]: Error: connect EHOSTUNREACH 158.69.210.98:443 Nov 02 18:42:37 volumio-wz volumio[13670]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Nov 02 18:42:37 volumio-wz volumio[13670]: errno: -113, Nov 02 18:42:37 volumio-wz volumio[13670]: code: 'EHOSTUNREACH', Nov 02 18:42:37 volumio-wz volumio[13670]: syscall: 'connect', Nov 02 18:42:37 volumio-wz volumio[13670]: address: '158.69.210.98', Nov 02 18:42:37 volumio-wz volumio[13670]: port: 443 Nov 02 18:42:37 volumio-wz volumio[13670]: } Nov 02 18:42:37 volumio-wz volumio[13670]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 18:42:37 volumio-wz sudo[14124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-02 18:41 Nov 02 18:42:37 volumio-wz sudo[14124]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"