-- Logs begin at Wed 2025-02-19 15:14:54 UTC, end at Wed 2025-02-19 15:40:13 UTC. -- Feb 19 15:39:00 volumio volumio[6901]: info: CoreCommandRouter::volumioGetState Feb 19 15:39:00 volumio volumio[6901]: info: CorePlayQueue::getTrack 0 Feb 19 15:39:02 volumio volumio-remote-updater[700]: [2025-02-19 15:39:02] [connect] Successful connection Feb 19 15:39:02 volumio volumio-remote-updater[700]: [2025-02-19 15:39:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739979542 101 Feb 19 15:39:02 volumio volumio[6901]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Feb 19 15:39:03 volumio volumio[6901]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Feb 19 15:39:03 volumio sudo[6941]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:04 volumio volumio[6901]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 19 15:39:05 volumio volumio[6901]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 19 15:39:05 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 19 15:39:05 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:39:05 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 19 15:39:05 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:39:05 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:39:05 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:39:05 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:39:05 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:39:05 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:39:05 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:39:05 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 19 15:39:05 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 19 15:39:08 volumio volumio[6901]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Feb 19 15:39:08 volumio sudo[7155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 19 15:39:08 volumio sudo[7155]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:08 volumio sudo[7155]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:08 volumio sudo[7158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 19 15:39:08 volumio sudo[7158]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:08 volumio sudo[7158]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:08 volumio sudo[7161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 19 15:39:08 volumio sudo[7161]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:08 volumio sudo[7161]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:08 volumio volumio[6901]: info: Upmpdcli Daemon Started Feb 19 15:39:13 volumio volumio[6901]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Feb 19 15:39:18 volumio volumio[6901]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 19 15:39:19 volumio volumio[6901]: error: MyVolumio Plugin failed to start in a timely fashion Feb 19 15:39:19 volumio volumio[6901]: [Metrics] CommandRouter: 21s 736.55ms Feb 19 15:39:19 volumio volumio[6901]: info: CoreCommandRouter::volumiosetStartupVolume Feb 19 15:39:19 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:19 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:39:19 volumio volumio[6901]: info: CoreCommandRouter::Close All Modals sent Feb 19 15:39:19 volumio volumio[6901]: info: CoreCommandRouter::Close All Modals sent Feb 19 15:39:20 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 19 15:39:20 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 19 15:39:20 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 19 15:39:26 volumio volumio[6901]: info: Adding plugin bluetooth to MyMusic Plugins Feb 19 15:39:26 volumio volumio[6901]: info: Adding plugin multiroom to MyMusic Plugins Feb 19 15:39:26 volumio volumio[6901]: info: Adding plugin metavolumio to MyMusic Plugins Feb 19 15:39:26 volumio volumio[6901]: info: Adding plugin cd_controller to MyMusic Plugins Feb 19 15:39:26 volumio volumio[6901]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 19 15:39:26 volumio volumio[6901]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 19 15:39:26 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:39:26 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:39:26 volumio volumio[6901]: info: Starting MyVolumio Remote Streaming Endpoints Feb 19 15:39:26 volumio volumio[6901]: info: MyVolumio not started Feb 19 15:39:26 volumio volumio[6901]: info: Initializing device activation check Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 19 15:39:26 volumio volumio[6901]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 19 15:39:27 volumio volumio[6901]: info: Starting Streaming Service Transparent Proxy Feb 19 15:39:27 volumio volumio[6901]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 19 15:39:27 volumio volumio[6901]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 19 15:39:27 volumio volumio[6901]: info: Streaming services startup Feb 19 15:39:27 volumio volumio[6901]: info: Starting Streaming Daemon Feb 19 15:39:27 volumio sudo[7196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 19 15:39:27 volumio sudo[7196]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:27 volumio sudo[7196]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:27 volumio volumio[6901]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 19 15:39:27 volumio volumio[6901]: info: BOOT COMPLETED Feb 19 15:39:27 volumio volumio[6901]: error: Cannot start Volumio Streaming Daemon Feb 19 15:39:27 volumio volumio[6901]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 19 15:39:27 volumio volumio[6901]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 19 15:39:27 volumio volumio[6901]: STREAMING PROXY: Starting server on port 3245 Feb 19 15:39:27 volumio volumio[6901]: Node JS runtime: 14 Feb 19 15:39:29 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 15:39:29 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:39:29 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 19 15:39:41 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 19 15:39:41 volumio volumio[6901]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 19 15:39:41 volumio volumio[6901]: info: CoreCommandRouter::volumioGetState Feb 19 15:39:41 volumio volumio[6901]: info: CorePlayQueue::getTrack 0 Feb 19 15:39:46 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:46 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:46 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 19 15:39:46 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 19 15:39:46 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 19 15:39:46 volumio volumio[6901]: info: CoreCommandRouter::volumioGetBrowseSources Feb 19 15:39:46 volumio volumio[6901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 15:39:50 volumio volumio[6901]: info: CoreCommandRouter::volumioGetState Feb 19 15:39:50 volumio volumio[6901]: info: CorePlayQueue::getTrack 0 Feb 19 15:39:52 volumio volumio[6901]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9E%97%E5%B3%AF/549d0347-ccd5-4a6a-bf4c-2a8a1b900291.jpg' Feb 19 15:39:52 volumio volumio[6901]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%B9%85%E7%9F%B3%E8%AD%B2/Encore/a58c0d28-5192-4de7-ad33-3eeac1137ed3.jpg' Feb 19 15:39:53 volumio volumio[6901]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 15:39:53 volumio volumio[6901]: Error: connect ECONNREFUSED 162.125.80.5:443 Feb 19 15:39:53 volumio volumio[6901]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 19 15:39:53 volumio volumio[6901]: errno: -111, Feb 19 15:39:53 volumio volumio[6901]: code: 'ECONNREFUSED', Feb 19 15:39:53 volumio volumio[6901]: syscall: 'connect', Feb 19 15:39:53 volumio volumio[6901]: address: '162.125.80.5', Feb 19 15:39:53 volumio volumio[6901]: port: 443 Feb 19 15:39:53 volumio volumio[6901]: } Feb 19 15:39:53 volumio volumio[6901]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 15:39:53 volumio sudo[7263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-19 15:38 Feb 19 15:39:53 volumio sudo[7263]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:53 volumio sudo[7263]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:53 volumio volumio-remote-updater[700]: [2025-02-19 15:39:53] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 19 15:39:53 volumio volumio-remote-updater[700]: [2025-02-19 15:39:53] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 19 15:39:53 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 19 15:39:53 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 19 15:39:53 volumio systemd[1]: Started dynamicswap service. Feb 19 15:39:53 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 19 15:39:53 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 19 15:39:53 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Feb 19 15:39:53 volumio systemd[1]: Stopped Volumio Backend Module. Feb 19 15:39:53 volumio systemd[1]: Started Volumio Backend Module. Feb 19 15:39:53 volumio systemd[1]: Started dynamicswap service. Feb 19 15:39:53 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 19 15:39:53 volumio volumio[7273]: info: ------------------------------------------- Feb 19 15:39:53 volumio volumio[7273]: info: ----- Volumio3 ---- Feb 19 15:39:53 volumio volumio[7273]: info: ------------------------------------------- Feb 19 15:39:53 volumio volumio[7273]: info: ----- System startup ---- Feb 19 15:39:53 volumio volumio[7273]: info: ------------------------------------------- Feb 19 15:39:54 volumio volumio[7273]: info: MYVOLUMIO Environment detected Feb 19 15:39:54 volumio volumio[7273]: info: Plugin folders cleanup Feb 19 15:39:54 volumio volumio[7273]: info: Scanning into folder /volumio/app/plugins/ Feb 19 15:39:54 volumio volumio[7273]: info: Scanning category audio_interface Feb 19 15:39:54 volumio volumio[7273]: info: Scanning category miscellanea Feb 19 15:39:54 volumio volumio[7273]: info: Scanning category music_service Feb 19 15:39:54 volumio volumio[7273]: info: Scanning category plugins.json Feb 19 15:39:54 volumio volumio[7273]: info: Scanning category system_controller Feb 19 15:39:54 volumio volumio[7273]: info: Scanning category user_interface Feb 19 15:39:54 volumio volumio[7273]: info: Scanning into folder /data/plugins/ Feb 19 15:39:54 volumio volumio[7273]: info: Plugin folders cleanup completed Feb 19 15:39:54 volumio volumio[7273]: info: ------------------------------------------- Feb 19 15:39:54 volumio volumio[7273]: info: ----- Core plugins startup ---- Feb 19 15:39:54 volumio volumio[7273]: info: ------------------------------------------- Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugins from folder /volumio/app/plugins/ Feb 19 15:39:54 volumio volumio[7273]: info: Adding plugin upnp to MyMusic Plugins Feb 19 15:39:54 volumio volumio[7273]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 19 15:39:54 volumio volumio[7273]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugins from folder /data/plugins/ Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "system"... Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "appearance"... Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "network"... Feb 19 15:39:54 volumio volumio[7273]: info: Refreshing Cached IP Addresses Feb 19 15:39:54 volumio sudo[7300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 19 15:39:54 volumio sudo[7300]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:54 volumio sudo[7300]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:54 volumio sudo[7302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 19 15:39:54 volumio sudo[7302]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:54 volumio sudo[7302]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "services"... Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "alsa_controller"... Feb 19 15:39:54 volumio sudo[7314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 19 15:39:54 volumio sudo[7314]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "wizard"... Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "networkfs"... Feb 19 15:39:54 volumio volumio[7273]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Feb 19 15:39:54 volumio volumio[7273]: info: Starting Udev Watcher for removable devices Feb 19 15:39:54 volumio volumio[7273]: info: Ignoring mount for partition: boot Feb 19 15:39:54 volumio volumio[7273]: info: Ignoring mount for partition: volumio Feb 19 15:39:54 volumio volumio[7273]: info: Ignoring mount for partition: volumio_data Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "volumio_command_line_client"... Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "upnp"... Feb 19 15:39:54 volumio volumio[7273]: info: [1739979594414] Starting Upmpd Daemon Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "my_music"... Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "mpd"... Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "upnp_browser"... Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "alarm-clock"... Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "airplay_emulation"... Feb 19 15:39:54 volumio volumio[7273]: info: Starting Shairport Sync Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "last_100"... Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "webradio"... Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "i2s_dacs"... Feb 19 15:39:54 volumio volumio[7273]: info: I2S DAC not set, start Auto-detection Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "volumiodiscovery"... Feb 19 15:39:54 volumio volumio[7273]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 19 15:39:54 volumio volumio[7273]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 15:39:54 volumio volumio[7273]: *** WARNING *** For more information see Feb 19 15:39:54 volumio volumio[7273]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 19 15:39:54 volumio node[7273]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 19 15:39:54 volumio volumio[7273]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 15:39:54 volumio volumio[7273]: *** WARNING *** For more information see Feb 19 15:39:54 volumio node[7273]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 15:39:54 volumio node[7273]: *** WARNING *** For more information see Feb 19 15:39:54 volumio node[7273]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 19 15:39:54 volumio node[7273]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 15:39:54 volumio node[7273]: *** WARNING *** For more information see Feb 19 15:39:54 volumio volumio[7273]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 19 15:39:54 volumio volumio[7273]: info: Discovery: Started advertising with name: Volumio Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "outputs"... Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "albumart"... Feb 19 15:39:54 volumio volumio[7273]: info: Plugin example_plugin is not enabled Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "inputs"... Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "updater_comm"... Feb 19 15:39:54 volumio volumio[7273]: info: Plugin mpdemulation is not enabled Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "rest_api"... Feb 19 15:39:54 volumio volumio[7273]: info: Loading plugin "websocket"... Feb 19 15:39:54 volumio volumio[7273]: info: Starting Socket.io Server version 2.3.0 Feb 19 15:39:54 volumio volumio[7273]: Forking 3 albumart workers Feb 19 15:39:54 volumio volumio[7273]: info: Loading i18n strings for locale zh Feb 19 15:39:54 volumio volumio[7273]: Updating browse sources language Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::initPlayerControls Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:39:54 volumio volumio[7273]: Express server listening on port 3000 Feb 19 15:39:54 volumio volumio[7273]: [Metrics] WebUI: 0s 912.52ms Feb 19 15:39:54 volumio volumio[7273]: info: Setting Device type: x86 Feb 19 15:39:54 volumio volumio[7273]: info: CoreStateMachine::resetVolumioState Feb 19 15:39:54 volumio volumio[7273]: info: CoreStateMachine::getcurrentVolume Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:39:54 volumio volumio[7273]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 19 15:39:54 volumio volumio[7273]: info: Completed loading Core Plugins Feb 19 15:39:54 volumio volumio[7273]: info: Preparing to generate the ALSA configuration file Feb 19 15:39:54 volumio volumio[7273]: info: Volumio Network Manager: Network status updated: 3 Feb 19 15:39:54 volumio volumio[7273]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 19 15:39:54 volumio volumio[7273]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 19 15:39:54 volumio volumio[7273]: Unable to parse: Feb 19 15:39:54 volumio volumio[7273]: Simple mixer control 'PCM',0 Feb 19 15:39:54 volumio volumio[7273]: Capabilities: pswitch pswitch-joined Feb 19 15:39:54 volumio volumio[7273]: Playback channels: Mono Feb 19 15:39:54 volumio volumio[7273]: Mono: Playback [on] Feb 19 15:39:54 volumio volumio[7273]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 19 15:39:54 volumio volumio[7273]: info: Reloading queue from file Feb 19 15:39:54 volumio volumio[7273]: info: Asound.conf file unchanged, so no further update is needed Feb 19 15:39:54 volumio volumio[7273]: info: Output device has changed, restarting MPD Feb 19 15:39:54 volumio volumio[7273]: Starting albumart workers Feb 19 15:39:54 volumio volumio[7273]: info: Output device has changed, restarting Shairport Sync Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:54 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:39:54 volumio sudo[7367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 15:39:54 volumio sudo[7367]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:54 volumio sudo[7367]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:54 volumio sudo[7369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 15:39:54 volumio sudo[7369]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:54 volumio volumio[7273]: Starting albumart workers Feb 19 15:39:54 volumio systemd[1]: Stopping Music Player Daemon... Feb 19 15:39:54 volumio volumio[7273]: Starting albumart workers Feb 19 15:39:54 volumio volumio[7273]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 15:39:54 volumio volumio[7273]: info: ___________ START PLUGINS ___________ Feb 19 15:39:54 volumio volumio[7273]: info: ControllerMpd::onStart: Initializing MPD Feb 19 15:39:54 volumio volumio[7273]: info: Creating MPD Configuration file Feb 19 15:39:54 volumio systemd[1]: mpd.service: Succeeded. Feb 19 15:39:54 volumio systemd[1]: Stopped Music Player Daemon. Feb 19 15:39:55 volumio systemd[1]: Starting Music Player Daemon... Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 15:39:55 volumio volumio[7273]: info: [1739979595020] CoreMusicLibrary::Adding element 媒体服务器 Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:39:55 volumio sudo[7377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 15:39:55 volumio sudo[7377]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:55 volumio sudo[7379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 15:39:55 volumio sudo[7379]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:55 volumio sudo[7377]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:39:55 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 19 15:39:55 volumio systemd[1]: mpd.service: Succeeded. Feb 19 15:39:55 volumio systemd[1]: Stopped Music Player Daemon. Feb 19 15:39:55 volumio systemd[1]: Starting Music Player Daemon... Feb 19 15:39:55 volumio volumio[7273]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 15:39:55 volumio volumio[7273]: info: [1739979595090] CoreMusicLibrary::Adding element Last_100 Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 15:39:55 volumio volumio[7273]: info: [1739979595091] CoreMusicLibrary::Adding element Webradio Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 15:39:55 volumio volumio[7273]: info: Initializing BBC Radios Feb 19 15:39:55 volumio sudo[7385]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 15:39:55 volumio sudo[7385]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:55 volumio sudo[7385]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: Volumio Calling Home Feb 19 15:39:55 volumio volumio[7273]: info: CoreStateMachine::setRepeat null single undefined Feb 19 15:39:55 volumio volumio[7273]: info: CoreStateMachine::pushState Feb 19 15:39:55 volumio volumio[7273]: info: CorePlayQueue::getTrack 0 Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::volumioPushState Feb 19 15:39:55 volumio volumio[7273]: info: CoreStateMachine::setRandom null Feb 19 15:39:55 volumio volumio[7273]: info: CoreStateMachine::pushState Feb 19 15:39:55 volumio volumio[7273]: info: CorePlayQueue::getTrack 0 Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::volumioPushState Feb 19 15:39:55 volumio volumio[7273]: Unable to parse: Feb 19 15:39:55 volumio volumio[7273]: Simple mixer control 'PCM',0 Feb 19 15:39:55 volumio volumio[7273]: Capabilities: pswitch pswitch-joined Feb 19 15:39:55 volumio volumio[7273]: Playback channels: Mono Feb 19 15:39:55 volumio volumio[7273]: Mono: Playback [on] Feb 19 15:39:55 volumio volumio[7273]: info: VolumeController:: Volume=undefined Mute =false Feb 19 15:39:55 volumio volumio[7273]: info: CoreStateMachine::pushState Feb 19 15:39:55 volumio volumio[7273]: info: CorePlayQueue::getTrack 0 Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::volumioPushState Feb 19 15:39:55 volumio volumio[7273]: info: CoreStateMachine::updateTrackBlock Feb 19 15:39:55 volumio volumio[7273]: info: CorePlayQueue::getTrackBlock Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 15:39:55 volumio volumio[7273]: info: MPD Permissions set Feb 19 15:39:55 volumio volumio[7273]: info: MPD Permissions set Feb 19 15:39:55 volumio volumio[7273]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 19 15:39:55 volumio volumio[7273]: Unable to parse: Feb 19 15:39:55 volumio volumio[7273]: Simple mixer control 'PCM',0 Feb 19 15:39:55 volumio volumio[7273]: Capabilities: pswitch pswitch-joined Feb 19 15:39:55 volumio volumio[7273]: Playback channels: Mono Feb 19 15:39:55 volumio volumio[7273]: Mono: Playback [on] Feb 19 15:39:55 volumio volumio[7273]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:39:55 volumio volumio[7273]: Unable to parse: Feb 19 15:39:55 volumio volumio[7273]: Simple mixer control 'PCM',0 Feb 19 15:39:55 volumio volumio[7273]: Capabilities: pswitch pswitch-joined Feb 19 15:39:55 volumio volumio[7273]: Playback channels: Mono Feb 19 15:39:55 volumio volumio[7273]: Mono: Playback [on] Feb 19 15:39:55 volumio volumio[7273]: info: VolumeController:: Volume=undefined Mute =false Feb 19 15:39:55 volumio volumio[7273]: info: CoreStateMachine::pushState Feb 19 15:39:55 volumio volumio[7273]: info: CorePlayQueue::getTrack 0 Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::volumioPushState Feb 19 15:39:55 volumio volumio[7273]: info: Starting Shairport Sync Feb 19 15:39:55 volumio volumio[7273]: info: Starting Shairport Sync Feb 19 15:39:55 volumio sudo[7444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 15:39:55 volumio sudo[7444]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:55 volumio volumio[7273]: info: Starting Shairport Sync Feb 19 15:39:55 volumio sudo[7446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 15:39:55 volumio sudo[7446]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 19 15:39:55 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 19 15:39:55 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 19 15:39:55 volumio sudo[7450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::volumioGetState Feb 19 15:39:55 volumio volumio[7273]: info: CorePlayQueue::getTrack 0 Feb 19 15:39:55 volumio sudo[7450]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 19 15:39:55 volumio sudo[7446]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:55 volumio sudo[7444]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 19 15:39:55 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 19 15:39:55 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 19 15:39:55 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 19 15:39:55 volumio volumio[7273]: info: Shairport-Sync Started Feb 19 15:39:55 volumio volumio[7273]: Error adding Membership: Error: addMembership EINVAL Feb 19 15:39:55 volumio volumio[7273]: info: Shairport-Sync Started Feb 19 15:39:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 19 15:39:55 volumio sudo[7450]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:55 volumio volumio[7273]: info: Shairport-Sync Started Feb 19 15:39:55 volumio mpd[7392]: Feb 19 15:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 19 15:39:55 volumio volumio[7273]: info: Discovery: adding 3a66fb0d-7cfc-4805-9244-7368ccc2b5bf Feb 19 15:39:55 volumio volumio[7273]: info: Discovery: Found device Volumio Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::volumioGetState Feb 19 15:39:55 volumio volumio[7273]: info: CorePlayQueue::getTrack 0 Feb 19 15:39:55 volumio volumio[7273]: info: Discovery: this is already registered, 3a66fb0d-7cfc-4805-9244-7368ccc2b5bf Feb 19 15:39:55 volumio volumio[7273]: info: Discovery: Found device Volumio Feb 19 15:39:55 volumio volumio[7273]: info: CoreCommandRouter::volumioGetState Feb 19 15:39:55 volumio volumio[7273]: info: CorePlayQueue::getTrack 0 Feb 19 15:39:55 volumio systemd[1]: Started Music Player Daemon. Feb 19 15:39:55 volumio sudo[7379]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:55 volumio sudo[7369]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:55 volumio volumio[7273]: info: Completed starting Core Plugins Feb 19 15:39:55 volumio volumio[7273]: info: ------------------------------------------- Feb 19 15:39:55 volumio volumio[7273]: info: ----- MyVolumio plugins startup ---- Feb 19 15:39:55 volumio volumio[7273]: info: ------------------------------------------- Feb 19 15:39:55 volumio volumio[7273]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 19 15:39:55 volumio volumio[7273]: error: MPD error: The expression evaluated to a falsy value: Feb 19 15:39:55 volumio volumio[7273]: assert.ok(self.idling) Feb 19 15:39:55 volumio volumio[7273]: error: The expression evaluated to a falsy value: Feb 19 15:39:55 volumio volumio[7273]: assert.ok(self.idling) Feb 19 15:39:55 volumio volumio[7273]: error: updateQueue error: null Feb 19 15:39:55 volumio volumio[7273]: info: MPD running with PID7392 Feb 19 15:39:55 volumio volumio[7273]: ,establishing connection Feb 19 15:39:55 volumio volumio[7273]: error: updateQueue error: null Feb 19 15:39:55 volumio volumio[7273]: info: Volumio called home Feb 19 15:39:57 volumio volumio[7273]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Feb 19 15:39:57 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 19 15:39:57 volumio volumio[7273]: info: CoreCommandRouter::volumioGetVisibleSources Feb 19 15:39:57 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:39:57 volumio volumio[7273]: info: CoreCommandRouter::volumioGetState Feb 19 15:39:57 volumio volumio[7273]: info: CorePlayQueue::getTrack 0 Feb 19 15:39:57 volumio volumio[7273]: info: CoreCommandRouter::volumioGetState Feb 19 15:39:57 volumio volumio[7273]: info: CorePlayQueue::getTrack 0 Feb 19 15:39:57 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 19 15:39:57 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 19 15:39:57 volumio volumio[7273]: info: Received Get System Info Feb 19 15:39:57 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 19 15:39:57 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 19 15:39:57 volumio volumio[7273]: info: Discovery: Getting this device information Feb 19 15:39:57 volumio volumio[7273]: info: CoreCommandRouter::volumioGetState Feb 19 15:39:57 volumio volumio[7273]: info: CorePlayQueue::getTrack 0 Feb 19 15:39:57 volumio volumio[7273]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 19 15:39:57 volumio volumio[7273]: info: CoreCommandRouter::volumioGetState Feb 19 15:39:57 volumio volumio[7273]: info: CorePlayQueue::getTrack 0 Feb 19 15:39:57 volumio volumio[7273]: info: Listing playlists Feb 19 15:39:57 volumio volumio[7273]: info: CoreCommandRouter::volumioGetQueue Feb 19 15:39:57 volumio volumio[7273]: info: CoreStateMachine::getQueue Feb 19 15:39:57 volumio volumio[7273]: info: CorePlayQueue::getQueue Feb 19 15:39:57 volumio volumio[7273]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Eagles/Hell%20Freezes%20Over%20%5BSHM-CD%5D/4ec4bdb0-7051-470a-ab19-8e7db20a4f7c.jpg' Feb 19 15:39:57 volumio volumio[7273]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Eagles/Hotel%20California/971b40ff-eae9-4e52-a412-d9df5071c11c.jpg' Feb 19 15:39:58 volumio volumio-remote-updater[700]: [2025-02-19 15:39:58] [connect] Successful connection Feb 19 15:39:58 volumio volumio-remote-updater[700]: [2025-02-19 15:39:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739979598 101 Feb 19 15:39:58 volumio volumio[7273]: 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 Feb 19 15:39:58 volumio volumio[7273]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 15:39:58 volumio volumio[7273]: Error: connect ECONNREFUSED 162.125.80.5:443 Feb 19 15:39:58 volumio volumio[7273]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 19 15:39:58 volumio volumio[7273]: errno: -111, Feb 19 15:39:58 volumio volumio[7273]: code: 'ECONNREFUSED', Feb 19 15:39:58 volumio volumio[7273]: syscall: 'connect', Feb 19 15:39:58 volumio volumio[7273]: address: '162.125.80.5', Feb 19 15:39:58 volumio volumio[7273]: port: 443 Feb 19 15:39:58 volumio volumio[7273]: } Feb 19 15:39:58 volumio volumio[7273]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 15:39:58 volumio sudo[7472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-19 15:38 Feb 19 15:39:58 volumio sudo[7472]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:58 volumio sudo[7472]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:58 volumio volumio-remote-updater[700]: [2025-02-19 15:39:58] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 19 15:39:58 volumio volumio-remote-updater[700]: [2025-02-19 15:39:58] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 19 15:39:58 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 19 15:39:58 volumio sudo[7314]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:58 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 19 15:39:58 volumio systemd[1]: Started dynamicswap service. Feb 19 15:39:58 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 19 15:39:59 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 19 15:39:59 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Feb 19 15:39:59 volumio systemd[1]: Stopped Volumio Backend Module. Feb 19 15:39:59 volumio systemd[1]: Started Volumio Backend Module. Feb 19 15:39:59 volumio systemd[1]: Started dynamicswap service. Feb 19 15:39:59 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 19 15:39:59 volumio volumio[7482]: info: ------------------------------------------- Feb 19 15:39:59 volumio volumio[7482]: info: ----- Volumio3 ---- Feb 19 15:39:59 volumio volumio[7482]: info: ------------------------------------------- Feb 19 15:39:59 volumio volumio[7482]: info: ----- System startup ---- Feb 19 15:39:59 volumio volumio[7482]: info: ------------------------------------------- Feb 19 15:39:59 volumio volumio[7482]: info: MYVOLUMIO Environment detected Feb 19 15:39:59 volumio volumio[7482]: info: Plugin folders cleanup Feb 19 15:39:59 volumio volumio[7482]: info: Scanning into folder /volumio/app/plugins/ Feb 19 15:39:59 volumio volumio[7482]: info: Scanning category audio_interface Feb 19 15:39:59 volumio volumio[7482]: info: Scanning category miscellanea Feb 19 15:39:59 volumio volumio[7482]: info: Scanning category music_service Feb 19 15:39:59 volumio volumio[7482]: info: Scanning category plugins.json Feb 19 15:39:59 volumio volumio[7482]: info: Scanning category system_controller Feb 19 15:39:59 volumio volumio[7482]: info: Scanning category user_interface Feb 19 15:39:59 volumio volumio[7482]: info: Scanning into folder /data/plugins/ Feb 19 15:39:59 volumio volumio[7482]: info: Plugin folders cleanup completed Feb 19 15:39:59 volumio volumio[7482]: info: ------------------------------------------- Feb 19 15:39:59 volumio volumio[7482]: info: ----- Core plugins startup ---- Feb 19 15:39:59 volumio volumio[7482]: info: ------------------------------------------- Feb 19 15:39:59 volumio volumio[7482]: info: Loading plugins from folder /volumio/app/plugins/ Feb 19 15:39:59 volumio volumio[7482]: info: Adding plugin upnp to MyMusic Plugins Feb 19 15:39:59 volumio volumio[7482]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 19 15:39:59 volumio volumio[7482]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 19 15:39:59 volumio volumio[7482]: info: Loading plugins from folder /data/plugins/ Feb 19 15:39:59 volumio volumio[7482]: info: Loading plugin "system"... Feb 19 15:39:59 volumio volumio[7482]: info: Loading plugin "appearance"... Feb 19 15:39:59 volumio volumio[7482]: info: Loading plugin "network"... Feb 19 15:39:59 volumio volumio[7482]: info: Refreshing Cached IP Addresses Feb 19 15:39:59 volumio sudo[7553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 19 15:39:59 volumio sudo[7553]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:59 volumio sudo[7553]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:59 volumio sudo[7556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 19 15:39:59 volumio sudo[7556]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:59 volumio sudo[7556]: pam_unix(sudo:session): session closed for user root Feb 19 15:39:59 volumio volumio[7482]: info: Loading plugin "services"... Feb 19 15:39:59 volumio volumio[7482]: info: Loading plugin "alsa_controller"... Feb 19 15:39:59 volumio sudo[7568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 19 15:39:59 volumio sudo[7568]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:39:59 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 15:39:59 volumio volumio[7482]: info: Loading plugin "wizard"... Feb 19 15:39:59 volumio volumio[7482]: info: Loading plugin "networkfs"... Feb 19 15:39:59 volumio volumio[7482]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Feb 19 15:39:59 volumio volumio[7482]: info: Starting Udev Watcher for removable devices Feb 19 15:39:59 volumio volumio[7482]: info: Ignoring mount for partition: boot Feb 19 15:39:59 volumio volumio[7482]: info: Ignoring mount for partition: volumio Feb 19 15:39:59 volumio volumio[7482]: info: Ignoring mount for partition: volumio_data Feb 19 15:39:59 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 15:39:59 volumio volumio[7482]: info: Loading plugin "volumio_command_line_client"... Feb 19 15:39:59 volumio volumio[7482]: info: Loading plugin "upnp"... Feb 19 15:39:59 volumio volumio[7482]: info: [1739979599954] Starting Upmpd Daemon Feb 19 15:39:59 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 15:39:59 volumio volumio[7482]: info: Loading plugin "my_music"... Feb 19 15:39:59 volumio volumio[7482]: info: Loading plugin "mpd"... Feb 19 15:40:00 volumio volumio[7482]: info: Loading plugin "upnp_browser"... Feb 19 15:40:00 volumio volumio[7482]: info: Loading plugin "alarm-clock"... Feb 19 15:40:00 volumio volumio[7482]: info: Loading plugin "airplay_emulation"... Feb 19 15:40:00 volumio volumio[7482]: info: Starting Shairport Sync Feb 19 15:40:00 volumio volumio[7482]: info: Loading plugin "last_100"... Feb 19 15:40:00 volumio volumio[7482]: info: Loading plugin "webradio"... Feb 19 15:40:00 volumio volumio[7482]: info: Loading plugin "i2s_dacs"... Feb 19 15:40:00 volumio volumio[7482]: info: I2S DAC not set, start Auto-detection Feb 19 15:40:00 volumio volumio[7482]: info: Loading plugin "volumiodiscovery"... Feb 19 15:40:00 volumio volumio[7482]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 19 15:40:00 volumio volumio[7482]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 15:40:00 volumio volumio[7482]: *** WARNING *** For more information see Feb 19 15:40:00 volumio volumio[7482]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 19 15:40:00 volumio volumio[7482]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 15:40:00 volumio volumio[7482]: *** WARNING *** For more information see Feb 19 15:40:00 volumio node[7482]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 19 15:40:00 volumio node[7482]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 15:40:00 volumio node[7482]: *** WARNING *** For more information see Feb 19 15:40:00 volumio node[7482]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 19 15:40:00 volumio node[7482]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 15:40:00 volumio node[7482]: *** WARNING *** For more information see Feb 19 15:40:00 volumio volumio[7482]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 19 15:40:00 volumio volumio[7482]: info: Discovery: Started advertising with name: Volumio Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 15:40:00 volumio volumio[7482]: info: Loading plugin "outputs"... Feb 19 15:40:00 volumio volumio[7482]: info: Loading plugin "albumart"... Feb 19 15:40:00 volumio volumio[7482]: info: Plugin example_plugin is not enabled Feb 19 15:40:00 volumio volumio[7482]: info: Loading plugin "inputs"... Feb 19 15:40:00 volumio volumio[7482]: info: Loading plugin "updater_comm"... Feb 19 15:40:00 volumio volumio[7482]: info: Plugin mpdemulation is not enabled Feb 19 15:40:00 volumio volumio[7482]: info: Loading plugin "rest_api"... Feb 19 15:40:00 volumio volumio[7482]: info: Loading plugin "websocket"... Feb 19 15:40:00 volumio volumio[7482]: Forking 3 albumart workers Feb 19 15:40:00 volumio volumio[7482]: info: Starting Socket.io Server version 2.3.0 Feb 19 15:40:00 volumio volumio[7482]: info: Loading i18n strings for locale zh Feb 19 15:40:00 volumio volumio[7482]: Updating browse sources language Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::initPlayerControls Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: Express server listening on port 3000 Feb 19 15:40:00 volumio volumio[7482]: [Metrics] WebUI: 0s 976.29ms Feb 19 15:40:00 volumio volumio[7482]: info: Setting Device type: x86 Feb 19 15:40:00 volumio volumio[7482]: info: CoreStateMachine::resetVolumioState Feb 19 15:40:00 volumio volumio[7482]: info: CoreStateMachine::getcurrentVolume Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 19 15:40:00 volumio volumio[7482]: info: Completed loading Core Plugins Feb 19 15:40:00 volumio volumio[7482]: info: Preparing to generate the ALSA configuration file Feb 19 15:40:00 volumio volumio[7482]: info: Volumio Network Manager: Network status updated: 3 Feb 19 15:40:00 volumio volumio[7482]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 19 15:40:00 volumio volumio[7482]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 19 15:40:00 volumio volumio[7482]: Unable to parse: Feb 19 15:40:00 volumio volumio[7482]: Simple mixer control 'PCM',0 Feb 19 15:40:00 volumio volumio[7482]: Capabilities: pswitch pswitch-joined Feb 19 15:40:00 volumio volumio[7482]: Playback channels: Mono Feb 19 15:40:00 volumio volumio[7482]: Mono: Playback [on] Feb 19 15:40:00 volumio volumio[7482]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 19 15:40:00 volumio volumio[7482]: Unable to parse: Feb 19 15:40:00 volumio volumio[7482]: Simple mixer control 'PCM',0 Feb 19 15:40:00 volumio volumio[7482]: Capabilities: pswitch pswitch-joined Feb 19 15:40:00 volumio volumio[7482]: Playback channels: Mono Feb 19 15:40:00 volumio volumio[7482]: Mono: Playback [on] Feb 19 15:40:00 volumio volumio[7482]: info: VolumeController:: Volume=undefined Mute =false Feb 19 15:40:00 volumio volumio[7482]: info: CoreStateMachine::pushState Feb 19 15:40:00 volumio volumio[7482]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::volumioPushState Feb 19 15:40:00 volumio volumio[7482]: info: CoreStateMachine::updateTrackBlock Feb 19 15:40:00 volumio volumio[7482]: info: CorePlayQueue::getTrackBlock Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 15:40:00 volumio volumio[7482]: Starting albumart workers Feb 19 15:40:00 volumio volumio[7482]: Starting albumart workers Feb 19 15:40:00 volumio volumio[7482]: Starting albumart workers Feb 19 15:40:00 volumio volumio[7482]: info: Reloading queue from file Feb 19 15:40:00 volumio volumio[7482]: info: Asound.conf file unchanged, so no further update is needed Feb 19 15:40:00 volumio volumio[7482]: info: Output device has changed, restarting MPD Feb 19 15:40:00 volumio sudo[7618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 15:40:00 volumio sudo[7618]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:00 volumio volumio[7482]: info: Output device has changed, restarting Shairport Sync Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:00 volumio sudo[7618]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:00 volumio sudo[7621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 15:40:00 volumio sudo[7621]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:00 volumio systemd[1]: Stopping Music Player Daemon... Feb 19 15:40:00 volumio volumio[7482]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 15:40:00 volumio volumio[7482]: info: ___________ START PLUGINS ___________ Feb 19 15:40:00 volumio volumio[7482]: info: ControllerMpd::onStart: Initializing MPD Feb 19 15:40:00 volumio volumio[7482]: info: Creating MPD Configuration file Feb 19 15:40:00 volumio systemd[1]: mpd.service: Succeeded. Feb 19 15:40:00 volumio systemd[1]: Stopped Music Player Daemon. Feb 19 15:40:00 volumio sudo[7628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 15:40:00 volumio sudo[7628]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 15:40:00 volumio volumio[7482]: info: [1739979600610] CoreMusicLibrary::Adding element 媒体服务器 Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:40:00 volumio systemd[1]: Starting Music Player Daemon... Feb 19 15:40:00 volumio sudo[7628]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:00 volumio sudo[7631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 15:40:00 volumio sudo[7631]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:00 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 19 15:40:00 volumio systemd[1]: mpd.service: Succeeded. Feb 19 15:40:00 volumio systemd[1]: Stopped Music Player Daemon. Feb 19 15:40:00 volumio systemd[1]: Starting Music Player Daemon... Feb 19 15:40:00 volumio volumio[7482]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 15:40:00 volumio volumio[7482]: info: [1739979600678] CoreMusicLibrary::Adding element Last_100 Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 15:40:00 volumio volumio[7482]: info: [1739979600679] CoreMusicLibrary::Adding element Webradio Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 15:40:00 volumio volumio[7482]: info: Initializing BBC Radios Feb 19 15:40:00 volumio sudo[7637]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 15:40:00 volumio sudo[7637]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:00 volumio sudo[7637]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: Volumio Calling Home Feb 19 15:40:00 volumio volumio[7482]: info: CoreStateMachine::setRepeat null single undefined Feb 19 15:40:00 volumio volumio[7482]: info: CoreStateMachine::pushState Feb 19 15:40:00 volumio volumio[7482]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::volumioPushState Feb 19 15:40:00 volumio volumio[7482]: info: CoreStateMachine::setRandom null Feb 19 15:40:00 volumio volumio[7482]: info: CoreStateMachine::pushState Feb 19 15:40:00 volumio volumio[7482]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::volumioPushState Feb 19 15:40:00 volumio volumio[7482]: Unable to parse: Feb 19 15:40:00 volumio volumio[7482]: Simple mixer control 'PCM',0 Feb 19 15:40:00 volumio volumio[7482]: Capabilities: pswitch pswitch-joined Feb 19 15:40:00 volumio volumio[7482]: Playback channels: Mono Feb 19 15:40:00 volumio volumio[7482]: Mono: Playback [on] Feb 19 15:40:00 volumio volumio[7482]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 19 15:40:00 volumio volumio[7482]: info: MPD Permissions set Feb 19 15:40:00 volumio volumio[7482]: info: MPD Permissions set Feb 19 15:40:00 volumio volumio[7482]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:00 volumio volumio[7482]: Unable to parse: Feb 19 15:40:00 volumio volumio[7482]: Simple mixer control 'PCM',0 Feb 19 15:40:00 volumio volumio[7482]: Capabilities: pswitch pswitch-joined Feb 19 15:40:00 volumio volumio[7482]: Playback channels: Mono Feb 19 15:40:00 volumio volumio[7482]: Mono: Playback [on] Feb 19 15:40:00 volumio volumio[7482]: info: VolumeController:: Volume=undefined Mute =false Feb 19 15:40:00 volumio volumio[7482]: info: CoreStateMachine::pushState Feb 19 15:40:00 volumio volumio[7482]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::volumioPushState Feb 19 15:40:00 volumio volumio[7482]: info: Starting Shairport Sync Feb 19 15:40:00 volumio volumio[7482]: info: Starting Shairport Sync Feb 19 15:40:00 volumio sudo[7694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 15:40:00 volumio sudo[7694]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:00 volumio volumio[7482]: info: Starting Shairport Sync Feb 19 15:40:00 volumio sudo[7697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 15:40:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 19 15:40:00 volumio sudo[7697]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:00 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 19 15:40:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 19 15:40:00 volumio sudo[7700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 15:40:00 volumio sudo[7700]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:00 volumio volumio[7482]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:00 volumio volumio[7482]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 19 15:40:00 volumio sudo[7697]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:00 volumio sudo[7694]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:00 volumio volumio[7482]: info: Shairport-Sync Started Feb 19 15:40:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 19 15:40:00 volumio volumio[7482]: Error adding Membership: Error: addMembership EINVAL Feb 19 15:40:00 volumio volumio[7482]: info: Shairport-Sync Started Feb 19 15:40:00 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 19 15:40:00 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 19 15:40:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 19 15:40:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 19 15:40:00 volumio sudo[7700]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:00 volumio volumio[7482]: info: Shairport-Sync Started Feb 19 15:40:01 volumio volumio[7482]: info: Discovery: adding 3a66fb0d-7cfc-4805-9244-7368ccc2b5bf Feb 19 15:40:01 volumio volumio[7482]: info: Discovery: Found device Volumio Feb 19 15:40:01 volumio volumio[7482]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:01 volumio volumio[7482]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:01 volumio volumio[7482]: info: Discovery: this is already registered, 3a66fb0d-7cfc-4805-9244-7368ccc2b5bf Feb 19 15:40:01 volumio volumio[7482]: info: Discovery: Found device Volumio Feb 19 15:40:01 volumio volumio[7482]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:01 volumio volumio[7482]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:01 volumio mpd[7651]: Feb 19 15:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 19 15:40:01 volumio systemd[1]: Started Music Player Daemon. Feb 19 15:40:01 volumio sudo[7631]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:01 volumio sudo[7621]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:01 volumio volumio[7482]: info: Completed starting Core Plugins Feb 19 15:40:01 volumio volumio[7482]: info: ------------------------------------------- Feb 19 15:40:01 volumio volumio[7482]: info: ----- MyVolumio plugins startup ---- Feb 19 15:40:01 volumio volumio[7482]: info: ------------------------------------------- Feb 19 15:40:01 volumio volumio[7482]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 19 15:40:01 volumio volumio[7482]: error: MPD error: The expression evaluated to a falsy value: Feb 19 15:40:01 volumio volumio[7482]: assert.ok(self.idling) Feb 19 15:40:01 volumio volumio[7482]: error: The expression evaluated to a falsy value: Feb 19 15:40:01 volumio volumio[7482]: assert.ok(self.idling) Feb 19 15:40:01 volumio volumio[7482]: error: updateQueue error: null Feb 19 15:40:01 volumio volumio[7482]: info: MPD running with PID7651 Feb 19 15:40:01 volumio volumio[7482]: ,establishing connection Feb 19 15:40:01 volumio volumio[7482]: error: updateQueue error: null Feb 19 15:40:01 volumio volumio[7482]: info: Volumio called home Feb 19 15:40:02 volumio volumio[7482]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Feb 19 15:40:03 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 19 15:40:03 volumio volumio[7482]: info: CoreCommandRouter::volumioGetVisibleSources Feb 19 15:40:03 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:40:03 volumio volumio[7482]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:03 volumio volumio[7482]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:03 volumio volumio[7482]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:03 volumio volumio[7482]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:03 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 19 15:40:03 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 19 15:40:03 volumio volumio[7482]: info: Received Get System Info Feb 19 15:40:03 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 19 15:40:03 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 19 15:40:03 volumio volumio[7482]: info: Discovery: Getting this device information Feb 19 15:40:03 volumio volumio[7482]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:03 volumio volumio[7482]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:03 volumio volumio[7482]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 19 15:40:03 volumio volumio[7482]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:03 volumio volumio[7482]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:03 volumio volumio[7482]: info: Listing playlists Feb 19 15:40:03 volumio volumio[7482]: info: CoreCommandRouter::volumioGetQueue Feb 19 15:40:03 volumio volumio[7482]: info: CoreStateMachine::getQueue Feb 19 15:40:03 volumio volumio[7482]: info: CorePlayQueue::getQueue Feb 19 15:40:03 volumio volumio[7482]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%8D%AB%E5%85%B0/Imagine/9bbd5b0d-509d-4101-9653-ea4498713fee.jpg' Feb 19 15:40:03 volumio volumio-remote-updater[700]: [2025-02-19 15:40:03] [connect] Successful connection Feb 19 15:40:03 volumio volumio-remote-updater[700]: [2025-02-19 15:40:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739979603 101 Feb 19 15:40:03 volumio volumio[7482]: 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 Feb 19 15:40:04 volumio volumio[7482]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 15:40:04 volumio volumio[7482]: Error: connect ECONNREFUSED 162.125.80.5:443 Feb 19 15:40:04 volumio volumio[7482]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 19 15:40:04 volumio volumio[7482]: errno: -111, Feb 19 15:40:04 volumio volumio[7482]: code: 'ECONNREFUSED', Feb 19 15:40:04 volumio volumio[7482]: syscall: 'connect', Feb 19 15:40:04 volumio volumio[7482]: address: '162.125.80.5', Feb 19 15:40:04 volumio volumio[7482]: port: 443 Feb 19 15:40:04 volumio volumio[7482]: } Feb 19 15:40:04 volumio volumio[7482]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 15:40:04 volumio sudo[7725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-19 15:39 Feb 19 15:40:04 volumio sudo[7725]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:04 volumio sudo[7725]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:04 volumio volumio-remote-updater[700]: [2025-02-19 15:40:04] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 19 15:40:04 volumio volumio-remote-updater[700]: [2025-02-19 15:40:04] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 19 15:40:04 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 19 15:40:04 volumio sudo[7568]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:04 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 19 15:40:04 volumio systemd[1]: Started dynamicswap service. Feb 19 15:40:04 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 19 15:40:05 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 19 15:40:05 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. Feb 19 15:40:05 volumio systemd[1]: Started dynamicswap service. Feb 19 15:40:05 volumio systemd[1]: Stopped Volumio Backend Module. Feb 19 15:40:05 volumio systemd[1]: Started Volumio Backend Module. Feb 19 15:40:05 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 19 15:40:05 volumio volumio[7737]: info: ------------------------------------------- Feb 19 15:40:05 volumio volumio[7737]: info: ----- Volumio3 ---- Feb 19 15:40:05 volumio volumio[7737]: info: ------------------------------------------- Feb 19 15:40:05 volumio volumio[7737]: info: ----- System startup ---- Feb 19 15:40:05 volumio volumio[7737]: info: ------------------------------------------- Feb 19 15:40:05 volumio volumio[7737]: info: MYVOLUMIO Environment detected Feb 19 15:40:05 volumio volumio[7737]: info: Plugin folders cleanup Feb 19 15:40:05 volumio volumio[7737]: info: Scanning into folder /volumio/app/plugins/ Feb 19 15:40:05 volumio volumio[7737]: info: Scanning category audio_interface Feb 19 15:40:05 volumio volumio[7737]: info: Scanning category miscellanea Feb 19 15:40:05 volumio volumio[7737]: info: Scanning category music_service Feb 19 15:40:05 volumio volumio[7737]: info: Scanning category plugins.json Feb 19 15:40:05 volumio volumio[7737]: info: Scanning category system_controller Feb 19 15:40:05 volumio volumio[7737]: info: Scanning category user_interface Feb 19 15:40:05 volumio volumio[7737]: info: Scanning into folder /data/plugins/ Feb 19 15:40:05 volumio volumio[7737]: info: Plugin folders cleanup completed Feb 19 15:40:05 volumio volumio[7737]: info: ------------------------------------------- Feb 19 15:40:05 volumio volumio[7737]: info: ----- Core plugins startup ---- Feb 19 15:40:05 volumio volumio[7737]: info: ------------------------------------------- Feb 19 15:40:05 volumio volumio[7737]: info: Loading plugins from folder /volumio/app/plugins/ Feb 19 15:40:05 volumio volumio[7737]: info: Adding plugin upnp to MyMusic Plugins Feb 19 15:40:05 volumio volumio[7737]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 19 15:40:05 volumio volumio[7737]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 19 15:40:05 volumio volumio[7737]: info: Loading plugins from folder /data/plugins/ Feb 19 15:40:05 volumio volumio[7737]: info: Loading plugin "system"... Feb 19 15:40:05 volumio volumio[7737]: info: Loading plugin "appearance"... Feb 19 15:40:05 volumio volumio[7737]: info: Loading plugin "network"... Feb 19 15:40:05 volumio volumio[7737]: info: Refreshing Cached IP Addresses Feb 19 15:40:05 volumio sudo[7763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 19 15:40:05 volumio sudo[7763]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:05 volumio sudo[7763]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:05 volumio sudo[7765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 19 15:40:05 volumio sudo[7765]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:05 volumio sudo[7765]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:05 volumio volumio[7737]: info: Loading plugin "services"... Feb 19 15:40:05 volumio volumio[7737]: info: Loading plugin "alsa_controller"... Feb 19 15:40:05 volumio sudo[7778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 19 15:40:05 volumio sudo[7778]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:05 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 15:40:05 volumio volumio[7737]: info: Loading plugin "wizard"... Feb 19 15:40:05 volumio volumio[7737]: info: Loading plugin "networkfs"... Feb 19 15:40:05 volumio volumio[7737]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Feb 19 15:40:05 volumio volumio[7737]: info: Starting Udev Watcher for removable devices Feb 19 15:40:05 volumio volumio[7737]: info: Ignoring mount for partition: boot Feb 19 15:40:05 volumio volumio[7737]: info: Ignoring mount for partition: volumio Feb 19 15:40:05 volumio volumio[7737]: info: Ignoring mount for partition: volumio_data Feb 19 15:40:05 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 15:40:05 volumio volumio[7737]: info: Loading plugin "volumio_command_line_client"... Feb 19 15:40:05 volumio volumio[7737]: info: Loading plugin "upnp"... Feb 19 15:40:05 volumio volumio[7737]: info: [1739979605864] Starting Upmpd Daemon Feb 19 15:40:05 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 15:40:05 volumio volumio[7737]: info: Loading plugin "my_music"... Feb 19 15:40:05 volumio volumio[7737]: info: Loading plugin "mpd"... Feb 19 15:40:05 volumio volumio[7737]: info: Loading plugin "upnp_browser"... Feb 19 15:40:06 volumio volumio[7737]: info: Loading plugin "alarm-clock"... Feb 19 15:40:06 volumio volumio[7737]: info: Loading plugin "airplay_emulation"... Feb 19 15:40:06 volumio volumio[7737]: info: Starting Shairport Sync Feb 19 15:40:06 volumio volumio[7737]: info: Loading plugin "last_100"... Feb 19 15:40:06 volumio volumio[7737]: info: Loading plugin "webradio"... Feb 19 15:40:06 volumio volumio[7737]: info: Loading plugin "i2s_dacs"... Feb 19 15:40:06 volumio volumio[7737]: info: I2S DAC not set, start Auto-detection Feb 19 15:40:06 volumio volumio[7737]: info: Loading plugin "volumiodiscovery"... Feb 19 15:40:06 volumio volumio[7737]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 19 15:40:06 volumio volumio[7737]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 15:40:06 volumio volumio[7737]: *** WARNING *** For more information see Feb 19 15:40:06 volumio volumio[7737]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 19 15:40:06 volumio volumio[7737]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 15:40:06 volumio volumio[7737]: *** WARNING *** For more information see Feb 19 15:40:06 volumio node[7737]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 19 15:40:06 volumio node[7737]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 15:40:06 volumio node[7737]: *** WARNING *** For more information see Feb 19 15:40:06 volumio node[7737]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 19 15:40:06 volumio node[7737]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 15:40:06 volumio node[7737]: *** WARNING *** For more information see Feb 19 15:40:06 volumio volumio[7737]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 19 15:40:06 volumio volumio[7737]: info: Discovery: Started advertising with name: Volumio Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 15:40:06 volumio volumio[7737]: info: Loading plugin "outputs"... Feb 19 15:40:06 volumio volumio[7737]: info: Loading plugin "albumart"... Feb 19 15:40:06 volumio volumio[7737]: info: Plugin example_plugin is not enabled Feb 19 15:40:06 volumio volumio[7737]: info: Loading plugin "inputs"... Feb 19 15:40:06 volumio volumio[7737]: info: Loading plugin "updater_comm"... Feb 19 15:40:06 volumio volumio[7737]: info: Plugin mpdemulation is not enabled Feb 19 15:40:06 volumio volumio[7737]: info: Loading plugin "rest_api"... Feb 19 15:40:06 volumio volumio[7737]: info: Loading plugin "websocket"... Feb 19 15:40:06 volumio volumio[7737]: info: Starting Socket.io Server version 2.3.0 Feb 19 15:40:06 volumio volumio[7737]: Forking 3 albumart workers Feb 19 15:40:06 volumio volumio[7737]: info: Loading i18n strings for locale zh Feb 19 15:40:06 volumio volumio[7737]: Updating browse sources language Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::initPlayerControls Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: Express server listening on port 3000 Feb 19 15:40:06 volumio volumio[7737]: [Metrics] WebUI: 0s 897.90ms Feb 19 15:40:06 volumio volumio[7737]: info: Setting Device type: x86 Feb 19 15:40:06 volumio volumio[7737]: info: CoreStateMachine::resetVolumioState Feb 19 15:40:06 volumio volumio[7737]: info: CoreStateMachine::getcurrentVolume Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 19 15:40:06 volumio volumio[7737]: info: Completed loading Core Plugins Feb 19 15:40:06 volumio volumio[7737]: info: Preparing to generate the ALSA configuration file Feb 19 15:40:06 volumio volumio[7737]: info: Volumio Network Manager: Network status updated: 3 Feb 19 15:40:06 volumio volumio[7737]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 19 15:40:06 volumio volumio[7737]: Unable to parse: Feb 19 15:40:06 volumio volumio[7737]: Simple mixer control 'PCM',0 Feb 19 15:40:06 volumio volumio[7737]: Capabilities: pswitch pswitch-joined Feb 19 15:40:06 volumio volumio[7737]: Playback channels: Mono Feb 19 15:40:06 volumio volumio[7737]: Mono: Playback [on] Feb 19 15:40:06 volumio volumio[7737]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 19 15:40:06 volumio volumio[7737]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 19 15:40:06 volumio volumio[7737]: Starting albumart workers Feb 19 15:40:06 volumio volumio[7737]: info: Reloading queue from file Feb 19 15:40:06 volumio volumio[7737]: Unable to parse: Feb 19 15:40:06 volumio volumio[7737]: Simple mixer control 'PCM',0 Feb 19 15:40:06 volumio volumio[7737]: Capabilities: pswitch pswitch-joined Feb 19 15:40:06 volumio volumio[7737]: Playback channels: Mono Feb 19 15:40:06 volumio volumio[7737]: Mono: Playback [on] Feb 19 15:40:06 volumio volumio[7737]: info: VolumeController:: Volume=undefined Mute =false Feb 19 15:40:06 volumio volumio[7737]: info: CoreStateMachine::pushState Feb 19 15:40:06 volumio volumio[7737]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::volumioPushState Feb 19 15:40:06 volumio volumio[7737]: info: CoreStateMachine::updateTrackBlock Feb 19 15:40:06 volumio volumio[7737]: info: CorePlayQueue::getTrackBlock Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 15:40:06 volumio volumio[7737]: info: Asound.conf file unchanged, so no further update is needed Feb 19 15:40:06 volumio volumio[7737]: info: Output device has changed, restarting MPD Feb 19 15:40:06 volumio volumio[7737]: Starting albumart workers Feb 19 15:40:06 volumio sudo[7828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 15:40:06 volumio sudo[7828]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:06 volumio sudo[7828]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:06 volumio volumio[7737]: info: Output device has changed, restarting Shairport Sync Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:06 volumio sudo[7831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 15:40:06 volumio sudo[7831]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:06 volumio systemd[1]: Stopping Music Player Daemon... Feb 19 15:40:06 volumio systemd[1]: mpd.service: Succeeded. Feb 19 15:40:06 volumio systemd[1]: Stopped Music Player Daemon. Feb 19 15:40:06 volumio volumio[7737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 15:40:06 volumio volumio[7737]: info: ___________ START PLUGINS ___________ Feb 19 15:40:06 volumio volumio[7737]: info: ControllerMpd::onStart: Initializing MPD Feb 19 15:40:06 volumio volumio[7737]: info: Creating MPD Configuration file Feb 19 15:40:06 volumio systemd[1]: Starting Music Player Daemon... Feb 19 15:40:06 volumio volumio[7737]: Starting albumart workers Feb 19 15:40:06 volumio sudo[7838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 15:40:06 volumio sudo[7838]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:06 volumio sudo[7836]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 15:40:06 volumio sudo[7836]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:06 volumio sudo[7836]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 15:40:06 volumio volumio[7737]: info: [1739979606566] CoreMusicLibrary::Adding element 媒体服务器 Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:40:06 volumio sudo[7838]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:06 volumio sudo[7842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 15:40:06 volumio sudo[7842]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:06 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 19 15:40:06 volumio systemd[1]: mpd.service: Succeeded. Feb 19 15:40:06 volumio systemd[1]: Stopped Music Player Daemon. Feb 19 15:40:06 volumio systemd[1]: Starting Music Player Daemon... Feb 19 15:40:06 volumio volumio[7737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:06 volumio sudo[7848]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 15:40:06 volumio sudo[7848]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:06 volumio sudo[7848]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:06 volumio volumio[7737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 15:40:06 volumio volumio[7737]: info: [1739979606681] CoreMusicLibrary::Adding element Last_100 Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 15:40:06 volumio volumio[7737]: info: [1739979606682] CoreMusicLibrary::Adding element Webradio Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 15:40:06 volumio volumio[7737]: info: Initializing BBC Radios Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: Volumio Calling Home Feb 19 15:40:06 volumio volumio[7737]: info: CoreStateMachine::setRepeat null single undefined Feb 19 15:40:06 volumio volumio[7737]: info: CoreStateMachine::pushState Feb 19 15:40:06 volumio volumio[7737]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::volumioPushState Feb 19 15:40:06 volumio volumio[7737]: info: CoreStateMachine::setRandom null Feb 19 15:40:06 volumio volumio[7737]: info: CoreStateMachine::pushState Feb 19 15:40:06 volumio volumio[7737]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::volumioPushState Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 19 15:40:06 volumio volumio[7737]: info: MPD Permissions set Feb 19 15:40:06 volumio volumio[7737]: info: MPD Permissions set Feb 19 15:40:06 volumio volumio[7737]: Unable to parse: Feb 19 15:40:06 volumio volumio[7737]: Simple mixer control 'PCM',0 Feb 19 15:40:06 volumio volumio[7737]: Capabilities: pswitch pswitch-joined Feb 19 15:40:06 volumio volumio[7737]: Playback channels: Mono Feb 19 15:40:06 volumio volumio[7737]: Mono: Playback [on] Feb 19 15:40:06 volumio volumio[7737]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::volumioGetVisibleSources Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:06 volumio volumio[7737]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:06 volumio volumio[7737]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 19 15:40:06 volumio volumio[7737]: info: Received Get System Info Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 19 15:40:06 volumio volumio[7737]: info: Discovery: Getting this device information Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:06 volumio volumio[7737]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:06 volumio volumio[7737]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:06 volumio volumio[7737]: info: Listing playlists Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::volumioGetQueue Feb 19 15:40:06 volumio volumio[7737]: info: CoreStateMachine::getQueue Feb 19 15:40:06 volumio volumio[7737]: info: CorePlayQueue::getQueue Feb 19 15:40:06 volumio volumio[7737]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 19 15:40:06 volumio volumio[7737]: Unable to parse: Feb 19 15:40:06 volumio volumio[7737]: Simple mixer control 'PCM',0 Feb 19 15:40:06 volumio volumio[7737]: Capabilities: pswitch pswitch-joined Feb 19 15:40:06 volumio volumio[7737]: Playback channels: Mono Feb 19 15:40:06 volumio volumio[7737]: Mono: Playback [on] Feb 19 15:40:06 volumio volumio[7737]: info: VolumeController:: Volume=undefined Mute =false Feb 19 15:40:06 volumio volumio[7737]: info: CoreStateMachine::pushState Feb 19 15:40:06 volumio volumio[7737]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::volumioPushState Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:06 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 19 15:40:07 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:07 volumio mpd[7861]: Feb 19 15:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 19 15:40:07 volumio systemd[1]: Started Music Player Daemon. Feb 19 15:40:07 volumio sudo[7831]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:07 volumio sudo[7842]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:07 volumio volumio[7737]: info: Starting Shairport Sync Feb 19 15:40:07 volumio volumio[7737]: info: Starting Shairport Sync Feb 19 15:40:07 volumio sudo[7909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 15:40:07 volumio sudo[7909]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:07 volumio volumio[7737]: info: Starting Shairport Sync Feb 19 15:40:07 volumio sudo[7911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 15:40:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 19 15:40:07 volumio sudo[7911]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:07 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 19 15:40:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 19 15:40:07 volumio sudo[7916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 15:40:07 volumio sudo[7916]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:07 volumio volumio[7737]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:07 volumio volumio[7737]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:07 volumio volumio[7737]: info: Completed starting Core Plugins Feb 19 15:40:07 volumio volumio[7737]: info: ------------------------------------------- Feb 19 15:40:07 volumio volumio[7737]: info: ----- MyVolumio plugins startup ---- Feb 19 15:40:07 volumio volumio[7737]: info: ------------------------------------------- Feb 19 15:40:07 volumio volumio[7737]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 19 15:40:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 19 15:40:07 volumio sudo[7909]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:07 volumio volumio[7737]: info: Discovery: adding 3a66fb0d-7cfc-4805-9244-7368ccc2b5bf Feb 19 15:40:07 volumio volumio[7737]: info: Discovery: Found device Volumio Feb 19 15:40:07 volumio volumio[7737]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:07 volumio volumio[7737]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 19 15:40:07 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 19 15:40:07 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 19 15:40:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 19 15:40:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 19 15:40:07 volumio sudo[7916]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:07 volumio sudo[7911]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:07 volumio volumio[7737]: error: MPD error: The expression evaluated to a falsy value: Feb 19 15:40:07 volumio volumio[7737]: assert.ok(self.idling) Feb 19 15:40:07 volumio volumio[7737]: error: The expression evaluated to a falsy value: Feb 19 15:40:07 volumio volumio[7737]: assert.ok(self.idling) Feb 19 15:40:07 volumio volumio[7737]: info: MPD running with PID7861 Feb 19 15:40:07 volumio volumio[7737]: ,establishing connection Feb 19 15:40:07 volumio volumio[7737]: info: Shairport-Sync Started Feb 19 15:40:07 volumio volumio[7737]: Error adding Membership: Error: addMembership EINVAL Feb 19 15:40:07 volumio volumio[7737]: error: updateQueue error: null Feb 19 15:40:07 volumio volumio[7737]: info: Discovery: this is already registered, 3a66fb0d-7cfc-4805-9244-7368ccc2b5bf Feb 19 15:40:07 volumio volumio[7737]: info: Discovery: Found device Volumio Feb 19 15:40:07 volumio volumio[7737]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:07 volumio volumio[7737]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:07 volumio volumio[7737]: info: Shairport-Sync Started Feb 19 15:40:07 volumio volumio[7737]: info: Shairport-Sync Started Feb 19 15:40:07 volumio volumio[7737]: info: Volumio called home Feb 19 15:40:07 volumio volumio[7737]: error: updateQueue error: null Feb 19 15:40:07 volumio volumio[7737]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Michael%20Jackson/Off%20The%20Wall/bb3de082-0243-42d0-9f33-586b010c0544.png' Feb 19 15:40:08 volumio volumio[7737]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 15:40:08 volumio volumio[7737]: Error: connect ECONNREFUSED 162.125.80.5:80 Feb 19 15:40:08 volumio volumio[7737]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 19 15:40:08 volumio volumio[7737]: errno: -111, Feb 19 15:40:08 volumio volumio[7737]: code: 'ECONNREFUSED', Feb 19 15:40:08 volumio volumio[7737]: syscall: 'connect', Feb 19 15:40:08 volumio volumio[7737]: address: '162.125.80.5', Feb 19 15:40:08 volumio volumio[7737]: port: 80 Feb 19 15:40:08 volumio volumio[7737]: } Feb 19 15:40:08 volumio volumio[7737]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 15:40:08 volumio sudo[7933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-19 15:39 Feb 19 15:40:08 volumio sudo[7933]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:08 volumio sudo[7933]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:09 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 19 15:40:09 volumio sudo[7778]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:09 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 19 15:40:09 volumio systemd[1]: Started dynamicswap service. Feb 19 15:40:09 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 19 15:40:09 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 19 15:40:09 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10. Feb 19 15:40:09 volumio systemd[1]: Stopped Volumio Backend Module. Feb 19 15:40:09 volumio systemd[1]: Started Volumio Backend Module. Feb 19 15:40:09 volumio systemd[1]: Started dynamicswap service. Feb 19 15:40:09 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 19 15:40:09 volumio volumio-remote-updater[700]: [2025-02-19 15:40:09] [info] asio async_connect error: system:111 (Connection refused) Feb 19 15:40:09 volumio volumio-remote-updater[700]: [2025-02-19 15:40:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 19 15:40:09 volumio volumio-remote-updater[700]: [2025-02-19 15:40:09] [error] handle_connect error: Underlying Transport Error Feb 19 15:40:10 volumio volumio[7946]: info: ------------------------------------------- Feb 19 15:40:10 volumio volumio[7946]: info: ----- Volumio3 ---- Feb 19 15:40:10 volumio volumio[7946]: info: ------------------------------------------- Feb 19 15:40:10 volumio volumio[7946]: info: ----- System startup ---- Feb 19 15:40:10 volumio volumio[7946]: info: ------------------------------------------- Feb 19 15:40:10 volumio volumio[7946]: info: MYVOLUMIO Environment detected Feb 19 15:40:10 volumio volumio[7946]: info: Plugin folders cleanup Feb 19 15:40:10 volumio volumio[7946]: info: Scanning into folder /volumio/app/plugins/ Feb 19 15:40:10 volumio volumio[7946]: info: Scanning category audio_interface Feb 19 15:40:10 volumio volumio[7946]: info: Scanning category miscellanea Feb 19 15:40:10 volumio volumio[7946]: info: Scanning category music_service Feb 19 15:40:10 volumio volumio[7946]: info: Scanning category plugins.json Feb 19 15:40:10 volumio volumio[7946]: info: Scanning category system_controller Feb 19 15:40:10 volumio volumio[7946]: info: Scanning category user_interface Feb 19 15:40:10 volumio volumio[7946]: info: Scanning into folder /data/plugins/ Feb 19 15:40:10 volumio volumio[7946]: info: Plugin folders cleanup completed Feb 19 15:40:10 volumio volumio[7946]: info: ------------------------------------------- Feb 19 15:40:10 volumio volumio[7946]: info: ----- Core plugins startup ---- Feb 19 15:40:10 volumio volumio[7946]: info: ------------------------------------------- Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugins from folder /volumio/app/plugins/ Feb 19 15:40:10 volumio volumio[7946]: info: Adding plugin upnp to MyMusic Plugins Feb 19 15:40:10 volumio volumio[7946]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 19 15:40:10 volumio volumio[7946]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugins from folder /data/plugins/ Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "system"... Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "appearance"... Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "network"... Feb 19 15:40:10 volumio volumio[7946]: info: Refreshing Cached IP Addresses Feb 19 15:40:10 volumio sudo[7973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 19 15:40:10 volumio sudo[7973]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:10 volumio sudo[7973]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:10 volumio sudo[7975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 19 15:40:10 volumio sudo[7975]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:10 volumio sudo[7975]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "services"... Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "alsa_controller"... Feb 19 15:40:10 volumio sudo[7988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 19 15:40:10 volumio sudo[7988]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:10 volumio sudo[7988]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:10 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "wizard"... Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "networkfs"... Feb 19 15:40:10 volumio volumio[7946]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Feb 19 15:40:10 volumio volumio[7946]: info: Starting Udev Watcher for removable devices Feb 19 15:40:10 volumio volumio[7946]: info: Ignoring mount for partition: boot Feb 19 15:40:10 volumio volumio[7946]: info: Ignoring mount for partition: volumio Feb 19 15:40:10 volumio volumio[7946]: info: Ignoring mount for partition: volumio_data Feb 19 15:40:10 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "volumio_command_line_client"... Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "upnp"... Feb 19 15:40:10 volumio volumio[7946]: info: [1739979610640] Starting Upmpd Daemon Feb 19 15:40:10 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "my_music"... Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "mpd"... Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "upnp_browser"... Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "alarm-clock"... Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "airplay_emulation"... Feb 19 15:40:10 volumio volumio[7946]: info: Starting Shairport Sync Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "last_100"... Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "webradio"... Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "i2s_dacs"... Feb 19 15:40:10 volumio volumio[7946]: info: I2S DAC not set, start Auto-detection Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "volumiodiscovery"... Feb 19 15:40:10 volumio volumio[7946]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 19 15:40:10 volumio volumio[7946]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 15:40:10 volumio volumio[7946]: *** WARNING *** For more information see Feb 19 15:40:10 volumio volumio[7946]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 19 15:40:10 volumio volumio[7946]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 15:40:10 volumio node[7946]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 19 15:40:10 volumio volumio[7946]: *** WARNING *** For more information see Feb 19 15:40:10 volumio node[7946]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 15:40:10 volumio node[7946]: *** WARNING *** For more information see Feb 19 15:40:10 volumio node[7946]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 19 15:40:10 volumio node[7946]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 15:40:10 volumio node[7946]: *** WARNING *** For more information see Feb 19 15:40:10 volumio volumio[7946]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 19 15:40:10 volumio volumio[7946]: info: Discovery: Started advertising with name: Volumio Feb 19 15:40:10 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "outputs"... Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "albumart"... Feb 19 15:40:10 volumio volumio[7946]: info: Plugin example_plugin is not enabled Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "inputs"... Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "updater_comm"... Feb 19 15:40:10 volumio volumio[7946]: info: Plugin mpdemulation is not enabled Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "rest_api"... Feb 19 15:40:10 volumio volumio[7946]: info: Loading plugin "websocket"... Feb 19 15:40:10 volumio volumio[7946]: Forking 3 albumart workers Feb 19 15:40:10 volumio volumio[7946]: info: Starting Socket.io Server version 2.3.0 Feb 19 15:40:10 volumio volumio[7946]: info: Loading i18n strings for locale zh Feb 19 15:40:10 volumio volumio[7946]: Updating browse sources language Feb 19 15:40:10 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:40:10 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:40:10 volumio volumio[7946]: info: CoreCommandRouter::initPlayerControls Feb 19 15:40:10 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:10 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:40:10 volumio volumio[7946]: Express server listening on port 3000 Feb 19 15:40:10 volumio volumio[7946]: [Metrics] WebUI: 0s 908.00ms Feb 19 15:40:10 volumio volumio[7946]: info: Setting Device type: x86 Feb 19 15:40:10 volumio volumio[7946]: info: CoreStateMachine::resetVolumioState Feb 19 15:40:10 volumio volumio[7946]: info: CoreStateMachine::getcurrentVolume Feb 19 15:40:10 volumio volumio[7946]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 15:40:10 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 19 15:40:11 volumio volumio[7946]: info: Completed loading Core Plugins Feb 19 15:40:11 volumio volumio[7946]: info: Preparing to generate the ALSA configuration file Feb 19 15:40:11 volumio volumio[7946]: info: Volumio Network Manager: Network status updated: 3 Feb 19 15:40:11 volumio volumio[7946]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 19 15:40:11 volumio volumio[7946]: wlan0 Interface doesn't support scanning : Device or resource busy Feb 19 15:40:11 volumio volumio[7946]: info: Cannot use regular scanning, forcing with ap-force Feb 19 15:40:11 volumio sudo[8032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 19 15:40:11 volumio sudo[8032]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:11 volumio sudo[8032]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:11 volumio volumio[7946]: command failed: Device or resource busy (-16) Feb 19 15:40:11 volumio volumio[7946]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 19 15:40:11 volumio volumio[7946]: command failed: Device or resource busy (-16) Feb 19 15:40:11 volumio volumio[7946]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 19 15:40:11 volumio volumio[7946]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 19 15:40:11 volumio volumio[7946]: Unable to parse: Feb 19 15:40:11 volumio volumio[7946]: Simple mixer control 'PCM',0 Feb 19 15:40:11 volumio volumio[7946]: Capabilities: pswitch pswitch-joined Feb 19 15:40:11 volumio volumio[7946]: Playback channels: Mono Feb 19 15:40:11 volumio volumio[7946]: Mono: Playback [on] Feb 19 15:40:11 volumio volumio[7946]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 19 15:40:11 volumio volumio[7946]: Starting albumart workers Feb 19 15:40:11 volumio volumio[7946]: Starting albumart workers Feb 19 15:40:11 volumio volumio[7946]: info: Reloading queue from file Feb 19 15:40:11 volumio volumio[7946]: info: Asound.conf file unchanged, so no further update is needed Feb 19 15:40:11 volumio volumio[7946]: info: Output device has changed, restarting MPD Feb 19 15:40:11 volumio volumio[7946]: Starting albumart workers Feb 19 15:40:11 volumio volumio[7946]: info: Output device has changed, restarting Shairport Sync Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:11 volumio sudo[8040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 15:40:11 volumio sudo[8040]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:11 volumio sudo[8040]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:11 volumio sudo[8042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 15:40:11 volumio sudo[8042]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:11 volumio systemd[1]: Stopping Music Player Daemon... Feb 19 15:40:11 volumio systemd[1]: mpd.service: Succeeded. Feb 19 15:40:11 volumio systemd[1]: Stopped Music Player Daemon. Feb 19 15:40:11 volumio volumio[7946]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 15:40:11 volumio volumio[7946]: info: ___________ START PLUGINS ___________ Feb 19 15:40:11 volumio volumio[7946]: info: ControllerMpd::onStart: Initializing MPD Feb 19 15:40:11 volumio volumio[7946]: info: Creating MPD Configuration file Feb 19 15:40:11 volumio systemd[1]: Starting Music Player Daemon... Feb 19 15:40:11 volumio sudo[8051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 15:40:11 volumio sudo[8051]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:11 volumio sudo[8051]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 15:40:11 volumio volumio[7946]: info: [1739979611295] CoreMusicLibrary::Adding element 媒体服务器 Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:40:11 volumio sudo[8054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 15:40:11 volumio sudo[8054]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:11 volumio sudo[8048]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 15:40:11 volumio sudo[8048]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:11 volumio sudo[8048]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:11 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 19 15:40:11 volumio systemd[1]: mpd.service: Succeeded. Feb 19 15:40:11 volumio systemd[1]: Stopped Music Player Daemon. Feb 19 15:40:11 volumio volumio[7946]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:11 volumio systemd[1]: Starting Music Player Daemon... Feb 19 15:40:11 volumio volumio[7946]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 15:40:11 volumio volumio[7946]: info: [1739979611379] CoreMusicLibrary::Adding element Last_100 Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 15:40:11 volumio volumio[7946]: info: [1739979611381] CoreMusicLibrary::Adding element Webradio Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 15:40:11 volumio volumio[7946]: info: Initializing BBC Radios Feb 19 15:40:11 volumio sudo[8059]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 15:40:11 volumio sudo[8059]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:11 volumio sudo[8059]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: Volumio Calling Home Feb 19 15:40:11 volumio volumio[7946]: info: CoreStateMachine::setRepeat null single undefined Feb 19 15:40:11 volumio volumio[7946]: info: CoreStateMachine::pushState Feb 19 15:40:11 volumio volumio[7946]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::volumioPushState Feb 19 15:40:11 volumio volumio[7946]: info: CoreStateMachine::setRandom null Feb 19 15:40:11 volumio volumio[7946]: info: CoreStateMachine::pushState Feb 19 15:40:11 volumio volumio[7946]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::volumioPushState Feb 19 15:40:11 volumio volumio[7946]: Unable to parse: Feb 19 15:40:11 volumio volumio[7946]: Simple mixer control 'PCM',0 Feb 19 15:40:11 volumio volumio[7946]: Capabilities: pswitch pswitch-joined Feb 19 15:40:11 volumio volumio[7946]: Playback channels: Mono Feb 19 15:40:11 volumio volumio[7946]: Mono: Playback [on] Feb 19 15:40:11 volumio volumio[7946]: info: VolumeController:: Volume=undefined Mute =false Feb 19 15:40:11 volumio volumio[7946]: info: CoreStateMachine::pushState Feb 19 15:40:11 volumio volumio[7946]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::volumioPushState Feb 19 15:40:11 volumio volumio[7946]: info: CoreStateMachine::updateTrackBlock Feb 19 15:40:11 volumio volumio[7946]: info: CorePlayQueue::getTrackBlock Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 15:40:11 volumio volumio[7946]: info: MPD Permissions set Feb 19 15:40:11 volumio volumio[7946]: info: MPD Permissions set Feb 19 15:40:11 volumio volumio[7946]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 15:40:11 volumio volumio[7946]: Unable to parse: Feb 19 15:40:11 volumio volumio[7946]: Simple mixer control 'PCM',0 Feb 19 15:40:11 volumio volumio[7946]: Capabilities: pswitch pswitch-joined Feb 19 15:40:11 volumio volumio[7946]: Playback channels: Mono Feb 19 15:40:11 volumio volumio[7946]: Mono: Playback [on] Feb 19 15:40:11 volumio volumio[7946]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 19 15:40:11 volumio volumio[7946]: info: Starting Shairport Sync Feb 19 15:40:11 volumio volumio[7946]: info: Starting Shairport Sync Feb 19 15:40:11 volumio sudo[8118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 15:40:11 volumio sudo[8118]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:11 volumio volumio[7946]: info: Starting Shairport Sync Feb 19 15:40:11 volumio sudo[8120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 15:40:11 volumio sudo[8120]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 19 15:40:11 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 19 15:40:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 19 15:40:11 volumio sudo[8124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 15:40:11 volumio sudo[8124]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:11 volumio volumio[7946]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:11 volumio volumio[7946]: Unable to parse: Feb 19 15:40:11 volumio volumio[7946]: Simple mixer control 'PCM',0 Feb 19 15:40:11 volumio volumio[7946]: Capabilities: pswitch pswitch-joined Feb 19 15:40:11 volumio volumio[7946]: Playback channels: Mono Feb 19 15:40:11 volumio volumio[7946]: Mono: Playback [on] Feb 19 15:40:11 volumio volumio[7946]: info: VolumeController:: Volume=undefined Mute =false Feb 19 15:40:11 volumio volumio[7946]: info: CoreStateMachine::pushState Feb 19 15:40:11 volumio volumio[7946]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::volumioPushState Feb 19 15:40:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 19 15:40:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 19 15:40:11 volumio sudo[8118]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:11 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 19 15:40:11 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 19 15:40:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 19 15:40:11 volumio volumio[7946]: info: Shairport-Sync Started Feb 19 15:40:11 volumio volumio[7946]: Error adding Membership: Error: addMembership EINVAL Feb 19 15:40:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 19 15:40:11 volumio sudo[8120]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:11 volumio volumio[7946]: info: Shairport-Sync Started Feb 19 15:40:11 volumio volumio[7946]: info: Discovery: adding 3a66fb0d-7cfc-4805-9244-7368ccc2b5bf Feb 19 15:40:11 volumio volumio[7946]: info: Discovery: Found device Volumio Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:11 volumio volumio[7946]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:11 volumio sudo[8124]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:11 volumio volumio[7946]: info: Discovery: this is already registered, 3a66fb0d-7cfc-4805-9244-7368ccc2b5bf Feb 19 15:40:11 volumio volumio[7946]: info: Discovery: Found device Volumio Feb 19 15:40:11 volumio volumio[7946]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:11 volumio volumio[7946]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:11 volumio volumio[7946]: info: Shairport-Sync Started Feb 19 15:40:11 volumio mpd[8074]: Feb 19 15:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 19 15:40:11 volumio systemd[1]: Started Music Player Daemon. Feb 19 15:40:11 volumio sudo[8054]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:11 volumio sudo[8042]: pam_unix(sudo:session): session closed for user root Feb 19 15:40:11 volumio volumio[7946]: info: Completed starting Core Plugins Feb 19 15:40:11 volumio volumio[7946]: info: ------------------------------------------- Feb 19 15:40:11 volumio volumio[7946]: info: ----- MyVolumio plugins startup ---- Feb 19 15:40:11 volumio volumio[7946]: info: ------------------------------------------- Feb 19 15:40:11 volumio volumio[7946]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 19 15:40:11 volumio volumio[7946]: error: MPD error: The expression evaluated to a falsy value: Feb 19 15:40:11 volumio volumio[7946]: assert.ok(self.idling) Feb 19 15:40:11 volumio volumio[7946]: error: The expression evaluated to a falsy value: Feb 19 15:40:11 volumio volumio[7946]: assert.ok(self.idling) Feb 19 15:40:11 volumio volumio[7946]: error: updateQueue error: null Feb 19 15:40:11 volumio volumio[7946]: info: MPD running with PID8074 Feb 19 15:40:11 volumio volumio[7946]: ,establishing connection Feb 19 15:40:11 volumio volumio[7946]: error: updateQueue error: null Feb 19 15:40:11 volumio volumio[7946]: info: Volumio called home Feb 19 15:40:12 volumio volumio[7946]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Feb 19 15:40:12 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 19 15:40:12 volumio volumio[7946]: info: CoreCommandRouter::volumioGetVisibleSources Feb 19 15:40:12 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 15:40:12 volumio volumio[7946]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:12 volumio volumio[7946]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:12 volumio volumio[7946]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:12 volumio volumio[7946]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:12 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 19 15:40:12 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 19 15:40:12 volumio volumio[7946]: info: Received Get System Info Feb 19 15:40:12 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 19 15:40:12 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 19 15:40:12 volumio volumio[7946]: info: Discovery: Getting this device information Feb 19 15:40:12 volumio volumio[7946]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:12 volumio volumio[7946]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:12 volumio volumio[7946]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 19 15:40:12 volumio volumio[7946]: info: CoreCommandRouter::volumioGetState Feb 19 15:40:12 volumio volumio[7946]: info: CorePlayQueue::getTrack 0 Feb 19 15:40:12 volumio volumio[7946]: info: Listing playlists Feb 19 15:40:12 volumio volumio[7946]: info: CoreCommandRouter::volumioGetQueue Feb 19 15:40:12 volumio volumio[7946]: info: CoreStateMachine::getQueue Feb 19 15:40:12 volumio volumio[7946]: info: CorePlayQueue::getQueue Feb 19 15:40:12 volumio volumio[7946]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%C3%96%C3%9C%C2%B4%C2%AB%C3%90%C3%9B/a92f3a7e-63ac-4205-af36-e81148198b14.jpg' Feb 19 15:40:13 volumio volumio[7946]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 15:40:13 volumio volumio[7946]: Error: connect ECONNREFUSED 162.125.80.5:443 Feb 19 15:40:13 volumio volumio[7946]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 19 15:40:13 volumio volumio[7946]: errno: -111, Feb 19 15:40:13 volumio volumio[7946]: code: 'ECONNREFUSED', Feb 19 15:40:13 volumio volumio[7946]: syscall: 'connect', Feb 19 15:40:13 volumio volumio[7946]: address: '162.125.80.5', Feb 19 15:40:13 volumio volumio[7946]: port: 443 Feb 19 15:40:13 volumio volumio[7946]: } Feb 19 15:40:13 volumio volumio[7946]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 15:40:13 volumio sudo[8146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-19 15:39 Feb 19 15:40:13 volumio sudo[8146]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"