-- Logs begin at Thu 2025-05-22 19:22:00 EDT, end at Sat 2025-05-24 03:18:23 EDT. -- May 24 03:17:00 volumio-bedroom sudo[1782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 03:16 May 24 03:17:00 volumio-bedroom sudo[1782]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:00 volumio-bedroom sudo[1782]: pam_unix(sudo:session): session closed for user root May 24 03:17:00 volumio-bedroom volumio-remote-updater[531]: [2025-05-24 03:17:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 24 03:17:00 volumio-bedroom volumio-remote-updater[531]: [2025-05-24 03:17:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 24 03:17:00 volumio-bedroom systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 24 03:17:00 volumio-bedroom sudo[1721]: pam_unix(sudo:session): session closed for user root May 24 03:17:00 volumio-bedroom sudo[1726]: pam_unix(sudo:session): session closed for user root May 24 03:17:01 volumio-bedroom systemd[1]: volumio.service: Failed with result 'exit-code'. May 24 03:17:01 volumio-bedroom systemd[1]: Started dynamicswap service. May 24 03:17:01 volumio-bedroom systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 24 03:17:01 volumio-bedroom systemd[1]: volumio.service: Scheduled restart job, restart counter is at 69. May 24 03:17:01 volumio-bedroom systemd[1]: dynamicswap.service: Succeeded. May 24 03:17:01 volumio-bedroom systemd[1]: Stopped Volumio Backend Module. May 24 03:17:01 volumio-bedroom systemd[1]: Started Volumio Backend Module. May 24 03:17:01 volumio-bedroom mpd[1745]: May 24 03:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 24 03:17:01 volumio-bedroom systemd[1]: Started Music Player Daemon. May 24 03:17:02 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 24 03:17:02 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 24 03:17:02 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 24 03:17:02 volumio-bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 24 03:17:04 volumio-bedroom volumio[1796]: info: ------------------------------------------- May 24 03:17:04 volumio-bedroom volumio[1796]: info: ----- Volumio3 ---- May 24 03:17:04 volumio-bedroom volumio[1796]: info: ------------------------------------------- May 24 03:17:04 volumio-bedroom volumio[1796]: info: ----- System startup ---- May 24 03:17:04 volumio-bedroom volumio[1796]: info: ------------------------------------------- May 24 03:17:05 volumio-bedroom wpa_supplicant[784]: wlan0: Trying to associate with SSID 'Home' May 24 03:17:05 volumio-bedroom volumio[1796]: info: MYVOLUMIO Environment detected May 24 03:17:05 volumio-bedroom wpa_supplicant[784]: wlan0: Associated with 3a:de:4b:76:33:a4 May 24 03:17:05 volumio-bedroom wpa_supplicant[784]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3a:de:4b:76:33:a4 completed [id=0 id_str=] May 24 03:17:05 volumio-bedroom dhcpcd[798]: wlan0: carrier acquired May 24 03:17:05 volumio-bedroom wpa_supplicant[784]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 24 03:17:05 volumio-bedroom wpa_supplicant[784]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US May 24 03:17:05 volumio-bedroom dhcpcd[798]: wlan0: IAID eb:35:96:7a May 24 03:17:05 volumio-bedroom volumio[1796]: info: Plugin folders cleanup May 24 03:17:05 volumio-bedroom volumio[1796]: info: Scanning into folder /volumio/app/plugins/ May 24 03:17:05 volumio-bedroom volumio[1796]: info: Scanning category audio_interface May 24 03:17:05 volumio-bedroom volumio[1796]: info: Scanning category miscellanea May 24 03:17:05 volumio-bedroom volumio[1796]: info: Scanning category music_service May 24 03:17:05 volumio-bedroom volumio[1796]: info: Scanning category plugins.json May 24 03:17:05 volumio-bedroom volumio[1796]: info: Scanning category system_controller May 24 03:17:05 volumio-bedroom volumio[1796]: info: Scanning category user_interface May 24 03:17:05 volumio-bedroom volumio[1796]: info: Scanning into folder /data/plugins/ May 24 03:17:05 volumio-bedroom volumio[1796]: info: Scanning category music_service May 24 03:17:05 volumio-bedroom volumio[1796]: info: Scanning category user_interface May 24 03:17:05 volumio-bedroom volumio[1796]: info: Plugin folders cleanup completed May 24 03:17:05 volumio-bedroom volumio[1796]: info: ------------------------------------------- May 24 03:17:05 volumio-bedroom volumio[1796]: info: ----- Core plugins startup ---- May 24 03:17:05 volumio-bedroom volumio[1796]: info: ------------------------------------------- May 24 03:17:05 volumio-bedroom volumio[1796]: info: Loading plugins from folder /volumio/app/plugins/ May 24 03:17:05 volumio-bedroom volumio[1796]: info: Adding plugin upnp to MyMusic Plugins May 24 03:17:05 volumio-bedroom volumio[1796]: info: Adding plugin airplay_emulation to MyMusic Plugins May 24 03:17:05 volumio-bedroom volumio[1796]: info: Adding plugin upnp_browser to MyMusic Plugins May 24 03:17:05 volumio-bedroom volumio[1796]: info: Loading plugins from folder /data/plugins/ May 24 03:17:05 volumio-bedroom volumio[1796]: info: Loading plugin "system"... May 24 03:17:05 volumio-bedroom volumio[1796]: info: Loading plugin "appearance"... May 24 03:17:05 volumio-bedroom dhcpcd[798]: wlan0: soliciting an IPv6 router May 24 03:17:05 volumio-bedroom dhcpcd[798]: wlan0: rebinding lease of 192.168.3.67 May 24 03:17:05 volumio-bedroom volumio-remote-updater[531]: [2025-05-24 03:17:05] [connect] Successful connection May 24 03:17:07 volumio-bedroom volumio[1796]: info: Loading plugin "network"... May 24 03:17:07 volumio-bedroom volumio[1796]: info: Refreshing Cached IP Addresses May 24 03:17:07 volumio-bedroom sudo[1823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 03:17:07 volumio-bedroom sudo[1823]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:07 volumio-bedroom sudo[1825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 03:17:07 volumio-bedroom sudo[1825]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:07 volumio-bedroom sudo[1825]: pam_unix(sudo:session): session closed for user root May 24 03:17:07 volumio-bedroom sudo[1823]: pam_unix(sudo:session): session closed for user root May 24 03:17:07 volumio-bedroom volumio[1796]: info: Loading plugin "services"... May 24 03:17:07 volumio-bedroom volumio[1796]: info: Loading plugin "alsa_controller"... May 24 03:17:07 volumio-bedroom sudo[1836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 24 03:17:07 volumio-bedroom sudo[1836]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:07 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 03:17:07 volumio-bedroom volumio[1796]: info: Loading plugin "wizard"... May 24 03:17:07 volumio-bedroom volumio[1796]: info: Loading plugin "networkfs"... May 24 03:17:07 volumio-bedroom volumio[1796]: info: Starting Udev Watcher for removable devices May 24 03:17:07 volumio-bedroom volumio[1796]: info: Ignoring mount for partition: boot May 24 03:17:07 volumio-bedroom volumio[1796]: info: Ignoring mount for partition: volumio May 24 03:17:07 volumio-bedroom volumio[1796]: info: Ignoring mount for partition: volumio_data May 24 03:17:07 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 03:17:07 volumio-bedroom volumio[1796]: info: Loading plugin "volumio_command_line_client"... May 24 03:17:07 volumio-bedroom volumio[1796]: info: Loading plugin "upnp"... May 24 03:17:07 volumio-bedroom volumio[1796]: info: [1748071027981] Starting Upmpd Daemon May 24 03:17:07 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 03:17:07 volumio-bedroom volumio[1796]: info: Loading plugin "my_music"... May 24 03:17:07 volumio-bedroom volumio[1796]: info: Loading plugin "mpd"... May 24 03:17:08 volumio-bedroom volumio[1796]: info: Loading plugin "upnp_browser"... May 24 03:17:09 volumio-bedroom volumio[1796]: info: Loading plugin "alarm-clock"... May 24 03:17:09 volumio-bedroom volumio[1796]: info: Plugin airplay_emulation is not enabled May 24 03:17:09 volumio-bedroom volumio[1796]: info: Loading plugin "last_100"... May 24 03:17:09 volumio-bedroom volumio[1796]: info: Loading plugin "webradio"... May 24 03:17:09 volumio-bedroom volumio[1796]: info: Loading plugin "i2s_dacs"... May 24 03:17:09 volumio-bedroom volumio[1796]: info: I2S DAC not set, start Auto-detection May 24 03:17:09 volumio-bedroom volumio[1796]: info: Loading plugin "volumiodiscovery"... May 24 03:17:09 volumio-bedroom volumio[1796]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 03:17:09 volumio-bedroom volumio[1796]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 03:17:09 volumio-bedroom volumio[1796]: *** WARNING *** For more information see May 24 03:17:09 volumio-bedroom volumio[1796]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 03:17:09 volumio-bedroom node[1796]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 03:17:09 volumio-bedroom volumio[1796]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 03:17:09 volumio-bedroom volumio[1796]: *** WARNING *** For more information see May 24 03:17:09 volumio-bedroom node[1796]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 03:17:09 volumio-bedroom node[1796]: *** WARNING *** For more information see May 24 03:17:09 volumio-bedroom node[1796]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 03:17:09 volumio-bedroom node[1796]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 03:17:09 volumio-bedroom node[1796]: *** WARNING *** For more information see May 24 03:17:09 volumio-bedroom volumio[1796]: info: Applying required configuration parameters for plugin volumiodiscovery May 24 03:17:09 volumio-bedroom volumio[1796]: info: Discovery: Started advertising with name: Volumio-Bedroom May 24 03:17:09 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 03:17:09 volumio-bedroom volumio[1796]: info: Loading plugin "squeezelite_mc"... May 24 03:17:10 volumio-bedroom dhcpcd[798]: wlan0: probing for an IPv4LL address May 24 03:17:10 volumio-bedroom dhcpcd[798]: wlan0: DHCP lease expired May 24 03:17:11 volumio-bedroom dhcpcd[798]: wlan0: soliciting a DHCP lease May 24 03:17:11 volumio-bedroom volumio[1796]: info: Loading plugin "outputs"... May 24 03:17:11 volumio-bedroom volumio[1796]: info: Loading plugin "albumart"... May 24 03:17:11 volumio-bedroom volumio[1796]: info: Plugin example_plugin is not enabled May 24 03:17:11 volumio-bedroom volumio[1796]: info: Loading plugin "inputs"... May 24 03:17:11 volumio-bedroom volumio[1796]: info: Loading plugin "updater_comm"... May 24 03:17:11 volumio-bedroom volumio[1796]: info: Plugin mpdemulation is not enabled May 24 03:17:11 volumio-bedroom volumio[1796]: info: Loading plugin "rest_api"... May 24 03:17:11 volumio-bedroom volumio[1796]: info: Loading plugin "websocket"... May 24 03:17:11 volumio-bedroom volumio[1796]: info: Starting Socket.io Server version 2.3.0 May 24 03:17:11 volumio-bedroom sudo[1836]: pam_unix(sudo:session): session closed for user root May 24 03:17:11 volumio-bedroom volumio[1796]: info: Loading plugin "Systeminfo"... May 24 03:17:12 volumio-bedroom volumio[1796]: Forking 3 albumart workers May 24 03:17:12 volumio-bedroom volumio[1796]: info: Loading i18n strings for locale en May 24 03:17:12 volumio-bedroom volumio[1796]: Updating browse sources language May 24 03:17:12 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::initPlayerControls May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 03:17:13 volumio-bedroom volumio[1796]: Express server listening on port 3000 May 24 03:17:13 volumio-bedroom volumio[1796]: [Metrics] WebUI: 10s 294.98ms May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreStateMachine::resetVolumioState May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreStateMachine::getcurrentVolume May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::volumioRetrievevolume May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreStateMachine::pushState May 24 03:17:13 volumio-bedroom volumio[1796]: info: CorePlayQueue::getTrack 0 May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::volumioPushState May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 03:17:13 volumio-bedroom volumio[1796]: info: Volumio Network Manager: Network status updated: 0 May 24 03:17:13 volumio-bedroom volumio[1796]: info: Reloading queue from file May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreStateMachine::setRepeat null single undefined May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreStateMachine::pushState May 24 03:17:13 volumio-bedroom volumio[1796]: info: CorePlayQueue::getTrack 0 May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::volumioPushState May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreStateMachine::setRandom null May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreStateMachine::pushState May 24 03:17:13 volumio-bedroom volumio[1796]: info: CorePlayQueue::getTrack 0 May 24 03:17:13 volumio-bedroom volumio[1796]: info: CoreCommandRouter::volumioPushState May 24 03:17:13 volumio-bedroom volumio[1796]: info: Setting Device type: Raspberry PI May 24 03:17:13 volumio-bedroom volumio[1796]: info: Completed loading Core Plugins May 24 03:17:13 volumio-bedroom volumio[1796]: info: Preparing to generate the ALSA configuration file May 24 03:17:13 volumio-bedroom volumio[1796]: info: Asound.conf file unchanged, so no further update is needed May 24 03:17:13 volumio-bedroom volumio[1796]: info: Output device has changed, restarting MPD May 24 03:17:14 volumio-bedroom volumio[1796]: info: ___________ START PLUGINS ___________ May 24 03:17:14 volumio-bedroom sudo[1898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 03:17:14 volumio-bedroom sudo[1898]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:14 volumio-bedroom sudo[1898]: pam_unix(sudo:session): session closed for user root May 24 03:17:14 volumio-bedroom volumio[1796]: info: ControllerMpd::onStart: Initializing MPD May 24 03:17:14 volumio-bedroom volumio[1796]: info: Creating MPD Configuration file May 24 03:17:14 volumio-bedroom volumio[1796]: Starting albumart workers May 24 03:17:14 volumio-bedroom sudo[1900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 03:17:14 volumio-bedroom sudo[1900]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:14 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 03:17:14 volumio-bedroom volumio[1796]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 03:17:14 volumio-bedroom volumio[1796]: info: [1748071034152] CoreMusicLibrary::Adding element Media Servers May 24 03:17:14 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 03:17:14 volumio-bedroom sudo[1903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 03:17:14 volumio-bedroom sudo[1903]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:14 volumio-bedroom volumio[1796]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 03:17:14 volumio-bedroom volumio[1796]: info: [1748071034191] CoreMusicLibrary::Adding element Last_100 May 24 03:17:14 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 03:17:14 volumio-bedroom volumio[1796]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 03:17:14 volumio-bedroom volumio[1796]: info: [1748071034198] CoreMusicLibrary::Adding element Webradio May 24 03:17:14 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 03:17:14 volumio-bedroom systemd[1]: Stopping Music Player Daemon... May 24 03:17:14 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 03:17:14 volumio-bedroom sudo[1906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 03:17:14 volumio-bedroom sudo[1903]: pam_unix(sudo:session): session closed for user root May 24 03:17:14 volumio-bedroom sudo[1906]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:14 volumio-bedroom volumio[1796]: info: Initializing BBC Radios May 24 03:17:14 volumio-bedroom systemd[1]: mpd.service: Succeeded. May 24 03:17:14 volumio-bedroom systemd[1]: Stopped Music Player Daemon. May 24 03:17:14 volumio-bedroom volumio[1796]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory May 24 03:17:14 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 03:17:14 volumio-bedroom volumio[1796]: Starting albumart workers May 24 03:17:14 volumio-bedroom volumio[1796]: Starting albumart workers May 24 03:17:14 volumio-bedroom systemd[1]: Starting Music Player Daemon... May 24 03:17:14 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 03:17:14 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 03:17:14 volumio-bedroom volumio[1796]: info: [squeezelite_mc] Starting proxy server... May 24 03:17:14 volumio-bedroom volumio[1796]: info: Volumio Calling Home May 24 03:17:14 volumio-bedroom sudo[1910]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 24 03:17:14 volumio-bedroom sudo[1910]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:14 volumio-bedroom sudo[1910]: pam_unix(sudo:session): session closed for user root May 24 03:17:14 volumio-bedroom volumio[1796]: info: [squeezelite_mc] Proxy server started on port 44039 May 24 03:17:15 volumio-bedroom volumio[1796]: info: CoreCommandRouter::volumioRetrievevolume May 24 03:17:15 volumio-bedroom volumio[1796]: info: CoreStateMachine::pushState May 24 03:17:15 volumio-bedroom volumio[1796]: info: CorePlayQueue::getTrack 0 May 24 03:17:15 volumio-bedroom volumio[1796]: info: CoreCommandRouter::volumioPushState May 24 03:17:15 volumio-bedroom volumio[1796]: info: [squeezelite_mc] Server discovery started May 24 03:17:15 volumio-bedroom volumio[1796]: info: [squeezelite_mc] Player finder started May 24 03:17:15 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:15 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:15 volumio-bedroom volumio[1796]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 03:17:15 volumio-bedroom volumio[1796]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true May 24 03:17:15 volumio-bedroom volumio[1796]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 03:17:15 volumio-bedroom volumio[1796]: Error: send ENETUNREACH 255.255.255.255:3483 May 24 03:17:15 volumio-bedroom volumio[1796]: at doSend (dgram.js:692:16) May 24 03:17:15 volumio-bedroom volumio[1796]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 24 03:17:15 volumio-bedroom volumio[1796]: at afterDns (dgram.js:638:5) May 24 03:17:15 volumio-bedroom volumio[1796]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 24 03:17:15 volumio-bedroom volumio[1796]: errno: -101, May 24 03:17:15 volumio-bedroom volumio[1796]: code: 'ENETUNREACH', May 24 03:17:15 volumio-bedroom volumio[1796]: syscall: 'send', May 24 03:17:15 volumio-bedroom volumio[1796]: address: '255.255.255.255', May 24 03:17:15 volumio-bedroom volumio[1796]: port: 3483 May 24 03:17:15 volumio-bedroom volumio[1796]: } May 24 03:17:15 volumio-bedroom volumio[1796]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 03:17:16 volumio-bedroom dhcpcd[798]: wlan0: using IPv4LL address 169.254.250.27 May 24 03:17:16 volumio-bedroom avahi-daemon[533]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.250.27. May 24 03:17:16 volumio-bedroom avahi-daemon[533]: New relevant interface wlan0.IPv4 for mDNS. May 24 03:17:16 volumio-bedroom avahi-daemon[533]: Registering new address record for 169.254.250.27 on wlan0.IPv4. May 24 03:17:16 volumio-bedroom dhcpcd[798]: wlan0: adding route to 169.254.0.0/16 May 24 03:17:16 volumio-bedroom dhcpcd[798]: wlan0: adding default route May 24 03:17:16 volumio-bedroom sudo[1972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 03:16 May 24 03:17:16 volumio-bedroom sudo[1972]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:17 volumio-bedroom sudo[1972]: pam_unix(sudo:session): session closed for user root May 24 03:17:17 volumio-bedroom mpd[1924]: May 24 03:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 24 03:17:17 volumio-bedroom systemd[1]: Started Music Player Daemon. May 24 03:17:17 volumio-bedroom sudo[1900]: pam_unix(sudo:session): session closed for user root May 24 03:17:17 volumio-bedroom sudo[1906]: pam_unix(sudo:session): session closed for user root May 24 03:17:17 volumio-bedroom ntpd[686]: Listen normally on 34 wlan0 169.254.250.27:123 May 24 03:17:17 volumio-bedroom ntpd[686]: new interface(s) found: waking up resolver May 24 03:17:18 volumio-bedroom volumio-remote-updater[531]: [2025-05-24 03:17:18] [connect] Successful connection May 24 03:17:21 volumio-bedroom wpa_supplicant[784]: RRM: Ignoring radio measurement request: Not RRM network May 24 03:17:33 volumio-bedroom volumio-remote-updater[531]: [2025-05-24 03:17:33] [connect] Successful connection May 24 03:17:33 volumio-bedroom wpa_supplicant[784]: RRM: Ignoring radio measurement request: Not RRM network May 24 03:17:37 volumio-bedroom volumio-remote-updater[531]: [2025-05-24 03:17:37] [info] asio async_read_at_least error: system:104 (Connection reset by peer) May 24 03:17:37 volumio-bedroom volumio-remote-updater[531]: [2025-05-24 03:17:37] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) May 24 03:17:37 volumio-bedroom volumio-remote-updater[531]: [2025-05-24 03:17:37] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 24 03:17:37 volumio-bedroom systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 24 03:17:37 volumio-bedroom systemd[1]: volumio.service: Failed with result 'exit-code'. May 24 03:17:37 volumio-bedroom systemd[1]: Started dynamicswap service. May 24 03:17:37 volumio-bedroom systemd[1]: dynamicswap.service: Succeeded. May 24 03:17:37 volumio-bedroom systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 24 03:17:37 volumio-bedroom systemd[1]: volumio.service: Scheduled restart job, restart counter is at 70. May 24 03:17:37 volumio-bedroom systemd[1]: Stopped Volumio Backend Module. May 24 03:17:37 volumio-bedroom systemd[1]: Started Volumio Backend Module. May 24 03:17:37 volumio-bedroom systemd[1]: Started dynamicswap service. May 24 03:17:37 volumio-bedroom systemd[1]: dynamicswap.service: Succeeded. May 24 03:17:40 volumio-bedroom dhcpcd[798]: wlan0: carrier lost May 24 03:17:40 volumio-bedroom wpa_supplicant[784]: wlan0: Associated with 3a:de:4b:76:33:a4 May 24 03:17:40 volumio-bedroom wpa_supplicant[784]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3a:de:4b:76:33:a4 completed [id=0 id_str=] May 24 03:17:40 volumio-bedroom wpa_supplicant[784]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 24 03:17:40 volumio-bedroom avahi-daemon[533]: Withdrawing address record for 169.254.250.27 on wlan0. May 24 03:17:40 volumio-bedroom avahi-daemon[533]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.250.27. May 24 03:17:40 volumio-bedroom dhcpcd[798]: wlan0: deleting route to 169.254.0.0/16 May 24 03:17:40 volumio-bedroom dhcpcd[798]: wlan0: deleting default route May 24 03:17:40 volumio-bedroom avahi-daemon[533]: Interface wlan0.IPv4 no longer relevant for mDNS. May 24 03:17:40 volumio-bedroom dhcpcd[798]: wlan0: carrier acquired May 24 03:17:40 volumio-bedroom dhcpcd[798]: wlan0: IAID eb:35:96:7a May 24 03:17:41 volumio-bedroom volumio[2057]: info: ------------------------------------------- May 24 03:17:41 volumio-bedroom volumio[2057]: info: ----- Volumio3 ---- May 24 03:17:41 volumio-bedroom volumio[2057]: info: ------------------------------------------- May 24 03:17:41 volumio-bedroom volumio[2057]: info: ----- System startup ---- May 24 03:17:41 volumio-bedroom volumio[2057]: info: ------------------------------------------- May 24 03:17:41 volumio-bedroom dhcpcd[798]: wlan0: soliciting an IPv6 router May 24 03:17:41 volumio-bedroom dhcpcd[798]: wlan0: soliciting a DHCP lease May 24 03:17:41 volumio-bedroom ntpd[686]: Deleting interface #34 wlan0, 169.254.250.27#123, interface stats: received=0, sent=6, dropped=0, active_time=24 secs May 24 03:17:41 volumio-bedroom ntpd[686]: 74.208.14.149 local addr 169.254.250.27 -> May 24 03:17:41 volumio-bedroom ntpd[686]: 158.51.99.19 local addr 169.254.250.27 -> May 24 03:17:41 volumio-bedroom ntpd[686]: 45.79.13.35 local addr 169.254.250.27 -> May 24 03:17:41 volumio-bedroom ntpd[686]: 5.161.111.190 local addr 169.254.250.27 -> May 24 03:17:41 volumio-bedroom ntpd[686]: 159.203.82.102 local addr 169.254.250.27 -> May 24 03:17:41 volumio-bedroom ntpd[686]: 23.155.40.38 local addr 169.254.250.27 -> May 24 03:17:42 volumio-bedroom volumio[2057]: info: MYVOLUMIO Environment detected May 24 03:17:42 volumio-bedroom volumio[2057]: info: Plugin folders cleanup May 24 03:17:42 volumio-bedroom volumio[2057]: info: Scanning into folder /volumio/app/plugins/ May 24 03:17:42 volumio-bedroom volumio[2057]: info: Scanning category audio_interface May 24 03:17:42 volumio-bedroom volumio[2057]: info: Scanning category miscellanea May 24 03:17:42 volumio-bedroom volumio[2057]: info: Scanning category music_service May 24 03:17:42 volumio-bedroom volumio[2057]: info: Scanning category plugins.json May 24 03:17:42 volumio-bedroom volumio[2057]: info: Scanning category system_controller May 24 03:17:42 volumio-bedroom volumio[2057]: info: Scanning category user_interface May 24 03:17:42 volumio-bedroom volumio[2057]: info: Scanning into folder /data/plugins/ May 24 03:17:42 volumio-bedroom volumio[2057]: info: Scanning category music_service May 24 03:17:42 volumio-bedroom volumio[2057]: info: Scanning category user_interface May 24 03:17:42 volumio-bedroom volumio[2057]: info: Plugin folders cleanup completed May 24 03:17:42 volumio-bedroom volumio[2057]: info: ------------------------------------------- May 24 03:17:42 volumio-bedroom volumio[2057]: info: ----- Core plugins startup ---- May 24 03:17:42 volumio-bedroom volumio[2057]: info: ------------------------------------------- May 24 03:17:42 volumio-bedroom volumio[2057]: info: Loading plugins from folder /volumio/app/plugins/ May 24 03:17:42 volumio-bedroom volumio[2057]: info: Adding plugin upnp to MyMusic Plugins May 24 03:17:42 volumio-bedroom volumio[2057]: info: Adding plugin airplay_emulation to MyMusic Plugins May 24 03:17:42 volumio-bedroom volumio[2057]: info: Adding plugin upnp_browser to MyMusic Plugins May 24 03:17:42 volumio-bedroom volumio[2057]: info: Loading plugins from folder /data/plugins/ May 24 03:17:42 volumio-bedroom volumio[2057]: info: Loading plugin "system"... May 24 03:17:42 volumio-bedroom volumio[2057]: info: Loading plugin "appearance"... May 24 03:17:44 volumio-bedroom volumio[2057]: info: Loading plugin "network"... May 24 03:17:44 volumio-bedroom volumio[2057]: info: Refreshing Cached IP Addresses May 24 03:17:44 volumio-bedroom sudo[2115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 03:17:44 volumio-bedroom sudo[2115]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:44 volumio-bedroom sudo[2117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 03:17:44 volumio-bedroom sudo[2117]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:44 volumio-bedroom sudo[2117]: pam_unix(sudo:session): session closed for user root May 24 03:17:44 volumio-bedroom sudo[2115]: pam_unix(sudo:session): session closed for user root May 24 03:17:44 volumio-bedroom volumio[2057]: info: Loading plugin "services"... May 24 03:17:44 volumio-bedroom volumio[2057]: info: Loading plugin "alsa_controller"... May 24 03:17:44 volumio-bedroom sudo[2132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 24 03:17:44 volumio-bedroom sudo[2132]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:44 volumio-bedroom ntpd[686]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 24 03:17:44 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 03:17:44 volumio-bedroom volumio[2057]: info: Loading plugin "wizard"... May 24 03:17:44 volumio-bedroom volumio[2057]: info: Loading plugin "networkfs"... May 24 03:17:45 volumio-bedroom volumio[2057]: info: Starting Udev Watcher for removable devices May 24 03:17:45 volumio-bedroom volumio[2057]: info: Ignoring mount for partition: boot May 24 03:17:45 volumio-bedroom volumio[2057]: info: Ignoring mount for partition: volumio May 24 03:17:45 volumio-bedroom volumio[2057]: info: Ignoring mount for partition: volumio_data May 24 03:17:45 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 03:17:45 volumio-bedroom volumio[2057]: info: Loading plugin "volumio_command_line_client"... May 24 03:17:45 volumio-bedroom volumio[2057]: info: Loading plugin "upnp"... May 24 03:17:45 volumio-bedroom volumio[2057]: info: [1748071065096] Starting Upmpd Daemon May 24 03:17:45 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 03:17:45 volumio-bedroom volumio[2057]: info: Loading plugin "my_music"... May 24 03:17:45 volumio-bedroom volumio[2057]: info: Loading plugin "mpd"... May 24 03:17:45 volumio-bedroom dhcpcd[798]: wlan0: offered 192.168.3.67 from 192.168.0.1 May 24 03:17:45 volumio-bedroom volumio[2057]: info: Loading plugin "upnp_browser"... May 24 03:17:45 volumio-bedroom dhcpcd[798]: wlan0: probing address 192.168.3.67/22 May 24 03:17:45 volumio-bedroom ntpd[686]: Soliciting pool server 23.186.168.132 May 24 03:17:46 volumio-bedroom volumio[2057]: info: Loading plugin "alarm-clock"... May 24 03:17:46 volumio-bedroom wpa_supplicant[784]: RRM: Ignoring radio measurement request: Not RRM network May 24 03:17:46 volumio-bedroom volumio[2057]: info: Plugin airplay_emulation is not enabled May 24 03:17:46 volumio-bedroom volumio[2057]: info: Loading plugin "last_100"... May 24 03:17:46 volumio-bedroom volumio[2057]: info: Loading plugin "webradio"... May 24 03:17:46 volumio-bedroom volumio[2057]: info: Loading plugin "i2s_dacs"... May 24 03:17:46 volumio-bedroom volumio[2057]: info: I2S DAC not set, start Auto-detection May 24 03:17:46 volumio-bedroom volumio[2057]: info: Loading plugin "volumiodiscovery"... May 24 03:17:47 volumio-bedroom volumio[2057]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 03:17:47 volumio-bedroom volumio[2057]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 03:17:47 volumio-bedroom volumio[2057]: *** WARNING *** For more information see May 24 03:17:47 volumio-bedroom volumio[2057]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 03:17:47 volumio-bedroom node[2057]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 03:17:47 volumio-bedroom volumio[2057]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 03:17:47 volumio-bedroom volumio[2057]: *** WARNING *** For more information see May 24 03:17:47 volumio-bedroom node[2057]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 03:17:47 volumio-bedroom node[2057]: *** WARNING *** For more information see May 24 03:17:47 volumio-bedroom node[2057]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 03:17:47 volumio-bedroom node[2057]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 03:17:47 volumio-bedroom node[2057]: *** WARNING *** For more information see May 24 03:17:47 volumio-bedroom volumio[2057]: info: Applying required configuration parameters for plugin volumiodiscovery May 24 03:17:47 volumio-bedroom volumio[2057]: info: Discovery: Started advertising with name: Volumio-Bedroom May 24 03:17:47 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 03:17:47 volumio-bedroom volumio[2057]: info: Loading plugin "squeezelite_mc"... May 24 03:17:47 volumio-bedroom volumio-remote-updater[531]: [2025-05-24 03:17:47] [connect] Successful connection May 24 03:17:48 volumio-bedroom volumio[2057]: info: Loading plugin "outputs"... May 24 03:17:48 volumio-bedroom volumio[2057]: info: Loading plugin "albumart"... May 24 03:17:48 volumio-bedroom volumio[2057]: info: Plugin example_plugin is not enabled May 24 03:17:48 volumio-bedroom volumio[2057]: info: Loading plugin "inputs"... May 24 03:17:48 volumio-bedroom volumio[2057]: info: Loading plugin "updater_comm"... May 24 03:17:49 volumio-bedroom sudo[2132]: pam_unix(sudo:session): session closed for user root May 24 03:17:49 volumio-bedroom volumio[2057]: info: Plugin mpdemulation is not enabled May 24 03:17:49 volumio-bedroom volumio[2057]: info: Loading plugin "rest_api"... May 24 03:17:49 volumio-bedroom volumio[2057]: info: Loading plugin "websocket"... May 24 03:17:49 volumio-bedroom volumio[2057]: info: Starting Socket.io Server version 2.3.0 May 24 03:17:49 volumio-bedroom volumio[2057]: info: Loading plugin "Systeminfo"... May 24 03:17:49 volumio-bedroom volumio[2057]: Forking 3 albumart workers May 24 03:17:50 volumio-bedroom volumio[2057]: info: Loading i18n strings for locale en May 24 03:17:50 volumio-bedroom volumio[2057]: Updating browse sources language May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 03:17:50 volumio-bedroom nmbd[647]: [2025/05/24 03:17:50.448105, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) May 24 03:17:50 volumio-bedroom nmbd[647]: reload_interfaces: No subnets to listen to. Waiting.. May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreCommandRouter::initPlayerControls May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 03:17:50 volumio-bedroom volumio[2057]: Express server listening on port 3000 May 24 03:17:50 volumio-bedroom volumio[2057]: [Metrics] WebUI: 10s 914.22ms May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreStateMachine::resetVolumioState May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreStateMachine::getcurrentVolume May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioRetrievevolume May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreStateMachine::pushState May 24 03:17:50 volumio-bedroom volumio[2057]: info: CorePlayQueue::getTrack 0 May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioPushState May 24 03:17:50 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 03:17:50 volumio-bedroom volumio[2057]: info: Volumio Network Manager: Network status updated: 0 May 24 03:17:51 volumio-bedroom volumio-remote-updater[531]: [2025-05-24 03:17:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748071067 101 May 24 03:17:51 volumio-bedroom volumio[2057]: 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: 1 May 24 03:17:51 volumio-bedroom volumio[2057]: info: Reloading queue from file May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreStateMachine::setRepeat null single undefined May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreStateMachine::pushState May 24 03:17:51 volumio-bedroom volumio[2057]: info: CorePlayQueue::getTrack 0 May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioPushState May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreStateMachine::setRandom null May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreStateMachine::pushState May 24 03:17:51 volumio-bedroom volumio[2057]: info: CorePlayQueue::getTrack 0 May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioPushState May 24 03:17:51 volumio-bedroom volumio[2057]: info: Setting Device type: Raspberry PI May 24 03:17:51 volumio-bedroom volumio[2057]: info: Completed loading Core Plugins May 24 03:17:51 volumio-bedroom volumio[2057]: info: Preparing to generate the ALSA configuration file May 24 03:17:51 volumio-bedroom volumio[2057]: Starting albumart workers May 24 03:17:51 volumio-bedroom volumio[2057]: Starting albumart workers May 24 03:17:51 volumio-bedroom volumio[2057]: info: Asound.conf file unchanged, so no further update is needed May 24 03:17:51 volumio-bedroom volumio[2057]: info: Output device has changed, restarting MPD May 24 03:17:51 volumio-bedroom volumio[2057]: Starting albumart workers May 24 03:17:51 volumio-bedroom volumio[2057]: info: ___________ START PLUGINS ___________ May 24 03:17:51 volumio-bedroom volumio[2057]: info: ControllerMpd::onStart: Initializing MPD May 24 03:17:51 volumio-bedroom volumio[2057]: info: Creating MPD Configuration file May 24 03:17:51 volumio-bedroom sudo[2184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 03:17:51 volumio-bedroom sudo[2184]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:51 volumio-bedroom sudo[2184]: pam_unix(sudo:session): session closed for user root May 24 03:17:51 volumio-bedroom sudo[2186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 03:17:51 volumio-bedroom sudo[2186]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 03:17:51 volumio-bedroom systemd[1]: Stopping Music Player Daemon... May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 03:17:51 volumio-bedroom volumio[2057]: info: [1748071071591] CoreMusicLibrary::Adding element Media Servers May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 03:17:51 volumio-bedroom dhcpcd[798]: wlan0: leased 192.168.3.67 for 7200 seconds May 24 03:17:51 volumio-bedroom avahi-daemon[533]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.67. May 24 03:17:51 volumio-bedroom dhcpcd[798]: wlan0: adding route to 192.168.0.0/22 May 24 03:17:51 volumio-bedroom dhcpcd[798]: wlan0: adding default route via 192.168.0.1 May 24 03:17:51 volumio-bedroom avahi-daemon[533]: New relevant interface wlan0.IPv4 for mDNS. May 24 03:17:51 volumio-bedroom avahi-daemon[533]: Registering new address record for 192.168.3.67 on wlan0.IPv4. May 24 03:17:51 volumio-bedroom sudo[2189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 03:17:51 volumio-bedroom sudo[2189]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:51 volumio-bedroom systemd[1]: mpd.service: Succeeded. May 24 03:17:51 volumio-bedroom sudo[2189]: pam_unix(sudo:session): session closed for user root May 24 03:17:51 volumio-bedroom systemd[1]: Stopped Music Player Daemon. May 24 03:17:51 volumio-bedroom sudo[2192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 03:17:51 volumio-bedroom sudo[2192]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 03:17:51 volumio-bedroom volumio[2057]: info: [1748071071722] CoreMusicLibrary::Adding element Last_100 May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 03:17:51 volumio-bedroom systemd[1]: Starting Music Player Daemon... May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 03:17:51 volumio-bedroom volumio[2057]: info: [1748071071758] CoreMusicLibrary::Adding element Webradio May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 03:17:51 volumio-bedroom systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 24 03:17:51 volumio-bedroom systemd[1]: mpd.service: Succeeded. May 24 03:17:51 volumio-bedroom volumio[2057]: info: Initializing BBC Radios May 24 03:17:51 volumio-bedroom systemd[1]: Stopped Music Player Daemon. May 24 03:17:51 volumio-bedroom systemd[1]: Starting Music Player Daemon... May 24 03:17:51 volumio-bedroom volumio[2057]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 03:17:51 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 03:17:52 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Starting proxy server... May 24 03:17:52 volumio-bedroom volumio[2057]: info: Volumio Calling Home May 24 03:17:52 volumio-bedroom sudo[2218]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 24 03:17:52 volumio-bedroom sudo[2218]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:52 volumio-bedroom sudo[2218]: pam_unix(sudo:session): session closed for user root May 24 03:17:52 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Proxy server started on port 39367 May 24 03:17:52 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioRetrievevolume May 24 03:17:52 volumio-bedroom volumio[2057]: info: CoreStateMachine::pushState May 24 03:17:52 volumio-bedroom volumio[2057]: info: CorePlayQueue::getTrack 0 May 24 03:17:52 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioPushState May 24 03:17:52 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Server discovery started May 24 03:17:52 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Player finder started May 24 03:17:52 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:52 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:52 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 03:17:52 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true May 24 03:17:52 volumio-bedroom ntpd[686]: Listen normally on 35 wlan0 192.168.3.67:123 May 24 03:17:52 volumio-bedroom ntpd[686]: new interface(s) found: waking up resolver May 24 03:17:53 volumio-bedroom volumio[2057]: info: MPD Permissions set May 24 03:17:53 volumio-bedroom volumio[2057]: info: MPD Permissions set May 24 03:17:53 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.3.61","name":"pCP-LMS","ver":"7.999.999","uuid":"3a9627f6-390a-4012-9749-e98428a5ceae","jsonPort":"9000","cliPort":"9090"} May 24 03:17:53 volumio-bedroom volumio[2057]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 24 03:17:53 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"] May 24 03:17:53 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"lms-bedroom","dsdFormat":null,"card":"1","mixerType":"None","mixer":null} May 24 03:17:53 volumio-bedroom volumio[2057]: 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 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 03:17:53 volumio-bedroom sudo[2251]: 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 24 03:17:53 volumio-bedroom sudo[2251]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:53 volumio-bedroom sudo[2251]: pam_unix(sudo:session): session closed for user root May 24 03:17:53 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Executing alsactl -L -R nrestore May 24 03:17:53 volumio-bedroom volumio[2057]: info: Volumio called home May 24 03:17:53 volumio-bedroom sudo[2275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore May 24 03:17:53 volumio-bedroom sudo[2275]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:53 volumio-bedroom volumio[2057]: info: Discovery: adding d7f7d63e-3ac3-4d8b-a175-7beac8d6b053 May 24 03:17:53 volumio-bedroom volumio[2057]: info: Discovery: Found device Volumio-Living May 24 03:17:53 volumio-bedroom volumio[2057]: info: Discovery: Connecting to remote: 169.254.34.138 May 24 03:17:53 volumio-bedroom volumio[2057]: info: Discovery: adding a6c0b88c-f8eb-4abe-9736-907f00d016d3 May 24 03:17:53 volumio-bedroom volumio[2057]: info: Discovery: Found device Volumio-Bedroom May 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioGetState May 24 03:17:53 volumio-bedroom volumio[2057]: info: CorePlayQueue::getTrack 0 May 24 03:17:53 volumio-bedroom sudo[2275]: pam_unix(sudo:session): session closed for user root May 24 03:17:53 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioGetState May 24 03:17:53 volumio-bedroom volumio[2057]: info: CorePlayQueue::getTrack 0 May 24 03:17:54 volumio-bedroom volumio[2057]: 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 24 03:17:54 volumio-bedroom sudo[2279]: 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 24 03:17:54 volumio-bedroom sudo[2279]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:54 volumio-bedroom sudo[2279]: pam_unix(sudo:session): session closed for user root May 24 03:17:54 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true May 24 03:17:54 volumio-bedroom sudo[2282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 24 03:17:54 volumio-bedroom sudo[2282]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:54 volumio-bedroom sudo[2282]: pam_unix(sudo:session): session closed for user root May 24 03:17:54 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 24 03:17:54 volumio-bedroom sudo[2286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 24 03:17:54 volumio-bedroom sudo[2286]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:54 volumio-bedroom sudo[2286]: pam_unix(sudo:session): session closed for user root May 24 03:17:54 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true May 24 03:17:54 volumio-bedroom sudo[2289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite May 24 03:17:54 volumio-bedroom sudo[2289]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:54 volumio-bedroom systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... May 24 03:17:54 volumio-bedroom systemd[1]: squeezelite.service: Succeeded. May 24 03:17:54 volumio-bedroom systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. May 24 03:17:54 volumio-bedroom sudo[2289]: pam_unix(sudo:session): session closed for user root May 24 03:17:54 volumio-bedroom volumio[2057]: info: Volumio Network Manager: Network status updated: 2 May 24 03:17:55 volumio-bedroom sudo[2306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 03:17:55 volumio-bedroom sudo[2306]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:55 volumio-bedroom sudo[2308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 03:17:55 volumio-bedroom sudo[2308]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:55 volumio-bedroom sudo[2306]: pam_unix(sudo:session): session closed for user root May 24 03:17:55 volumio-bedroom sudo[2308]: pam_unix(sudo:session): session closed for user root May 24 03:17:55 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Notification listener started May 24 03:17:55 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Getting players connected to pCP-LMS (192.168.3.61) May 24 03:17:55 volumio-bedroom sudo[2314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 24 03:17:55 volumio-bedroom sudo[2314]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:55 volumio-bedroom sudo[2314]: pam_unix(sudo:session): session closed for user root May 24 03:17:55 volumio-bedroom volumio[2057]: info: Upmpdcli Daemon Started May 24 03:17:55 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Players connected to pCP-LMS (192.168.3.61): [] May 24 03:17:55 volumio-bedroom mpd[2231]: May 24 03:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 24 03:17:55 volumio-bedroom systemd[1]: Started Music Player Daemon. May 24 03:17:55 volumio-bedroom sudo[2186]: pam_unix(sudo:session): session closed for user root May 24 03:17:55 volumio-bedroom sudo[2192]: pam_unix(sudo:session): session closed for user root May 24 03:17:55 volumio-bedroom volumio[2057]: error: MPD error: The expression evaluated to a falsy value: May 24 03:17:55 volumio-bedroom volumio[2057]: assert.ok(self.idling) May 24 03:17:55 volumio-bedroom volumio[2057]: error: The expression evaluated to a falsy value: May 24 03:17:55 volumio-bedroom volumio[2057]: assert.ok(self.idling) May 24 03:17:55 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 24 03:17:55 volumio-bedroom volumio[2057]: error: updateQueue error: null May 24 03:17:55 volumio-bedroom volumio[2057]: info: MPD running with PID2231 May 24 03:17:55 volumio-bedroom volumio[2057]: ,establishing connection May 24 03:17:55 volumio-bedroom volumio[2057]: error: updateQueue error: null May 24 03:17:55 volumio-bedroom sudo[2321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 24 03:17:55 volumio-bedroom sudo[2321]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:55 volumio-bedroom sudo[2321]: pam_unix(sudo:session): session closed for user root May 24 03:17:55 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log May 24 03:17:55 volumio-bedroom sudo[2325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log May 24 03:17:55 volumio-bedroom sudo[2325]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:55 volumio-bedroom sudo[2325]: pam_unix(sudo:session): session closed for user root May 24 03:17:55 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true May 24 03:17:55 volumio-bedroom sudo[2328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite May 24 03:17:55 volumio-bedroom sudo[2328]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:56 volumio-bedroom systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... May 24 03:17:56 volumio-bedroom systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. May 24 03:17:56 volumio-bedroom sudo[2328]: pam_unix(sudo:session): session closed for user root May 24 03:17:56 volumio-bedroom volumio[2057]: info: [squeezelite_mc] 'client' notification received from pCP-LMS (192.168.3.61); type is 'connect' May 24 03:17:56 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Getting players connected to pCP-LMS (192.168.3.61) May 24 03:17:56 volumio-bedroom wpa_supplicant[784]: RRM: Ignoring radio measurement request: Not RRM network May 24 03:17:56 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Players connected to pCP-LMS (192.168.3.61): [{"id":"b8:27:eb:35:96:7a","uuid":null,"ip":"192.168.3.67","name":"lms-bedroom","server":{"ip":"192.168.3.61","name":"pCP-LMS","ver":"7.999.999","uuid":"3a9627f6-390a-4012-9749-e98428a5ceae","jsonPort":"9000","cliPort":"9090"}}] May 24 03:17:56 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Player found: {"id":"b8:27:eb:35:96:7a","uuid":null,"ip":"192.168.3.67","name":"lms-bedroom","server":{"ip":"192.168.3.61","name":"pCP-LMS","ver":"7.999.999","uuid":"3a9627f6-390a-4012-9749-e98428a5ceae","jsonPort":"9000","cliPort":"9090"}} May 24 03:17:56 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 24 03:17:56 volumio-bedroom sudo[2335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 24 03:17:56 volumio-bedroom sudo[2335]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:56 volumio-bedroom sudo[2335]: pam_unix(sudo:session): session closed for user root May 24 03:17:57 volumio-bedroom ntpd[686]: Soliciting pool server 198.137.202.32 May 24 03:17:57 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 24 03:17:57 volumio-bedroom sudo[2338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 24 03:17:57 volumio-bedroom sudo[2338]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:57 volumio-bedroom sudo[2338]: pam_unix(sudo:session): session closed for user root May 24 03:17:57 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 24 03:17:57 volumio-bedroom ntpd[686]: Soliciting pool server 208.88.126.235 May 24 03:17:57 volumio-bedroom sudo[2341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 24 03:17:57 volumio-bedroom sudo[2341]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:57 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioGetState May 24 03:17:57 volumio-bedroom volumio[2057]: info: CorePlayQueue::getTrack 0 May 24 03:17:57 volumio-bedroom sudo[2341]: pam_unix(sudo:session): session closed for user root May 24 03:17:58 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 24 03:17:58 volumio-bedroom sudo[2344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 24 03:17:58 volumio-bedroom sudo[2344]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:58 volumio-bedroom sudo[2344]: pam_unix(sudo:session): session closed for user root May 24 03:17:59 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 24 03:17:59 volumio-bedroom sudo[2347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 24 03:17:59 volumio-bedroom sudo[2347]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:17:59 volumio-bedroom sudo[2347]: pam_unix(sudo:session): session closed for user root May 24 03:17:59 volumio-bedroom volumio[2057]: info: Completed starting Core Plugins May 24 03:17:59 volumio-bedroom volumio[2057]: info: ------------------------------------------- May 24 03:17:59 volumio-bedroom volumio[2057]: info: ----- MyVolumio plugins startup ---- May 24 03:17:59 volumio-bedroom volumio[2057]: info: ------------------------------------------- May 24 03:17:59 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Fetching plans data.... May 24 03:18:00 volumio-bedroom volumio[2057]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 24 03:18:00 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioGetState May 24 03:18:00 volumio-bedroom volumio[2057]: info: CorePlayQueue::getTrack 0 May 24 03:18:00 volumio-bedroom volumio[2057]: info: Listing playlists May 24 03:18:00 volumio-bedroom volumio[2057]: info: Listing playlists May 24 03:18:02 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioGetState May 24 03:18:02 volumio-bedroom volumio[2057]: info: CorePlayQueue::getTrack 0 May 24 03:18:02 volumio-bedroom volumio[2057]: verbose: New Socket.io Connection to 192.168.3.67:3000 from 192.168.3.65 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 24 03:18:02 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 24 03:18:02 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioGetState May 24 03:18:02 volumio-bedroom volumio[2057]: info: CorePlayQueue::getTrack 0 May 24 03:18:02 volumio-bedroom volumio[2057]: info: [squeezelite_mc] 'client' notification received from pCP-LMS (192.168.3.61); type is 'connect' May 24 03:18:02 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Getting players connected to pCP-LMS (192.168.3.61) May 24 03:18:02 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Players connected to pCP-LMS (192.168.3.61): [{"id":"b8:27:eb:35:96:7a","uuid":null,"ip":"192.168.3.67","name":"lms-bedroom","server":{"ip":"192.168.3.61","name":"pCP-LMS","ver":"7.999.999","uuid":"3a9627f6-390a-4012-9749-e98428a5ceae","jsonPort":"9000","cliPort":"9090"}}] May 24 03:18:03 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioGetState May 24 03:18:03 volumio-bedroom volumio[2057]: info: CorePlayQueue::getTrack 0 May 24 03:18:06 volumio-bedroom wpa_supplicant[784]: RRM: Ignoring radio measurement request: Not RRM network May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 24 03:18:07 volumio-bedroom volumio[2057]: info: Adding plugin bluetooth to MyMusic Plugins May 24 03:18:07 volumio-bedroom volumio[2057]: info: Adding plugin multiroom to MyMusic Plugins May 24 03:18:07 volumio-bedroom volumio[2057]: info: Adding plugin metavolumio to MyMusic Plugins May 24 03:18:07 volumio-bedroom volumio[2057]: info: Adding plugin cd_controller to MyMusic Plugins May 24 03:18:07 volumio-bedroom volumio[2057]: info: Adding plugin smart_inputs to MyMusic Plugins May 24 03:18:07 volumio-bedroom volumio[2057]: info: Adding plugin tidalconnect to MyMusic Plugins May 24 03:18:07 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 24 03:18:10 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 24 03:18:10 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 24 03:18:10 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 03:18:10 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 03:18:10 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 03:18:10 volumio-bedroom volumio[2057]: info: Starting MyVolumio Remote Streaming Endpoints May 24 03:18:10 volumio-bedroom volumio[2057]: info: MyVolumio login type: Token May 24 03:18:10 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 24 03:18:10 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 24 03:18:10 volumio-bedroom ntpd[686]: Soliciting pool server 23.111.186.186 May 24 03:18:13 volumio-bedroom volumio[2057]: info: Starting Streaming Service Transparent Proxy May 24 03:18:13 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 24 03:18:13 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 24 03:18:13 volumio-bedroom volumio[2057]: info: Streaming services startup May 24 03:18:13 volumio-bedroom volumio[2057]: info: Starting Streaming Daemon May 24 03:18:13 volumio-bedroom sudo[2375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 24 03:18:13 volumio-bedroom sudo[2375]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 03:18:13 volumio-bedroom volumio[2057]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 24 03:18:13 volumio-bedroom sudo[2375]: pam_unix(sudo:session): session closed for user root May 24 03:18:13 volumio-bedroom volumio[2057]: error: Cannot start Volumio Streaming Daemon May 24 03:18:13 volumio-bedroom volumio[2057]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 24 03:18:13 volumio-bedroom volumio[2057]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 24 03:18:13 volumio-bedroom volumio[2057]: info: CoreCommandRouter::volumioGetState May 24 03:18:13 volumio-bedroom volumio[2057]: info: CorePlayQueue::getTrack 0 May 24 03:18:13 volumio-bedroom volumio[2057]: error: MyVolumio Custom Token format not valid, refreshing it May 24 03:18:14 volumio-bedroom volumio[2057]: info: [squeezelite_mc] 'client' notification received from pCP-LMS (192.168.3.61); type is 'connect' May 24 03:18:14 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Getting players connected to pCP-LMS (192.168.3.61) May 24 03:18:14 volumio-bedroom volumio[2057]: info: [squeezelite_mc] Players connected to pCP-LMS (192.168.3.61): [{"id":"b8:27:eb:53:22:24","uuid":null,"ip":"192.168.3.66","name":"lms-office","server":{"ip":"192.168.3.61","name":"pCP-LMS","ver":"7.999.999","uuid":"3a9627f6-390a-4012-9749-e98428a5ceae","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:35:96:7a","uuid":null,"ip":"192.168.3.67","name":"lms-bedroom","server":{"ip":"192.168.3.61","name":"pCP-LMS","ver":"7.999.999","uuid":"3a9627f6-390a-4012-9749-e98428a5ceae","jsonPort":"9000","cliPort":"9090"}}] May 24 03:18:14 volumio-bedroom volumio[2057]: info: MyVolumio login type: Token May 24 03:18:15 volumio-bedroom volumio[2057]: info: Discovery: adding dc8c9251-3a0a-4ae7-bee2-b890c2987eb3 May 24 03:18:15 volumio-bedroom volumio[2057]: info: Discovery: Found device Volumio-Office May 24 03:18:15 volumio-bedroom volumio[2057]: info: Discovery: Connecting to remote: 192.168.3.66 May 24 03:18:15 volumio-bedroom volumio[2057]: STREAMING PROXY: Starting server on port 3245 May 24 03:18:15 volumio-bedroom volumio[2057]: Node JS runtime: 14 May 24 03:18:16 volumio-bedroom volumio[2057]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 24 03:18:16 volumio-bedroom wpa_supplicant[784]: RRM: Ignoring radio measurement request: Not RRM network May 24 03:18:16 volumio-bedroom dhcpcd[798]: wlan0: carrier lost May 24 03:18:16 volumio-bedroom wpa_supplicant[784]: wlan0: Associated with 3e:de:4b:76:33:a5 May 24 03:18:16 volumio-bedroom wpa_supplicant[784]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3e:de:4b:76:33:a5 completed [id=0 id_str=] May 24 03:18:16 volumio-bedroom wpa_supplicant[784]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 24 03:18:16 volumio-bedroom avahi-daemon[533]: Withdrawing address record for 192.168.3.67 on wlan0. May 24 03:18:16 volumio-bedroom avahi-daemon[533]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.67. May 24 03:18:16 volumio-bedroom dhcpcd[798]: wlan0: deleting route to 192.168.0.0/22 May 24 03:18:16 volumio-bedroom dhcpcd[798]: wlan0: deleting default route via 192.168.0.1 May 24 03:18:16 volumio-bedroom avahi-daemon[533]: Interface wlan0.IPv4 no longer relevant for mDNS. May 24 03:18:16 volumio-bedroom volumio[2057]: info: Discovery: A device disappeared from network May 24 03:18:16 volumio-bedroom volumio[2057]: info: Discovery: Device volumio-office disappeared from network May 24 03:18:16 volumio-bedroom volumio[2057]: info: Discovery: A device disappeared from network May 24 03:18:16 volumio-bedroom volumio[2057]: info: Discovery: Device volumio-bedroom disappeared from network May 24 03:18:16 volumio-bedroom volumio[2057]: info: Discovery: A device disappeared from network May 24 03:18:16 volumio-bedroom volumio[2057]: info: Discovery: Device volumio-living disappeared from network May 24 03:18:16 volumio-bedroom dhcpcd[798]: wlan0: carrier acquired May 24 03:18:16 volumio-bedroom dhcpcd[798]: wlan0: IAID eb:35:96:7a May 24 03:18:17 volumio-bedroom dhcpcd[798]: wlan0: rebinding lease of 192.168.3.67 May 24 03:18:17 volumio-bedroom dhcpcd[798]: wlan0: probing address 192.168.3.67/22 May 24 03:18:17 volumio-bedroom dhcpcd[798]: wlan0: soliciting an IPv6 router May 24 03:18:17 volumio-bedroom ntpd[686]: Deleting interface #35 wlan0, 192.168.3.67#123, interface stats: received=38, sent=40, dropped=0, active_time=25 secs May 24 03:18:17 volumio-bedroom ntpd[686]: 198.137.202.32 local addr 192.168.3.67 -> May 24 03:18:17 volumio-bedroom ntpd[686]: 74.208.14.149 local addr 192.168.3.67 -> May 24 03:18:17 volumio-bedroom ntpd[686]: 158.51.99.19 local addr 192.168.3.67 -> May 24 03:18:17 volumio-bedroom ntpd[686]: 45.79.13.35 local addr 192.168.3.67 -> May 24 03:18:17 volumio-bedroom ntpd[686]: 5.161.111.190 local addr 192.168.3.67 -> May 24 03:18:17 volumio-bedroom ntpd[686]: 159.203.82.102 local addr 192.168.3.67 -> May 24 03:18:17 volumio-bedroom ntpd[686]: 23.155.40.38 local addr 192.168.3.67 -> May 24 03:18:21 volumio-bedroom wpa_supplicant[784]: RRM: Ignoring radio measurement request: Not RRM network May 24 03:18:22 volumio-bedroom volumio[2057]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory May 24 03:18:22 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 03:18:22 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 03:18:22 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 03:18:22 volumio-bedroom volumio[2057]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 24 03:18:22 volumio-bedroom volumio[2057]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 03:18:22 volumio-bedroom volumio[2057]: Error: send ENETUNREACH 255.255.255.255:3483 May 24 03:18:22 volumio-bedroom volumio[2057]: at doSend (dgram.js:692:16) May 24 03:18:22 volumio-bedroom volumio[2057]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 24 03:18:22 volumio-bedroom volumio[2057]: at afterDns (dgram.js:638:5) May 24 03:18:22 volumio-bedroom volumio[2057]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 24 03:18:22 volumio-bedroom volumio[2057]: errno: -101, May 24 03:18:22 volumio-bedroom volumio[2057]: code: 'ENETUNREACH', May 24 03:18:22 volumio-bedroom volumio[2057]: syscall: 'send', May 24 03:18:22 volumio-bedroom volumio[2057]: address: '255.255.255.255', May 24 03:18:22 volumio-bedroom volumio[2057]: port: 3483 May 24 03:18:22 volumio-bedroom volumio[2057]: } May 24 03:18:22 volumio-bedroom volumio[2057]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 03:18:23 volumio-bedroom dhcpcd[798]: wlan0: leased 192.168.3.67 for 7200 seconds May 24 03:18:23 volumio-bedroom avahi-daemon[533]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.67. May 24 03:18:23 volumio-bedroom avahi-daemon[533]: New relevant interface wlan0.IPv4 for mDNS. May 24 03:18:23 volumio-bedroom dhcpcd[798]: wlan0: adding route to 192.168.0.0/22 May 24 03:18:23 volumio-bedroom avahi-daemon[533]: Registering new address record for 192.168.3.67 on wlan0.IPv4. May 24 03:18:23 volumio-bedroom dhcpcd[798]: wlan0: adding default route via 192.168.0.1 May 24 03:18:23 volumio-bedroom sudo[2461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 03:17 May 24 03:18:23 volumio-bedroom sudo[2461]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"