-- Logs begin at Thu 2019-02-14 18:11:58 HKT, end at Sun 2025-06-15 09:42:36 HKT. -- Jun 15 09:42:05 volumio systemd-timedated[1424]: Changed local time to Sun Jun 15 09:42:05 2025 Jun 15 09:42:05 volumio sudo[1418]: pam_unix(sudo:session): session closed for user root Jun 15 09:42:05 volumio volumio[853]: info: Discovery: A device disappeared from network Jun 15 09:42:05 volumio volumio[853]: info: Discovery: Device volumio disappeared from network Jun 15 09:42:05 volumio volumio-time-update[537]: volumio-time-update-util: System time updated successfully. Jun 15 09:42:05 volumio systemd[1]: Started Volumio Time Update Utility. Jun 15 09:42:05 volumio systemd[1]: Reached target Multi-User System. Jun 15 09:42:05 volumio systemd[1]: Reached target Graphical Interface. Jun 15 09:42:05 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 15 09:42:05 volumio systemd[1]: Starting Daily apt download activities... Jun 15 09:42:05 volumio systemd[1]: Starting Daily man-db regeneration... Jun 15 09:42:05 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 15 09:42:05 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 15 09:42:05 volumio systemd[1]: man-db.service: Succeeded. Jun 15 09:42:05 volumio systemd[1]: Started Daily man-db regeneration. Jun 15 09:42:05 volumio volumio[853]: info: Discovery: adding 110f4e1b-11e6-4461-ad55-f1211245f6dc Jun 15 09:42:05 volumio volumio[853]: info: Discovery: Found device Volumio Jun 15 09:42:05 volumio volumio[853]: info: CoreCommandRouter::volumioGetState Jun 15 09:42:05 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:05 volumio systemd[1]: apt-daily.service: Succeeded. Jun 15 09:42:05 volumio systemd[1]: Started Daily apt download activities. Jun 15 09:42:05 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Jun 15 09:42:05 volumio volumio[853]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=tinkerboard&variante=volumio&version=3.804&uuid=c2eaeafba45b44130ec58c7548a2fe10" http://updates.volumio.org/downloader-v1/track-device Jun 15 09:42:05 volumio volumio[853]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 15 09:42:05 volumio volumio[853]: Dload Upload Total Spent Left Speed Jun 15 09:42:05 volumio volumio[853]: [132B blob data] Jun 15 09:42:05 volumio volumio[853]: retrying in 5 seconds, trial 0 Jun 15 09:42:05 volumio volumio[853]: info: Volumio Calling Home Jun 15 09:42:05 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Jun 15 09:42:05 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jun 15 09:42:05 volumio systemd[1]: Startup finished in 14.781s (kernel) + 20.296s (userspace) = 35.077s. Jun 15 09:42:05 volumio ntpd[1319]: Soliciting pool server 202.118.1.130 Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 15 09:42:06 volumio volumio[853]: info: Adding plugin bluetooth to MyMusic Plugins Jun 15 09:42:06 volumio volumio[853]: info: Adding plugin multiroom to MyMusic Plugins Jun 15 09:42:06 volumio volumio[853]: info: Adding plugin metavolumio to MyMusic Plugins Jun 15 09:42:06 volumio volumio[853]: info: Adding plugin cd_controller to MyMusic Plugins Jun 15 09:42:06 volumio volumio[853]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 15 09:42:06 volumio volumio[853]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 15 09:42:06 volumio ntpd[1319]: Soliciting pool server 119.28.183.184 Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 15 09:42:06 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 09:42:06 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 09:42:06 volumio volumio[853]: info: Starting MyVolumio Remote Streaming Endpoints Jun 15 09:42:06 volumio volumio[853]: info: MyVolumio not started Jun 15 09:42:06 volumio volumio[853]: info: Initializing device activation check Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 15 09:42:06 volumio volumio[853]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 15 09:42:07 volumio volumio[853]: info: Starting Streaming Service Transparent Proxy Jun 15 09:42:07 volumio volumio[853]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 15 09:42:07 volumio volumio[853]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 15 09:42:07 volumio volumio[853]: info: Streaming services startup Jun 15 09:42:07 volumio volumio[853]: info: Starting Streaming Daemon Jun 15 09:42:07 volumio sudo[1543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 15 09:42:07 volumio sudo[1543]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:42:07 volumio volumio[853]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 15 09:42:07 volumio sudo[1543]: pam_unix(sudo:session): session closed for user root Jun 15 09:42:07 volumio volumio[853]: info: Volumio called home Jun 15 09:42:07 volumio volumio[853]: error: Cannot start Volumio Streaming Daemon Jun 15 09:42:07 volumio volumio[853]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 15 09:42:07 volumio volumio[853]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 15 09:42:08 volumio volumio[1398]: ...........................................................................................++++ Jun 15 09:42:08 volumio volumio[853]: STREAMING PROXY: Starting server on port 3245 Jun 15 09:42:08 volumio volumio[853]: Node JS runtime: 14 Jun 15 09:42:09 volumio volumio[1398]: .................................++++ Jun 15 09:42:09 volumio volumio[1398]: e is 65537 (0x010001) Jun 15 09:42:09 volumio volumio[1398]: writing RSA key Jun 15 09:42:11 volumio sudo[1576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 15 09:42:11 volumio sudo[1576]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:42:11 volumio sudo[1576]: pam_unix(sudo:session): session closed for user root Jun 15 09:42:11 volumio sudo[1581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 15 09:42:11 volumio sudo[1581]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:42:11 volumio sudo[1581]: pam_unix(sudo:session): session closed for user root Jun 15 09:42:11 volumio volumio[853]: verbose: New Socket.io Connection to 192.168.0.106 from 192.168.0.101 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Jun 15 09:42:11 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 15 09:42:12 volumio volumio[853]: info: CoreCommandRouter::volumioGetVisibleSources Jun 15 09:42:12 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 09:42:12 volumio volumio[853]: info: CoreCommandRouter::volumioGetState Jun 15 09:42:12 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:12 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 15 09:42:12 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 15 09:42:12 volumio volumio[853]: info: Received Get System Info Jun 15 09:42:12 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 09:42:12 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 09:42:12 volumio volumio[853]: info: Discovery: Getting this device information Jun 15 09:42:12 volumio volumio[853]: info: CoreCommandRouter::volumioGetState Jun 15 09:42:12 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:12 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 09:42:12 volumio ntpd[1319]: receive: Unexpected origin timestamp 0xebf8a373.d0c5419b does not match aorg 0000000000.00000000 from server@119.28.206.193 xmt 0xebf8a374.dc0ba6c7 Jun 15 09:42:12 volumio volumio[853]: info: CoreCommandRouter::volumioGetState Jun 15 09:42:12 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:12 volumio volumio[853]: info: Listing playlists Jun 15 09:42:12 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 15 09:42:12 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 15 09:42:12 volumio ntpd[1319]: receive: Unexpected origin timestamp 0xebf8a373.d0c2cd1b does not match aorg 0000000000.00000000 from server@202.118.1.81 xmt 0xebf8a374.e09320b4 Jun 15 09:42:12 volumio ntpd[1319]: receive: Unexpected origin timestamp 0xebf8a373.d0be9375 does not match aorg 0000000000.00000000 from server@202.118.1.130 xmt 0xebf8a374.e06faef0 Jun 15 09:42:13 volumio ntpd[1319]: receive: Unexpected origin timestamp 0xebf8a373.d0c64ab6 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xebf8a374.ede8e4ce Jun 15 09:42:13 volumio ntpd[1319]: receive: Unexpected origin timestamp 0xebf8a373.d0c867e4 does not match aorg 0000000000.00000000 from server@185.209.85.222 xmt 0xebf8a374.eaaae5c8 Jun 15 09:42:13 volumio ntpd[1319]: receive: Unexpected origin timestamp 0xebf8a373.d0c41f3e does not match aorg 0000000000.00000000 from server@108.59.2.24 xmt 0xebf8a374.f50cbcd8 Jun 15 09:42:13 volumio volumio[853]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%BE%9D%E7%84%B6%E8%8C%83%E7%89%B9%E8%A5%BF/6bd836bf-5625-4d02-8ef2-a4b22edf9cad.jpg' Jun 15 09:42:13 volumio ntpd[1319]: receive: Unexpected origin timestamp 0xebf8a373.d0c98c37 does not match aorg 0000000000.00000000 from server@193.182.111.14 xmt 0xebf8a374.f84fe87e Jun 15 09:42:13 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 15 09:42:13 volumio volumio[853]: info: CoreCommandRouter::volumioGetState Jun 15 09:42:13 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 15 09:42:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 09:42:14 volumio volumio[853]: info: Received Get System Info Jun 15 09:42:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 09:42:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 09:42:14 volumio volumio[853]: info: Discovery: Getting this device information Jun 15 09:42:14 volumio volumio[853]: info: CoreCommandRouter::volumioGetState Jun 15 09:42:14 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 09:42:18 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 15 09:42:20 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 15 09:42:22 volumio volumio[853]: info: CoreCommandRouter::volumioGetQueue Jun 15 09:42:22 volumio volumio[853]: info: CoreStateMachine::getQueue Jun 15 09:42:22 volumio volumio[853]: info: CorePlayQueue::getQueue Jun 15 09:42:22 volumio volumio[853]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Chick%20Corea/Return%20To%20Forever/a4eca85e-59f4-43e7-8ef6-2f4b71064d7e.jpg' Jun 15 09:42:22 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 09:42:22 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 15 09:42:22 volumio volumio[853]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 15 09:42:22 volumio volumio[853]: info: CoreCommandRouter::volumioGetState Jun 15 09:42:22 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:24 volumio volumio[853]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Chick%20Corea/e60786dd-ad24-4438-897e-133b0684f3de.jpg' Jun 15 09:42:24 volumio volumio[853]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 09:42:24 volumio volumio[853]: Error: connect ECONNREFUSED 103.42.176.244:443 Jun 15 09:42:24 volumio volumio[853]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 15 09:42:24 volumio volumio[853]: errno: -111, Jun 15 09:42:24 volumio volumio[853]: code: 'ECONNREFUSED', Jun 15 09:42:24 volumio volumio[853]: syscall: 'connect', Jun 15 09:42:24 volumio volumio[853]: address: '103.42.176.244', Jun 15 09:42:24 volumio volumio[853]: port: 443 Jun 15 09:42:24 volumio volumio[853]: } Jun 15 09:42:24 volumio volumio[853]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 09:42:25 volumio sudo[1638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-15 09:41 Jun 15 09:42:25 volumio sudo[1638]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:42:25 volumio sudo[1638]: pam_unix(sudo:session): session closed for user root Jun 15 09:42:27 volumio volumio-remote-updater[546]: [2025-06-15 09:42:27] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 15 09:42:27 volumio volumio-remote-updater[546]: [2025-06-15 09:42:27] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 15 09:42:27 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 15 09:42:27 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 15 09:42:27 volumio systemd[1]: Started dynamicswap service. Jun 15 09:42:27 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 15 09:42:27 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 15 09:42:27 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jun 15 09:42:27 volumio systemd[1]: Started dynamicswap service. Jun 15 09:42:27 volumio systemd[1]: Stopped Volumio Backend Module. Jun 15 09:42:27 volumio systemd[1]: Started Volumio Backend Module. Jun 15 09:42:27 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 15 09:42:28 volumio volumio[1659]: info: ------------------------------------------- Jun 15 09:42:28 volumio volumio[1659]: info: ----- Volumio3 ---- Jun 15 09:42:28 volumio volumio[1659]: info: ------------------------------------------- Jun 15 09:42:28 volumio volumio[1659]: info: ----- System startup ---- Jun 15 09:42:28 volumio volumio[1659]: info: ------------------------------------------- Jun 15 09:42:29 volumio volumio[1659]: info: MYVOLUMIO Environment detected Jun 15 09:42:29 volumio volumio[1659]: info: Plugin folders cleanup Jun 15 09:42:29 volumio volumio[1659]: info: Scanning into folder /volumio/app/plugins/ Jun 15 09:42:29 volumio volumio[1659]: info: Scanning category audio_interface Jun 15 09:42:29 volumio volumio[1659]: info: Scanning category miscellanea Jun 15 09:42:29 volumio volumio[1659]: info: Scanning category music_service Jun 15 09:42:29 volumio volumio[1659]: info: Scanning category plugins.json Jun 15 09:42:29 volumio volumio[1659]: info: Scanning category system_controller Jun 15 09:42:29 volumio volumio[1659]: info: Scanning category user_interface Jun 15 09:42:29 volumio volumio[1659]: info: Scanning into folder /data/plugins/ Jun 15 09:42:29 volumio volumio[1659]: info: Plugin folders cleanup completed Jun 15 09:42:29 volumio volumio[1659]: info: ------------------------------------------- Jun 15 09:42:29 volumio volumio[1659]: info: ----- Core plugins startup ---- Jun 15 09:42:29 volumio volumio[1659]: info: ------------------------------------------- Jun 15 09:42:29 volumio volumio[1659]: info: Loading plugins from folder /volumio/app/plugins/ Jun 15 09:42:29 volumio volumio[1659]: info: Adding plugin upnp to MyMusic Plugins Jun 15 09:42:29 volumio volumio[1659]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 15 09:42:29 volumio volumio[1659]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 15 09:42:29 volumio volumio[1659]: info: Loading plugins from folder /data/plugins/ Jun 15 09:42:29 volumio volumio[1659]: info: Loading plugin "system"... Jun 15 09:42:29 volumio volumio[1659]: info: Loading plugin "appearance"... Jun 15 09:42:29 volumio volumio[1659]: info: Loading plugin "network"... Jun 15 09:42:29 volumio volumio[1659]: info: Refreshing Cached IP Addresses Jun 15 09:42:29 volumio sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 15 09:42:29 volumio volumio[1659]: info: Loading plugin "services"... Jun 15 09:42:29 volumio volumio[1659]: info: Loading plugin "alsa_controller"... Jun 15 09:42:29 volumio sudo[1687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 15 09:42:29 volumio sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:42:29 volumio sudo[1685]: pam_unix(sudo:session): session closed for user root Jun 15 09:42:29 volumio sudo[1687]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:42:29 volumio sudo[1687]: pam_unix(sudo:session): session closed for user root Jun 15 09:42:29 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 15 09:42:29 volumio volumio[1659]: info: Loading plugin "wizard"... Jun 15 09:42:29 volumio volumio[1659]: info: Loading plugin "networkfs"... Jun 15 09:42:29 volumio volumio[1659]: info: Cannot mount NAS Multimedia at system boot, trial number 1 ,retrying in 5 seconds Jun 15 09:42:29 volumio volumio[1659]: info: Starting Udev Watcher for removable devices Jun 15 09:42:29 volumio volumio[1659]: info: Ignoring mount for partition: boot Jun 15 09:42:29 volumio volumio[1659]: info: Ignoring mount for partition: volumio Jun 15 09:42:29 volumio volumio[1659]: info: Ignoring mount for partition: volumio_data Jun 15 09:42:29 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 15 09:42:29 volumio volumio[1659]: info: Loading plugin "volumio_command_line_client"... Jun 15 09:42:29 volumio volumio[1659]: info: Loading plugin "upnp"... Jun 15 09:42:29 volumio volumio[1659]: info: [1749951749749] Starting Upmpd Daemon Jun 15 09:42:29 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 15 09:42:29 volumio volumio[1659]: info: Loading plugin "my_music"... Jun 15 09:42:29 volumio volumio[1659]: info: Loading plugin "mpd"... Jun 15 09:42:29 volumio volumio[1659]: info: Loading plugin "upnp_browser"... Jun 15 09:42:30 volumio volumio[1659]: info: Loading plugin "alarm-clock"... Jun 15 09:42:30 volumio volumio[1659]: info: Loading plugin "airplay_emulation"... Jun 15 09:42:30 volumio volumio[1659]: info: Starting Shairport Sync Jun 15 09:42:30 volumio volumio[1659]: info: Loading plugin "last_100"... Jun 15 09:42:30 volumio volumio[1659]: info: Loading plugin "webradio"... Jun 15 09:42:30 volumio volumio[1659]: info: Loading plugin "i2s_dacs"... Jun 15 09:42:30 volumio volumio[1659]: info: I2S DAC not set, start Auto-detection Jun 15 09:42:30 volumio volumio[1659]: info: Loading plugin "volumiodiscovery"... Jun 15 09:42:30 volumio volumio[1659]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 15 09:42:30 volumio volumio[1659]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 15 09:42:30 volumio volumio[1659]: *** WARNING *** For more information see Jun 15 09:42:30 volumio volumio[1659]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 15 09:42:30 volumio node[1659]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 15 09:42:30 volumio volumio[1659]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 15 09:42:30 volumio volumio[1659]: *** WARNING *** For more information see Jun 15 09:42:30 volumio node[1659]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 15 09:42:30 volumio node[1659]: *** WARNING *** For more information see Jun 15 09:42:30 volumio node[1659]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 15 09:42:30 volumio node[1659]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 15 09:42:30 volumio node[1659]: *** WARNING *** For more information see Jun 15 09:42:30 volumio volumio[1659]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 15 09:42:30 volumio volumio[1659]: info: Discovery: Started advertising with name: Volumio Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 15 09:42:30 volumio volumio[1659]: info: Loading plugin "outputs"... Jun 15 09:42:30 volumio volumio[1659]: info: Loading plugin "albumart"... Jun 15 09:42:30 volumio volumio[1659]: info: Plugin example_plugin is not enabled Jun 15 09:42:30 volumio volumio[1659]: info: Loading plugin "inputs"... Jun 15 09:42:30 volumio volumio[1659]: info: Loading plugin "updater_comm"... Jun 15 09:42:30 volumio volumio[1659]: info: Plugin mpdemulation is not enabled Jun 15 09:42:30 volumio volumio[1659]: info: Loading plugin "rest_api"... Jun 15 09:42:30 volumio volumio[1659]: info: Loading plugin "websocket"... Jun 15 09:42:30 volumio volumio[1659]: info: Starting Socket.io Server version 2.3.0 Jun 15 09:42:30 volumio volumio[1659]: info: Loading i18n strings for locale ja Jun 15 09:42:30 volumio volumio[1659]: Updating browse sources language Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 09:42:30 volumio volumio[1659]: Forking 3 albumart workers Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::initPlayerControls Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 15 09:42:30 volumio volumio[1659]: Express server listening on port 3000 Jun 15 09:42:30 volumio volumio[1659]: [Metrics] WebUI: 2s 218.76ms Jun 15 09:42:30 volumio volumio[1659]: info: CoreStateMachine::resetVolumioState Jun 15 09:42:30 volumio volumio[1659]: info: CoreStateMachine::getcurrentVolume Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::volumioRetrievevolume Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 09:42:30 volumio volumio[1659]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 15 09:42:30 volumio volumio[1659]: info: Completed loading Core Plugins Jun 15 09:42:30 volumio volumio[1659]: info: Preparing to generate the ALSA configuration file Jun 15 09:42:30 volumio volumio[1659]: info: Volumio Network Manager: Network status updated: 1 Jun 15 09:42:30 volumio volumio[1659]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 15 09:42:30 volumio volumio[1659]: info: Reading ALSA contributions from plugins. Jun 15 09:42:30 volumio volumio[1659]: verbose: New Socket.io Connection to 192.168.0.106 from 192.168.0.101 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Jun 15 09:42:30 volumio volumio[1659]: info: CoreStateMachine::pushState Jun 15 09:42:30 volumio volumio[1659]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::volumioPushState Jun 15 09:42:30 volumio volumio[1659]: info: CoreStateMachine::updateTrackBlock Jun 15 09:42:30 volumio volumio[1659]: info: CorePlayQueue::getTrackBlock Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::volumioRetrievevolume Jun 15 09:42:30 volumio volumio[1659]: info: Reloading queue from file Jun 15 09:42:30 volumio volumio[1659]: info: CoreStateMachine::setRepeat null single undefined Jun 15 09:42:30 volumio volumio[1659]: info: CoreStateMachine::pushState Jun 15 09:42:30 volumio volumio[1659]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::volumioPushState Jun 15 09:42:30 volumio volumio[1659]: info: CoreStateMachine::setRandom null Jun 15 09:42:30 volumio volumio[1659]: info: CoreStateMachine::pushState Jun 15 09:42:30 volumio volumio[1659]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::volumioPushState Jun 15 09:42:30 volumio volumio[1659]: info: Setting Device type: Tinkerboard Jun 15 09:42:30 volumio volumio[1659]: verbose: New Socket.io Connection to 192.168.0.106 from 192.168.0.101 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 Jun 15 09:42:30 volumio volumio[1659]: info: CoreStateMachine::pushState Jun 15 09:42:30 volumio volumio[1659]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::volumioPushState Jun 15 09:42:30 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 15 09:42:30 volumio volumio[1659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Anton%20Bruckner/0a6ea896-87e5-4212-9d7c-e4a444f1d6a8.jpg' Jun 15 09:42:31 volumio volumio[1659]: info: Asound.conf file unchanged, so no further update is needed Jun 15 09:42:31 volumio volumio[1659]: info: Output device has changed, restarting MPD Jun 15 09:42:31 volumio volumio[1659]: info: Output device has changed, restarting Shairport Sync Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 09:42:31 volumio sudo[1775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 15 09:42:31 volumio sudo[1773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 15 09:42:31 volumio sudo[1775]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:42:31 volumio volumio[1659]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 15 09:42:31 volumio volumio[1659]: info: ___________ START PLUGINS ___________ Jun 15 09:42:31 volumio volumio[1659]: info: ControllerMpd::onStart: Initializing MPD Jun 15 09:42:31 volumio volumio[1659]: info: Creating MPD Configuration file Jun 15 09:42:31 volumio sudo[1773]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:42:31 volumio systemd[1]: Stopping Music Player Daemon... Jun 15 09:42:31 volumio sudo[1773]: pam_unix(sudo:session): session closed for user root Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 15 09:42:31 volumio volumio[1659]: info: [1749951751135] CoreMusicLibrary::Adding element DLNAサーバー Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 09:42:31 volumio sudo[1791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 15 09:42:31 volumio volumio[1659]: Starting albumart workers Jun 15 09:42:31 volumio sudo[1792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 15 09:42:31 volumio volumio[1659]: Starting albumart workers Jun 15 09:42:31 volumio sudo[1791]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:42:31 volumio sudo[1791]: pam_unix(sudo:session): session closed for user root Jun 15 09:42:31 volumio sudo[1792]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:42:31 volumio volumio[1659]: Starting albumart workers Jun 15 09:42:31 volumio volumio[1659]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 09:42:31 volumio systemd[1]: mpd.service: Succeeded. Jun 15 09:42:31 volumio systemd[1]: Stopped Music Player Daemon. Jun 15 09:42:31 volumio systemd[1]: Starting Music Player Daemon... Jun 15 09:42:31 volumio volumio[1659]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 15 09:42:31 volumio volumio[1659]: info: [1749951751266] CoreMusicLibrary::Adding element Last_100 Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 15 09:42:31 volumio volumio[1659]: info: [1749951751269] CoreMusicLibrary::Adding element Webradio Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 15 09:42:31 volumio volumio[1659]: info: Initializing BBC Radios Jun 15 09:42:31 volumio sudo[1814]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 15 09:42:31 volumio sudo[1814]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:42:31 volumio sudo[1814]: pam_unix(sudo:session): session closed for user root Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: Volumio Calling Home Jun 15 09:42:31 volumio volumio[1659]: info: MPD Permissions set Jun 15 09:42:31 volumio volumio[1659]: info: MPD Permissions set Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::volumioGetVisibleSources Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::volumioGetState Jun 15 09:42:31 volumio volumio[1659]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::volumioGetState Jun 15 09:42:31 volumio volumio[1659]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 15 09:42:31 volumio volumio[1659]: info: Received Get System Info Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 09:42:31 volumio volumio[1659]: info: Discovery: Getting this device information Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::volumioGetState Jun 15 09:42:31 volumio volumio[1659]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::volumioGetState Jun 15 09:42:31 volumio volumio[1659]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:31 volumio volumio[1659]: info: Listing playlists Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::volumioGetQueue Jun 15 09:42:31 volumio volumio[1659]: info: CoreStateMachine::getQueue Jun 15 09:42:31 volumio volumio[1659]: info: CorePlayQueue::getQueue Jun 15 09:42:31 volumio volumio[1659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ant%C3%B4nio%20Carlos%20Jobim/1d4e4e3e-7907-4f1f-947b-4022115e3e35.jpg' Jun 15 09:42:31 volumio volumio[1659]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 09:42:31 volumio volumio[1659]: info: Starting Shairport Sync Jun 15 09:42:31 volumio volumio[1659]: info: Starting Shairport Sync Jun 15 09:42:31 volumio volumio[1659]: info: Starting Shairport Sync Jun 15 09:42:31 volumio volumio[1659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Astor%20Piazzolla/1d917785-fb5b-48f6-b58a-efbb71e43f78.jpg' Jun 15 09:42:31 volumio sudo[1874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 15 09:42:31 volumio sudo[1874]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:42:31 volumio sudo[1876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 15 09:42:31 volumio sudo[1879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 15 09:42:31 volumio sudo[1876]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:42:31 volumio sudo[1879]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:42:31 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 15 09:42:31 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 15 09:42:31 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 15 09:42:31 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 15 09:42:31 volumio sudo[1874]: pam_unix(sudo:session): session closed for user root Jun 15 09:42:31 volumio volumio[1659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Avril%20Lavigne/92b59980-9bf4-4f9e-b6be-5fa6bf32ea68.jpg' Jun 15 09:42:31 volumio volumio[1659]: info: Shairport-Sync Started Jun 15 09:42:31 volumio volumio[1659]: Error adding Membership: Error: addMembership EINVAL Jun 15 09:42:31 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 15 09:42:31 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 15 09:42:31 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 15 09:42:31 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 15 09:42:31 volumio sudo[1876]: pam_unix(sudo:session): session closed for user root Jun 15 09:42:31 volumio sudo[1879]: pam_unix(sudo:session): session closed for user root Jun 15 09:42:31 volumio volumio[1659]: info: Discovery: adding 110f4e1b-11e6-4461-ad55-f1211245f6dc Jun 15 09:42:31 volumio volumio[1659]: info: Discovery: Found device Volumio Jun 15 09:42:31 volumio volumio[1659]: info: CoreCommandRouter::volumioGetState Jun 15 09:42:31 volumio volumio[1659]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:31 volumio volumio[1659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/B%27z/a0ee1f2f-e03a-4901-9b4a-4076fd943725.jpg' Jun 15 09:42:32 volumio volumio[1659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Backstreet%20Boys/12277161-da5f-4ccb-a16c-2eb077f26d34.jpg' Jun 15 09:42:32 volumio volumio[1659]: info: Shairport-Sync Started Jun 15 09:42:32 volumio volumio[1659]: info: Shairport-Sync Started Jun 15 09:42:32 volumio volumio[1659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Beach%20Boys/24af22b1-78e5-44b4-b52b-9d39abbb914c.jpg' Jun 15 09:42:32 volumio volumio[1659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Beethoven/2b72f51c-45a4-4f76-80df-717812a06615.jpg' Jun 15 09:42:32 volumio volumio[1659]: info: CoreCommandRouter::volumioGetState Jun 15 09:42:32 volumio volumio[1659]: info: CorePlayQueue::getTrack 0 Jun 15 09:42:32 volumio volumio[1659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bill%20Evans/cd1f45ed-9dc8-4a62-9b70-ecbf9a97b7b8.jpg' Jun 15 09:42:32 volumio volumio[1659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bill%20Evans%20%26%20Jim%20Hall/ab404806-2b6f-4131-aada-a6e62487818d.jpg' Jun 15 09:42:32 volumio volumio[1659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Billie%20Holiday/13bbf8f8-0db7-442c-968d-e67daedb995a.jpg' Jun 15 09:42:32 volumio volumio[1659]: info: Volumio called home Jun 15 09:42:32 volumio mpd[1835]: Jun 15 09:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 15 09:42:32 volumio systemd[1]: Started Music Player Daemon. Jun 15 09:42:32 volumio sudo[1792]: pam_unix(sudo:session): session closed for user root Jun 15 09:42:32 volumio sudo[1775]: pam_unix(sudo:session): session closed for user root Jun 15 09:42:32 volumio volumio-remote-updater[546]: [2025-06-15 09:42:32] [connect] Successful connection Jun 15 09:42:32 volumio volumio-remote-updater[546]: [2025-06-15 09:42:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749951752 101 Jun 15 09:42:32 volumio volumio[1659]: 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: 4 Jun 15 09:42:32 volumio volumio[1659]: info: Completed starting Core Plugins Jun 15 09:42:32 volumio volumio[1659]: info: ------------------------------------------- Jun 15 09:42:32 volumio volumio[1659]: info: ----- MyVolumio plugins startup ---- Jun 15 09:42:32 volumio volumio[1659]: info: ------------------------------------------- Jun 15 09:42:32 volumio volumio[1659]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 15 09:42:32 volumio volumio[1659]: error: MPD error: The expression evaluated to a falsy value: Jun 15 09:42:32 volumio volumio[1659]: assert.ok(self.idling) Jun 15 09:42:32 volumio volumio[1659]: error: The expression evaluated to a falsy value: Jun 15 09:42:32 volumio volumio[1659]: assert.ok(self.idling) Jun 15 09:42:32 volumio volumio[1659]: error: updateQueue error: null Jun 15 09:42:32 volumio volumio[1659]: info: MPD running with PID1835 Jun 15 09:42:32 volumio volumio[1659]: ,establishing connection Jun 15 09:42:32 volumio volumio[1659]: error: updateQueue error: null Jun 15 09:42:34 volumio volumio[1659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BoA%20Feat.%20DABO/25b6b598-53ba-47a1-b18d-651bb80da8bc.jpg' Jun 15 09:42:34 volumio volumio[1659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bob%20Marley%20%26%20The%20Wailers/4da2cdba-6f7c-4230-8bc0-c8ebec1ef531.jpg' Jun 15 09:42:34 volumio volumio[1659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bon%20Jovi/5dce3bf6-14a8-429e-b714-f81d579aad1b.jpg' Jun 15 09:42:34 volumio volumio[1659]: info: Cannot mount NAS Multimedia at system boot, trial number 2 ,retrying in 5 seconds Jun 15 09:42:35 volumio volumio[1659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BoA/637bef3d-2f13-4e12-bbc0-475c6ae1d6ca.jpg' Jun 15 09:42:35 volumio volumio[1659]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Chicago/6f64580b-7bd6-4424-884a-50c09f97cd41.jpg' Jun 15 09:42:35 volumio volumio[1659]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 09:42:35 volumio volumio[1659]: Error: connect ECONNREFUSED 103.42.176.244:80 Jun 15 09:42:35 volumio volumio[1659]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 15 09:42:35 volumio volumio[1659]: errno: -111, Jun 15 09:42:35 volumio volumio[1659]: code: 'ECONNREFUSED', Jun 15 09:42:35 volumio volumio[1659]: syscall: 'connect', Jun 15 09:42:35 volumio volumio[1659]: address: '103.42.176.244', Jun 15 09:42:35 volumio volumio[1659]: port: 80 Jun 15 09:42:35 volumio volumio[1659]: } Jun 15 09:42:35 volumio volumio[1659]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 09:42:36 volumio sudo[1932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-15 09:41 Jun 15 09:42:36 volumio sudo[1932]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:53:51 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="53825f995260683aee752aed6b29f24e"