-- Logs begin at Fri 2025-02-28 10:46:41 CET, end at Sun 2025-03-02 10:00:21 CET. -- Mar 02 09:59:10 volumio volumio-remote-updater[658]: [2025-03-02 09:59:10] [connect] Successful connection Mar 02 09:59:16 volumio volumio[5460]: info: Cannot mount NAS music at system boot, trial number 2 ,retrying in 5 seconds Mar 02 09:59:16 volumio sudo[5806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 02 09:59:16 volumio sudo[5806]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 09:59:16 volumio volumio[5460]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Mar 02 09:59:16 volumio volumio[5460]: error: Plugin system_controller volumio_command_line_client failed to complete 'onVolumioStart' in a timely fashion Mar 02 09:59:16 volumio volumio[5460]: error: Plugin system_controller i2s_dacs failed to complete 'onVolumioStart' in a timely fashion Mar 02 09:59:16 volumio volumio[5460]: info: Preparing to generate the ALSA configuration file Mar 02 09:59:16 volumio sudo[5806]: pam_unix(sudo:session): session closed for user root Mar 02 09:59:16 volumio sudo[5808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 09:59:16 volumio sudo[5808]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 09:59:16 volumio sudo[5808]: pam_unix(sudo:session): session closed for user root Mar 02 09:59:16 volumio volumio[5460]: info: Reloading queue from file Mar 02 09:59:16 volumio volumio[5460]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Paul%20Hardcastle/Jazzmasters%204/e06493b9-e7bd-466b-bcca-710965d5b2cb.jpg' Mar 02 09:59:16 volumio volumio[5460]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Jennie%20L%C3%B6fgren/Meant%20to%20Be/f917caf9-3301-4e0d-92c0-bf49359313bd.png' Mar 02 09:59:16 volumio volumio[5460]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Paul%20Hardcastle/Jazzmasters%203/7b1ab62e-42a5-416b-aad9-bdc239add5d3.jpg' Mar 02 09:59:16 volumio volumio[5460]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Paul%20Hardcastle/The%20Chill%20Lounge%20Vol%201/42d295b6-b323-49e5-b527-b2fac0f9332b.jpg' Mar 02 09:59:16 volumio volumio[5460]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Paul%20Hardcastle/Jazzmasters%20V/79fa9ed8-85d9-483b-8175-c14c739ed58d.jpg' Mar 02 09:59:16 volumio volumio[5460]: info: VolumeController:: Volume=65 Mute =false Mar 02 09:59:16 volumio volumio[5460]: info: CoreStateMachine::pushState Mar 02 09:59:16 volumio volumio[5460]: info: CorePlayQueue::getTrack 0 Mar 02 09:59:16 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 09:59:16 volumio volumio[5460]: info: CoreCommandRouter::volumioPushState Mar 02 09:59:16 volumio volumio[5460]: info: CoreStateMachine::updateTrackBlock Mar 02 09:59:16 volumio volumio[5460]: info: CorePlayQueue::getTrackBlock Mar 02 09:59:16 volumio volumio[5460]: info: CoreCommandRouter::volumioRetrievevolume Mar 02 09:59:16 volumio volumio[5460]: info: CoreStateMachine::setRepeat false single undefined Mar 02 09:59:16 volumio volumio[5460]: info: CoreStateMachine::pushState Mar 02 09:59:16 volumio volumio[5460]: info: CorePlayQueue::getTrack 0 Mar 02 09:59:16 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 09:59:16 volumio volumio[5460]: info: CoreCommandRouter::volumioPushState Mar 02 09:59:16 volumio volumio[5460]: info: CoreStateMachine::setRandom false Mar 02 09:59:16 volumio volumio[5460]: info: CoreStateMachine::pushState Mar 02 09:59:16 volumio volumio[5460]: info: CorePlayQueue::getTrack 0 Mar 02 09:59:16 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 09:59:16 volumio volumio[5460]: info: CoreCommandRouter::volumioPushState Mar 02 09:59:16 volumio volumio[5460]: info: Setting Device type: Raspberry PI Mar 02 09:59:16 volumio volumio[5460]: info: Asound.conf file unchanged, so no further update is needed Mar 02 09:59:16 volumio volumio[5460]: info: Output device has changed, restarting MPD Mar 02 09:59:16 volumio volumio[5460]: info: Output device has changed, restarting Shairport Sync Mar 02 09:59:16 volumio sudo[5819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 02 09:59:16 volumio sudo[5819]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 09:59:16 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:16 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 09:59:16 volumio sudo[5819]: pam_unix(sudo:session): session closed for user root Mar 02 09:59:16 volumio sudo[5821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 02 09:59:16 volumio sudo[5821]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 09:59:17 volumio systemd[1]: Stopping Music Player Daemon... Mar 02 09:59:17 volumio volumio[5460]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 02 09:59:17 volumio systemd[1]: mpd.service: Succeeded. Mar 02 09:59:17 volumio systemd[1]: Stopped Music Player Daemon. Mar 02 09:59:17 volumio volumio[5460]: info: ___________ START PLUGINS ___________ Mar 02 09:59:17 volumio volumio[5460]: info: ControllerMpd::onStart: Initializing MPD Mar 02 09:59:17 volumio volumio[5460]: info: Creating MPD Configuration file Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 09:59:17 volumio systemd[1]: Starting Music Player Daemon... Mar 02 09:59:17 volumio sudo[5829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 02 09:59:17 volumio sudo[5829]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 09:59:17 volumio sudo[5829]: pam_unix(sudo:session): session closed for user root Mar 02 09:59:17 volumio sudo[5831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 02 09:59:17 volumio sudo[5831]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 09:59:17 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 02 09:59:17 volumio systemd[1]: mpd.service: Succeeded. Mar 02 09:59:17 volumio systemd[1]: Stopped Music Player Daemon. Mar 02 09:59:17 volumio systemd[1]: Starting Music Player Daemon... Mar 02 09:59:17 volumio volumio[5460]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 02 09:59:17 volumio volumio[5460]: info: [1740905957229] CoreMusicLibrary::Adding element Last_100 Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 02 09:59:17 volumio volumio[5460]: info: [1740905957235] CoreMusicLibrary::Adding element Webradio Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 02 09:59:17 volumio volumio[5460]: info: Initializing BBC Radios Mar 02 09:59:17 volumio sudo[5838]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 02 09:59:17 volumio sudo[5838]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 09:59:17 volumio sudo[5838]: pam_unix(sudo:session): session closed for user root Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: Volumio Calling Home Mar 02 09:59:17 volumio volumio[5460]: info: MPD Permissions set Mar 02 09:59:17 volumio volumio[5460]: info: MPD Permissions set Mar 02 09:59:17 volumio volumio[5460]: info: VolumeController:: Volume=65 Mute =false Mar 02 09:59:17 volumio volumio[5460]: info: CoreStateMachine::pushState Mar 02 09:59:17 volumio volumio[5460]: info: CorePlayQueue::getTrack 0 Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::volumioPushState Mar 02 09:59:17 volumio volumio[5460]: info: Volumio called home Mar 02 09:59:17 volumio sudo[5858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 02 09:59:17 volumio sudo[5858]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 09:59:17 volumio volumio[5460]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 02 09:59:17 volumio sudo[5858]: pam_unix(sudo:session): session closed for user root Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 09:59:17 volumio volumio[5460]: info: Discovery: adding 3b7d3e81-c4fa-470e-aeb9-8a884bfcfd3d Mar 02 09:59:17 volumio volumio[5460]: info: Discovery: Found device Volumio Mar 02 09:59:17 volumio volumio[5460]: info: CoreCommandRouter::volumioGetState Mar 02 09:59:17 volumio volumio[5460]: info: CorePlayQueue::getTrack 0 Mar 02 09:59:18 volumio volumio[5460]: info: Upmpdcli Daemon Started Mar 02 09:59:18 volumio mpd[5850]: Mar 02 09:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 02 09:59:18 volumio systemd[1]: Started Music Player Daemon. Mar 02 09:59:18 volumio sudo[5821]: pam_unix(sudo:session): session closed for user root Mar 02 09:59:18 volumio sudo[5831]: pam_unix(sudo:session): session closed for user root Mar 02 09:59:25 volumio volumio-remote-updater[658]: [2025-03-02 09:59:25] [connect] Successful connection Mar 02 09:59:40 volumio volumio-remote-updater[658]: [2025-03-02 09:59:40] [connect] Successful connection Mar 02 09:59:55 volumio volumio-remote-updater[658]: [2025-03-02 09:59:55] [connect] Successful connection Mar 02 10:00:10 volumio volumio-remote-updater[658]: [2025-03-02 10:00:10] [connect] Successful connection Mar 02 10:00:20 volumio volumio[5460]: info: Cannot mount NAS music at system boot, trial number 3 ,retrying in 5 seconds Mar 02 10:00:20 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 02 10:00:20 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 10:00:20 volumio volumio[5460]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 02 10:00:20 volumio volumio[5460]: error: Plugin music_service mpd failed to complete 'onStart' in a timely fashion Mar 02 10:00:20 volumio volumio[5460]: info: ------------------------------------------- Mar 02 10:00:20 volumio volumio[5460]: info: ----- MyVolumio plugins startup ---- Mar 02 10:00:20 volumio volumio[5460]: info: ------------------------------------------- Mar 02 10:00:20 volumio volumio[5460]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 02 10:00:20 volumio volumio[5460]: info: Starting Shairport Sync Mar 02 10:00:20 volumio volumio[5460]: info: Starting Shairport Sync Mar 02 10:00:20 volumio volumio[5460]: info: Starting Shairport Sync Mar 02 10:00:20 volumio sudo[5927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 02 10:00:20 volumio sudo[5927]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 10:00:20 volumio sudo[5929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 02 10:00:20 volumio sudo[5929]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 10:00:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 02 10:00:20 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 02 10:00:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 02 10:00:20 volumio sudo[5933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 02 10:00:20 volumio sudo[5933]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 10:00:20 volumio volumio[5460]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 02 10:00:20 volumio volumio[5460]: Error: Client network socket disconnected before secure TLS connection was established Mar 02 10:00:20 volumio volumio[5460]: at connResetException (internal/errors.js:607:14) Mar 02 10:00:20 volumio volumio[5460]: at TLSSocket.onConnectEnd (_tls_wrap.js:1544:19) Mar 02 10:00:20 volumio volumio[5460]: at TLSSocket.emit (events.js:327:22) Mar 02 10:00:20 volumio volumio[5460]: at endReadableNT (internal/streams/readable.js:1327:12) Mar 02 10:00:20 volumio volumio[5460]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Mar 02 10:00:20 volumio volumio[5460]: code: 'ECONNRESET', Mar 02 10:00:20 volumio volumio[5460]: path: null, Mar 02 10:00:20 volumio volumio[5460]: host: 'lastfm.freetls.fastly.net', Mar 02 10:00:20 volumio volumio[5460]: port: 443, Mar 02 10:00:20 volumio volumio[5460]: localAddress: undefined Mar 02 10:00:20 volumio volumio[5460]: } Mar 02 10:00:20 volumio volumio[5460]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 02 10:00:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 02 10:00:20 volumio sudo[5927]: pam_unix(sudo:session): session closed for user root Mar 02 10:00:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 02 10:00:20 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 02 10:00:20 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 02 10:00:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 02 10:00:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 02 10:00:20 volumio sudo[5933]: pam_unix(sudo:session): session closed for user root Mar 02 10:00:20 volumio sudo[5929]: pam_unix(sudo:session): session closed for user root Mar 02 10:00:21 volumio sudo[5948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-02 09:59 Mar 02 10:00:21 volumio sudo[5948]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"