-- Logs begin at Thu 2019-02-14 19:11:59 KST, end at Fri 2025-09-05 07:26:50 KST. -- Sep 05 07:26:09 volumio ntpd[664]: receive: Unexpected origin timestamp 0xec5f72bb.b7753d3c does not match aorg 0000000000.00000000 from server@221.151.118.78 xmt 0xec649081.8c2e6d76 Sep 05 07:26:09 volumio systemd[1]: Starting Daily apt download activities... Sep 05 07:26:09 volumio systemd[1]: Starting Daily man-db regeneration... Sep 05 07:26:09 volumio volumio[860]: info: Discovery: A device disappeared from network Sep 05 07:26:09 volumio volumio[860]: info: Discovery: Device volumio disappeared from network Sep 05 07:26:09 volumio volumio[860]: info: Discovery: adding de6bd77a-f716-4e70-b3c6-168074c014ef Sep 05 07:26:09 volumio volumio[860]: info: Discovery: Found device Volumio Sep 05 07:26:09 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Sep 05 07:26:09 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Sep 05 07:26:09 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 05 07:26:10 volumio systemd[1]: man-db.service: Succeeded. Sep 05 07:26:10 volumio systemd[1]: Started Daily man-db regeneration. Sep 05 07:26:10 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 05 07:26:10 volumio volumio[860]: info: Received Get System Info Sep 05 07:26:10 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 07:26:10 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 07:26:10 volumio volumio[860]: info: Discovery: Getting this device information Sep 05 07:26:10 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Sep 05 07:26:10 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Sep 05 07:26:10 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 07:26:11 volumio volumio[860]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Sep 05 07:26:11 volumio volumio[860]: info: Preparing to generate the ALSA configuration file Sep 05 07:26:12 volumio volumio[860]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Sep 05 07:26:12 volumio volumio[860]: info: Reading ALSA contributions from plugins. Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 05 07:26:12 volumio volumio[860]: info: Asound.conf file unchanged, so no further update is needed Sep 05 07:26:12 volumio volumio[860]: info: Output device has changed, restarting MPD Sep 05 07:26:12 volumio sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 07:26:12 volumio volumio[860]: info: Output device has changed, restarting Shairport Sync Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:12 volumio sudo[1121]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:12 volumio sudo[1121]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:12 volumio sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 07:26:12 volumio sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:12 volumio volumio[860]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 05 07:26:12 volumio volumio[860]: info: ___________ START PLUGINS ___________ Sep 05 07:26:12 volumio volumio[860]: info: ControllerMpd::onStart: Initializing MPD Sep 05 07:26:12 volumio volumio[860]: info: Creating MPD Configuration file Sep 05 07:26:12 volumio sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 07:26:12 volumio sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 07:26:12 volumio volumio[860]: info: [1757024772328] CoreMusicLibrary::Adding element 미디어 서버 Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 07:26:12 volumio systemd[1]: Listening on mpd.socket. Sep 05 07:26:12 volumio systemd[1]: Starting Music Player Daemon... Sep 05 07:26:12 volumio sudo[1131]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:12 volumio sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 07:26:12 volumio sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:12 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 07:26:12 volumio systemd[1]: mpd.service: Succeeded. Sep 05 07:26:12 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 07:26:12 volumio systemd[1]: Starting Music Player Daemon... Sep 05 07:26:12 volumio volumio[860]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:12 volumio volumio[860]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 07:26:12 volumio volumio[860]: info: [1757024772666] CoreMusicLibrary::Adding element Last_100 Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 07:26:12 volumio volumio[860]: info: [1757024772675] CoreMusicLibrary::Adding element Webradio Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 07:26:12 volumio volumio[860]: info: Adding Calm Radio to Browse Sources Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 07:26:12 volumio volumio[860]: info: [1757024772701] CoreMusicLibrary::Adding element Calm Radio Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 07:26:12 volumio volumio[860]: Cannot find translation for source Calm Radio Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 07:26:12 volumio volumio[860]: info: [1757024772726] CoreMusicLibrary::Adding element YouTube2 Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 07:26:12 volumio volumio[860]: Cannot find translation for source Calm Radio Sep 05 07:26:12 volumio volumio[860]: Cannot find translation for source YouTube2 Sep 05 07:26:12 volumio volumio[860]: info: Loading i18n strings for locale ko Sep 05 07:26:12 volumio volumio[860]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 05 07:26:12 volumio volumio[860]: info: /tmp/myfifo created Sep 05 07:26:12 volumio sudo[1147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 Sep 05 07:26:12 volumio sudo[1147]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:12 volumio sudo[1147]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:12 volumio volumio[860]: info: snd-dummy loaded Sep 05 07:26:12 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:13 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:13 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:13 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Sep 05 07:26:13 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:13 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:13 volumio volumio[860]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 05 07:26:13 volumio volumio[860]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 07:26:13 volumio volumio[860]: info: [1757024773216] CoreMusicLibrary::Adding element Mother Earth Radio Sep 05 07:26:13 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 07:26:13 volumio volumio[860]: Cannot find translation for source Calm Radio Sep 05 07:26:13 volumio volumio[860]: Cannot find translation for source YouTube2 Sep 05 07:26:13 volumio volumio[860]: Cannot find translation for source Mother Earth Radio Sep 05 07:26:13 volumio sudo[1159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Sep 05 07:26:13 volumio sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:13 volumio systemd[1]: Started RoonBridge. Sep 05 07:26:13 volumio volumio[860]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 07:26:13 volumio volumio[860]: info: [1757024773417] CoreMusicLibrary::Adding element 개인 라디오 Sep 05 07:26:13 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 07:26:13 volumio volumio[860]: Cannot find translation for source Calm Radio Sep 05 07:26:13 volumio volumio[860]: Cannot find translation for source YouTube2 Sep 05 07:26:13 volumio volumio[860]: Cannot find translation for source Mother Earth Radio Sep 05 07:26:13 volumio volumio[860]: Cannot find translation for source 개인 라디오 Sep 05 07:26:13 volumio volumio[860]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 07:26:13 volumio sudo[1159]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:13 volumio volumio[860]: info: [1757024773440] CoreMusicLibrary::Adding element Radio Paradise Sep 05 07:26:13 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 07:26:13 volumio volumio[860]: Cannot find translation for source Calm Radio Sep 05 07:26:13 volumio volumio[860]: Cannot find translation for source YouTube2 Sep 05 07:26:13 volumio volumio[860]: Cannot find translation for source Mother Earth Radio Sep 05 07:26:13 volumio volumio[860]: Cannot find translation for source 개인 라디오 Sep 05 07:26:13 volumio volumio[860]: Cannot find translation for source Radio Paradise Sep 05 07:26:13 volumio volumio[860]: info: Loading i18n strings for locale ko Sep 05 07:26:13 volumio volumio[860]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 05 07:26:13 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 07:26:13 volumio volumio[860]: info: Volumio Calling Home Sep 05 07:26:13 volumio sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Sep 05 07:26:13 volumio sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:13 volumio sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Sep 05 07:26:13 volumio sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:13 volumio sudo[1180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Sep 05 07:26:13 volumio sudo[1180]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:13 volumio volumio[860]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Sep 05 07:26:14 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Sep 05 07:26:14 volumio volumio[860]: info: MPD Permissions set Sep 05 07:26:14 volumio volumio[860]: info: MPD Permissions set Sep 05 07:26:14 volumio volumio[860]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:14 volumio systemd[1]: Reloading. Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:14 volumio volumio[860]: info: Preload queue cleared Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 05 07:26:14 volumio volumio[860]: info: CoreStateMachine::ClearQueue Sep 05 07:26:14 volumio volumio[860]: info: CoreStateMachine::stop Sep 05 07:26:14 volumio volumio[860]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 07:26:14 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 05 07:26:14 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Sep 05 07:26:14 volumio kernel: CIFS: Attempting to mount \\192.168.0.20\music Sep 05 07:26:14 volumio sudo[1075]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:14 volumio volumio[860]: info: CorePlayQueue::clearPlayQueue Sep 05 07:26:14 volumio volumio[860]: info: CorePlayQueue::saveQueue Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::volumioPushQueue Sep 05 07:26:14 volumio volumio[860]: info: CoreStateMachine::addQueueItems Sep 05 07:26:14 volumio volumio[860]: info: CorePlayQueue::addQueueItems Sep 05 07:26:14 volumio volumio[860]: info: Preload queue cleared Sep 05 07:26:14 volumio volumio[860]: info: Adding Item to queue: music-library/USB/01DC19762C662800/Classic/Beethoven, Brahms & Vitali - Violin Concerto, Sonata & Chaconne Sep 05 07:26:14 volumio volumio[860]: info: Exploding uri music-library/USB/01DC19762C662800/Classic/Beethoven, Brahms & Vitali - Violin Concerto, Sonata & Chaconne in service mpd Sep 05 07:26:14 volumio volumio[860]: error: Failed to stat '/mnt/USB/01DC19762C662800/Classic/Beethoven, Brahms & Vitali - Violin Concerto, Sonata & Chaconne' Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::volumioPushQueue Sep 05 07:26:14 volumio volumio[860]: info: CorePlayQueue::saveQueue Sep 05 07:26:14 volumio volumio[860]: info: CoreStateMachine::updateTrackBlock Sep 05 07:26:14 volumio volumio[860]: info: CorePlayQueue::getTrackBlock Sep 05 07:26:14 volumio volumio[860]: info: CoreCommandRouter::volumioPlay Sep 05 07:26:14 volumio volumio[860]: info: CoreStateMachine::play index 0 Sep 05 07:26:14 volumio volumio[860]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 07:26:14 volumio volumio[860]: info: CoreStateMachine::stop Sep 05 07:26:14 volumio volumio[860]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 07:26:14 volumio volumio[860]: info: CoreStateMachine::play index undefined Sep 05 07:26:14 volumio volumio[860]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 07:26:14 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Sep 05 07:26:14 volumio RoonBridge[1161]: Unhandled Exception: Sep 05 07:26:14 volumio RoonBridge[1161]: System.IO.FileNotFoundException: Could not load file or assembly 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' or one of its dependencies. Sep 05 07:26:14 volumio RoonBridge[1161]: File name: 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' Sep 05 07:26:14 volumio RoonBridge[1161]: [ERROR] FATAL UNHANDLED EXCEPTION: System.IO.FileNotFoundException: Could not load file or assembly 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' or one of its dependencies. Sep 05 07:26:14 volumio RoonBridge[1161]: File name: 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' Sep 05 07:26:15 volumio RoonBridge[1161]: /data/plugins/music_service/RoonBridge/RoonBridge/start.sh: 37: kill: No such process Sep 05 07:26:15 volumio sudo[1234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Sep 05 07:26:15 volumio sudo[1234]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:15 volumio volumio[860]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Sep 05 07:26:15 volumio sudo[1234]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:15 volumio sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Sep 05 07:26:15 volumio sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:15 volumio sudo[1240]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:15 volumio volumio[860]: info: Volumio called home Sep 05 07:26:15 volumio volumio[860]: info: Cannot mount NAS GMK at system boot, trial number 3 ,retrying in 5 seconds Sep 05 07:26:15 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 05 07:26:15 volumio volumio[860]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Sep 05 07:26:15 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Sep 05 07:26:15 volumio volumio[860]: info: Starting Shairport Sync Sep 05 07:26:15 volumio sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 07:26:15 volumio sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:15 volumio volumio[860]: info: Starting Shairport Sync Sep 05 07:26:15 volumio sudo[1246]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:15 volumio sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 05 07:26:15 volumio volumio[860]: info: Starting Shairport Sync Sep 05 07:26:15 volumio sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:15 volumio volumio[860]: info: Starting Shairport Sync Sep 05 07:26:15 volumio sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 05 07:26:15 volumio sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:15 volumio sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 05 07:26:15 volumio sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:15 volumio volumio[860]: (node:860) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Sep 05 07:26:15 volumio volumio[860]: (Use `node --trace-deprecation ...` to show where the warning was created) Sep 05 07:26:15 volumio volumio[860]: (node:860) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. Sep 05 07:26:15 volumio sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 05 07:26:15 volumio sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:15 volumio volumio[860]: info: MPD Permissions set Sep 05 07:26:15 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:15 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:15 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:15 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:15 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:15 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:15 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:15 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:15 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:15 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 07:26:15 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Sep 05 07:26:15 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Sep 05 07:26:16 volumio sudo[1180]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:16 volumio volumio[860]: info: touch_display: systemctl daemon-reload succeeded. Sep 05 07:26:16 volumio systemd[1]: apt-daily.service: Succeeded. Sep 05 07:26:16 volumio systemd[1]: Started Daily apt download activities. Sep 05 07:26:16 volumio systemd[1]: roonbridge.service: Succeeded. Sep 05 07:26:16 volumio systemd[1]: Reloading. Sep 05 07:26:16 volumio sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Sep 05 07:26:16 volumio sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:17 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 05 07:26:17 volumio volumio[860]: info: Preload queue cleared Sep 05 07:26:17 volumio volumio[860]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 05 07:26:17 volumio volumio[860]: info: CoreStateMachine::ClearQueue Sep 05 07:26:17 volumio volumio[860]: info: CoreStateMachine::stop Sep 05 07:26:17 volumio volumio[860]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 07:26:17 volumio volumio[860]: info: CorePlayQueue::clearPlayQueue Sep 05 07:26:17 volumio volumio[860]: info: CorePlayQueue::saveQueue Sep 05 07:26:17 volumio volumio[860]: info: CoreCommandRouter::volumioPushQueue Sep 05 07:26:17 volumio volumio[860]: info: CoreStateMachine::addQueueItems Sep 05 07:26:17 volumio volumio[860]: info: CorePlayQueue::addQueueItems Sep 05 07:26:17 volumio volumio[860]: info: Preload queue cleared Sep 05 07:26:17 volumio volumio[860]: info: Adding Item to queue: music-library/USB/01DC19762C662800/Classic/Beethoven, Brahms & Vitali - Violin Concerto, Sonata & Chaconne Sep 05 07:26:17 volumio volumio[860]: info: Using cached record of: music-library/USB/01DC19762C662800/Classic/Beethoven, Brahms & Vitali - Violin Concerto, Sonata & Chaconne Sep 05 07:26:17 volumio volumio[860]: info: CoreCommandRouter::volumioPushQueue Sep 05 07:26:17 volumio volumio[860]: info: CorePlayQueue::saveQueue Sep 05 07:26:17 volumio volumio[860]: info: CoreStateMachine::updateTrackBlock Sep 05 07:26:17 volumio volumio[860]: info: CorePlayQueue::getTrackBlock Sep 05 07:26:17 volumio volumio[860]: info: CoreCommandRouter::volumioPlay Sep 05 07:26:17 volumio volumio[860]: info: CoreStateMachine::play index 0 Sep 05 07:26:17 volumio volumio[860]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 07:26:17 volumio volumio[860]: info: CoreStateMachine::stop Sep 05 07:26:17 volumio volumio[860]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 07:26:17 volumio volumio[860]: info: CoreStateMachine::play index undefined Sep 05 07:26:17 volumio volumio[860]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 07:26:17 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Sep 05 07:26:17 volumio mpd[1138]: Sep 05 07:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 07:26:18 volumio sudo[1175]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:18 volumio systemd[1]: Started Music Player Daemon. Sep 05 07:26:18 volumio volumio[860]: info: touch_display: systemctl disable getty@tty1.service succeeded. Sep 05 07:26:18 volumio sudo[1123]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:18 volumio sudo[1133]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:18 volumio systemd[1]: roonbridge.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 07:26:18 volumio systemd[1]: roonbridge.service: Scheduled restart job, restart counter is at 1. Sep 05 07:26:18 volumio volumio[860]: error: MPD error: The expression evaluated to a falsy value: Sep 05 07:26:18 volumio volumio[860]: assert.ok(self.idling) Sep 05 07:26:18 volumio volumio[860]: error: The expression evaluated to a falsy value: Sep 05 07:26:18 volumio volumio[860]: assert.ok(self.idling) Sep 05 07:26:18 volumio systemd[1]: Started Volumio Kiosk. Sep 05 07:26:18 volumio volumio[860]: error: updateQueue error: null Sep 05 07:26:18 volumio volumio[860]: info: MPD running with PID1138 Sep 05 07:26:18 volumio volumio[860]: ,establishing connection Sep 05 07:26:18 volumio sudo[1265]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 05 07:26:18 volumio volumio[860]: error: updateQueue error: null Sep 05 07:26:18 volumio volumio[860]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Sep 05 07:26:18 volumio volumio[860]: info: touch_display: Volumio Kiosk started. Sep 05 07:26:18 volumio systemd[1]: Stopped RoonBridge. Sep 05 07:26:18 volumio systemd[1]: Started RoonBridge. Sep 05 07:26:18 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Sep 05 07:26:18 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Sep 05 07:26:18 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Sep 05 07:26:18 volumio volumio[860]: info: Completed starting Core Plugins Sep 05 07:26:18 volumio volumio[860]: info: ------------------------------------------- Sep 05 07:26:18 volumio volumio[860]: info: ----- MyVolumio plugins startup ---- Sep 05 07:26:18 volumio volumio[860]: info: ------------------------------------------- Sep 05 07:26:18 volumio volumio[860]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 05 07:26:18 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Sep 05 07:26:18 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Sep 05 07:26:18 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 05 07:26:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 05 07:26:18 volumio sudo[1173]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:18 volumio volumio[860]: info: touch_display: X display number found: 0 Sep 05 07:26:18 volumio volumio[860]: info: touch_display: systemctl stop getty@tty1.service succeeded. Sep 05 07:26:18 volumio startx[1284]: X.Org X Server 1.20.4 Sep 05 07:26:18 volumio startx[1284]: X Protocol Version 11, Revision 0 Sep 05 07:26:18 volumio startx[1284]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Sep 05 07:26:18 volumio startx[1284]: Current Operating System: Linux volumio 6.1.69-v7+ #1710 SMP Thu Dec 21 13:14:13 GMT 2023 armv7l Sep 05 07:26:18 volumio startx[1284]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1024 bcm2708_fb.fbheight=600 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 Sep 05 07:26:18 volumio startx[1284]: Build Date: 04 April 2023 07:50:56AM Sep 05 07:26:18 volumio startx[1284]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Sep 05 07:26:18 volumio startx[1284]: Current version of pixman: 0.36.0 Sep 05 07:26:18 volumio startx[1284]: Before reporting problems, check http://wiki.x.org Sep 05 07:26:18 volumio startx[1284]: to make sure that you have the latest version. Sep 05 07:26:18 volumio startx[1284]: Markers: (--) probed, (**) from config file, (==) default setting, Sep 05 07:26:18 volumio startx[1284]: (++) from command line, (!!) notice, (II) informational, Sep 05 07:26:18 volumio startx[1284]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Sep 05 07:26:18 volumio startx[1284]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Sep 5 07:26:18 2025 Sep 05 07:26:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 05 07:26:18 volumio startx[1284]: (==) Using config directory: "/etc/X11/xorg.conf.d" Sep 05 07:26:18 volumio startx[1284]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Sep 05 07:26:18 volumio sudo[1254]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:18 volumio sudo[1252]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:18 volumio sudo[1257]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:18 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:19 volumio volumio[860]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Sep 05 07:26:19 volumio volumio[860]: info: Shairport-Sync Started Sep 05 07:26:19 volumio volumio[860]: Error adding Membership: Error: addMembership EINVAL Sep 05 07:26:19 volumio volumio[860]: info: Shairport-Sync Started Sep 05 07:26:19 volumio volumio[860]: info: touch_display: X display number found: 0 Sep 05 07:26:19 volumio volumio[860]: info: Shairport-Sync Started Sep 05 07:26:19 volumio volumio[860]: info: Shairport-Sync Started Sep 05 07:26:19 volumio volumio[860]: info: touch_display: X display number found: 0 Sep 05 07:26:19 volumio RoonBridge[1287]: Unhandled Exception: Sep 05 07:26:19 volumio RoonBridge[1287]: System.IO.FileNotFoundException: Could not load file or assembly 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' or one of its dependencies. Sep 05 07:26:19 volumio RoonBridge[1287]: File name: 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' Sep 05 07:26:19 volumio RoonBridge[1287]: [ERROR] FATAL UNHANDLED EXCEPTION: System.IO.FileNotFoundException: Could not load file or assembly 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' or one of its dependencies. Sep 05 07:26:19 volumio RoonBridge[1287]: File name: 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' Sep 05 07:26:19 volumio RoonBridge[1287]: /data/plugins/music_service/RoonBridge/RoonBridge/start.sh: 37: kill: No such process Sep 05 07:26:19 volumio systemd[1]: roonbridge.service: Succeeded. Sep 05 07:26:19 volumio systemd[1]: roonbridge.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 07:26:19 volumio systemd[1]: roonbridge.service: Scheduled restart job, restart counter is at 2. Sep 05 07:26:19 volumio systemd[1]: Stopped RoonBridge. Sep 05 07:26:19 volumio systemd[1]: Started RoonBridge. Sep 05 07:26:20 volumio RoonBridge[1346]: Unhandled Exception: Sep 05 07:26:20 volumio RoonBridge[1346]: System.IO.FileNotFoundException: Could not load file or assembly 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' or one of its dependencies. Sep 05 07:26:20 volumio RoonBridge[1346]: File name: 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' Sep 05 07:26:20 volumio RoonBridge[1346]: [ERROR] FATAL UNHANDLED EXCEPTION: System.IO.FileNotFoundException: Could not load file or assembly 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' or one of its dependencies. Sep 05 07:26:20 volumio RoonBridge[1346]: File name: 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' Sep 05 07:26:20 volumio RoonBridge[1346]: /data/plugins/music_service/RoonBridge/RoonBridge/start.sh: 37: kill: No such process Sep 05 07:26:20 volumio systemd[1]: roonbridge.service: Succeeded. Sep 05 07:26:20 volumio systemd[1]: roonbridge.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 07:26:20 volumio systemd[1]: roonbridge.service: Scheduled restart job, restart counter is at 3. Sep 05 07:26:20 volumio systemd[1]: Stopped RoonBridge. Sep 05 07:26:20 volumio systemd[1]: Started RoonBridge. Sep 05 07:26:20 volumio sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=dhstudio,password=bts%1956DHS,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //nas.bitsori.net/music /mnt/NAS/GMK Sep 05 07:26:20 volumio sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:20 volumio sudo[1092]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:20 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 05 07:26:20 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 05 07:26:20 volumio kernel: CIFS: Attempting to mount \\nas.bitsori.net\music Sep 05 07:26:20 volumio volumio[860]: info: Cannot mount NAS Bitsori_Music at system boot, trial number 3 ,retrying in 5 seconds Sep 05 07:26:20 volumio RoonBridge[1357]: Unhandled Exception: Sep 05 07:26:20 volumio RoonBridge[1357]: System.IO.FileNotFoundException: Could not load file or assembly 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' or one of its dependencies. Sep 05 07:26:20 volumio RoonBridge[1357]: File name: 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' Sep 05 07:26:20 volumio RoonBridge[1357]: [ERROR] FATAL UNHANDLED EXCEPTION: System.IO.FileNotFoundException: Could not load file or assembly 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' or one of its dependencies. Sep 05 07:26:20 volumio RoonBridge[1357]: File name: 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' Sep 05 07:26:20 volumio RoonBridge[1357]: /data/plugins/music_service/RoonBridge/RoonBridge/start.sh: 37: kill: No such process Sep 05 07:26:20 volumio systemd[1]: roonbridge.service: Succeeded. Sep 05 07:26:21 volumio systemd[1]: roonbridge.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 07:26:21 volumio systemd[1]: roonbridge.service: Scheduled restart job, restart counter is at 4. Sep 05 07:26:21 volumio systemd[1]: Stopped RoonBridge. Sep 05 07:26:21 volumio systemd[1]: Started RoonBridge. Sep 05 07:26:21 volumio startx[1284]: [1B blob data] Sep 05 07:26:21 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Sep 05 07:26:21 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Sep 05 07:26:21 volumio RoonBridge[1380]: Unhandled Exception: Sep 05 07:26:21 volumio RoonBridge[1380]: System.IO.FileNotFoundException: Could not load file or assembly 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' or one of its dependencies. Sep 05 07:26:21 volumio RoonBridge[1380]: File name: 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' Sep 05 07:26:21 volumio RoonBridge[1380]: [ERROR] FATAL UNHANDLED EXCEPTION: System.IO.FileNotFoundException: Could not load file or assembly 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' or one of its dependencies. Sep 05 07:26:21 volumio RoonBridge[1380]: File name: 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' Sep 05 07:26:21 volumio RoonBridge[1380]: /data/plugins/music_service/RoonBridge/RoonBridge/start.sh: 37: kill: No such process Sep 05 07:26:21 volumio systemd[1]: roonbridge.service: Succeeded. Sep 05 07:26:21 volumio systemd[1]: roonbridge.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 07:26:21 volumio systemd[1]: roonbridge.service: Scheduled restart job, restart counter is at 5. Sep 05 07:26:21 volumio systemd[1]: Stopped RoonBridge. Sep 05 07:26:21 volumio volumio[1026]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 05 07:26:21 volumio systemd[1]: Started RoonBridge. Sep 05 07:26:22 volumio RoonBridge[1428]: Unhandled Exception: Sep 05 07:26:22 volumio RoonBridge[1428]: System.IO.FileNotFoundException: Could not load file or assembly 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' or one of its dependencies. Sep 05 07:26:22 volumio RoonBridge[1428]: File name: 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' Sep 05 07:26:22 volumio RoonBridge[1428]: [ERROR] FATAL UNHANDLED EXCEPTION: System.IO.FileNotFoundException: Could not load file or assembly 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' or one of its dependencies. Sep 05 07:26:22 volumio RoonBridge[1428]: File name: 'Base, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null' Sep 05 07:26:22 volumio RoonBridge[1428]: /data/plugins/music_service/RoonBridge/RoonBridge/start.sh: 37: kill: No such process Sep 05 07:26:22 volumio systemd[1]: roonbridge.service: Succeeded. Sep 05 07:26:22 volumio startx[1284]: waiting for X server to begin accepting connections . Sep 05 07:26:22 volumio systemd[1]: roonbridge.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 07:26:22 volumio systemd[1]: roonbridge.service: Scheduled restart job, restart counter is at 6. Sep 05 07:26:22 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Sep 05 07:26:22 volumio systemd[1]: Started Daily apt upgrade and clean activities. Sep 05 07:26:22 volumio systemd[1]: Stopped RoonBridge. Sep 05 07:26:22 volumio systemd[1]: roonbridge.service: Start request repeated too quickly. Sep 05 07:26:22 volumio systemd[1]: roonbridge.service: Failed with result 'start-limit-hit'. Sep 05 07:26:22 volumio systemd[1]: Failed to start RoonBridge. Sep 05 07:26:22 volumio volumio[860]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 05 07:26:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 07:26:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 07:26:26 volumio volumio[860]: info: Discovery: Getting this device information Sep 05 07:26:26 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Sep 05 07:26:26 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Sep 05 07:26:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 07:26:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 05 07:26:26 volumio sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=dhstudio,password=bts%1956DHS,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.20/music /mnt/NAS/Bitsori_Music Sep 05 07:26:26 volumio volumio[860]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 05 07:26:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 05 07:26:26 volumio sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:26 volumio volumio[860]: info: Received Get System Version Sep 05 07:26:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 07:26:26 volumio volumio[860]: info: Received Get System Info Sep 05 07:26:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 07:26:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 07:26:26 volumio volumio[860]: info: Discovery: Getting this device information Sep 05 07:26:26 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Sep 05 07:26:26 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Sep 05 07:26:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 05 07:26:27 volumio volumio[860]: info: Adding plugin bluetooth to MyMusic Plugins Sep 05 07:26:27 volumio volumio[860]: info: Adding plugin multiroom to MyMusic Plugins Sep 05 07:26:27 volumio volumio[860]: info: Adding plugin metavolumio to MyMusic Plugins Sep 05 07:26:27 volumio volumio[860]: info: Adding plugin cd_controller to MyMusic Plugins Sep 05 07:26:27 volumio volumio[860]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 05 07:26:27 volumio volumio[860]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 05 07:26:27 volumio volumio[860]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 05 07:26:30 volumio volumio[860]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 05 07:26:30 volumio volumio[860]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 05 07:26:30 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:30 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:30 volumio volumio[860]: info: Starting MyVolumio Remote Streaming Endpoints Sep 05 07:26:30 volumio volumio[860]: info: MyVolumio login type: Token Sep 05 07:26:30 volumio volumio[860]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 05 07:26:30 volumio volumio[860]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 05 07:26:30 volumio sudo[1360]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:30 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 05 07:26:30 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Sep 05 07:26:30 volumio kernel: CIFS: Attempting to mount \\192.168.0.20\music Sep 05 07:26:33 volumio volumio[860]: info: Starting Streaming Service Transparent Proxy Sep 05 07:26:33 volumio volumio[860]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 05 07:26:33 volumio volumio[860]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 05 07:26:33 volumio volumio[860]: info: Streaming services startup Sep 05 07:26:33 volumio volumio[860]: info: Starting Streaming Daemon Sep 05 07:26:33 volumio volumio[860]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 05 07:26:33 volumio sudo[1589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 05 07:26:33 volumio sudo[1589]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:33 volumio sudo[1589]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:33 volumio volumio[860]: error: Cannot start Volumio Streaming Daemon Sep 05 07:26:33 volumio volumio[860]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 05 07:26:33 volumio volumio[860]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 05 07:26:33 volumio volumio[860]: info: Cannot mount NAS GMK at system boot, trial number 4 ,retrying in 5 seconds Sep 05 07:26:34 volumio volumio[860]: error: MyVolumio Custom Token format not valid, refreshing it Sep 05 07:26:35 volumio volumio[860]: info: MyVolumio login type: Token Sep 05 07:26:36 volumio volumio[860]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 05 07:26:36 volumio sudo[1541]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:36 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 05 07:26:36 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 05 07:26:36 volumio volumio[860]: info: Cannot mount NAS Bitsori_Music at system boot, trial number 4 ,retrying in 5 seconds Sep 05 07:26:37 volumio volumio[860]: info: MyVolumio token set successfully Sep 05 07:26:37 volumio volumio[860]: info: MYVOLUMIO: Adding device Sep 05 07:26:37 volumio volumio[860]: info: MYVOLUMIO: Evaluating Server Sep 05 07:26:38 volumio volumio[860]: STREAMING PROXY: Starting server on port 3245 Sep 05 07:26:38 volumio volumio[860]: Node JS runtime: 14 Sep 05 07:26:39 volumio volumio[860]: info: MyVolumio status changed Sep 05 07:26:39 volumio volumio[860]: info: Streaming services startup Sep 05 07:26:39 volumio volumio[860]: info: Starting Streaming Daemon Sep 05 07:26:39 volumio volumio[860]: info: Removing browser output: myVolumio user plan is not superstar Sep 05 07:26:39 volumio volumio[860]: info: Removing audio output: Sep 05 07:26:39 volumio volumio[860]: info: Stoppping Tunnel 1 Sep 05 07:26:39 volumio sudo[1688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=dhstudio,password=bts%1956DHS,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //nas.bitsori.net/music /mnt/NAS/GMK Sep 05 07:26:39 volumio sudo[1688]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:39 volumio kernel: CIFS: Attempting to mount \\nas.bitsori.net\music Sep 05 07:26:39 volumio sudo[1692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 05 07:26:39 volumio sudo[1692]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:39 volumio sudo[1690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 05 07:26:39 volumio volumio[860]: info: Setting Geolocation for MyVolumio to as1 Sep 05 07:26:39 volumio sudo[1690]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:39 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:39 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:39 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:39 volumio sudo[1692]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:39 volumio volumio[860]: info: Remote SSH Stopped Sep 05 07:26:39 volumio sudo[1690]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:39 volumio volumio[860]: error: Cannot start Volumio Streaming Daemon Sep 05 07:26:39 volumio volumio[860]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 05 07:26:39 volumio volumio[860]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 05 07:26:39 volumio volumio[860]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 05 07:26:40 volumio volumio[860]: info: Updating MyVolumio device info Sep 05 07:26:40 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:40 volumio volumio[860]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 05 07:26:42 volumio sudo[1699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=dhstudio,password=bts%1956DHS,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.20/music /mnt/NAS/Bitsori_Music Sep 05 07:26:42 volumio sudo[1699]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 07:26:43 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 07:26:43 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:43 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 05 07:26:43 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 05 07:26:43 volumio volumio-remote-updater[532]: No test mode Sep 05 07:26:43 volumio volumio-remote-updater[532]: No alpha test mode Sep 05 07:26:43 volumio volumio[860]: info: MYVOLUMIO: Adding device Sep 05 07:26:43 volumio volumio[860]: info: MYVOLUMIO: Evaluating Server Sep 05 07:26:44 volumio volumio[860]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n\n

NEW ADDITIONS

\n\n","title":"Update v3.832","updateavailable":true} Sep 05 07:26:45 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 05 07:26:45 volumio volumio[860]: info: Setting Geolocation for MyVolumio to as1 Sep 05 07:26:45 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:45 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:45 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:45 volumio volumio[860]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 8 Sep 05 07:26:45 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 05 07:26:45 volumio volumio[860]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Sep 05 07:26:45 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Sep 05 07:26:45 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Sep 05 07:26:45 volumio volumio[860]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 05 07:26:46 volumio volumio[860]: info: Updating MyVolumio device info Sep 05 07:26:46 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:46 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:46 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 07:26:46 volumio volumio[860]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 05 07:26:48 volumio volumio[860]: info: Preload queue cleared Sep 05 07:26:48 volumio volumio[860]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 05 07:26:48 volumio volumio[860]: info: CoreStateMachine::ClearQueue Sep 05 07:26:48 volumio volumio[860]: info: CoreStateMachine::stop Sep 05 07:26:48 volumio volumio[860]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 07:26:48 volumio volumio[860]: info: CorePlayQueue::clearPlayQueue Sep 05 07:26:48 volumio volumio[860]: info: CorePlayQueue::saveQueue Sep 05 07:26:48 volumio volumio[860]: info: CoreCommandRouter::volumioPushQueue Sep 05 07:26:48 volumio volumio[860]: info: CoreStateMachine::addQueueItems Sep 05 07:26:48 volumio volumio[860]: info: CorePlayQueue::addQueueItems Sep 05 07:26:48 volumio volumio[860]: info: Preload queue cleared Sep 05 07:26:48 volumio volumio[860]: info: Adding Item to queue: music-library/USB/01DC19762C662800/Classic/Haydn - String Quartets Op.20 Sep 05 07:26:48 volumio volumio[860]: info: Exploding uri music-library/USB/01DC19762C662800/Classic/Haydn - String Quartets Op.20 in service mpd Sep 05 07:26:48 volumio volumio[860]: info: ALBUMART /albumart?cacheid=884&web=The%20London%20Haydn%20Quartet/Haydn%20-%20String%20Quartets%2C%20Op.%2020%20-%20CD1/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FHaydn%20-%20String%20Quartets%20Op.20%2FCD1%2FHaydn%20-%20String%20Quartets%2C%20Op.%2020%20-%20CD1.cue&metadata=false Sep 05 07:26:48 volumio volumio[860]: info: URI /mnt/USB/01DC19762C662800/Classic/Haydn - String Quartets Op.20/CD1/Haydn - String Quartets, Op. 20 - CD1.cue Sep 05 07:26:48 volumio volumio[860]: info: ALBUMART /albumart?cacheid=884&web=The%20London%20Haydn%20Quartet/Haydn%20-%20String%20Quartets%2C%20Op.%2020%20-%20CD1/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FHaydn%20-%20String%20Quartets%20Op.20%2FCD1%2FHaydn%20-%20String%20Quartets%2C%20Op.%2020%20-%20CD1.cue&metadata=false Sep 05 07:26:48 volumio volumio[860]: info: URI /mnt/USB/01DC19762C662800/Classic/Haydn - String Quartets Op.20/CD1/Haydn - String Quartets, Op. 20 - CD1.cue Sep 05 07:26:48 volumio volumio[860]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 07:26:48 volumio volumio[860]: Error: Unable to resolve or reject the same promise twice Sep 05 07:26:48 volumio volumio[860]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Sep 05 07:26:48 volumio volumio[860]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Sep 05 07:26:48 volumio volumio[860]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Sep 05 07:26:48 volumio volumio[860]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Sep 05 07:26:48 volumio volumio[860]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Sep 05 07:26:48 volumio volumio[860]: at Socket.emit (events.js:315:20) Sep 05 07:26:48 volumio volumio[860]: at addChunk (internal/streams/readable.js:309:12) Sep 05 07:26:48 volumio volumio[860]: at readableAddChunk (internal/streams/readable.js:280:11) Sep 05 07:26:48 volumio volumio[860]: at Socket.Readable.push (internal/streams/readable.js:223:10) Sep 05 07:26:48 volumio volumio[860]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Sep 05 07:26:48 volumio volumio[860]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 07:26:49 volumio sudo[1688]: pam_unix(sudo:session): session closed for user root Sep 05 07:26:49 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 05 07:26:49 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Sep 05 07:26:49 volumio kernel: CIFS: Attempting to mount \\192.168.0.20\music Sep 05 07:26:50 volumio sudo[1730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 07:25 Sep 05 07:26:50 volumio sudo[1730]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"