-- Logs begin at Thu 2025-05-15 13:45:55 CEST, end at Thu 2025-05-15 23:54:19 CEST. -- May 15 23:53:00 volumio-bedroom volumio[11839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:00 volumio-bedroom volumio[11839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:00 volumio-bedroom volumio[11839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:00 volumio-bedroom volumio[11839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:00 volumio-bedroom volumio[11839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:00 volumio-bedroom volumio[11839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:00 volumio-bedroom volumio[11839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:00 volumio-bedroom volumio[11839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:00 volumio-bedroom volumio[11839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:00 volumio-bedroom volumio[11839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:00 volumio-bedroom volumio[11839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:00 volumio-bedroom volumio[11839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:00 volumio-bedroom volumio[11839]: info: VolumeController:: Volume=100 Mute =false May 15 23:53:00 volumio-bedroom volumio[11839]: info: CoreStateMachine::pushState May 15 23:53:00 volumio-bedroom volumio[11839]: info: CorePlayQueue::getTrack 0 May 15 23:53:00 volumio-bedroom volumio[11839]: info: CoreCommandRouter::volumioPushState May 15 23:53:00 volumio-bedroom volumio[11839]: info: [squeezelite_mc] Server discovery started May 15 23:53:00 volumio-bedroom volumio[11839]: info: [squeezelite_mc] Player finder started May 15 23:53:00 volumio-bedroom dhcpcd[1023]: wlan0: rebinding lease of 192.168.2.26 May 15 23:53:00 volumio-bedroom volumio[11839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:00 volumio-bedroom volumio[11839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:53:00 volumio-bedroom volumio[11839]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true May 15 23:53:00 volumio-bedroom volumio[11839]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 23:53:00 volumio-bedroom volumio[11839]: Error: send ENETUNREACH 255.255.255.255:3483 May 15 23:53:00 volumio-bedroom volumio[11839]: at doSend (dgram.js:692:16) May 15 23:53:00 volumio-bedroom volumio[11839]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 15 23:53:00 volumio-bedroom volumio[11839]: at afterDns (dgram.js:638:5) May 15 23:53:00 volumio-bedroom volumio[11839]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 15 23:53:00 volumio-bedroom volumio[11839]: errno: -101, May 15 23:53:00 volumio-bedroom volumio[11839]: code: 'ENETUNREACH', May 15 23:53:00 volumio-bedroom volumio[11839]: syscall: 'send', May 15 23:53:00 volumio-bedroom volumio[11839]: address: '255.255.255.255', May 15 23:53:00 volumio-bedroom volumio[11839]: port: 3483 May 15 23:53:00 volumio-bedroom volumio[11839]: } May 15 23:53:00 volumio-bedroom volumio[11839]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 23:53:00 volumio-bedroom 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. May 15 23:53:00 volumio-bedroom dhcpcd[1023]: wlan0: probing address 192.168.2.26/24 May 15 23:53:00 volumio-bedroom mpd[11997]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 23:53:00 volumio-bedroom mpd[11997]: output: No 'audio_output' defined in config file May 15 23:53:00 volumio-bedroom mpd[11997]: output: Successfully detected a sndio audio device May 15 23:53:00 volumio-bedroom mpd[11997]: zeroconf: No global port, disabling zeroconf May 15 23:53:00 volumio-bedroom systemd[1]: Started Music Player Daemon. May 15 23:53:00 volumio-bedroom sudo[11954]: pam_unix(sudo:session): session closed for user root May 15 23:53:00 volumio-bedroom sudo[11939]: pam_unix(sudo:session): session closed for user root May 15 23:53:00 volumio-bedroom sudo[12023]: pam_unix(sudo:session): session closed for user root May 15 23:53:00 volumio-bedroom sudo[12061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-15 23:52 May 15 23:53:00 volumio-bedroom sudo[12061]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:00 volumio-bedroom sudo[12061]: pam_unix(sudo:session): session closed for user root May 15 23:53:00 volumio-bedroom volumio-remote-updater[677]: [2025-05-15 23:53:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 15 23:53:00 volumio-bedroom volumio-remote-updater[677]: [2025-05-15 23:53:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 15 23:53:00 volumio-bedroom systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 15 23:53:00 volumio-bedroom systemd[1]: volumio.service: Failed with result 'exit-code'. May 15 23:53:00 volumio-bedroom systemd[1]: Started dynamicswap service. May 15 23:53:00 volumio-bedroom systemd[1]: dynamicswap.service: Succeeded. May 15 23:53:00 volumio-bedroom systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 15 23:53:00 volumio-bedroom systemd[1]: volumio.service: Scheduled restart job, restart counter is at 32. May 15 23:53:00 volumio-bedroom systemd[1]: Started dynamicswap service. May 15 23:53:00 volumio-bedroom systemd[1]: Stopped Volumio Backend Module. May 15 23:53:00 volumio-bedroom systemd[1]: Started Volumio Backend Module. May 15 23:53:00 volumio-bedroom systemd[1]: dynamicswap.service: Succeeded. May 15 23:53:01 volumio-bedroom volumio[12075]: info: ------------------------------------------- May 15 23:53:01 volumio-bedroom volumio[12075]: info: ----- Volumio3 ---- May 15 23:53:01 volumio-bedroom volumio[12075]: info: ------------------------------------------- May 15 23:53:01 volumio-bedroom volumio[12075]: info: ----- System startup ---- May 15 23:53:01 volumio-bedroom volumio[12075]: info: ------------------------------------------- May 15 23:53:02 volumio-bedroom volumio[12075]: info: MYVOLUMIO Environment detected May 15 23:53:02 volumio-bedroom volumio[12075]: info: Plugin folders cleanup May 15 23:53:02 volumio-bedroom volumio[12075]: info: Scanning into folder /volumio/app/plugins/ May 15 23:53:02 volumio-bedroom volumio[12075]: info: Scanning category audio_interface May 15 23:53:02 volumio-bedroom volumio[12075]: info: Scanning category miscellanea May 15 23:53:02 volumio-bedroom volumio[12075]: info: Scanning category music_service May 15 23:53:02 volumio-bedroom volumio[12075]: info: Scanning category plugins.json May 15 23:53:02 volumio-bedroom volumio[12075]: info: Scanning category system_controller May 15 23:53:02 volumio-bedroom volumio[12075]: info: Scanning category user_interface May 15 23:53:02 volumio-bedroom volumio[12075]: info: Scanning into folder /data/plugins/ May 15 23:53:02 volumio-bedroom volumio[12075]: info: Scanning category music_service May 15 23:53:02 volumio-bedroom volumio[12075]: info: Scanning category system_hardware May 15 23:53:02 volumio-bedroom volumio[12075]: info: Scanning category user_interface May 15 23:53:02 volumio-bedroom volumio[12075]: info: Plugin folders cleanup completed May 15 23:53:02 volumio-bedroom volumio[12075]: info: ------------------------------------------- May 15 23:53:02 volumio-bedroom volumio[12075]: info: ----- Core plugins startup ---- May 15 23:53:02 volumio-bedroom volumio[12075]: info: ------------------------------------------- May 15 23:53:02 volumio-bedroom volumio[12075]: info: Loading plugins from folder /volumio/app/plugins/ May 15 23:53:02 volumio-bedroom volumio[12075]: info: Adding plugin upnp to MyMusic Plugins May 15 23:53:02 volumio-bedroom volumio[12075]: info: Adding plugin airplay_emulation to MyMusic Plugins May 15 23:53:02 volumio-bedroom volumio[12075]: info: Adding plugin upnp_browser to MyMusic Plugins May 15 23:53:02 volumio-bedroom volumio[12075]: info: Loading plugins from folder /data/plugins/ May 15 23:53:02 volumio-bedroom volumio[12075]: info: Loading plugin "system"... May 15 23:53:02 volumio-bedroom volumio[12075]: info: Loading plugin "appearance"... May 15 23:53:02 volumio-bedroom volumio[12075]: info: Loading plugin "network"... May 15 23:53:02 volumio-bedroom volumio[12075]: info: Refreshing Cached IP Addresses May 15 23:53:02 volumio-bedroom sudo[12102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 23:53:02 volumio-bedroom sudo[12102]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:02 volumio-bedroom sudo[12102]: pam_unix(sudo:session): session closed for user root May 15 23:53:02 volumio-bedroom sudo[12104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 23:53:02 volumio-bedroom sudo[12104]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:02 volumio-bedroom sudo[12104]: pam_unix(sudo:session): session closed for user root May 15 23:53:02 volumio-bedroom volumio[12075]: info: Loading plugin "services"... May 15 23:53:02 volumio-bedroom volumio[12075]: info: Loading plugin "alsa_controller"... May 15 23:53:02 volumio-bedroom sudo[12113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 15 23:53:02 volumio-bedroom sudo[12113]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:03 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 23:53:03 volumio-bedroom volumio[12075]: info: Loading plugin "wizard"... May 15 23:53:03 volumio-bedroom volumio[12075]: info: Loading plugin "networkfs"... May 15 23:53:03 volumio-bedroom volumio[12075]: info: Cannot mount NAS DSD at system boot, trial number 1 ,retrying in 5 seconds May 15 23:53:03 volumio-bedroom volumio[12075]: info: Starting Udev Watcher for removable devices May 15 23:53:03 volumio-bedroom volumio[12075]: info: Ignoring mount for partition: boot May 15 23:53:03 volumio-bedroom volumio[12075]: info: Ignoring mount for partition: volumio May 15 23:53:03 volumio-bedroom volumio[12075]: info: Ignoring mount for partition: volumio_data May 15 23:53:03 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 15 23:53:03 volumio-bedroom volumio[12075]: info: Loading plugin "volumio_command_line_client"... May 15 23:53:03 volumio-bedroom volumio[12075]: info: Loading plugin "upnp"... May 15 23:53:03 volumio-bedroom volumio[12075]: info: [1747345983081] Starting Upmpd Daemon May 15 23:53:03 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 15 23:53:03 volumio-bedroom volumio[12075]: info: Loading plugin "my_music"... May 15 23:53:03 volumio-bedroom volumio[12075]: info: Loading plugin "mpd"... May 15 23:53:03 volumio-bedroom volumio[12075]: info: Loading plugin "upnp_browser"... May 15 23:53:03 volumio-bedroom volumio[12075]: info: Loading plugin "alarm-clock"... May 15 23:53:03 volumio-bedroom volumio[12075]: info: Loading plugin "airplay_emulation"... May 15 23:53:03 volumio-bedroom volumio[12075]: info: Starting Shairport Sync May 15 23:53:03 volumio-bedroom volumio[12075]: info: Loading plugin "last_100"... May 15 23:53:03 volumio-bedroom volumio[12075]: info: Loading plugin "webradio"... May 15 23:53:03 volumio-bedroom volumio[12075]: info: Loading plugin "i2s_dacs"... May 15 23:53:03 volumio-bedroom volumio[12075]: info: Loading plugin "volumiodiscovery"... May 15 23:53:03 volumio-bedroom volumio[12075]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 15 23:53:03 volumio-bedroom volumio[12075]: *** WARNING *** Please fix your application to use the native API of Avahi! May 15 23:53:03 volumio-bedroom node[12075]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 15 23:53:03 volumio-bedroom volumio[12075]: *** WARNING *** For more information see May 15 23:53:03 volumio-bedroom volumio[12075]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 15 23:53:03 volumio-bedroom volumio[12075]: *** WARNING *** Please fix your application to use the native API of Avahi! May 15 23:53:03 volumio-bedroom volumio[12075]: *** WARNING *** For more information see May 15 23:53:03 volumio-bedroom node[12075]: *** WARNING *** Please fix your application to use the native API of Avahi! May 15 23:53:03 volumio-bedroom node[12075]: *** WARNING *** For more information see May 15 23:53:03 volumio-bedroom node[12075]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 15 23:53:03 volumio-bedroom node[12075]: *** WARNING *** Please fix your application to use the native API of Avahi! May 15 23:53:03 volumio-bedroom node[12075]: *** WARNING *** For more information see May 15 23:53:03 volumio-bedroom volumio[12075]: info: Applying required configuration parameters for plugin volumiodiscovery May 15 23:53:03 volumio-bedroom volumio[12075]: info: Discovery: Started advertising with name: Volumio Bedroom May 15 23:53:03 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 15 23:53:03 volumio-bedroom volumio[12075]: info: Plugin spop is not enabled May 15 23:53:03 volumio-bedroom volumio[12075]: info: Loading plugin "squeezelite_mc"... May 15 23:53:04 volumio-bedroom volumio[12075]: info: Plugin now_playing is not enabled May 15 23:53:04 volumio-bedroom volumio[12075]: info: Loading plugin "outputs"... May 15 23:53:04 volumio-bedroom volumio[12075]: info: Loading plugin "albumart"... May 15 23:53:04 volumio-bedroom volumio[12075]: info: Plugin example_plugin is not enabled May 15 23:53:04 volumio-bedroom volumio[12075]: info: Loading plugin "inputs"... May 15 23:53:04 volumio-bedroom volumio[12075]: info: Loading plugin "updater_comm"... May 15 23:53:04 volumio-bedroom volumio[12075]: info: Plugin mpdemulation is not enabled May 15 23:53:04 volumio-bedroom volumio[12075]: info: Loading plugin "rest_api"... May 15 23:53:04 volumio-bedroom volumio[12075]: info: Loading plugin "websocket"... May 15 23:53:04 volumio-bedroom volumio[12075]: info: Starting Socket.io Server version 2.3.0 May 15 23:53:04 volumio-bedroom volumio[12075]: info: Loading plugin "audiophonics_evo_sabre"... May 15 23:53:04 volumio-bedroom volumio[12075]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre May 15 23:53:04 volumio-bedroom volumio[12075]: Forking 3 albumart workers May 15 23:53:04 volumio-bedroom volumio[12075]: info: Plugin mpd_oled is not enabled May 15 23:53:04 volumio-bedroom volumio[12075]: info: Loading i18n strings for locale en May 15 23:53:04 volumio-bedroom volumio[12075]: Updating browse sources language May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::initPlayerControls May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:53:04 volumio-bedroom volumio[12075]: Express server listening on port 3000 May 15 23:53:04 volumio-bedroom volumio[12075]: [Metrics] WebUI: 2s 983.27ms May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreStateMachine::resetVolumioState May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreStateMachine::getcurrentVolume May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioRetrievevolume May 15 23:53:04 volumio-bedroom volumio[12075]: info: Volumio Network Manager: Network status updated: 1 May 15 23:53:04 volumio-bedroom volumio[12075]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:04 volumio-bedroom volumio[12075]: info: Reloading queue from file May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreStateMachine::setRepeat null single undefined May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreStateMachine::pushState May 15 23:53:04 volumio-bedroom volumio[12075]: info: CorePlayQueue::getTrack 0 May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioPushState May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreStateMachine::setRandom null May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreStateMachine::pushState May 15 23:53:04 volumio-bedroom volumio[12075]: info: CorePlayQueue::getTrack 0 May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioPushState May 15 23:53:04 volumio-bedroom volumio[12075]: info: VolumeController:: Volume=100 Mute =false May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreStateMachine::pushState May 15 23:53:04 volumio-bedroom volumio[12075]: info: CorePlayQueue::getTrack 0 May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioPushState May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreStateMachine::updateTrackBlock May 15 23:53:04 volumio-bedroom volumio[12075]: info: CorePlayQueue::getTrackBlock May 15 23:53:04 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioRetrievevolume May 15 23:53:04 volumio-bedroom volumio[12075]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 15 23:53:04 volumio-bedroom volumio[12075]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 May 15 23:53:04 volumio-bedroom volumio[12075]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 15 23:53:04 volumio-bedroom volumio[12075]: info: Setting Device type: Raspberry PI May 15 23:53:04 volumio-bedroom volumio[12075]: info: Completed loading Core Plugins May 15 23:53:04 volumio-bedroom volumio[12075]: info: Preparing to generate the ALSA configuration file May 15 23:53:04 volumio-bedroom volumio[12075]: Starting albumart workers May 15 23:53:05 volumio-bedroom volumio[12075]: info: Discovery: adding 438c7928-318e-4db1-84d6-a5fcd9009883 May 15 23:53:05 volumio-bedroom volumio[12075]: info: Discovery: Found device VolumioWoonkamer May 15 23:53:05 volumio-bedroom volumio[12075]: info: Discovery: Connecting to remote: 192.168.211.1 May 15 23:53:05 volumio-bedroom volumio[12075]: info: Asound.conf file unchanged, so no further update is needed May 15 23:53:05 volumio-bedroom volumio[12075]: info: Output device has changed, restarting MPD May 15 23:53:05 volumio-bedroom sudo[12176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 23:53:05 volumio-bedroom sudo[12176]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:05 volumio-bedroom sudo[12176]: pam_unix(sudo:session): session closed for user root May 15 23:53:05 volumio-bedroom volumio[12075]: info: Output device has changed, restarting Shairport Sync May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:05 volumio-bedroom sudo[12179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 23:53:05 volumio-bedroom sudo[12179]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:05 volumio-bedroom systemd[1]: Stopping Music Player Daemon... May 15 23:53:05 volumio-bedroom volumio[12075]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 23:53:05 volumio-bedroom volumio[12075]: info: ___________ START PLUGINS ___________ May 15 23:53:05 volumio-bedroom systemd[1]: mpd.service: Succeeded. May 15 23:53:05 volumio-bedroom systemd[1]: Stopped Music Player Daemon. May 15 23:53:05 volumio-bedroom volumio[12075]: info: ControllerMpd::onStart: Initializing MPD May 15 23:53:05 volumio-bedroom volumio[12075]: info: Creating MPD Configuration file May 15 23:53:05 volumio-bedroom volumio[12075]: Starting albumart workers May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 23:53:05 volumio-bedroom volumio[12075]: info: [1747345985287] CoreMusicLibrary::Adding element Media Servers May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 23:53:05 volumio-bedroom sudo[12188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 23:53:05 volumio-bedroom dhcpcd[1023]: wlan0: leased 192.168.2.26 for 86400 seconds May 15 23:53:05 volumio-bedroom systemd[1]: Starting Music Player Daemon... May 15 23:53:05 volumio-bedroom sudo[12188]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:05 volumio-bedroom avahi-daemon[672]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.26. May 15 23:53:05 volumio-bedroom sudo[12186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 23:53:05 volumio-bedroom sudo[12186]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:05 volumio-bedroom volumio[12075]: Starting albumart workers May 15 23:53:05 volumio-bedroom dhcpcd[1023]: wlan0: adding route to 192.168.2.0/24 May 15 23:53:05 volumio-bedroom avahi-daemon[672]: New relevant interface wlan0.IPv4 for mDNS. May 15 23:53:05 volumio-bedroom dhcpcd[1023]: wlan0: adding default route via 192.168.2.1 May 15 23:53:05 volumio-bedroom avahi-daemon[672]: Registering new address record for 192.168.2.26 on wlan0.IPv4. May 15 23:53:05 volumio-bedroom sudo[12186]: pam_unix(sudo:session): session closed for user root May 15 23:53:05 volumio-bedroom systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 15 23:53:05 volumio-bedroom systemd[1]: mpd.service: Succeeded. May 15 23:53:05 volumio-bedroom systemd[1]: Stopped Music Player Daemon. May 15 23:53:05 volumio-bedroom systemd[1]: Starting Music Player Daemon... May 15 23:53:05 volumio-bedroom volumio[12075]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:05 volumio-bedroom sudo[12208]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 15 23:53:05 volumio-bedroom sudo[12208]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:05 volumio-bedroom volumio[12075]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 23:53:05 volumio-bedroom volumio[12075]: info: [1747345985471] CoreMusicLibrary::Adding element Last_100 May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 23:53:05 volumio-bedroom sudo[12208]: pam_unix(sudo:session): session closed for user root May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 23:53:05 volumio-bedroom volumio[12075]: info: [1747345985478] CoreMusicLibrary::Adding element Webradio May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 23:53:05 volumio-bedroom volumio[12075]: info: Initializing BBC Radios May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:05 volumio-bedroom volumio[12075]: info: [squeezelite_mc] Starting proxy server... May 15 23:53:05 volumio-bedroom volumio[12075]: info: EVO SABRE : Starting Plugin May 15 23:53:05 volumio-bedroom volumio[12075]: info: Loading i18n strings for locale en May 15 23:53:05 volumio-bedroom volumio[12075]: info: Volumio Calling Home May 15 23:53:05 volumio-bedroom volumio-remote-updater[677]: [2025-05-15 23:53:05] [connect] Successful connection May 15 23:53:05 volumio-bedroom volumio[12075]: info: [squeezelite_mc] Proxy server started on port 43705 May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioRetrievevolume May 15 23:53:05 volumio-bedroom volumio[12075]: info: Discovery: adding 1ae4494d-87e1-45e1-802e-ef1104d1910e May 15 23:53:05 volumio-bedroom volumio[12075]: info: Discovery: Found device Volumio Bedroom May 15 23:53:05 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:05 volumio-bedroom volumio[12075]: info: CorePlayQueue::getTrack 0 May 15 23:53:06 volumio-bedroom volumio[12075]: info: MPD Permissions set May 15 23:53:06 volumio-bedroom volumio[12075]: info: MPD Permissions set May 15 23:53:06 volumio-bedroom volumio[12075]: info: VolumeController:: Volume=100 Mute =false May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreStateMachine::pushState May 15 23:53:06 volumio-bedroom volumio[12075]: info: CorePlayQueue::getTrack 0 May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioPushState May 15 23:53:06 volumio-bedroom volumio[12075]: verbose: New Socket.io Connection to 192.168.2.25:3000 from 192.168.2.194 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 15 23:53:06 volumio-bedroom volumio-remote-updater[677]: [2025-05-15 23:53:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747345985 101 May 15 23:53:06 volumio-bedroom volumio[12075]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 15 23:53:06 volumio-bedroom volumio[12075]: verbose: New Socket.io Connection to 192.168.2.25:3000 from 192.168.2.194 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 15 23:53:06 volumio-bedroom sudo[12264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 15 23:53:06 volumio-bedroom sudo[12264]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:06 volumio-bedroom volumio[12075]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 15 23:53:06 volumio-bedroom volumio[12075]: info: VolumeController:: Volume=100 Mute =false May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreStateMachine::pushState May 15 23:53:06 volumio-bedroom volumio[12075]: info: CorePlayQueue::getTrack 0 May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioPushState May 15 23:53:06 volumio-bedroom systemd[1]: Reloading. May 15 23:53:06 volumio-bedroom volumio[12075]: info: [squeezelite_mc] Server discovery started May 15 23:53:06 volumio-bedroom volumio[12075]: info: [squeezelite_mc] Player finder started May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:53:06 volumio-bedroom volumio[12075]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:06 volumio-bedroom volumio[12075]: info: Volumio called home May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 15 23:53:06 volumio-bedroom volumio[12075]: verbose: New Socket.io Connection to 192.168.2.25:3000 from 192.168.2.194 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 15 23:53:06 volumio-bedroom volumio[12075]: error: [squeezelite_mc] Could not query supported ALSA formats for card 1 because device is busy May 15 23:53:06 volumio-bedroom volumio[12075]: info: Starting Shairport Sync May 15 23:53:06 volumio-bedroom volumio[12075]: info: Starting Shairport Sync May 15 23:53:06 volumio-bedroom volumio[12075]: info: Starting Shairport Sync May 15 23:53:06 volumio-bedroom sudo[12293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 23:53:06 volumio-bedroom sudo[12293]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:06 volumio-bedroom volumio[12075]: info: CorePlayQueue::getTrack 0 May 15 23:53:06 volumio-bedroom sudo[12297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 23:53:06 volumio-bedroom sudo[12297]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:06 volumio-bedroom sudo[12295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 23:53:06 volumio-bedroom 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. May 15 23:53:06 volumio-bedroom volumio[12075]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.2.38","name":"RoonServer","ver":"9.0.2","uuid":"50479c15-33c8-4deb-adb4-42cfa52a8c4a","jsonPort":"9000","cliPort":"9090"} May 15 23:53:06 volumio-bedroom sudo[12295]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:06 volumio-bedroom mpd[12221]: May 15 23:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 23:53:06 volumio-bedroom sudo[12113]: pam_unix(sudo:session): session closed for user root May 15 23:53:06 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:06 volumio-bedroom volumio[12075]: info: CorePlayQueue::getTrack 0 May 15 23:53:06 volumio-bedroom sudo[12264]: pam_unix(sudo:session): session closed for user root May 15 23:53:06 volumio-bedroom volumio[12075]: info: EVO SABRE : systemd cmd daemon-reload : success May 15 23:53:06 volumio-bedroom systemd[1]: Started Music Player Daemon. May 15 23:53:06 volumio-bedroom sudo[12188]: pam_unix(sudo:session): session closed for user root May 15 23:53:06 volumio-bedroom sudo[12179]: pam_unix(sudo:session): session closed for user root May 15 23:53:06 volumio-bedroom sudo[12304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service May 15 23:53:06 volumio-bedroom sudo[12304]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:06 volumio-bedroom systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 23:53:06 volumio-bedroom systemd[1]: shairport-sync.service: Succeeded. May 15 23:53:06 volumio-bedroom systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 23:53:06 volumio-bedroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 23:53:06 volumio-bedroom sudo[12297]: pam_unix(sudo:session): session closed for user root May 15 23:53:06 volumio-bedroom sudo[12293]: pam_unix(sudo:session): session closed for user root May 15 23:53:06 volumio-bedroom sudo[12295]: pam_unix(sudo:session): session closed for user root May 15 23:53:06 volumio-bedroom systemd[1]: Stopping OLED Display Service for EVO SABRE... May 15 23:53:06 volumio-bedroom volumio[12075]: error: MPD error: The expression evaluated to a falsy value: May 15 23:53:06 volumio-bedroom volumio[12075]: assert.ok(self.idling) May 15 23:53:06 volumio-bedroom volumio[12075]: error: The expression evaluated to a falsy value: May 15 23:53:06 volumio-bedroom volumio[12075]: assert.ok(self.idling) May 15 23:53:06 volumio-bedroom sudo[11253]: pam_unix(sudo:session): session closed for user root May 15 23:53:06 volumio-bedroom systemd[1]: evo_oled2.service: Succeeded. May 15 23:53:06 volumio-bedroom systemd[1]: Stopped OLED Display Service for EVO SABRE. May 15 23:53:06 volumio-bedroom volumio[12075]: info: Shairport-Sync Started May 15 23:53:06 volumio-bedroom volumio[12075]: Error adding Membership: Error: addMembership EINVAL May 15 23:53:06 volumio-bedroom volumio[12075]: info: Shairport-Sync Started May 15 23:53:06 volumio-bedroom volumio[12075]: info: Shairport-Sync Started May 15 23:53:06 volumio-bedroom volumio[12075]: info: MPD running with PID12221 May 15 23:53:06 volumio-bedroom volumio[12075]: ,establishing connection May 15 23:53:06 volumio-bedroom volumio[12075]: error: updateQueue error: null May 15 23:53:06 volumio-bedroom volumio[12075]: error: updateQueue error: null May 15 23:53:07 volumio-bedroom systemd[1]: Started OLED Display Service for EVO SABRE. May 15 23:53:07 volumio-bedroom sudo[12304]: pam_unix(sudo:session): session closed for user root May 15 23:53:07 volumio-bedroom volumio[12075]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success May 15 23:53:07 volumio-bedroom sudo[12312]: root : TTY=unknown ; PWD=/data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled ; USER=root ; COMMAND=/usr/bin/node /data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled/index.js volumio May 15 23:53:07 volumio-bedroom sudo[12312]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:07 volumio-bedroom sudo[12315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service May 15 23:53:07 volumio-bedroom sudo[12315]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:07 volumio-bedroom lircd[11267]: lircd-0.10.1[11267]: Notice: caught signal May 15 23:53:07 volumio-bedroom systemd[1]: Stopping evo_remote.service... May 15 23:53:07 volumio-bedroom lircd-0.10.1[11267]: Notice: caught signal May 15 23:53:07 volumio-bedroom systemd[1]: evo_remote.service: Main process exited, code=killed, status=15/TERM May 15 23:53:07 volumio-bedroom systemd[1]: evo_remote.service: Succeeded. May 15 23:53:07 volumio-bedroom systemd[1]: Stopped evo_remote.service. May 15 23:53:07 volumio-bedroom systemd[1]: evo_irexec.service: Succeeded. May 15 23:53:07 volumio-bedroom systemd[1]: Starting lircd(8) initialization helper tool... May 15 23:53:07 volumio-bedroom lircd-setup[12322]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. May 15 23:53:07 volumio-bedroom lircd-setup[12322]: parser = configparser.SafeConfigParser() May 15 23:53:07 volumio-bedroom systemd[1]: lircd-setup.service: Succeeded. May 15 23:53:07 volumio-bedroom systemd[1]: Started lircd(8) initialization helper tool. May 15 23:53:07 volumio-bedroom systemd[1]: Started evo_remote.service. May 15 23:53:07 volumio-bedroom sudo[12315]: pam_unix(sudo:session): session closed for user root May 15 23:53:07 volumio-bedroom volumio[12075]: info: EVO SABRE : systemd cmd restart evo_remote.service : success May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Info: lircd: Opening log, level: Info May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Version: lircd 0.10.1 May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: System info: Linux volumio-bedroom 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l GNU/Linux May 15 23:53:07 volumio-bedroom sudo[12328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service May 15 23:53:07 volumio-bedroom sudo[12328]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Info: Initial device: /dev/lirc0 May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Info: [lirc] protocol is enabled May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Info: Initial device: /dev/lirc0 May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Info: [lirc] protocol is enabled May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: driver: default May 15 23:53:07 volumio-bedroom lircd[12324]: lircd-0.10.1[12324]: Info: lircd: Opening log, level: Info May 15 23:53:07 volumio-bedroom lircd[12324]: lircd-0.10.1[12324]: Warning: Running as root May 15 23:53:07 volumio-bedroom lircd[12324]: lircd-0.10.1[12324]: Info: Using remote: ApEvo. May 15 23:53:07 volumio-bedroom lircd[12324]: lircd-0.10.1[12324]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: output: /var/run/lirc/lircd May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: nodaemon: 1 May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: logfile: syslog May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: immediate-init: 0 May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: permission: 666 May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: driver-options: May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: pidfile: /var/run/lirc/lircd.pid May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: listen: 0 May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: connect: (null) May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: userelease: 0 May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: effective_user: (null) May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: release_suffix: _EVUP May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: allow_simulate: 0 May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: repeat_max: 600 May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Options: dynamic_codes: (null) May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Current driver: default May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Driver API version: 3 May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Driver version: 0.10.0 May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Info: lircd: Opening log, level: Info May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Warning: Running as root May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Info: Using remote: ApEvo. May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 15 23:53:07 volumio-bedroom systemd[1]: Starting lircd(8) initialization helper tool... May 15 23:53:07 volumio-bedroom lircd-setup[12330]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. May 15 23:53:07 volumio-bedroom lircd-setup[12330]: parser = configparser.SafeConfigParser() May 15 23:53:07 volumio-bedroom systemd[1]: lircd-setup.service: Succeeded. May 15 23:53:07 volumio-bedroom systemd[1]: Started lircd(8) initialization helper tool. May 15 23:53:07 volumio-bedroom systemd[1]: Started evo_irexec.service. May 15 23:53:07 volumio-bedroom lircd[12324]: lircd-0.10.1[12324]: Notice: accepted new client on /var/run/lirc/lircd May 15 23:53:07 volumio-bedroom lircd[12324]: lircd-0.10.1[12324]: Info: [lirc] protocol is enabled May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Notice: accepted new client on /var/run/lirc/lircd May 15 23:53:07 volumio-bedroom lircd-0.10.1[12324]: Info: [lirc] protocol is enabled May 15 23:53:07 volumio-bedroom sudo[12328]: pam_unix(sudo:session): session closed for user root May 15 23:53:07 volumio-bedroom volumio[12075]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success May 15 23:53:07 volumio-bedroom volumio[12075]: info: Completed starting Core Plugins May 15 23:53:07 volumio-bedroom volumio[12075]: info: ------------------------------------------- May 15 23:53:07 volumio-bedroom volumio[12075]: info: ----- MyVolumio plugins startup ---- May 15 23:53:07 volumio-bedroom volumio[12075]: info: ------------------------------------------- May 15 23:53:07 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Fetching plans data.... May 15 23:53:08 volumio-bedroom volumio[12075]: info: Cannot mount NAS DSD at system boot, trial number 2 ,retrying in 5 seconds May 15 23:53:08 volumio-bedroom ntpd[821]: Listen normally on 1695 wlan0 192.168.2.26:123 May 15 23:53:08 volumio-bedroom ntpd[821]: new interface(s) found: waking up resolver May 15 23:53:08 volumio-bedroom volumio[12075]: info: [squeezelite_mc] Notification listener started May 15 23:53:08 volumio-bedroom volumio[12075]: info: [squeezelite_mc] Getting players connected to RoonServer (192.168.2.38) May 15 23:53:08 volumio-bedroom volumio[12075]: info: [squeezelite_mc] Players connected to RoonServer (192.168.2.38): [{"id":"e4:5f:01:f3:b3:e1","uuid":null,"ip":"192.168.2.26","name":"volumio-bedroom","server":{"ip":"192.168.2.38","name":"RoonServer","ver":"9.0.2","uuid":"50479c15-33c8-4deb-adb4-42cfa52a8c4a","jsonPort":"9000","cliPort":"9090"}},{"id":"2c:cf:67:5a:ba:4c","uuid":null,"ip":"192.168.2.194","name":"volumiowoonkamer","server":{"ip":"192.168.2.38","name":"RoonServer","ver":"9.0.2","uuid":"50479c15-33c8-4deb-adb4-42cfa52a8c4a","jsonPort":"9000","cliPort":"9090"}}] May 15 23:53:08 volumio-bedroom volumio[12075]: info: [squeezelite_mc] Player found: {"id":"e4:5f:01:f3:b3:e1","uuid":null,"ip":"192.168.2.26","name":"volumio-bedroom","server":{"ip":"192.168.2.38","name":"RoonServer","ver":"9.0.2","uuid":"50479c15-33c8-4deb-adb4-42cfa52a8c4a","jsonPort":"9000","cliPort":"9090"}} May 15 23:53:08 volumio-bedroom volumio[12075]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 15 23:53:08 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:08 volumio-bedroom volumio[12075]: info: CorePlayQueue::getTrack 0 May 15 23:53:08 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:08 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:08 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:08 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:08 volumio-bedroom volumio[12075]: info: CorePlayQueue::getTrack 0 May 15 23:53:09 volumio-bedroom volumio[12075]: verbose: New Socket.io Connection to 192.168.2.25:3000 from 192.168.2.194 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 15 23:53:09 volumio-bedroom volumio[12075]: info: [squeezelite_mc] Squeezelite in sync group with sync master 2c:cf:67:5a:ba:4c. May 15 23:53:09 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:09 volumio-bedroom volumio[12075]: info: CorePlayQueue::getTrack 0 May 15 23:53:09 volumio-bedroom volumio[12075]: info: [squeezelite_mc] 'play' status received while not being the current service. May 15 23:53:09 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:09 volumio-bedroom volumio[12075]: info: CorePlayQueue::getTrack 0 May 15 23:53:09 volumio-bedroom volumio[12075]: info: [squeezelite_mc] Stopping playback by current service... May 15 23:53:09 volumio-bedroom volumio[12075]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 23:53:09 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:09 volumio-bedroom volumio[12075]: info: CorePlayQueue::getTrack 0 May 15 23:53:09 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioStop May 15 23:53:09 volumio-bedroom volumio[12075]: info: CoreStateMachine::stop May 15 23:53:09 volumio-bedroom volumio[12075]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 23:53:09 volumio-bedroom volumio[12075]: info: [squeezelite_mc] Setting ourselves as the current service... May 15 23:53:09 volumio-bedroom volumio[12075]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 23:53:09 volumio-bedroom volumio[12075]: info: [squeezelite_mc] #pushState(): {"status":"play","service":"squeezelite_mc","title":"Now Playing info goes here","album":"RMF Classic (Lesser Poland Voivodeship)","albumart":"http://192.168.2.25:43705/?server_name=RoonServer&url=http%3A%2F%2F192.168.2.38%3A9000%2Fimageproxy%2Fhttp%253A%252F%252Fcdn-profiles.tunein.com%252Fs48202%252Fimages%252Flogoq.png%253Ft%253D1661351338000%2Fimage.png&fallback=http%3A%2F%2F192.168.2.25%2Falbumart","uri":"","trackType":"MP3 Radio","duration":0,"samplerate":"128kbps CBR","isStreaming":true,"volume":100,"repeat":false,"repeatSingle":false,"random":false} May 15 23:53:09 volumio-bedroom volumio[12075]: info: CoreCommandRouter::servicePushState May 15 23:53:09 volumio-bedroom volumio[12075]: info: CoreStateMachine::pushState May 15 23:53:09 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 23:53:09 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioPushState May 15 23:53:10 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:10 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:10 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:10 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:11 volumio-bedroom volumio[12075]: info: Discovery: this is already registered, 1ae4494d-87e1-45e1-802e-ef1104d1910e May 15 23:53:11 volumio-bedroom volumio[12075]: info: Discovery: Found device Volumio Bedroom May 15 23:53:11 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:11 volumio-bedroom volumio[12075]: info: Discovery: this is already registered, 438c7928-318e-4db1-84d6-a5fcd9009883 May 15 23:53:11 volumio-bedroom volumio[12075]: info: Discovery: Found device VolumioWoonkamer May 15 23:53:11 volumio-bedroom volumio[12075]: info: Discovery: Connecting to remote: 192.168.2.194 May 15 23:53:11 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:11 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:11 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:11 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:11 volumio-bedroom volumio[12075]: info: Discovery: Connected to remote: 192.168.2.194 May 15 23:53:12 volumio-bedroom volumio[12075]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 15 23:53:12 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:12 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:12 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:12 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:12 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:13 volumio-bedroom volumio[12075]: info: Cannot mount NAS DSD at system boot, trial number 3 ,retrying in 5 seconds May 15 23:53:13 volumio-bedroom sudo[12351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 23:53:13 volumio-bedroom sudo[12351]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:13 volumio-bedroom sudo[12353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 23:53:13 volumio-bedroom sudo[12353]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:13 volumio-bedroom sudo[12351]: pam_unix(sudo:session): session closed for user root May 15 23:53:13 volumio-bedroom sudo[12353]: pam_unix(sudo:session): session closed for user root May 15 23:53:13 volumio-bedroom volumio[12075]: info: Volumio Network Manager: Network status updated: 3 May 15 23:53:13 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:13 volumio-bedroom volumio[12075]: info: [squeezelite_mc] #pushState(): {"status":"play","service":"squeezelite_mc","title":"Now Playing info goes here","album":"RMF Classic (Lesser Poland Voivodeship)","albumart":"http://192.168.2.25:43705/?server_name=RoonServer&url=http%3A%2F%2F192.168.2.38%3A9000%2Fimageproxy%2Fhttp%253A%252F%252Fcdn-profiles.tunein.com%252Fs48202%252Fimages%252Flogoq.png%253Ft%253D1661351338000%2Fimage.png&fallback=http%3A%2F%2F192.168.2.25%2Falbumart","uri":"","trackType":"MP3 Radio","duration":0,"samplerate":"128kbps CBR","isStreaming":true,"volume":100,"repeat":false,"repeatSingle":false,"random":false} May 15 23:53:13 volumio-bedroom volumio[12075]: info: CoreCommandRouter::servicePushState May 15 23:53:13 volumio-bedroom volumio[12075]: info: CoreStateMachine::pushState May 15 23:53:13 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioPushState May 15 23:53:13 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:13 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:13 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:13 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:14 volumio-bedroom sudo[12357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 15 23:53:14 volumio-bedroom sudo[12357]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:14 volumio-bedroom sudo[12357]: pam_unix(sudo:session): session closed for user root May 15 23:53:14 volumio-bedroom volumio[12075]: info: Upmpdcli Daemon Started May 15 23:53:14 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:14 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:14 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:14 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:15 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:15 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:15 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:15 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 15 23:53:15 volumio-bedroom volumio[12075]: info: Adding plugin bluetooth to MyMusic Plugins May 15 23:53:15 volumio-bedroom volumio[12075]: info: Adding plugin multiroom to MyMusic Plugins May 15 23:53:15 volumio-bedroom volumio[12075]: info: Adding plugin metavolumio to MyMusic Plugins May 15 23:53:15 volumio-bedroom volumio[12075]: info: Adding plugin cd_controller to MyMusic Plugins May 15 23:53:15 volumio-bedroom volumio[12075]: info: Adding plugin qobuzconnect to MyMusic Plugins May 15 23:53:15 volumio-bedroom volumio[12075]: info: Adding plugin smart_inputs to MyMusic Plugins May 15 23:53:15 volumio-bedroom volumio[12075]: info: Adding plugin tidalconnect to MyMusic Plugins May 15 23:53:15 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 15 23:53:16 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 15 23:53:16 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 15 23:53:16 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:16 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:16 volumio-bedroom volumio[12075]: info: Starting MyVolumio Remote Streaming Endpoints May 15 23:53:16 volumio-bedroom volumio[12075]: info: MyVolumio login type: Token May 15 23:53:16 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 15 23:53:16 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 15 23:53:17 volumio-bedroom volumio[12075]: info: Starting Streaming Service Transparent Proxy May 15 23:53:17 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 15 23:53:17 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 15 23:53:17 volumio-bedroom volumio[12075]: info: Streaming services startup May 15 23:53:17 volumio-bedroom volumio[12075]: info: Starting Streaming Daemon May 15 23:53:17 volumio-bedroom sudo[12367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 15 23:53:17 volumio-bedroom sudo[12367]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:17 volumio-bedroom sudo[12367]: pam_unix(sudo:session): session closed for user root May 15 23:53:17 volumio-bedroom volumio[12075]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 15 23:53:17 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:17 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:17 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:17 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:17 volumio-bedroom volumio[12075]: error: Cannot start Volumio Streaming Daemon May 15 23:53:17 volumio-bedroom volumio[12075]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 15 23:53:17 volumio-bedroom volumio[12075]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 15 23:53:17 volumio-bedroom volumio[12075]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 15 23:53:17 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:17 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:17 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:17 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:17 volumio-bedroom volumio[12075]: STREAMING PROXY: Starting server on port 3245 May 15 23:53:17 volumio-bedroom volumio[12075]: Node JS runtime: 14 May 15 23:53:17 volumio-bedroom volumio[12075]: info: MyVolumio token set successfully May 15 23:53:17 volumio-bedroom volumio[12075]: info: MYVOLUMIO: Adding device May 15 23:53:17 volumio-bedroom volumio[12075]: info: MYVOLUMIO: Evaluating Server May 15 23:53:18 volumio-bedroom volumio[12075]: info: Cannot mount NAS DSD at system boot, trial number 4 ,retrying in 5 seconds May 15 23:53:18 volumio-bedroom volumio[12075]: info: MyVolumio status changed May 15 23:53:18 volumio-bedroom volumio[12075]: info: Streaming services startup May 15 23:53:18 volumio-bedroom volumio[12075]: info: Starting Streaming Daemon May 15 23:53:18 volumio-bedroom volumio[12075]: info: Removing browser output: myVolumio user plan is not superstar May 15 23:53:18 volumio-bedroom volumio[12075]: info: Removing audio output: May 15 23:53:18 volumio-bedroom volumio[12075]: info: Stoppping Tunnel 1 May 15 23:53:18 volumio-bedroom sudo[12392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 15 23:53:18 volumio-bedroom sudo[12392]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:18 volumio-bedroom sudo[12392]: pam_unix(sudo:session): session closed for user root May 15 23:53:18 volumio-bedroom sudo[12395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 15 23:53:18 volumio-bedroom sudo[12395]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:18 volumio-bedroom volumio[12075]: error: Cannot start Volumio Streaming Daemon May 15 23:53:18 volumio-bedroom volumio[12075]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 15 23:53:18 volumio-bedroom volumio[12075]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 15 23:53:18 volumio-bedroom sudo[12395]: pam_unix(sudo:session): session closed for user root May 15 23:53:18 volumio-bedroom volumio[12075]: info: Remote SSH Stopped May 15 23:53:18 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:18 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:18 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:18 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:18 volumio-bedroom volumio[12075]: info: Setting Geolocation for MyVolumio to eu5 May 15 23:53:18 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:18 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:18 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:19 volumio-bedroom volumio[12075]: info: Successfully Added MyVolumio device May 15 23:53:19 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:19 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:19 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:19 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:19 volumio-bedroom volumio[12075]: info: Updating MyVolumio device info May 15 23:53:19 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:19 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:19 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:19 volumio-bedroom volumio[12075]: verbose: New Socket.io Connection to 192.168.2.25:3000 from 192.168.2.194 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 15 23:53:19 volumio-bedroom volumio[12075]: info: Successfully Updated MyVolumio device May 15 23:53:20 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:20 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:20 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:20 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:21 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:21 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:21 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:21 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:22 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:22 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:22 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:22 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:23 volumio-bedroom volumio[12075]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 15 23:53:23 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:23 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:23 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:23 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:24 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:24 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:24 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:24 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:25 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:25 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:25 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:25 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:26 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:26 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:26 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:26 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:27 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:27 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:27 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:27 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:28 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:28 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:28 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:28 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:29 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:29 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:29 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:29 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:30 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:30 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:30 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:30 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:31 volumio-bedroom wpa_supplicant[960]: wlan0: CTRL-EVENT-DISCONNECTED bssid=18:e8:29:b0:ed:30 reason=0 locally_generated=1 May 15 23:53:31 volumio-bedroom dhcpcd[1023]: wlan0: carrier lost May 15 23:53:31 volumio-bedroom wpa_supplicant[960]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 15 23:53:31 volumio-bedroom wpa_supplicant[960]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=NL May 15 23:53:31 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 15 23:53:31 volumio-bedroom volumio[12075]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 15 23:53:31 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:31 volumio-bedroom avahi-daemon[672]: Withdrawing address record for 192.168.2.26 on wlan0. May 15 23:53:31 volumio-bedroom avahi-daemon[672]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.26. May 15 23:53:31 volumio-bedroom avahi-daemon[672]: Interface wlan0.IPv4 no longer relevant for mDNS. May 15 23:53:31 volumio-bedroom dhcpcd[1023]: wlan0: deleting route to 192.168.2.0/24 May 15 23:53:31 volumio-bedroom dhcpcd[1023]: wlan0: deleting default route via 192.168.2.1 May 15 23:53:31 volumio-bedroom volumio[12075]: info: Discovery: A device disappeared from network May 15 23:53:31 volumio-bedroom volumio[12075]: info: Discovery: Device volumiowoonkamer disappeared from network May 15 23:53:31 volumio-bedroom volumio[12075]: info: Discovery: Disconnected from remote: 192.168.2.194 May 15 23:53:31 volumio-bedroom volumio[12075]: info: Discovery: A device disappeared from network May 15 23:53:31 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:31 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:31 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:31 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:32 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:32 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:32 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:32 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:33 volumio-bedroom volumio[12075]: info: Volumio Network Manager: Network status updated: 1 May 15 23:53:33 volumio-bedroom ntpd[821]: Deleting interface #1695 wlan0, 192.168.2.26#123, interface stats: received=0, sent=0, dropped=0, active_time=25 secs May 15 23:53:33 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:33 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:33 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:33 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:34 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:34 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:34 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:34 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:35 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 15 23:53:35 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 15 23:53:35 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 23:53:35 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:35 volumio-bedroom volumio[12075]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 15 23:53:35 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetState May 15 23:53:35 volumio-bedroom volumio[12075]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:35 volumio-bedroom volumio[12075]: info: CoreStateMachine::getQueue May 15 23:53:35 volumio-bedroom volumio[12075]: info: CorePlayQueue::getQueue May 15 23:53:35 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 15 23:53:35 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 15 23:53:36 volumio-bedroom volumio[12075]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 23:53:36 volumio-bedroom volumio[12075]: Error: send ENETUNREACH 255.255.255.255:3483 May 15 23:53:36 volumio-bedroom volumio[12075]: at doSend (dgram.js:692:16) May 15 23:53:36 volumio-bedroom volumio[12075]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 15 23:53:36 volumio-bedroom volumio[12075]: at afterDns (dgram.js:638:5) May 15 23:53:36 volumio-bedroom volumio[12075]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 15 23:53:36 volumio-bedroom volumio[12075]: errno: -101, May 15 23:53:36 volumio-bedroom volumio[12075]: code: 'ENETUNREACH', May 15 23:53:36 volumio-bedroom volumio[12075]: syscall: 'send', May 15 23:53:36 volumio-bedroom volumio[12075]: address: '255.255.255.255', May 15 23:53:36 volumio-bedroom volumio[12075]: port: 3483 May 15 23:53:36 volumio-bedroom volumio[12075]: } May 15 23:53:36 volumio-bedroom volumio[12075]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 23:53:36 volumio-bedroom sudo[12479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-15 23:52 May 15 23:53:36 volumio-bedroom sudo[12479]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:36 volumio-bedroom sudo[12479]: pam_unix(sudo:session): session closed for user root May 15 23:53:36 volumio-bedroom volumio-remote-updater[677]: [2025-05-15 23:53:36] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 15 23:53:36 volumio-bedroom volumio-remote-updater[677]: [2025-05-15 23:53:36] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 15 23:53:36 volumio-bedroom systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 15 23:53:36 volumio-bedroom systemd[1]: volumio.service: Failed with result 'exit-code'. May 15 23:53:36 volumio-bedroom systemd[1]: Started dynamicswap service. May 15 23:53:36 volumio-bedroom systemd[1]: dynamicswap.service: Succeeded. May 15 23:53:36 volumio-bedroom systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 15 23:53:36 volumio-bedroom systemd[1]: volumio.service: Scheduled restart job, restart counter is at 33. May 15 23:53:36 volumio-bedroom systemd[1]: Started dynamicswap service. May 15 23:53:36 volumio-bedroom systemd[1]: Stopped Volumio Backend Module. May 15 23:53:36 volumio-bedroom systemd[1]: Started Volumio Backend Module. May 15 23:53:36 volumio-bedroom systemd[1]: dynamicswap.service: Succeeded. May 15 23:53:37 volumio-bedroom wpa_supplicant[960]: wlan0: Trying to associate with SSID 'van Hooft 5gHz' May 15 23:53:37 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 15 23:53:37 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 15 23:53:37 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 15 23:53:37 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 15 23:53:37 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 15 23:53:37 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 15 23:53:37 volumio-bedroom dhcpcd[1023]: wlan0: carrier acquired May 15 23:53:37 volumio-bedroom wpa_supplicant[960]: wlan0: Associated with 18:e8:29:b0:ed:30 May 15 23:53:37 volumio-bedroom wpa_supplicant[960]: wlan0: CTRL-EVENT-CONNECTED - Connection to 18:e8:29:b0:ed:30 completed [id=0 id_str=] May 15 23:53:37 volumio-bedroom wpa_supplicant[960]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 15 23:53:37 volumio-bedroom wpa_supplicant[960]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=NL May 15 23:53:37 volumio-bedroom dhcpcd[1023]: wlan0: IAID 01:f3:b3:e2 May 15 23:53:37 volumio-bedroom dhcpcd[1023]: wlan0: carrier lost May 15 23:53:37 volumio-bedroom dhcpcd[1023]: wlan0: carrier acquired May 15 23:53:37 volumio-bedroom dhcpcd[1023]: wlan0: IAID 01:f3:b3:e2 May 15 23:53:37 volumio-bedroom volumio[12493]: info: ------------------------------------------- May 15 23:53:37 volumio-bedroom volumio[12493]: info: ----- Volumio3 ---- May 15 23:53:37 volumio-bedroom volumio[12493]: info: ------------------------------------------- May 15 23:53:37 volumio-bedroom volumio[12493]: info: ----- System startup ---- May 15 23:53:37 volumio-bedroom volumio[12493]: info: ------------------------------------------- May 15 23:53:38 volumio-bedroom dhcpcd[1023]: wlan0: rebinding lease of 192.168.2.26 May 15 23:53:38 volumio-bedroom dhcpcd[1023]: wlan0: probing address 192.168.2.26/24 May 15 23:53:38 volumio-bedroom volumio[12493]: info: MYVOLUMIO Environment detected May 15 23:53:38 volumio-bedroom volumio[12493]: info: Plugin folders cleanup May 15 23:53:38 volumio-bedroom volumio[12493]: info: Scanning into folder /volumio/app/plugins/ May 15 23:53:38 volumio-bedroom volumio[12493]: info: Scanning category audio_interface May 15 23:53:38 volumio-bedroom volumio[12493]: info: Scanning category miscellanea May 15 23:53:38 volumio-bedroom volumio[12493]: info: Scanning category music_service May 15 23:53:38 volumio-bedroom volumio[12493]: info: Scanning category plugins.json May 15 23:53:38 volumio-bedroom volumio[12493]: info: Scanning category system_controller May 15 23:53:38 volumio-bedroom volumio[12493]: info: Scanning category user_interface May 15 23:53:38 volumio-bedroom volumio[12493]: info: Scanning into folder /data/plugins/ May 15 23:53:38 volumio-bedroom volumio[12493]: info: Scanning category music_service May 15 23:53:38 volumio-bedroom volumio[12493]: info: Scanning category system_hardware May 15 23:53:38 volumio-bedroom volumio[12493]: info: Scanning category user_interface May 15 23:53:38 volumio-bedroom volumio[12493]: info: Plugin folders cleanup completed May 15 23:53:38 volumio-bedroom volumio[12493]: info: ------------------------------------------- May 15 23:53:38 volumio-bedroom volumio[12493]: info: ----- Core plugins startup ---- May 15 23:53:38 volumio-bedroom volumio[12493]: info: ------------------------------------------- May 15 23:53:38 volumio-bedroom volumio[12493]: info: Loading plugins from folder /volumio/app/plugins/ May 15 23:53:38 volumio-bedroom volumio[12493]: info: Adding plugin upnp to MyMusic Plugins May 15 23:53:38 volumio-bedroom volumio[12493]: info: Adding plugin airplay_emulation to MyMusic Plugins May 15 23:53:38 volumio-bedroom volumio[12493]: info: Adding plugin upnp_browser to MyMusic Plugins May 15 23:53:38 volumio-bedroom volumio[12493]: info: Loading plugins from folder /data/plugins/ May 15 23:53:38 volumio-bedroom volumio[12493]: info: Loading plugin "system"... May 15 23:53:38 volumio-bedroom dhcpcd[1023]: wlan0: soliciting an IPv6 router May 15 23:53:38 volumio-bedroom volumio[12493]: info: Loading plugin "appearance"... May 15 23:53:38 volumio-bedroom volumio[12493]: info: Loading plugin "network"... May 15 23:53:38 volumio-bedroom volumio[12493]: info: Refreshing Cached IP Addresses May 15 23:53:38 volumio-bedroom sudo[12539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 23:53:38 volumio-bedroom sudo[12539]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:38 volumio-bedroom sudo[12539]: pam_unix(sudo:session): session closed for user root May 15 23:53:39 volumio-bedroom sudo[12541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 23:53:39 volumio-bedroom sudo[12541]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:39 volumio-bedroom sudo[12541]: pam_unix(sudo:session): session closed for user root May 15 23:53:39 volumio-bedroom volumio[12493]: info: Loading plugin "services"... May 15 23:53:39 volumio-bedroom volumio[12493]: info: Loading plugin "alsa_controller"... May 15 23:53:39 volumio-bedroom sudo[12550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 15 23:53:39 volumio-bedroom sudo[12550]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:39 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 23:53:39 volumio-bedroom volumio[12493]: info: Loading plugin "wizard"... May 15 23:53:39 volumio-bedroom volumio[12493]: info: Loading plugin "networkfs"... May 15 23:53:39 volumio-bedroom volumio[12493]: info: Cannot mount NAS DSD at system boot, trial number 1 ,retrying in 5 seconds May 15 23:53:39 volumio-bedroom volumio[12493]: info: Starting Udev Watcher for removable devices May 15 23:53:39 volumio-bedroom volumio[12493]: info: Ignoring mount for partition: boot May 15 23:53:39 volumio-bedroom volumio[12493]: info: Ignoring mount for partition: volumio May 15 23:53:39 volumio-bedroom volumio[12493]: info: Ignoring mount for partition: volumio_data May 15 23:53:39 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 15 23:53:39 volumio-bedroom volumio[12493]: info: Loading plugin "volumio_command_line_client"... May 15 23:53:39 volumio-bedroom volumio[12493]: info: Loading plugin "upnp"... May 15 23:53:39 volumio-bedroom volumio[12493]: info: [1747346019148] Starting Upmpd Daemon May 15 23:53:39 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 15 23:53:39 volumio-bedroom volumio[12493]: info: Loading plugin "my_music"... May 15 23:53:39 volumio-bedroom volumio[12493]: info: Loading plugin "mpd"... May 15 23:53:39 volumio-bedroom volumio[12493]: info: Loading plugin "upnp_browser"... May 15 23:53:39 volumio-bedroom volumio[12493]: info: Loading plugin "alarm-clock"... May 15 23:53:39 volumio-bedroom volumio[12493]: info: Loading plugin "airplay_emulation"... May 15 23:53:39 volumio-bedroom volumio[12493]: info: Starting Shairport Sync May 15 23:53:39 volumio-bedroom volumio[12493]: info: Loading plugin "last_100"... May 15 23:53:39 volumio-bedroom volumio[12493]: info: Loading plugin "webradio"... May 15 23:53:39 volumio-bedroom volumio[12493]: info: Loading plugin "i2s_dacs"... May 15 23:53:39 volumio-bedroom volumio[12493]: info: Loading plugin "volumiodiscovery"... May 15 23:53:39 volumio-bedroom volumio[12493]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 15 23:53:39 volumio-bedroom volumio[12493]: *** WARNING *** Please fix your application to use the native API of Avahi! May 15 23:53:39 volumio-bedroom node[12493]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 15 23:53:39 volumio-bedroom volumio[12493]: *** WARNING *** For more information see May 15 23:53:39 volumio-bedroom volumio[12493]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 15 23:53:39 volumio-bedroom volumio[12493]: *** WARNING *** Please fix your application to use the native API of Avahi! May 15 23:53:39 volumio-bedroom volumio[12493]: *** WARNING *** For more information see May 15 23:53:39 volumio-bedroom node[12493]: *** WARNING *** Please fix your application to use the native API of Avahi! May 15 23:53:39 volumio-bedroom node[12493]: *** WARNING *** For more information see May 15 23:53:39 volumio-bedroom node[12493]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 15 23:53:39 volumio-bedroom node[12493]: *** WARNING *** Please fix your application to use the native API of Avahi! May 15 23:53:39 volumio-bedroom node[12493]: *** WARNING *** For more information see May 15 23:53:39 volumio-bedroom volumio[12493]: info: Applying required configuration parameters for plugin volumiodiscovery May 15 23:53:39 volumio-bedroom volumio[12493]: info: Discovery: Started advertising with name: Volumio Bedroom May 15 23:53:39 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 15 23:53:39 volumio-bedroom volumio[12493]: info: Plugin spop is not enabled May 15 23:53:39 volumio-bedroom volumio[12493]: info: Loading plugin "squeezelite_mc"... May 15 23:53:40 volumio-bedroom volumio[12493]: info: Plugin now_playing is not enabled May 15 23:53:40 volumio-bedroom volumio[12493]: info: Loading plugin "outputs"... May 15 23:53:40 volumio-bedroom volumio[12493]: info: Loading plugin "albumart"... May 15 23:53:40 volumio-bedroom volumio[12493]: info: Plugin example_plugin is not enabled May 15 23:53:40 volumio-bedroom volumio[12493]: info: Loading plugin "inputs"... May 15 23:53:40 volumio-bedroom volumio[12493]: info: Loading plugin "updater_comm"... May 15 23:53:40 volumio-bedroom volumio[12493]: info: Plugin mpdemulation is not enabled May 15 23:53:40 volumio-bedroom volumio[12493]: info: Loading plugin "rest_api"... May 15 23:53:40 volumio-bedroom volumio[12493]: info: Loading plugin "websocket"... May 15 23:53:40 volumio-bedroom volumio[12493]: info: Starting Socket.io Server version 2.3.0 May 15 23:53:40 volumio-bedroom volumio[12493]: info: Loading plugin "audiophonics_evo_sabre"... May 15 23:53:40 volumio-bedroom volumio[12493]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre May 15 23:53:40 volumio-bedroom volumio[12493]: info: Plugin mpd_oled is not enabled May 15 23:53:40 volumio-bedroom volumio[12493]: info: Loading i18n strings for locale en May 15 23:53:40 volumio-bedroom volumio[12493]: Updating browse sources language May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 23:53:40 volumio-bedroom volumio[12493]: Forking 3 albumart workers May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::initPlayerControls May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:53:40 volumio-bedroom volumio[12493]: Express server listening on port 3000 May 15 23:53:40 volumio-bedroom volumio[12493]: [Metrics] WebUI: 2s 946.05ms May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreStateMachine::resetVolumioState May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreStateMachine::getcurrentVolume May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::volumioRetrievevolume May 15 23:53:40 volumio-bedroom volumio[12493]: info: Volumio Network Manager: Network status updated: 1 May 15 23:53:40 volumio-bedroom volumio[12493]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:40 volumio-bedroom volumio[12493]: info: Reloading queue from file May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::volumioGetState May 15 23:53:40 volumio-bedroom volumio[12493]: info: CorePlayQueue::getTrack 0 May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreStateMachine::setRepeat null single undefined May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreStateMachine::pushState May 15 23:53:40 volumio-bedroom volumio[12493]: info: CorePlayQueue::getTrack 0 May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::volumioPushState May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreStateMachine::setRandom null May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreStateMachine::pushState May 15 23:53:40 volumio-bedroom volumio[12493]: info: CorePlayQueue::getTrack 0 May 15 23:53:40 volumio-bedroom volumio[12493]: info: CoreCommandRouter::volumioPushState May 15 23:53:40 volumio-bedroom volumio[12493]: info: Setting Device type: Raspberry PI May 15 23:53:40 volumio-bedroom volumio[12493]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 15 23:53:40 volumio-bedroom volumio[12493]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 May 15 23:53:40 volumio-bedroom volumio[12493]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 15 23:53:41 volumio-bedroom volumio[12493]: info: Completed loading Core Plugins May 15 23:53:41 volumio-bedroom volumio[12493]: info: Preparing to generate the ALSA configuration file May 15 23:53:41 volumio-bedroom volumio[12493]: info: VolumeController:: Volume=100 Mute =false May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreStateMachine::pushState May 15 23:53:41 volumio-bedroom volumio[12493]: info: CorePlayQueue::getTrack 0 May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::volumioPushState May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreStateMachine::updateTrackBlock May 15 23:53:41 volumio-bedroom volumio[12493]: info: CorePlayQueue::getTrackBlock May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::volumioRetrievevolume May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreStateMachine::getQueue May 15 23:53:41 volumio-bedroom volumio[12493]: info: CorePlayQueue::getQueue May 15 23:53:41 volumio-bedroom volumio[12493]: Starting albumart workers May 15 23:53:41 volumio-bedroom volumio[12493]: info: Discovery: adding 438c7928-318e-4db1-84d6-a5fcd9009883 May 15 23:53:41 volumio-bedroom volumio[12493]: info: Discovery: Found device VolumioWoonkamer May 15 23:53:41 volumio-bedroom volumio[12493]: info: Discovery: Connecting to remote: 192.168.211.1 May 15 23:53:41 volumio-bedroom volumio[12493]: Starting albumart workers May 15 23:53:41 volumio-bedroom volumio[12493]: Starting albumart workers May 15 23:53:41 volumio-bedroom volumio[12493]: info: Asound.conf file unchanged, so no further update is needed May 15 23:53:41 volumio-bedroom volumio[12493]: info: Output device has changed, restarting MPD May 15 23:53:41 volumio-bedroom sudo[12610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 23:53:41 volumio-bedroom sudo[12610]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:41 volumio-bedroom sudo[12610]: pam_unix(sudo:session): session closed for user root May 15 23:53:41 volumio-bedroom volumio[12493]: info: Output device has changed, restarting Shairport Sync May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:41 volumio-bedroom sudo[12613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 23:53:41 volumio-bedroom sudo[12613]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:41 volumio-bedroom volumio[12493]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 23:53:41 volumio-bedroom volumio[12493]: info: ___________ START PLUGINS ___________ May 15 23:53:41 volumio-bedroom systemd[1]: Stopping Music Player Daemon... May 15 23:53:41 volumio-bedroom volumio[12493]: info: ControllerMpd::onStart: Initializing MPD May 15 23:53:41 volumio-bedroom volumio[12493]: info: Creating MPD Configuration file May 15 23:53:41 volumio-bedroom systemd[1]: mpd.service: Succeeded. May 15 23:53:41 volumio-bedroom systemd[1]: Stopped Music Player Daemon. May 15 23:53:41 volumio-bedroom sudo[12619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 23:53:41 volumio-bedroom sudo[12619]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 23:53:41 volumio-bedroom volumio[12493]: info: [1747346021286] CoreMusicLibrary::Adding element Media Servers May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 23:53:41 volumio-bedroom sudo[12619]: pam_unix(sudo:session): session closed for user root May 15 23:53:41 volumio-bedroom sudo[12623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 23:53:41 volumio-bedroom sudo[12623]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:41 volumio-bedroom systemd[1]: Starting Music Player Daemon... May 15 23:53:41 volumio-bedroom systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 15 23:53:41 volumio-bedroom systemd[1]: mpd.service: Succeeded. May 15 23:53:41 volumio-bedroom systemd[1]: Stopped Music Player Daemon. May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:41 volumio-bedroom systemd[1]: Starting Music Player Daemon... May 15 23:53:41 volumio-bedroom sudo[12625]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 15 23:53:41 volumio-bedroom sudo[12625]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:41 volumio-bedroom sudo[12625]: pam_unix(sudo:session): session closed for user root May 15 23:53:41 volumio-bedroom volumio[12493]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:41 volumio-bedroom volumio-remote-updater[677]: [2025-05-15 23:53:41] [connect] Successful connection May 15 23:53:41 volumio-bedroom volumio[12493]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 23:53:41 volumio-bedroom volumio[12493]: info: [1747346021587] CoreMusicLibrary::Adding element Last_100 May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 23:53:41 volumio-bedroom volumio[12493]: info: [1747346021589] CoreMusicLibrary::Adding element Webradio May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 23:53:41 volumio-bedroom volumio[12493]: info: Initializing BBC Radios May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:41 volumio-bedroom volumio[12493]: info: [squeezelite_mc] Starting proxy server... May 15 23:53:41 volumio-bedroom volumio[12493]: info: EVO SABRE : Starting Plugin May 15 23:53:41 volumio-bedroom volumio[12493]: info: Loading i18n strings for locale en May 15 23:53:41 volumio-bedroom volumio[12493]: info: Volumio Calling Home May 15 23:53:41 volumio-bedroom volumio[12493]: info: [squeezelite_mc] Proxy server started on port 45781 May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::volumioRetrievevolume May 15 23:53:41 volumio-bedroom volumio[12493]: info: Discovery: adding 1ae4494d-87e1-45e1-802e-ef1104d1910e May 15 23:53:41 volumio-bedroom volumio[12493]: info: Discovery: Found device Volumio Bedroom May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::volumioGetState May 15 23:53:41 volumio-bedroom volumio[12493]: info: CorePlayQueue::getTrack 0 May 15 23:53:41 volumio-bedroom volumio[12493]: info: MPD Permissions set May 15 23:53:41 volumio-bedroom volumio[12493]: info: MPD Permissions set May 15 23:53:41 volumio-bedroom volumio[12493]: info: VolumeController:: Volume=100 Mute =false May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreStateMachine::pushState May 15 23:53:41 volumio-bedroom volumio[12493]: info: CorePlayQueue::getTrack 0 May 15 23:53:41 volumio-bedroom volumio[12493]: info: CoreCommandRouter::volumioPushState May 15 23:53:42 volumio-bedroom sudo[12675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 15 23:53:42 volumio-bedroom sudo[12675]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:42 volumio-bedroom volumio[12493]: 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: 2 May 15 23:53:42 volumio-bedroom volumio-remote-updater[677]: [2025-05-15 23:53:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747346021 101 May 15 23:53:42 volumio-bedroom systemd[1]: Reloading. May 15 23:53:42 volumio-bedroom volumio[12493]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: VolumeController:: Volume=100 Mute =false May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreStateMachine::pushState May 15 23:53:42 volumio-bedroom volumio[12493]: info: CorePlayQueue::getTrack 0 May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::volumioPushState May 15 23:53:42 volumio-bedroom volumio[12493]: info: [squeezelite_mc] Server discovery started May 15 23:53:42 volumio-bedroom volumio[12493]: info: [squeezelite_mc] Player finder started May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:53:42 volumio-bedroom volumio[12493]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true May 15 23:53:42 volumio-bedroom volumio[12493]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 23:53:42 volumio-bedroom volumio[12493]: Error: send ENETUNREACH 255.255.255.255:3483 May 15 23:53:42 volumio-bedroom volumio[12493]: at doSend (dgram.js:692:16) May 15 23:53:42 volumio-bedroom volumio[12493]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 15 23:53:42 volumio-bedroom volumio[12493]: at afterDns (dgram.js:638:5) May 15 23:53:42 volumio-bedroom volumio[12493]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 15 23:53:42 volumio-bedroom volumio[12493]: errno: -101, May 15 23:53:42 volumio-bedroom volumio[12493]: code: 'ENETUNREACH', May 15 23:53:42 volumio-bedroom volumio[12493]: syscall: 'send', May 15 23:53:42 volumio-bedroom volumio[12493]: address: '255.255.255.255', May 15 23:53:42 volumio-bedroom volumio[12493]: port: 3483 May 15 23:53:42 volumio-bedroom volumio[12493]: } May 15 23:53:42 volumio-bedroom volumio[12493]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 23:53:42 volumio-bedroom 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. May 15 23:53:42 volumio-bedroom mpd[12630]: May 15 23:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 23:53:42 volumio-bedroom sudo[12712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-15 23:52 May 15 23:53:42 volumio-bedroom sudo[12712]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:42 volumio-bedroom systemd[1]: Started Music Player Daemon. May 15 23:53:42 volumio-bedroom sudo[12675]: pam_unix(sudo:session): session closed for user root May 15 23:53:42 volumio-bedroom sudo[12613]: pam_unix(sudo:session): session closed for user root May 15 23:53:42 volumio-bedroom sudo[12623]: pam_unix(sudo:session): session closed for user root May 15 23:53:42 volumio-bedroom sudo[12550]: pam_unix(sudo:session): session closed for user root May 15 23:53:42 volumio-bedroom sudo[12712]: pam_unix(sudo:session): session closed for user root May 15 23:53:42 volumio-bedroom volumio-remote-updater[677]: [2025-05-15 23:53:42] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 15 23:53:42 volumio-bedroom volumio-remote-updater[677]: [2025-05-15 23:53:42] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 15 23:53:42 volumio-bedroom systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 15 23:53:42 volumio-bedroom systemd[1]: volumio.service: Failed with result 'exit-code'. May 15 23:53:42 volumio-bedroom systemd[1]: Started dynamicswap service. May 15 23:53:42 volumio-bedroom systemd[1]: dynamicswap.service: Succeeded. May 15 23:53:43 volumio-bedroom dhcpcd[1023]: wlan0: leased 192.168.2.26 for 86400 seconds May 15 23:53:43 volumio-bedroom avahi-daemon[672]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.26. May 15 23:53:43 volumio-bedroom avahi-daemon[672]: New relevant interface wlan0.IPv4 for mDNS. May 15 23:53:43 volumio-bedroom dhcpcd[1023]: wlan0: adding route to 192.168.2.0/24 May 15 23:53:43 volumio-bedroom avahi-daemon[672]: Registering new address record for 192.168.2.26 on wlan0.IPv4. May 15 23:53:43 volumio-bedroom dhcpcd[1023]: wlan0: adding default route via 192.168.2.1 May 15 23:53:43 volumio-bedroom systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 15 23:53:43 volumio-bedroom systemd[1]: volumio.service: Scheduled restart job, restart counter is at 34. May 15 23:53:43 volumio-bedroom systemd[1]: Started dynamicswap service. May 15 23:53:43 volumio-bedroom systemd[1]: Stopped Volumio Backend Module. May 15 23:53:43 volumio-bedroom systemd[1]: Started Volumio Backend Module. May 15 23:53:43 volumio-bedroom systemd[1]: dynamicswap.service: Succeeded. May 15 23:53:44 volumio-bedroom volumio[12748]: info: ------------------------------------------- May 15 23:53:44 volumio-bedroom volumio[12748]: info: ----- Volumio3 ---- May 15 23:53:44 volumio-bedroom volumio[12748]: info: ------------------------------------------- May 15 23:53:44 volumio-bedroom volumio[12748]: info: ----- System startup ---- May 15 23:53:44 volumio-bedroom volumio[12748]: info: ------------------------------------------- May 15 23:53:44 volumio-bedroom ntpd[821]: Listen normally on 1696 wlan0 192.168.2.26:123 May 15 23:53:44 volumio-bedroom ntpd[821]: new interface(s) found: waking up resolver May 15 23:53:44 volumio-bedroom volumio[12748]: info: MYVOLUMIO Environment detected May 15 23:53:44 volumio-bedroom volumio[12748]: info: Plugin folders cleanup May 15 23:53:44 volumio-bedroom volumio[12748]: info: Scanning into folder /volumio/app/plugins/ May 15 23:53:44 volumio-bedroom volumio[12748]: info: Scanning category audio_interface May 15 23:53:44 volumio-bedroom volumio[12748]: info: Scanning category miscellanea May 15 23:53:44 volumio-bedroom volumio[12748]: info: Scanning category music_service May 15 23:53:44 volumio-bedroom volumio[12748]: info: Scanning category plugins.json May 15 23:53:44 volumio-bedroom volumio[12748]: info: Scanning category system_controller May 15 23:53:44 volumio-bedroom volumio[12748]: info: Scanning category user_interface May 15 23:53:44 volumio-bedroom volumio[12748]: info: Scanning into folder /data/plugins/ May 15 23:53:44 volumio-bedroom volumio[12748]: info: Scanning category music_service May 15 23:53:44 volumio-bedroom volumio[12748]: info: Scanning category system_hardware May 15 23:53:44 volumio-bedroom volumio[12748]: info: Scanning category user_interface May 15 23:53:44 volumio-bedroom volumio[12748]: info: Plugin folders cleanup completed May 15 23:53:44 volumio-bedroom volumio[12748]: info: ------------------------------------------- May 15 23:53:44 volumio-bedroom volumio[12748]: info: ----- Core plugins startup ---- May 15 23:53:44 volumio-bedroom volumio[12748]: info: ------------------------------------------- May 15 23:53:44 volumio-bedroom volumio[12748]: info: Loading plugins from folder /volumio/app/plugins/ May 15 23:53:44 volumio-bedroom volumio[12748]: info: Adding plugin upnp to MyMusic Plugins May 15 23:53:44 volumio-bedroom volumio[12748]: info: Adding plugin airplay_emulation to MyMusic Plugins May 15 23:53:44 volumio-bedroom volumio[12748]: info: Adding plugin upnp_browser to MyMusic Plugins May 15 23:53:44 volumio-bedroom volumio[12748]: info: Loading plugins from folder /data/plugins/ May 15 23:53:44 volumio-bedroom volumio[12748]: info: Loading plugin "system"... May 15 23:53:44 volumio-bedroom volumio[12748]: info: Loading plugin "appearance"... May 15 23:53:45 volumio-bedroom volumio[12748]: info: Loading plugin "network"... May 15 23:53:45 volumio-bedroom volumio[12748]: info: Refreshing Cached IP Addresses May 15 23:53:45 volumio-bedroom sudo[12777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 23:53:45 volumio-bedroom sudo[12777]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:45 volumio-bedroom sudo[12777]: pam_unix(sudo:session): session closed for user root May 15 23:53:45 volumio-bedroom sudo[12779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 23:53:45 volumio-bedroom sudo[12779]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:45 volumio-bedroom sudo[12779]: pam_unix(sudo:session): session closed for user root May 15 23:53:45 volumio-bedroom volumio[12748]: info: Loading plugin "services"... May 15 23:53:45 volumio-bedroom volumio[12748]: info: Loading plugin "alsa_controller"... May 15 23:53:45 volumio-bedroom sudo[12792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 15 23:53:45 volumio-bedroom sudo[12792]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:45 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 23:53:45 volumio-bedroom volumio[12748]: info: Loading plugin "wizard"... May 15 23:53:45 volumio-bedroom volumio[12748]: info: Loading plugin "networkfs"... May 15 23:53:45 volumio-bedroom volumio[12748]: info: Cannot mount NAS DSD at system boot, trial number 1 ,retrying in 5 seconds May 15 23:53:45 volumio-bedroom volumio[12748]: info: Starting Udev Watcher for removable devices May 15 23:53:45 volumio-bedroom volumio[12748]: info: Ignoring mount for partition: boot May 15 23:53:45 volumio-bedroom volumio[12748]: info: Ignoring mount for partition: volumio May 15 23:53:45 volumio-bedroom volumio[12748]: info: Ignoring mount for partition: volumio_data May 15 23:53:45 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 15 23:53:45 volumio-bedroom volumio[12748]: info: Loading plugin "volumio_command_line_client"... May 15 23:53:45 volumio-bedroom volumio[12748]: info: Loading plugin "upnp"... May 15 23:53:45 volumio-bedroom volumio[12748]: info: [1747346025425] Starting Upmpd Daemon May 15 23:53:45 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 15 23:53:45 volumio-bedroom volumio[12748]: info: Loading plugin "my_music"... May 15 23:53:45 volumio-bedroom volumio[12748]: info: Loading plugin "mpd"... May 15 23:53:45 volumio-bedroom volumio[12748]: info: Loading plugin "upnp_browser"... May 15 23:53:45 volumio-bedroom volumio[12748]: info: Loading plugin "alarm-clock"... May 15 23:53:45 volumio-bedroom volumio[12748]: info: Loading plugin "airplay_emulation"... May 15 23:53:45 volumio-bedroom volumio[12748]: info: Starting Shairport Sync May 15 23:53:45 volumio-bedroom volumio[12748]: info: Loading plugin "last_100"... May 15 23:53:45 volumio-bedroom volumio[12748]: info: Loading plugin "webradio"... May 15 23:53:45 volumio-bedroom volumio[12748]: info: Loading plugin "i2s_dacs"... May 15 23:53:45 volumio-bedroom volumio[12748]: info: Loading plugin "volumiodiscovery"... May 15 23:53:45 volumio-bedroom volumio[12748]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 15 23:53:45 volumio-bedroom volumio[12748]: *** WARNING *** Please fix your application to use the native API of Avahi! May 15 23:53:45 volumio-bedroom node[12748]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 15 23:53:45 volumio-bedroom volumio[12748]: *** WARNING *** For more information see May 15 23:53:45 volumio-bedroom volumio[12748]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 15 23:53:45 volumio-bedroom volumio[12748]: *** WARNING *** Please fix your application to use the native API of Avahi! May 15 23:53:45 volumio-bedroom volumio[12748]: *** WARNING *** For more information see May 15 23:53:45 volumio-bedroom node[12748]: *** WARNING *** Please fix your application to use the native API of Avahi! May 15 23:53:45 volumio-bedroom node[12748]: *** WARNING *** For more information see May 15 23:53:45 volumio-bedroom node[12748]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 15 23:53:45 volumio-bedroom node[12748]: *** WARNING *** Please fix your application to use the native API of Avahi! May 15 23:53:45 volumio-bedroom node[12748]: *** WARNING *** For more information see May 15 23:53:45 volumio-bedroom volumio[12748]: info: Applying required configuration parameters for plugin volumiodiscovery May 15 23:53:45 volumio-bedroom volumio[12748]: info: Discovery: Started advertising with name: Volumio Bedroom May 15 23:53:45 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 15 23:53:45 volumio-bedroom volumio[12748]: info: Plugin spop is not enabled May 15 23:53:46 volumio-bedroom volumio[12748]: info: Loading plugin "squeezelite_mc"... May 15 23:53:46 volumio-bedroom volumio[12748]: info: Plugin now_playing is not enabled May 15 23:53:46 volumio-bedroom volumio[12748]: info: Loading plugin "outputs"... May 15 23:53:46 volumio-bedroom volumio[12748]: info: Loading plugin "albumart"... May 15 23:53:46 volumio-bedroom volumio[12748]: info: Plugin example_plugin is not enabled May 15 23:53:46 volumio-bedroom volumio[12748]: info: Loading plugin "inputs"... May 15 23:53:46 volumio-bedroom volumio[12748]: info: Loading plugin "updater_comm"... May 15 23:53:46 volumio-bedroom volumio[12748]: info: Plugin mpdemulation is not enabled May 15 23:53:46 volumio-bedroom volumio[12748]: info: Loading plugin "rest_api"... May 15 23:53:46 volumio-bedroom volumio[12748]: info: Loading plugin "websocket"... May 15 23:53:46 volumio-bedroom volumio[12748]: info: Starting Socket.io Server version 2.3.0 May 15 23:53:46 volumio-bedroom volumio[12748]: info: Loading plugin "audiophonics_evo_sabre"... May 15 23:53:46 volumio-bedroom volumio[12748]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre May 15 23:53:46 volumio-bedroom volumio[12748]: Forking 3 albumart workers May 15 23:53:46 volumio-bedroom volumio[12748]: info: Plugin mpd_oled is not enabled May 15 23:53:46 volumio-bedroom volumio[12748]: info: Loading i18n strings for locale en May 15 23:53:46 volumio-bedroom volumio[12748]: Updating browse sources language May 15 23:53:46 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 23:53:46 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:53:46 volumio-bedroom volumio[12748]: info: CoreCommandRouter::initPlayerControls May 15 23:53:46 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:46 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:46 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:46 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:46 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:46 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:46 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:46 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:46 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:53:46 volumio-bedroom volumio[12748]: Express server listening on port 3000 May 15 23:53:46 volumio-bedroom volumio[12748]: [Metrics] WebUI: 3s 110.28ms May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreStateMachine::resetVolumioState May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreStateMachine::getcurrentVolume May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioRetrievevolume May 15 23:53:47 volumio-bedroom volumio[12748]: info: Volumio Network Manager: Network status updated: 3 May 15 23:53:47 volumio-bedroom volumio[12748]: verbose: New Socket.io Connection to 192.168.2.25:3000 from 192.168.2.194 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 15 23:53:47 volumio-bedroom volumio[12748]: verbose: New Socket.io Connection to 192.168.2.25:3000 from 192.168.2.194 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 15 23:53:47 volumio-bedroom volumio[12748]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 15 23:53:47 volumio-bedroom volumio[12748]: verbose: New Socket.io Connection to 192.168.2.26:3000 from 192.168.2.194 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 15 23:53:47 volumio-bedroom volumio[12748]: Starting albumart workers May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:47 volumio-bedroom volumio[12748]: info: Reloading queue from file May 15 23:53:47 volumio-bedroom volumio[12748]: Starting albumart workers May 15 23:53:47 volumio-bedroom volumio[12748]: info: Setting Device type: Raspberry PI May 15 23:53:47 volumio-bedroom volumio[12748]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 15 23:53:47 volumio-bedroom volumio[12748]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 May 15 23:53:47 volumio-bedroom volumio[12748]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreStateMachine::setRepeat null single undefined May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreStateMachine::pushState May 15 23:53:47 volumio-bedroom volumio[12748]: Starting albumart workers May 15 23:53:47 volumio-bedroom volumio[12748]: info: CorePlayQueue::getTrack 0 May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioPushState May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreStateMachine::setRandom null May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreStateMachine::pushState May 15 23:53:47 volumio-bedroom volumio[12748]: info: CorePlayQueue::getTrack 0 May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioPushState May 15 23:53:47 volumio-bedroom volumio[12748]: info: Completed loading Core Plugins May 15 23:53:47 volumio-bedroom volumio[12748]: info: Preparing to generate the ALSA configuration file May 15 23:53:47 volumio-bedroom volumio[12748]: info: VolumeController:: Volume=100 Mute =false May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreStateMachine::pushState May 15 23:53:47 volumio-bedroom volumio[12748]: info: CorePlayQueue::getTrack 0 May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioPushState May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreStateMachine::updateTrackBlock May 15 23:53:47 volumio-bedroom volumio[12748]: info: CorePlayQueue::getTrackBlock May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioRetrievevolume May 15 23:53:47 volumio-bedroom volumio[12748]: info: Asound.conf file unchanged, so no further update is needed May 15 23:53:47 volumio-bedroom volumio[12748]: info: Output device has changed, restarting MPD May 15 23:53:47 volumio-bedroom sudo[12849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 23:53:47 volumio-bedroom sudo[12849]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:47 volumio-bedroom volumio[12748]: info: Output device has changed, restarting Shairport Sync May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:47 volumio-bedroom sudo[12849]: pam_unix(sudo:session): session closed for user root May 15 23:53:47 volumio-bedroom sudo[12851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 23:53:47 volumio-bedroom sudo[12851]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:47 volumio-bedroom systemd[1]: Stopping Music Player Daemon... May 15 23:53:47 volumio-bedroom volumio[12748]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 23:53:47 volumio-bedroom volumio[12748]: info: ___________ START PLUGINS ___________ May 15 23:53:47 volumio-bedroom volumio[12748]: info: ControllerMpd::onStart: Initializing MPD May 15 23:53:47 volumio-bedroom volumio[12748]: info: Creating MPD Configuration file May 15 23:53:47 volumio-bedroom systemd[1]: mpd.service: Succeeded. May 15 23:53:47 volumio-bedroom systemd[1]: Stopped Music Player Daemon. May 15 23:53:47 volumio-bedroom sudo[12858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 23:53:47 volumio-bedroom volumio[12748]: info: [1747346027756] CoreMusicLibrary::Adding element Media Servers May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:47 volumio-bedroom systemd[1]: Starting Music Player Daemon... May 15 23:53:47 volumio-bedroom sudo[12858]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:47 volumio-bedroom sudo[12858]: pam_unix(sudo:session): session closed for user root May 15 23:53:47 volumio-bedroom sudo[12860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 23:53:47 volumio-bedroom sudo[12860]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:47 volumio-bedroom volumio-remote-updater[677]: [2025-05-15 23:53:47] [connect] Successful connection May 15 23:53:47 volumio-bedroom systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 15 23:53:47 volumio-bedroom systemd[1]: mpd.service: Succeeded. May 15 23:53:47 volumio-bedroom systemd[1]: Stopped Music Player Daemon. May 15 23:53:47 volumio-bedroom systemd[1]: Starting Music Player Daemon... May 15 23:53:47 volumio-bedroom volumio[12748]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:47 volumio-bedroom volumio[12748]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 23:53:47 volumio-bedroom volumio[12748]: info: [1747346027956] CoreMusicLibrary::Adding element Last_100 May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 23:53:47 volumio-bedroom volumio[12748]: info: [1747346027959] CoreMusicLibrary::Adding element Webradio May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 23:53:47 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 23:53:47 volumio-bedroom volumio[12748]: info: Initializing BBC Radios May 15 23:53:47 volumio-bedroom sudo[12875]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 15 23:53:47 volumio-bedroom sudo[12875]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:47 volumio-bedroom sudo[12875]: pam_unix(sudo:session): session closed for user root May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Starting proxy server... May 15 23:53:48 volumio-bedroom volumio[12748]: info: EVO SABRE : Starting Plugin May 15 23:53:48 volumio-bedroom volumio[12748]: info: Loading i18n strings for locale en May 15 23:53:48 volumio-bedroom volumio[12748]: info: Volumio Calling Home May 15 23:53:48 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Proxy server started on port 36285 May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioRetrievevolume May 15 23:53:48 volumio-bedroom volumio[12748]: info: Discovery: adding 438c7928-318e-4db1-84d6-a5fcd9009883 May 15 23:53:48 volumio-bedroom volumio[12748]: info: Discovery: Found device VolumioWoonkamer May 15 23:53:48 volumio-bedroom volumio[12748]: info: Discovery: Connecting to remote: 192.168.211.1 May 15 23:53:48 volumio-bedroom volumio[12748]: info: MPD Permissions set May 15 23:53:48 volumio-bedroom volumio[12748]: info: MPD Permissions set May 15 23:53:48 volumio-bedroom volumio[12748]: info: VolumeController:: Volume=100 Mute =true May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreStateMachine::pushState May 15 23:53:48 volumio-bedroom volumio[12748]: info: CorePlayQueue::getTrack 0 May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioPushState May 15 23:53:48 volumio-bedroom sudo[12931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 15 23:53:48 volumio-bedroom volumio[12748]: info: Discovery: adding 1ae4494d-87e1-45e1-802e-ef1104d1910e May 15 23:53:48 volumio-bedroom volumio[12748]: info: Discovery: Found device Volumio Bedroom May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:53:48 volumio-bedroom volumio[12748]: info: CorePlayQueue::getTrack 0 May 15 23:53:48 volumio-bedroom sudo[12931]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:48 volumio-bedroom volumiologrotate[663]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 15 23:53:48 volumio-bedroom systemd[1]: Reloading. May 15 23:53:48 volumio-bedroom volumiologrotate[663]: ls: cannot access 'BEDROOM': No such file or directory May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 15 23:53:48 volumio-bedroom volumio-remote-updater[677]: [2025-05-15 23:53:48] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747346027 101 May 15 23:53:48 volumio-bedroom volumio[12748]: 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: 5 May 15 23:53:48 volumio-bedroom volumio[12748]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: Volumio called home May 15 23:53:48 volumio-bedroom volumio[12748]: info: VolumeController:: Volume=100 Mute =true May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreStateMachine::pushState May 15 23:53:48 volumio-bedroom volumio[12748]: info: CorePlayQueue::getTrack 0 May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioPushState May 15 23:53:48 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Server discovery started May 15 23:53:48 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Player finder started May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:53:48 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true May 15 23:53:48 volumio-bedroom volumio[12748]: info: Discovery: this is already registered, 1ae4494d-87e1-45e1-802e-ef1104d1910e May 15 23:53:48 volumio-bedroom volumio[12748]: info: Discovery: Found device Volumio Bedroom May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:53:48 volumio-bedroom volumio[12748]: info: CorePlayQueue::getTrack 0 May 15 23:53:48 volumio-bedroom volumio[12748]: info: Discovery: this is already registered, 438c7928-318e-4db1-84d6-a5fcd9009883 May 15 23:53:48 volumio-bedroom volumio[12748]: info: Discovery: Found device VolumioWoonkamer May 15 23:53:48 volumio-bedroom volumio[12748]: info: Discovery: Connecting to remote: 192.168.211.1 May 15 23:53:48 volumio-bedroom 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. May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:53:48 volumio-bedroom volumio[12748]: info: CorePlayQueue::getTrack 0 May 15 23:53:48 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["S16_LE","S32_LE"] May 15 23:53:48 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio-bedroom","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"Digital"} May 15 23:53:48 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf May 15 23:53:48 volumio-bedroom volumio[12748]: info: Starting Shairport Sync May 15 23:53:48 volumio-bedroom volumio[12748]: info: Starting Shairport Sync May 15 23:53:48 volumio-bedroom volumio[12748]: info: Starting Shairport Sync May 15 23:53:48 volumio-bedroom sudo[12986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 23:53:48 volumio-bedroom sudo[12986]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:48 volumio-bedroom sudo[12988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 23:53:48 volumio-bedroom sudo[12988]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:48 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:53:48 volumio-bedroom sudo[12984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf May 15 23:53:48 volumio-bedroom sudo[12984]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:48 volumio-bedroom volumio[12748]: info: CorePlayQueue::getTrack 0 May 15 23:53:48 volumio-bedroom sudo[12992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 23:53:48 volumio-bedroom sudo[12992]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:48 volumio-bedroom sudo[12984]: pam_unix(sudo:session): session closed for user root May 15 23:53:48 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Executing alsactl -L -R nrestore May 15 23:53:48 volumio-bedroom sudo[12997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore May 15 23:53:49 volumio-bedroom mpd[12885]: May 15 23:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 23:53:49 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.2.38","name":"RoonServer","ver":"9.0.2","uuid":"50479c15-33c8-4deb-adb4-42cfa52a8c4a","jsonPort":"9000","cliPort":"9090"} May 15 23:53:49 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service May 15 23:53:48 volumio-bedroom sudo[12997]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:48 volumio-bedroom sudo[12997]: pam_unix(sudo:session): session closed for user root May 15 23:53:49 volumio-bedroom sudo[12792]: pam_unix(sudo:session): session closed for user root May 15 23:53:49 volumio-bedroom sudo[13001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service May 15 23:53:49 volumio-bedroom sudo[13001]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:49 volumio-bedroom sudo[12931]: pam_unix(sudo:session): session closed for user root May 15 23:53:49 volumio-bedroom volumio[12748]: info: EVO SABRE : systemd cmd daemon-reload : success May 15 23:53:49 volumio-bedroom sudo[13001]: pam_unix(sudo:session): session closed for user root May 15 23:53:49 volumio-bedroom systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 23:53:49 volumio-bedroom systemd[1]: shairport-sync.service: Succeeded. May 15 23:53:49 volumio-bedroom systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 23:53:49 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true May 15 23:53:49 volumio-bedroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 23:53:49 volumio-bedroom sudo[12988]: pam_unix(sudo:session): session closed for user root May 15 23:53:49 volumio-bedroom sudo[12986]: pam_unix(sudo:session): session closed for user root May 15 23:53:49 volumio-bedroom sudo[12992]: pam_unix(sudo:session): session closed for user root May 15 23:53:49 volumio-bedroom sudo[13004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service May 15 23:53:49 volumio-bedroom sudo[13004]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:49 volumio-bedroom volumio[12748]: info: Shairport-Sync Started May 15 23:53:49 volumio-bedroom volumio[12748]: Error adding Membership: Error: addMembership EINVAL May 15 23:53:49 volumio-bedroom sudo[13007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 15 23:53:49 volumio-bedroom volumio[12748]: info: Shairport-Sync Started May 15 23:53:49 volumio-bedroom sudo[13007]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:49 volumio-bedroom systemd[1]: Stopping OLED Display Service for EVO SABRE... May 15 23:53:49 volumio-bedroom systemd[1]: Started Music Player Daemon. May 15 23:53:49 volumio-bedroom sudo[12851]: pam_unix(sudo:session): session closed for user root May 15 23:53:49 volumio-bedroom sudo[12860]: pam_unix(sudo:session): session closed for user root May 15 23:53:49 volumio-bedroom systemd[1]: Reloading. May 15 23:53:49 volumio-bedroom sudo[12312]: pam_unix(sudo:session): session closed for user root May 15 23:53:49 volumio-bedroom volumio[12748]: error: MPD error: The expression evaluated to a falsy value: May 15 23:53:49 volumio-bedroom volumio[12748]: assert.ok(self.idling) May 15 23:53:49 volumio-bedroom volumio[12748]: error: The expression evaluated to a falsy value: May 15 23:53:49 volumio-bedroom volumio[12748]: assert.ok(self.idling) May 15 23:53:49 volumio-bedroom volumio[12748]: info: MPD running with PID12885 May 15 23:53:49 volumio-bedroom volumio[12748]: ,establishing connection May 15 23:53:49 volumio-bedroom volumio[12748]: error: updateQueue error: null May 15 23:53:49 volumio-bedroom volumio[12748]: error: updateQueue error: null May 15 23:53:49 volumio-bedroom 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. May 15 23:53:49 volumio-bedroom systemd[1]: evo_oled2.service: Succeeded. May 15 23:53:49 volumio-bedroom systemd[1]: Stopped OLED Display Service for EVO SABRE. May 15 23:53:49 volumio-bedroom sudo[13007]: pam_unix(sudo:session): session closed for user root May 15 23:53:49 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 15 23:53:49 volumio-bedroom systemd[1]: Started OLED Display Service for EVO SABRE. May 15 23:53:49 volumio-bedroom sudo[13004]: pam_unix(sudo:session): session closed for user root May 15 23:53:49 volumio-bedroom volumio[12748]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success May 15 23:53:49 volumio-bedroom sudo[13029]: root : TTY=unknown ; PWD=/data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled ; USER=root ; COMMAND=/usr/bin/node /data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled/index.js volumio May 15 23:53:49 volumio-bedroom sudo[13029]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:49 volumio-bedroom sudo[13031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 15 23:53:49 volumio-bedroom sudo[13031]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:49 volumio-bedroom sudo[13035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service May 15 23:53:49 volumio-bedroom sudo[13035]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:49 volumio-bedroom lircd[12324]: lircd-0.10.1[12324]: Notice: caught signal May 15 23:53:49 volumio-bedroom lircd-0.10.1[12324]: Notice: caught signal May 15 23:53:49 volumio-bedroom systemd[1]: Stopping evo_remote.service... May 15 23:53:49 volumio-bedroom sudo[13031]: pam_unix(sudo:session): session closed for user root May 15 23:53:49 volumio-bedroom systemd[1]: evo_remote.service: Main process exited, code=killed, status=15/TERM May 15 23:53:49 volumio-bedroom systemd[1]: evo_remote.service: Succeeded. May 15 23:53:49 volumio-bedroom systemd[1]: Stopped evo_remote.service. May 15 23:53:49 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true May 15 23:53:49 volumio-bedroom systemd[1]: evo_irexec.service: Succeeded. May 15 23:53:49 volumio-bedroom sudo[13045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite May 15 23:53:49 volumio-bedroom sudo[13045]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:49 volumio-bedroom systemd[1]: Starting lircd(8) initialization helper tool... May 15 23:53:49 volumio-bedroom systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... May 15 23:53:50 volumio-bedroom lircd-setup[13044]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. May 15 23:53:50 volumio-bedroom lircd-setup[13044]: parser = configparser.SafeConfigParser() May 15 23:53:50 volumio-bedroom systemd[1]: lircd-setup.service: Succeeded. May 15 23:53:50 volumio-bedroom systemd[1]: Started lircd(8) initialization helper tool. May 15 23:53:50 volumio-bedroom systemd[1]: Started evo_remote.service. May 15 23:53:50 volumio-bedroom sudo[13035]: pam_unix(sudo:session): session closed for user root May 15 23:53:50 volumio-bedroom volumio[12748]: info: EVO SABRE : systemd cmd restart evo_remote.service : success May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Info: lircd: Opening log, level: Info May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Version: lircd 0.10.1 May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: System info: Linux volumio-bedroom 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l GNU/Linux May 15 23:53:50 volumio-bedroom systemd[1]: squeezelite.service: Succeeded. May 15 23:53:50 volumio-bedroom systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. May 15 23:53:50 volumio-bedroom sudo[13045]: pam_unix(sudo:session): session closed for user root May 15 23:53:50 volumio-bedroom sudo[13051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service May 15 23:53:50 volumio-bedroom sudo[13051]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Info: Initial device: /dev/lirc0 May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Info: [lirc] protocol is enabled May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Info: Initial device: /dev/lirc0 May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Info: [lirc] protocol is enabled May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: driver: default May 15 23:53:50 volumio-bedroom lircd[13047]: lircd-0.10.1[13047]: Info: lircd: Opening log, level: Info May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: output: /var/run/lirc/lircd May 15 23:53:50 volumio-bedroom lircd[13047]: lircd-0.10.1[13047]: Warning: Running as root May 15 23:53:50 volumio-bedroom lircd[13047]: lircd-0.10.1[13047]: Info: Using remote: ApEvo. May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: nodaemon: 1 May 15 23:53:50 volumio-bedroom lircd[13047]: lircd-0.10.1[13047]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: logfile: syslog May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: immediate-init: 0 May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: permission: 666 May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: driver-options: May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: pidfile: /var/run/lirc/lircd.pid May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: listen: 0 May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: connect: (null) May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: userelease: 0 May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: effective_user: (null) May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: release_suffix: _EVUP May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: allow_simulate: 0 May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: repeat_max: 600 May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Options: dynamic_codes: (null) May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Current driver: default May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Driver API version: 3 May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Driver version: 0.10.0 May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Info: lircd: Opening log, level: Info May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Warning: Running as root May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Info: Using remote: ApEvo. May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 15 23:53:50 volumio-bedroom systemd[1]: Starting lircd(8) initialization helper tool... May 15 23:53:50 volumio-bedroom lircd-setup[13053]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. May 15 23:53:50 volumio-bedroom lircd-setup[13053]: parser = configparser.SafeConfigParser() May 15 23:53:50 volumio-bedroom systemd[1]: lircd-setup.service: Succeeded. May 15 23:53:50 volumio-bedroom systemd[1]: Started lircd(8) initialization helper tool. May 15 23:53:50 volumio-bedroom systemd[1]: Started evo_irexec.service. May 15 23:53:50 volumio-bedroom sudo[13051]: pam_unix(sudo:session): session closed for user root May 15 23:53:50 volumio-bedroom lircd[13047]: lircd-0.10.1[13047]: Notice: accepted new client on /var/run/lirc/lircd May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Notice: accepted new client on /var/run/lirc/lircd May 15 23:53:50 volumio-bedroom volumio[12748]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success May 15 23:53:50 volumio-bedroom lircd[13047]: lircd-0.10.1[13047]: Info: [lirc] protocol is enabled May 15 23:53:50 volumio-bedroom lircd-0.10.1[13047]: Info: [lirc] protocol is enabled May 15 23:53:50 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Notification listener started May 15 23:53:50 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Getting players connected to RoonServer (192.168.2.38) May 15 23:53:50 volumio-bedroom volumio[12748]: info: Cannot mount NAS DSD at system boot, trial number 2 ,retrying in 5 seconds May 15 23:53:50 volumio-bedroom volumio[12748]: info: [squeezelite_mc] 'client' notification received from RoonServer (192.168.2.38); type is 'disconnect' May 15 23:53:50 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Players connected to RoonServer (192.168.2.38): [{"id":"2c:cf:67:5a:ba:4c","uuid":null,"ip":"192.168.2.194","name":"volumiowoonkamer","server":{"ip":"192.168.2.38","name":"RoonServer","ver":"9.0.2","uuid":"50479c15-33c8-4deb-adb4-42cfa52a8c4a","jsonPort":"9000","cliPort":"9090"}}] May 15 23:53:50 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 15 23:53:50 volumio-bedroom sudo[13063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 15 23:53:50 volumio-bedroom sudo[13063]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:50 volumio-bedroom sudo[13063]: pam_unix(sudo:session): session closed for user root May 15 23:53:50 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log May 15 23:53:50 volumio-bedroom sudo[13067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log May 15 23:53:50 volumio-bedroom sudo[13067]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:50 volumio-bedroom sudo[13067]: pam_unix(sudo:session): session closed for user root May 15 23:53:50 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true May 15 23:53:50 volumio-bedroom sudo[13070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite May 15 23:53:50 volumio-bedroom sudo[13070]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:50 volumio-bedroom systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... May 15 23:53:50 volumio-bedroom systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. May 15 23:53:50 volumio-bedroom sudo[13070]: pam_unix(sudo:session): session closed for user root May 15 23:53:51 volumio-bedroom volumio[12748]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 15 23:53:51 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:53:51 volumio-bedroom volumio[12748]: info: CorePlayQueue::getTrack 0 May 15 23:53:51 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 15 23:53:51 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:51 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:53:51 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:53:51 volumio-bedroom sudo[13077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 15 23:53:51 volumio-bedroom sudo[13077]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:51 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:53:51 volumio-bedroom volumio[12748]: info: CorePlayQueue::getTrack 0 May 15 23:53:51 volumio-bedroom sudo[13077]: pam_unix(sudo:session): session closed for user root May 15 23:53:51 volumio-bedroom volumio[12748]: info: [squeezelite_mc] 'client' notification received from RoonServer (192.168.2.38); type is 'connect' May 15 23:53:51 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Getting players connected to RoonServer (192.168.2.38) May 15 23:53:51 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Players connected to RoonServer (192.168.2.38): [{"id":"e4:5f:01:f3:b3:e1","uuid":null,"ip":"192.168.2.26","name":"volumio-bedroom","server":{"ip":"192.168.2.38","name":"RoonServer","ver":"9.0.2","uuid":"50479c15-33c8-4deb-adb4-42cfa52a8c4a","jsonPort":"9000","cliPort":"9090"}},{"id":"2c:cf:67:5a:ba:4c","uuid":null,"ip":"192.168.2.194","name":"volumiowoonkamer","server":{"ip":"192.168.2.38","name":"RoonServer","ver":"9.0.2","uuid":"50479c15-33c8-4deb-adb4-42cfa52a8c4a","jsonPort":"9000","cliPort":"9090"}}] May 15 23:53:51 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Player found: {"id":"e4:5f:01:f3:b3:e1","uuid":null,"ip":"192.168.2.26","name":"volumio-bedroom","server":{"ip":"192.168.2.38","name":"RoonServer","ver":"9.0.2","uuid":"50479c15-33c8-4deb-adb4-42cfa52a8c4a","jsonPort":"9000","cliPort":"9090"}} May 15 23:53:51 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 15 23:53:51 volumio-bedroom sudo[13080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 15 23:53:51 volumio-bedroom sudo[13080]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:51 volumio-bedroom sudo[13080]: pam_unix(sudo:session): session closed for user root May 15 23:53:52 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 15 23:53:52 volumio-bedroom sudo[13083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 15 23:53:52 volumio-bedroom sudo[13083]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:52 volumio-bedroom sudo[13083]: pam_unix(sudo:session): session closed for user root May 15 23:53:53 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 15 23:53:53 volumio-bedroom sudo[13086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 15 23:53:53 volumio-bedroom sudo[13086]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:53 volumio-bedroom sudo[13086]: pam_unix(sudo:session): session closed for user root May 15 23:53:53 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 15 23:53:53 volumio-bedroom sudo[13089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 15 23:53:53 volumio-bedroom sudo[13089]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:53 volumio-bedroom sudo[13089]: pam_unix(sudo:session): session closed for user root May 15 23:53:53 volumio-bedroom volumio[12748]: info: Completed starting Core Plugins May 15 23:53:53 volumio-bedroom volumio[12748]: info: ------------------------------------------- May 15 23:53:53 volumio-bedroom volumio[12748]: info: ----- MyVolumio plugins startup ---- May 15 23:53:53 volumio-bedroom volumio[12748]: info: ------------------------------------------- May 15 23:53:53 volumio-bedroom volumio[12748]: info: [MyVolumio PluginManager] Fetching plans data.... May 15 23:53:53 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Squeezelite in sync group with sync master 2c:cf:67:5a:ba:4c. May 15 23:53:53 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:53:53 volumio-bedroom volumio[12748]: info: CorePlayQueue::getTrack 0 May 15 23:53:53 volumio-bedroom volumio[12748]: info: [squeezelite_mc] 'play' status received while not being the current service. May 15 23:53:53 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:53:53 volumio-bedroom volumio[12748]: info: CorePlayQueue::getTrack 0 May 15 23:53:53 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Stopping playback by current service... May 15 23:53:53 volumio-bedroom volumio[12748]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 23:53:53 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:53:53 volumio-bedroom volumio[12748]: info: CorePlayQueue::getTrack 0 May 15 23:53:53 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioStop May 15 23:53:53 volumio-bedroom volumio[12748]: info: CoreStateMachine::stop May 15 23:53:53 volumio-bedroom volumio[12748]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 23:53:53 volumio-bedroom volumio[12748]: info: [squeezelite_mc] Setting ourselves as the current service... May 15 23:53:53 volumio-bedroom volumio[12748]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 23:53:53 volumio-bedroom volumio[12748]: info: [squeezelite_mc] #pushState(): {"status":"play","service":"squeezelite_mc","title":"Now Playing info goes here","album":"RMF Classic (Lesser Poland Voivodeship)","albumart":"http://192.168.2.25:36285/?server_name=RoonServer&url=http%3A%2F%2F192.168.2.38%3A9000%2Fimageproxy%2Fhttp%253A%252F%252Fcdn-profiles.tunein.com%252Fs48202%252Fimages%252Flogoq.png%253Ft%253D1661351338000%2Fimage.png&fallback=http%3A%2F%2F192.168.2.25%2Falbumart","uri":"","trackType":"MP3 Radio","duration":0,"samplerate":"128kbps CBR","isStreaming":true,"volume":100,"repeat":false,"repeatSingle":false,"random":false} May 15 23:53:53 volumio-bedroom volumio[12748]: info: CoreCommandRouter::servicePushState May 15 23:53:53 volumio-bedroom volumio[12748]: info: CoreStateMachine::pushState May 15 23:53:53 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 23:53:53 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioPushState May 15 23:53:54 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:53:54 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:54 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:53:54 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:53:54 volumio-bedroom volumio[12748]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 15 23:53:55 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:53:55 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:55 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:53:55 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:53:55 volumio-bedroom volumio[12748]: info: Cannot mount NAS DSD at system boot, trial number 3 ,retrying in 5 seconds May 15 23:53:55 volumio-bedroom sudo[13107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 23:53:55 volumio-bedroom sudo[13107]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:55 volumio-bedroom sudo[13107]: pam_unix(sudo:session): session closed for user root May 15 23:53:55 volumio-bedroom sudo[13110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 23:53:55 volumio-bedroom sudo[13110]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:55 volumio-bedroom sudo[13110]: pam_unix(sudo:session): session closed for user root May 15 23:53:56 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:53:56 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:56 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:53:56 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:53:56 volumio-bedroom sudo[13113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 15 23:53:56 volumio-bedroom sudo[13113]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:53:56 volumio-bedroom sudo[13113]: pam_unix(sudo:session): session closed for user root May 15 23:53:56 volumio-bedroom volumio[12748]: info: Upmpdcli Daemon Started May 15 23:53:56 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:53:57 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:53:57 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:57 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:53:57 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:53:58 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:53:58 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:58 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:53:58 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:53:59 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:53:59 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:53:59 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:53:59 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:53:59 volumio-bedroom wpa_supplicant[960]: wlan0: CTRL-EVENT-DISCONNECTED bssid=18:e8:29:b0:ed:30 reason=0 locally_generated=1 May 15 23:53:59 volumio-bedroom dhcpcd[1023]: wlan0: carrier lost May 15 23:53:59 volumio-bedroom wpa_supplicant[960]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 15 23:53:59 volumio-bedroom wpa_supplicant[960]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=NL May 15 23:53:59 volumio-bedroom avahi-daemon[672]: Withdrawing address record for 192.168.2.26 on wlan0. May 15 23:53:59 volumio-bedroom avahi-daemon[672]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.26. May 15 23:53:59 volumio-bedroom avahi-daemon[672]: Interface wlan0.IPv4 no longer relevant for mDNS. May 15 23:53:59 volumio-bedroom dhcpcd[1023]: wlan0: deleting route to 192.168.2.0/24 May 15 23:53:59 volumio-bedroom dhcpcd[1023]: wlan0: deleting default route via 192.168.2.1 May 15 23:53:59 volumio-bedroom volumio[12748]: info: Discovery: A device disappeared from network May 15 23:53:59 volumio-bedroom volumio[12748]: info: Discovery: Device volumiowoonkamer disappeared from network May 15 23:53:59 volumio-bedroom volumio[12748]: info: Discovery: A device disappeared from network May 15 23:54:00 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:00 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:00 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:00 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:00 volumio-bedroom volumio[12748]: info: Cannot mount NAS DSD at system boot, trial number 4 ,retrying in 5 seconds May 15 23:54:01 volumio-bedroom ntpd[821]: Deleting interface #1696 wlan0, 192.168.2.26#123, interface stats: received=0, sent=0, dropped=0, active_time=17 secs May 15 23:54:01 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:01 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:01 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:01 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:02 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:02 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:02 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:02 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:03 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:03 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:03 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:03 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:03 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 15 23:54:03 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 15 23:54:04 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 15 23:54:04 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 15 23:54:04 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:04 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:04 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:04 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:05 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:05 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:05 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:05 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:05 volumio-bedroom volumio[12748]: info: Volumio Network Manager: Network status updated: 1 May 15 23:54:05 volumio-bedroom volumio[12748]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 15 23:54:05 volumio-bedroom wpa_supplicant[960]: wlan0: Trying to associate with SSID 'van Hooft 5gHz' May 15 23:54:05 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 15 23:54:05 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 15 23:54:05 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 15 23:54:05 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 15 23:54:05 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 15 23:54:05 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 15 23:54:06 volumio-bedroom wpa_supplicant[960]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 May 15 23:54:06 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:06 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:06 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:06 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:07 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:07 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:07 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:07 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:08 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:08 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:08 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:08 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:09 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:09 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:09 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:09 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:10 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:10 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:10 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:10 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:10 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 15 23:54:10 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 15 23:54:10 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 15 23:54:10 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 15 23:54:11 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:11 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:11 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:11 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:12 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:12 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:12 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:12 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:12 volumio-bedroom wpa_supplicant[960]: wlan0: Trying to associate with SSID 'van Hooft 5gHz' May 15 23:54:12 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 15 23:54:12 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 15 23:54:12 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 15 23:54:12 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 15 23:54:12 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 15 23:54:12 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 15 23:54:12 volumio-bedroom dhcpcd[1023]: wlan0: carrier acquired May 15 23:54:12 volumio-bedroom wpa_supplicant[960]: wlan0: Associated with 18:e8:29:b0:ed:30 May 15 23:54:12 volumio-bedroom wpa_supplicant[960]: wlan0: CTRL-EVENT-CONNECTED - Connection to 18:e8:29:b0:ed:30 completed [id=0 id_str=] May 15 23:54:12 volumio-bedroom wpa_supplicant[960]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 15 23:54:12 volumio-bedroom wpa_supplicant[960]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=NL May 15 23:54:12 volumio-bedroom dhcpcd[1023]: wlan0: IAID 01:f3:b3:e2 May 15 23:54:12 volumio-bedroom dhcpcd[1023]: wlan0: carrier lost May 15 23:54:12 volumio-bedroom dhcpcd[1023]: wlan0: carrier acquired May 15 23:54:12 volumio-bedroom dhcpcd[1023]: wlan0: IAID 01:f3:b3:e2 May 15 23:54:13 volumio-bedroom dhcpcd[1023]: wlan0: rebinding lease of 192.168.2.26 May 15 23:54:13 volumio-bedroom dhcpcd[1023]: wlan0: probing address 192.168.2.26/24 May 15 23:54:13 volumio-bedroom dhcpcd[1023]: wlan0: soliciting an IPv6 router May 15 23:54:13 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:13 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:13 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:13 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:13 volumio-bedroom volumio[12748]: error: MyVolumio Plugin failed to start in a timely fashion May 15 23:54:13 volumio-bedroom volumio[12748]: [Metrics] CommandRouter: 29s 485.68ms May 15 23:54:13 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumiosetStartupVolume May 15 23:54:13 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:54:13 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:54:13 volumio-bedroom volumio[12748]: info: CoreCommandRouter::Close All Modals sent May 15 23:54:13 volumio-bedroom volumio[12748]: info: CoreCommandRouter::Close All Modals sent May 15 23:54:14 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:14 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:14 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:14 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:14 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 15 23:54:14 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 23:54:14 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 15 23:54:15 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:15 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:15 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:15 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:16 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:16 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:16 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:16 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:17 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:17 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:17 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:17 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:18 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 23:54:18 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:54:18 volumio-bedroom volumio[12748]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 15 23:54:18 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetState May 15 23:54:18 volumio-bedroom volumio[12748]: info: CoreCommandRouter::volumioGetQueue May 15 23:54:18 volumio-bedroom volumio[12748]: info: CoreStateMachine::getQueue May 15 23:54:18 volumio-bedroom volumio[12748]: info: CorePlayQueue::getQueue May 15 23:54:18 volumio-bedroom volumio[12748]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 23:54:18 volumio-bedroom volumio[12748]: Error: send ENETUNREACH 255.255.255.255:3483 May 15 23:54:18 volumio-bedroom volumio[12748]: at doSend (dgram.js:692:16) May 15 23:54:18 volumio-bedroom volumio[12748]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 15 23:54:18 volumio-bedroom volumio[12748]: at afterDns (dgram.js:638:5) May 15 23:54:18 volumio-bedroom volumio[12748]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 15 23:54:18 volumio-bedroom volumio[12748]: errno: -101, May 15 23:54:18 volumio-bedroom volumio[12748]: code: 'ENETUNREACH', May 15 23:54:18 volumio-bedroom volumio[12748]: syscall: 'send', May 15 23:54:18 volumio-bedroom volumio[12748]: address: '255.255.255.255', May 15 23:54:18 volumio-bedroom volumio[12748]: port: 3483 May 15 23:54:18 volumio-bedroom volumio[12748]: } May 15 23:54:18 volumio-bedroom volumio[12748]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 23:54:18 volumio-bedroom dhcpcd[1023]: wlan0: leased 192.168.2.26 for 86400 seconds May 15 23:54:18 volumio-bedroom avahi-daemon[672]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.26. May 15 23:54:18 volumio-bedroom dhcpcd[1023]: wlan0: adding route to 192.168.2.0/24 May 15 23:54:18 volumio-bedroom dhcpcd[1023]: wlan0: adding default route via 192.168.2.1 May 15 23:54:18 volumio-bedroom avahi-daemon[672]: New relevant interface wlan0.IPv4 for mDNS. May 15 23:54:18 volumio-bedroom avahi-daemon[672]: Registering new address record for 192.168.2.26 on wlan0.IPv4. May 15 23:54:19 volumio-bedroom sudo[13242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-15 23:53 May 15 23:54:19 volumio-bedroom sudo[13242]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"