-- Logs begin at Sun 2024-09-08 21:51:40 CST, end at Sun 2024-09-08 22:56:21 CST. -- Sep 08 22:55:00 volumio volumio[6589]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 08 22:55:00 volumio volumio[6589]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Sep 08 22:55:00 volumio volumio[6589]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Sep 08 22:55:00 volumio volumio[6589]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:55:02 volumio volumio[6589]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 08 22:55:02 volumio sudo[6769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 22:55:02 volumio sudo[6769]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:02 volumio sudo[6769]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:02 volumio sudo[6772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 22:55:02 volumio sudo[6772]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:02 volumio sudo[6772]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:02 volumio sudo[6775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 08 22:55:02 volumio sudo[6775]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:02 volumio sudo[6775]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:02 volumio volumio[6589]: info: Upmpdcli Daemon Started Sep 08 22:55:02 volumio volumio[6589]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 08 22:55:03 volumio volumio[6589]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 08 22:55:03 volumio sudo[6778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.250/共享文件/音乐 /mnt/NAS/共享文件 Sep 08 22:55:03 volumio sudo[6778]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:03 volumio kernel: CIFS: Attempting to mount //192.168.1.250/共享文件/音乐 Sep 08 22:55:03 volumio sudo[6778]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:03 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Sep 08 22:55:03 volumio volumio[6589]: info: Cannot mount NAS 共享文件 at system boot, trial number 3 ,retrying in 5 seconds Sep 08 22:55:04 volumio volumio[6589]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 08 22:55:04 volumio volumio[6589]: info: Adding plugin bluetooth to MyMusic Plugins Sep 08 22:55:04 volumio volumio[6589]: info: Adding plugin multiroom to MyMusic Plugins Sep 08 22:55:04 volumio volumio[6589]: info: Adding plugin metavolumio to MyMusic Plugins Sep 08 22:55:04 volumio volumio[6589]: info: Adding plugin cd_controller to MyMusic Plugins Sep 08 22:55:04 volumio volumio[6589]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 08 22:55:04 volumio volumio[6589]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 08 22:55:04 volumio volumio[6589]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:04 volumio volumio[6589]: info: Starting MyVolumio Remote Streaming Endpoints Sep 08 22:55:04 volumio volumio[6589]: info: MyVolumio not started Sep 08 22:55:04 volumio volumio[6589]: info: Initializing device activation check Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 08 22:55:04 volumio volumio[6589]: info: Starting Streaming Service Transparent Proxy Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 08 22:55:04 volumio volumio[6589]: info: Streaming services startup Sep 08 22:55:04 volumio volumio[6589]: info: Starting Streaming Daemon Sep 08 22:55:04 volumio sudo[6791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 08 22:55:04 volumio sudo[6791]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:04 volumio sudo[6791]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:04 volumio volumio[6589]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 08 22:55:04 volumio volumio[6589]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:55:04 volumio volumio[6589]: error: Cannot start Volumio Streaming Daemon Sep 08 22:55:04 volumio volumio[6589]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 08 22:55:04 volumio volumio[6589]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 08 22:55:04 volumio volumio[6589]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 22:55:04 volumio volumio[6589]: Error: dns service error: unknown Sep 08 22:55:04 volumio volumio[6589]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Sep 08 22:55:04 volumio volumio[6589]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Sep 08 22:55:04 volumio volumio[6589]: errorCode: -65537 Sep 08 22:55:04 volumio volumio[6589]: } Sep 08 22:55:04 volumio volumio[6589]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 22:55:05 volumio sudo[6806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-08 22:54 Sep 08 22:55:05 volumio sudo[6806]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:05 volumio sudo[6806]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:05 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 08 22:55:05 volumio volumio-remote-updater[712]: [2024-09-08 22:55:05] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 08 22:55:05 volumio volumio-remote-updater[712]: [2024-09-08 22:55:05] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 08 22:55:05 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 08 22:55:05 volumio systemd[1]: Started dynamicswap service. Sep 08 22:55:05 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 08 22:55:05 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 08 22:55:05 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Sep 08 22:55:05 volumio systemd[1]: Started dynamicswap service. Sep 08 22:55:05 volumio systemd[1]: Stopped Volumio Backend Module. Sep 08 22:55:05 volumio systemd[1]: Started Volumio Backend Module. Sep 08 22:55:05 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 08 22:55:06 volumio volumio[6817]: info: ------------------------------------------- Sep 08 22:55:06 volumio volumio[6817]: info: ----- Volumio3 ---- Sep 08 22:55:06 volumio volumio[6817]: info: ------------------------------------------- Sep 08 22:55:06 volumio volumio[6817]: info: ----- System startup ---- Sep 08 22:55:06 volumio volumio[6817]: info: ------------------------------------------- Sep 08 22:55:06 volumio volumio[6817]: info: MYVOLUMIO Environment detected Sep 08 22:55:06 volumio volumio[6817]: info: Plugin folders cleanup Sep 08 22:55:06 volumio volumio[6817]: info: Scanning into folder /volumio/app/plugins/ Sep 08 22:55:06 volumio volumio[6817]: info: Scanning category audio_interface Sep 08 22:55:06 volumio volumio[6817]: info: Scanning category miscellanea Sep 08 22:55:06 volumio volumio[6817]: info: Scanning category music_service Sep 08 22:55:06 volumio volumio[6817]: info: Scanning category plugins.json Sep 08 22:55:06 volumio volumio[6817]: info: Scanning category system_controller Sep 08 22:55:06 volumio volumio[6817]: info: Scanning category user_interface Sep 08 22:55:06 volumio volumio[6817]: info: Scanning into folder /data/plugins/ Sep 08 22:55:06 volumio volumio[6817]: info: Plugin folders cleanup completed Sep 08 22:55:06 volumio volumio[6817]: info: ------------------------------------------- Sep 08 22:55:06 volumio volumio[6817]: info: ----- Core plugins startup ---- Sep 08 22:55:06 volumio volumio[6817]: info: ------------------------------------------- Sep 08 22:55:06 volumio volumio[6817]: info: Loading plugins from folder /volumio/app/plugins/ Sep 08 22:55:06 volumio volumio[6817]: info: Adding plugin upnp to MyMusic Plugins Sep 08 22:55:06 volumio volumio[6817]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 08 22:55:06 volumio volumio[6817]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 08 22:55:06 volumio volumio[6817]: info: Loading plugins from folder /data/plugins/ Sep 08 22:55:06 volumio volumio[6817]: info: Loading plugin "system"... Sep 08 22:55:06 volumio volumio[6817]: info: Loading plugin "appearance"... Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "network"... Sep 08 22:55:07 volumio volumio[6817]: info: Refreshing Cached IP Addresses Sep 08 22:55:07 volumio sudo[6843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 22:55:07 volumio sudo[6843]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:07 volumio sudo[6843]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "services"... Sep 08 22:55:07 volumio sudo[6845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 22:55:07 volumio sudo[6845]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "alsa_controller"... Sep 08 22:55:07 volumio sudo[6845]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:07 volumio sudo[6847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 08 22:55:07 volumio sudo[6847]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:07 volumio sudo[6847]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:07 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "wizard"... Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "networkfs"... Sep 08 22:55:07 volumio volumio[6817]: info: Starting Udev Watcher for removable devices Sep 08 22:55:07 volumio volumio[6817]: info: Ignoring mount for partition: boot Sep 08 22:55:07 volumio volumio[6817]: info: Ignoring mount for partition: volumio Sep 08 22:55:07 volumio volumio[6817]: info: Ignoring mount for partition: volumio_data Sep 08 22:55:07 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "volumio_command_line_client"... Sep 08 22:55:07 volumio sudo[6856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.250/共享文件/音乐 /mnt/NAS/共享文件 Sep 08 22:55:07 volumio sudo[6856]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "upnp"... Sep 08 22:55:07 volumio volumio[6817]: info: [1725807307385] Starting Upmpd Daemon Sep 08 22:55:07 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "my_music"... Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "mpd"... Sep 08 22:55:07 volumio kernel: CIFS: Attempting to mount //192.168.1.250/共享文件/音乐 Sep 08 22:55:07 volumio sudo[6856]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:07 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "upnp_browser"... Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "alarm-clock"... Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "airplay_emulation"... Sep 08 22:55:07 volumio volumio[6817]: info: Starting Shairport Sync Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "last_100"... Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "webradio"... Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "i2s_dacs"... Sep 08 22:55:07 volumio volumio[6817]: info: I2S DAC not set, start Auto-detection Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "volumiodiscovery"... Sep 08 22:55:07 volumio volumio[6817]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 22:55:07 volumio volumio[6817]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 22:55:07 volumio volumio[6817]: *** WARNING *** For more information see Sep 08 22:55:07 volumio volumio[6817]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 22:55:07 volumio node[6817]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 22:55:07 volumio volumio[6817]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 22:55:07 volumio volumio[6817]: *** WARNING *** For more information see Sep 08 22:55:07 volumio node[6817]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 22:55:07 volumio node[6817]: *** WARNING *** For more information see Sep 08 22:55:07 volumio node[6817]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 22:55:07 volumio node[6817]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 22:55:07 volumio node[6817]: *** WARNING *** For more information see Sep 08 22:55:07 volumio volumio[6817]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 08 22:55:07 volumio volumio[6817]: info: Discovery: Started advertising with name: Volumio Sep 08 22:55:07 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "outputs"... Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "albumart"... Sep 08 22:55:07 volumio volumio[6817]: info: Plugin example_plugin is not enabled Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "inputs"... Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "updater_comm"... Sep 08 22:55:07 volumio volumio[6817]: info: Plugin mpdemulation is not enabled Sep 08 22:55:07 volumio volumio[6817]: info: Loading plugin "rest_api"... Sep 08 22:55:08 volumio volumio[6817]: info: Loading plugin "websocket"... Sep 08 22:55:08 volumio volumio[6817]: info: Starting Socket.io Server version 2.3.0 Sep 08 22:55:08 volumio volumio[6817]: info: Loading i18n strings for locale zh Sep 08 22:55:08 volumio volumio[6817]: Updating browse sources language Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 22:55:08 volumio volumio[6817]: Forking 1 albumart workers Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::initPlayerControls Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 22:55:08 volumio volumio[6817]: Express server listening on port 3000 Sep 08 22:55:08 volumio volumio[6817]: [Metrics] WebUI: 1s 963.12ms Sep 08 22:55:08 volumio volumio[6817]: info: Setting Device type: x86 Sep 08 22:55:08 volumio volumio[6817]: info: CoreStateMachine::resetVolumioState Sep 08 22:55:08 volumio volumio[6817]: info: CoreStateMachine::getcurrentVolume Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:08 volumio volumio[6817]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 08 22:55:08 volumio volumio[6817]: info: Completed loading Core Plugins Sep 08 22:55:08 volumio volumio[6817]: info: Preparing to generate the ALSA configuration file Sep 08 22:55:08 volumio volumio[6817]: info: Cannot read play queue from file Sep 08 22:55:08 volumio volumio[6817]: info: Cannot mount NAS 共享文件 at system boot, trial number 1 ,retrying in 5 seconds Sep 08 22:55:08 volumio volumio[6817]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 08 22:55:08 volumio volumio[6817]: wlan0 Interface doesn't support scanning : Operation not supported Sep 08 22:55:08 volumio volumio[6817]: info: Cannot use regular scanning, forcing with ap-force Sep 08 22:55:08 volumio sudo[6879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 08 22:55:08 volumio sudo[6879]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:08 volumio sudo[6879]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:08 volumio volumio[6817]: command failed: Operation not supported (-95) Sep 08 22:55:08 volumio volumio[6817]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 08 22:55:08 volumio volumio[6817]: command failed: Operation not supported (-95) Sep 08 22:55:08 volumio volumio[6817]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Sep 08 22:55:08 volumio volumio[6817]: verbose: New Socket.io Connection to 192.168.1.146 from 192.168.1.171 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 1 Sep 08 22:55:08 volumio volumio[6817]: verbose: New Socket.io Connection to 192.168.1.146 from 192.168.1.171 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 2 Sep 08 22:55:08 volumio volumio[6817]: verbose: New Socket.io Connection to 192.168.1.146 from 192.168.1.171 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 3 Sep 08 22:55:08 volumio volumio[6817]: 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: 4 Sep 08 22:55:08 volumio volumio[6817]: verbose: New Socket.io Connection to 192.168.1.146 from 192.168.1.171 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 5 Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:08 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 08 22:55:08 volumio volumio[6817]: Starting albumart workers Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:08 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:08 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 22:55:08 volumio volumio[6817]: info: Received Get System Info Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 22:55:08 volumio volumio[6817]: info: Discovery: Getting this device information Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:08 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:08 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:08 volumio volumio[6817]: info: Listing playlists Sep 08 22:55:08 volumio volumio[6817]: verbose: New Socket.io Connection to 192.168.1.146 from 192.168.1.171 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Sep 08 22:55:08 volumio volumio[6817]: info: VolumeController:: Volume=100 Mute =false Sep 08 22:55:08 volumio volumio[6817]: info: CoreStateMachine::pushState Sep 08 22:55:08 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::volumioPushState Sep 08 22:55:08 volumio volumio[6817]: info: CoreStateMachine::updateTrackBlock Sep 08 22:55:08 volumio volumio[6817]: info: CorePlayQueue::getTrackBlock Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 22:55:08 volumio volumio[6817]: info: Asound.conf file unchanged, so no further update is needed Sep 08 22:55:08 volumio volumio[6817]: info: Output device has changed, restarting MPD Sep 08 22:55:08 volumio sudo[6889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 22:55:08 volumio sudo[6889]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:08 volumio volumio[6817]: info: Output device has changed, restarting Shairport Sync Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:08 volumio sudo[6889]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:08 volumio sudo[6892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 22:55:08 volumio sudo[6892]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:08 volumio volumio[6817]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 22:55:08 volumio volumio[6817]: info: ___________ START PLUGINS ___________ Sep 08 22:55:08 volumio volumio[6817]: info: ControllerMpd::onStart: Initializing MPD Sep 08 22:55:08 volumio volumio[6817]: info: Creating MPD Configuration file Sep 08 22:55:08 volumio systemd[1]: Stopping Music Player Daemon... Sep 08 22:55:08 volumio systemd[1]: mpd.service: Succeeded. Sep 08 22:55:08 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 22:55:08 volumio sudo[6898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 22:55:08 volumio sudo[6898]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:08 volumio systemd[1]: Starting Music Player Daemon... Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 22:55:08 volumio sudo[6898]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 22:55:08 volumio volumio[6817]: info: [1725807308787] CoreMusicLibrary::Adding element 媒体服务器 Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 22:55:08 volumio sudo[6902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 22:55:08 volumio sudo[6902]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:08 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 08 22:55:08 volumio systemd[1]: mpd.service: Succeeded. Sep 08 22:55:08 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 22:55:08 volumio systemd[1]: Starting Music Player Daemon... Sep 08 22:55:08 volumio volumio[6817]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:08 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:08 volumio sudo[6905]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 22:55:08 volumio sudo[6905]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:08 volumio sudo[6905]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:09 volumio volumio[6817]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 22:55:09 volumio volumio[6817]: info: [1725807309084] CoreMusicLibrary::Adding element Last_100 Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 22:55:09 volumio volumio[6817]: info: [1725807309091] CoreMusicLibrary::Adding element Webradio Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 22:55:09 volumio volumio[6817]: info: Initializing BBC Radios Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:09 volumio volumio[6817]: info: Volumio Calling Home Sep 08 22:55:09 volumio volumio[6817]: info: CoreStateMachine::setRepeat null single undefined Sep 08 22:55:09 volumio volumio[6817]: info: CoreStateMachine::pushState Sep 08 22:55:09 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::volumioPushState Sep 08 22:55:09 volumio volumio[6817]: info: CoreStateMachine::setRandom null Sep 08 22:55:09 volumio volumio[6817]: info: CoreStateMachine::pushState Sep 08 22:55:09 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::volumioPushState Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:09 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 22:55:09 volumio volumio[6817]: info: Received Get System Info Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 22:55:09 volumio volumio[6817]: info: Discovery: Getting this device information Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:09 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:09 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:09 volumio volumio[6817]: info: Listing playlists Sep 08 22:55:09 volumio volumio[6817]: info: Received Get System Info Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 22:55:09 volumio volumio[6817]: info: Discovery: Getting this device information Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:09 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:09 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:09 volumio volumio[6817]: info: Received Get System Info Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 22:55:09 volumio volumio[6817]: info: Discovery: Getting this device information Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:09 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:09 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:09 volumio volumio[6817]: info: Received Get System Info Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 22:55:09 volumio volumio[6817]: info: Discovery: Getting this device information Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:09 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:09 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:55:09 volumio volumio[6817]: info: MPD Permissions set Sep 08 22:55:09 volumio volumio[6817]: info: MPD Permissions set Sep 08 22:55:09 volumio volumio[6817]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:09 volumio mpd[6910]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 08 22:55:09 volumio mpd[6910]: output: No 'audio_output' defined in config file Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 08 22:55:09 volumio mpd[6910]: couldn't open play stream: No such file or directory Sep 08 22:55:09 volumio mpd[6910]: sndio_output: Error opening default sndio device Sep 08 22:55:09 volumio mpd[6910]: output: Successfully detected a alsa audio device Sep 08 22:55:09 volumio mpd[6910]: zeroconf: No global port, disabling zeroconf Sep 08 22:55:09 volumio systemd[1]: Started Music Player Daemon. Sep 08 22:55:09 volumio sudo[6892]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:09 volumio sudo[6902]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:09 volumio volumio[6817]: info: VolumeController:: Volume=100 Mute =false Sep 08 22:55:09 volumio volumio[6817]: info: CoreStateMachine::pushState Sep 08 22:55:09 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::volumioPushState Sep 08 22:55:09 volumio volumio[6817]: info: VolumeController::SetAlsaVolume0 Sep 08 22:55:09 volumio volumio[6817]: info: CoreStateMachine::pushState Sep 08 22:55:09 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 22:55:09 volumio volumio[6817]: info: CoreCommandRouter::volumioPushState Sep 08 22:55:09 volumio volumio[6817]: info: Completed starting Core Plugins Sep 08 22:55:09 volumio volumio[6817]: info: ------------------------------------------- Sep 08 22:55:09 volumio volumio[6817]: info: ----- MyVolumio plugins startup ---- Sep 08 22:55:09 volumio volumio[6817]: info: ------------------------------------------- Sep 08 22:55:09 volumio volumio[6817]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 08 22:55:09 volumio volumio[6817]: info: MPD running with PID6910 Sep 08 22:55:09 volumio volumio[6817]: ,establishing connection Sep 08 22:55:09 volumio volumio[6817]: info: Starting Shairport Sync Sep 08 22:55:09 volumio volumio[6817]: info: Starting Shairport Sync Sep 08 22:55:09 volumio sudo[6956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 22:55:09 volumio sudo[6956]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:09 volumio volumio[6817]: info: Starting Shairport Sync Sep 08 22:55:09 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 22:55:09 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 22:55:09 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 22:55:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 22:55:09 volumio sudo[6959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 22:55:09 volumio sudo[6959]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:10 volumio sudo[6956]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:10 volumio volumio[6817]: info: Discovery: adding 876276c4-a573-4aa2-b5cf-d3871f3c4e65 Sep 08 22:55:10 volumio volumio[6817]: info: Discovery: Found device Volumio Sep 08 22:55:10 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:10 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 22:55:10 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 08 22:55:10 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 22:55:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 22:55:10 volumio sudo[6963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 22:55:10 volumio sudo[6963]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 22:55:10 volumio sudo[6959]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 22:55:10 volumio volumio[6817]: error: MPD error: The expression evaluated to a falsy value: Sep 08 22:55:10 volumio volumio[6817]: assert.ok(self.idling) Sep 08 22:55:10 volumio volumio[6817]: error: The expression evaluated to a falsy value: Sep 08 22:55:10 volumio volumio[6817]: assert.ok(self.idling) Sep 08 22:55:10 volumio volumio[6817]: error: MPD error: The expression evaluated to a falsy value: Sep 08 22:55:10 volumio volumio[6817]: assert.ok(self.idling) Sep 08 22:55:10 volumio volumio[6817]: error: The expression evaluated to a falsy value: Sep 08 22:55:10 volumio volumio[6817]: assert.ok(self.idling) Sep 08 22:55:10 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 08 22:55:10 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 22:55:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 22:55:10 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:10 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:10 volumio volumio[6817]: info: Discovery: this is already registered, 876276c4-a573-4aa2-b5cf-d3871f3c4e65 Sep 08 22:55:10 volumio volumio[6817]: info: Discovery: Found device Volumio Sep 08 22:55:10 volumio volumio[6817]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:10 volumio volumio[6817]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 22:55:10 volumio sudo[6963]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:10 volumio volumio[6817]: info: Shairport-Sync Started Sep 08 22:55:10 volumio volumio[6817]: Error adding Membership: Error: addMembership EINVAL Sep 08 22:55:10 volumio volumio[6817]: info: Shairport-Sync Started Sep 08 22:55:10 volumio volumio[6817]: info: Volumio called home Sep 08 22:55:10 volumio volumio[6817]: error: updateQueue error: null Sep 08 22:55:10 volumio volumio[6817]: info: Shairport-Sync Started Sep 08 22:55:10 volumio volumio-remote-updater[712]: [2024-09-08 22:55:10] [connect] Successful connection Sep 08 22:55:10 volumio volumio-remote-updater[712]: [2024-09-08 22:55:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725807310 101 Sep 08 22:55:10 volumio volumio[6817]: 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: 8 Sep 08 22:55:13 volumio sudo[6973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.250/共享文件/音乐 /mnt/NAS/共享文件 Sep 08 22:55:13 volumio sudo[6973]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:13 volumio kernel: CIFS: Attempting to mount //192.168.1.250/共享文件/音乐 Sep 08 22:55:13 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Sep 08 22:55:13 volumio sudo[6973]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:13 volumio volumio[6817]: info: Cannot mount NAS 共享文件 at system boot, trial number 2 ,retrying in 5 seconds Sep 08 22:55:13 volumio volumio[6817]: info: Discovery: Browse raised the following error Error: dns service error: unknown Sep 08 22:55:14 volumio volumio[6817]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 22:55:14 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 08 22:55:14 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 08 22:55:14 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 08 22:55:14 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 08 22:55:14 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 22:55:14 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 22:55:14 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 22:55:14 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 22:55:14 volumio volumio[6817]: info: CoreCommandRouter::volumioGetBrowseSources Sep 08 22:55:14 volumio volumio[6817]: info: CoreCommandRouter::volumioGetBrowseSources Sep 08 22:55:14 volumio volumio[6817]: info: CoreCommandRouter::volumioGetBrowseSources Sep 08 22:55:15 volumio mpd[6910]: exception: No database Sep 08 22:55:15 volumio mpd[6910]: exception: No database Sep 08 22:55:15 volumio volumio[6817]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Sep 08 22:55:15 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:55:15 volumio mpd[6910]: exception: No database Sep 08 22:55:15 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 08 22:55:16 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 08 22:55:17 volumio sudo[6985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 22:55:17 volumio sudo[6985]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:17 volumio sudo[6985]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:17 volumio sudo[6988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 22:55:17 volumio sudo[6988]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:17 volumio sudo[6988]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:17 volumio sudo[6991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 08 22:55:17 volumio sudo[6991]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:17 volumio sudo[6991]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:17 volumio volumio[6817]: info: Upmpdcli Daemon Started Sep 08 22:55:17 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 08 22:55:17 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 08 22:55:18 volumio sudo[6994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.250/共享文件/音乐 /mnt/NAS/共享文件 Sep 08 22:55:18 volumio sudo[6994]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:18 volumio kernel: CIFS: Attempting to mount //192.168.1.250/共享文件/音乐 Sep 08 22:55:18 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Sep 08 22:55:18 volumio sudo[6994]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:18 volumio volumio[6817]: info: Cannot mount NAS 共享文件 at system boot, trial number 3 ,retrying in 5 seconds Sep 08 22:55:18 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 08 22:55:19 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:55:19 volumio mpd[6910]: exception: No database Sep 08 22:55:19 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 08 22:55:19 volumio volumio[6817]: info: Adding plugin bluetooth to MyMusic Plugins Sep 08 22:55:19 volumio volumio[6817]: info: Adding plugin multiroom to MyMusic Plugins Sep 08 22:55:19 volumio volumio[6817]: info: Adding plugin metavolumio to MyMusic Plugins Sep 08 22:55:19 volumio volumio[6817]: info: Adding plugin cd_controller to MyMusic Plugins Sep 08 22:55:19 volumio volumio[6817]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 08 22:55:19 volumio volumio[6817]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 08 22:55:19 volumio volumio[6817]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 08 22:55:20 volumio volumio[6817]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 08 22:55:20 volumio volumio[6817]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 08 22:55:20 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:20 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:20 volumio volumio[6817]: info: Starting MyVolumio Remote Streaming Endpoints Sep 08 22:55:20 volumio volumio[6817]: info: MyVolumio not started Sep 08 22:55:20 volumio volumio[6817]: info: Initializing device activation check Sep 08 22:55:20 volumio volumio[6817]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 08 22:55:20 volumio volumio[6817]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 08 22:55:20 volumio volumio[6817]: info: Starting Streaming Service Transparent Proxy Sep 08 22:55:20 volumio volumio[6817]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 08 22:55:20 volumio volumio[6817]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 08 22:55:20 volumio volumio[6817]: info: Streaming services startup Sep 08 22:55:20 volumio volumio[6817]: info: Starting Streaming Daemon Sep 08 22:55:20 volumio sudo[7009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 08 22:55:20 volumio sudo[7009]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:20 volumio sudo[7009]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:20 volumio volumio[6817]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 08 22:55:20 volumio volumio[6817]: error: Cannot start Volumio Streaming Daemon Sep 08 22:55:20 volumio volumio[6817]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 08 22:55:20 volumio volumio[6817]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 08 22:55:20 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 08 22:55:20 volumio volumio[6817]: STREAMING PROXY: Starting server on port 3245 Sep 08 22:55:20 volumio volumio[6817]: Node JS runtime: 14 Sep 08 22:55:21 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Sep 08 22:55:21 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Sep 08 22:55:23 volumio volumio[6817]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:55:23 volumio mpd[6910]: exception: No database Sep 08 22:55:23 volumio volumio[6817]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 22:55:23 volumio volumio[6817]: TypeError: Cannot read property 'replace' of undefined Sep 08 22:55:23 volumio volumio[6817]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Sep 08 22:55:23 volumio volumio[6817]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Sep 08 22:55:23 volumio volumio[6817]: at listOnTimeout (internal/timers.js:557:17) Sep 08 22:55:23 volumio volumio[6817]: at processTimers (internal/timers.js:500:7) Sep 08 22:55:23 volumio volumio[6817]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 22:55:23 volumio sudo[7072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-08 22:54 Sep 08 22:55:23 volumio sudo[7072]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:23 volumio sudo[7072]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:26 volumio volumio-remote-updater[712]: [2024-09-08 22:55:26] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 08 22:55:26 volumio volumio-remote-updater[712]: [2024-09-08 22:55:26] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 08 22:55:26 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 08 22:55:26 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 08 22:55:26 volumio systemd[1]: Started dynamicswap service. Sep 08 22:55:26 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 08 22:55:26 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 08 22:55:26 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Sep 08 22:55:26 volumio systemd[1]: Started dynamicswap service. Sep 08 22:55:26 volumio systemd[1]: Stopped Volumio Backend Module. Sep 08 22:55:26 volumio systemd[1]: Started Volumio Backend Module. Sep 08 22:55:26 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 08 22:55:27 volumio volumio[7086]: info: ------------------------------------------- Sep 08 22:55:27 volumio volumio[7086]: info: ----- Volumio3 ---- Sep 08 22:55:27 volumio volumio[7086]: info: ------------------------------------------- Sep 08 22:55:27 volumio volumio[7086]: info: ----- System startup ---- Sep 08 22:55:27 volumio volumio[7086]: info: ------------------------------------------- Sep 08 22:55:27 volumio volumio[7086]: info: MYVOLUMIO Environment detected Sep 08 22:55:27 volumio volumio[7086]: info: Plugin folders cleanup Sep 08 22:55:27 volumio volumio[7086]: info: Scanning into folder /volumio/app/plugins/ Sep 08 22:55:27 volumio volumio[7086]: info: Scanning category audio_interface Sep 08 22:55:27 volumio volumio[7086]: info: Scanning category miscellanea Sep 08 22:55:27 volumio volumio[7086]: info: Scanning category music_service Sep 08 22:55:27 volumio volumio[7086]: info: Scanning category plugins.json Sep 08 22:55:27 volumio volumio[7086]: info: Scanning category system_controller Sep 08 22:55:27 volumio volumio[7086]: info: Scanning category user_interface Sep 08 22:55:27 volumio volumio[7086]: info: Scanning into folder /data/plugins/ Sep 08 22:55:27 volumio volumio[7086]: info: Plugin folders cleanup completed Sep 08 22:55:27 volumio volumio[7086]: info: ------------------------------------------- Sep 08 22:55:27 volumio volumio[7086]: info: ----- Core plugins startup ---- Sep 08 22:55:27 volumio volumio[7086]: info: ------------------------------------------- Sep 08 22:55:27 volumio volumio[7086]: info: Loading plugins from folder /volumio/app/plugins/ Sep 08 22:55:27 volumio volumio[7086]: info: Adding plugin upnp to MyMusic Plugins Sep 08 22:55:27 volumio volumio[7086]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 08 22:55:27 volumio volumio[7086]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 08 22:55:27 volumio volumio[7086]: info: Loading plugins from folder /data/plugins/ Sep 08 22:55:27 volumio volumio[7086]: info: Loading plugin "system"... Sep 08 22:55:28 volumio volumio[7086]: info: Loading plugin "appearance"... Sep 08 22:55:28 volumio volumio[7086]: info: Loading plugin "network"... Sep 08 22:55:28 volumio volumio[7086]: info: Refreshing Cached IP Addresses Sep 08 22:55:28 volumio sudo[7112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 22:55:28 volumio sudo[7112]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:28 volumio sudo[7112]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:28 volumio sudo[7114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 22:55:28 volumio volumio[7086]: info: Loading plugin "services"... Sep 08 22:55:28 volumio sudo[7114]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:28 volumio volumio[7086]: info: Loading plugin "alsa_controller"... Sep 08 22:55:28 volumio sudo[7114]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:28 volumio sudo[7117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 08 22:55:28 volumio sudo[7117]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:28 volumio sudo[7117]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:28 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 22:55:28 volumio volumio[7086]: info: Loading plugin "wizard"... Sep 08 22:55:28 volumio volumio[7086]: info: Loading plugin "networkfs"... Sep 08 22:55:28 volumio volumio[7086]: info: Starting Udev Watcher for removable devices Sep 08 22:55:28 volumio volumio[7086]: info: Ignoring mount for partition: boot Sep 08 22:55:28 volumio volumio[7086]: info: Ignoring mount for partition: volumio Sep 08 22:55:28 volumio volumio[7086]: info: Ignoring mount for partition: volumio_data Sep 08 22:55:28 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 22:55:28 volumio volumio[7086]: info: Loading plugin "volumio_command_line_client"... Sep 08 22:55:28 volumio volumio[7086]: info: Loading plugin "upnp"... Sep 08 22:55:28 volumio volumio[7086]: info: [1725807328613] Starting Upmpd Daemon Sep 08 22:55:28 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 22:55:28 volumio volumio[7086]: info: Loading plugin "my_music"... Sep 08 22:55:28 volumio volumio[7086]: info: Loading plugin "mpd"... Sep 08 22:55:28 volumio volumio[7086]: info: Loading plugin "upnp_browser"... Sep 08 22:55:28 volumio volumio[7086]: info: Loading plugin "alarm-clock"... Sep 08 22:55:28 volumio volumio[7086]: info: Loading plugin "airplay_emulation"... Sep 08 22:55:29 volumio volumio[7086]: info: Starting Shairport Sync Sep 08 22:55:29 volumio volumio[7086]: info: Loading plugin "last_100"... Sep 08 22:55:29 volumio volumio[7086]: info: Loading plugin "webradio"... Sep 08 22:55:29 volumio volumio[7086]: info: Loading plugin "i2s_dacs"... Sep 08 22:55:29 volumio volumio[7086]: info: I2S DAC not set, start Auto-detection Sep 08 22:55:29 volumio volumio[7086]: info: Loading plugin "volumiodiscovery"... Sep 08 22:55:29 volumio volumio[7086]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 22:55:29 volumio volumio[7086]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 22:55:29 volumio volumio[7086]: *** WARNING *** For more information see Sep 08 22:55:29 volumio volumio[7086]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 22:55:29 volumio node[7086]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 22:55:29 volumio volumio[7086]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 22:55:29 volumio volumio[7086]: *** WARNING *** For more information see Sep 08 22:55:29 volumio node[7086]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 22:55:29 volumio node[7086]: *** WARNING *** For more information see Sep 08 22:55:29 volumio node[7086]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 22:55:29 volumio node[7086]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 22:55:29 volumio node[7086]: *** WARNING *** For more information see Sep 08 22:55:29 volumio volumio[7086]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 08 22:55:29 volumio volumio[7086]: info: Discovery: Started advertising with name: Volumio Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 22:55:29 volumio volumio[7086]: info: Loading plugin "outputs"... Sep 08 22:55:29 volumio volumio[7086]: info: Loading plugin "albumart"... Sep 08 22:55:29 volumio volumio[7086]: info: Plugin example_plugin is not enabled Sep 08 22:55:29 volumio volumio[7086]: info: Loading plugin "inputs"... Sep 08 22:55:29 volumio volumio[7086]: info: Loading plugin "updater_comm"... Sep 08 22:55:29 volumio volumio[7086]: Forking 1 albumart workers Sep 08 22:55:29 volumio volumio[7086]: info: Plugin mpdemulation is not enabled Sep 08 22:55:29 volumio volumio[7086]: info: Loading plugin "rest_api"... Sep 08 22:55:29 volumio volumio[7086]: info: Loading plugin "websocket"... Sep 08 22:55:29 volumio volumio[7086]: info: Starting Socket.io Server version 2.3.0 Sep 08 22:55:29 volumio volumio[7086]: info: Loading i18n strings for locale zh Sep 08 22:55:29 volumio volumio[7086]: Updating browse sources language Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::initPlayerControls Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 22:55:29 volumio volumio[7086]: Express server listening on port 3000 Sep 08 22:55:29 volumio volumio[7086]: [Metrics] WebUI: 2s 14.97ms Sep 08 22:55:29 volumio volumio[7086]: info: Setting Device type: x86 Sep 08 22:55:29 volumio volumio[7086]: info: CoreStateMachine::resetVolumioState Sep 08 22:55:29 volumio volumio[7086]: info: CoreStateMachine::getcurrentVolume Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:29 volumio volumio[7086]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 08 22:55:29 volumio volumio[7086]: info: Completed loading Core Plugins Sep 08 22:55:29 volumio volumio[7086]: info: Preparing to generate the ALSA configuration file Sep 08 22:55:29 volumio volumio[7086]: info: Cannot read play queue from file Sep 08 22:55:29 volumio volumio[7086]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 08 22:55:29 volumio volumio[7086]: wlan0 Interface doesn't support scanning : Operation not supported Sep 08 22:55:29 volumio volumio[7086]: info: Cannot use regular scanning, forcing with ap-force Sep 08 22:55:29 volumio sudo[7142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 08 22:55:29 volumio sudo[7142]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:29 volumio sudo[7142]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:29 volumio volumio[7086]: command failed: Operation not supported (-95) Sep 08 22:55:29 volumio volumio[7086]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 08 22:55:29 volumio volumio[7086]: command failed: Operation not supported (-95) Sep 08 22:55:29 volumio volumio[7086]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Sep 08 22:55:29 volumio volumio[7086]: Starting albumart workers Sep 08 22:55:29 volumio volumio[7086]: 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 Sep 08 22:55:29 volumio volumio[7086]: verbose: New Socket.io Connection to 192.168.1.146 from 192.168.1.171 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 2 Sep 08 22:55:29 volumio volumio[7086]: verbose: New Socket.io Connection to 192.168.1.146 from 192.168.1.171 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 3 Sep 08 22:55:29 volumio volumio[7086]: verbose: New Socket.io Connection to 192.168.1.146 from 192.168.1.171 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 4 Sep 08 22:55:29 volumio volumio[7086]: info: VolumeController:: Volume=0 Mute =false Sep 08 22:55:29 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:55:29 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:55:29 volumio volumio[7086]: info: CoreStateMachine::updateTrackBlock Sep 08 22:55:29 volumio volumio[7086]: info: CorePlayQueue::getTrackBlock Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 22:55:29 volumio volumio[7086]: info: Asound.conf file unchanged, so no further update is needed Sep 08 22:55:29 volumio volumio[7086]: info: Output device has changed, restarting MPD Sep 08 22:55:29 volumio sudo[7152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 22:55:29 volumio sudo[7152]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:29 volumio volumio[7086]: info: Output device has changed, restarting Shairport Sync Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:29 volumio sudo[7152]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:29 volumio sudo[7155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 22:55:29 volumio sudo[7155]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:29 volumio systemd[1]: Stopping Music Player Daemon... Sep 08 22:55:29 volumio volumio[7086]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 22:55:29 volumio volumio[7086]: info: ___________ START PLUGINS ___________ Sep 08 22:55:29 volumio systemd[1]: mpd.service: Succeeded. Sep 08 22:55:29 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 22:55:29 volumio volumio[7086]: info: ControllerMpd::onStart: Initializing MPD Sep 08 22:55:29 volumio volumio[7086]: info: Creating MPD Configuration file Sep 08 22:55:29 volumio systemd[1]: Starting Music Player Daemon... Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 22:55:29 volumio volumio[7086]: info: [1725807329985] CoreMusicLibrary::Adding element 媒体服务器 Sep 08 22:55:29 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 22:55:29 volumio sudo[7162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 22:55:29 volumio sudo[7162]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:30 volumio sudo[7162]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:30 volumio sudo[7164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 22:55:30 volumio sudo[7164]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:30 volumio sudo[7160]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 22:55:30 volumio sudo[7160]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:30 volumio sudo[7160]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:30 volumio systemd[1]: mpd.service: Succeeded. Sep 08 22:55:30 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 22:55:30 volumio systemd[1]: Starting Music Player Daemon... Sep 08 22:55:30 volumio volumio[7086]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:30 volumio sudo[7168]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 22:55:30 volumio sudo[7168]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:30 volumio sudo[7168]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:30 volumio volumio[7086]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 22:55:30 volumio volumio[7086]: info: [1725807330209] CoreMusicLibrary::Adding element Last_100 Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 22:55:30 volumio volumio[7086]: info: [1725807330215] CoreMusicLibrary::Adding element Webradio Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 22:55:30 volumio volumio[7086]: info: Initializing BBC Radios Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: Volumio Calling Home Sep 08 22:55:30 volumio volumio[7086]: info: CoreStateMachine::setRepeat null single undefined Sep 08 22:55:30 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:55:30 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:55:30 volumio volumio[7086]: info: CoreStateMachine::setRandom null Sep 08 22:55:30 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:55:30 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:55:30 volumio volumio[7086]: 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: 5 Sep 08 22:55:30 volumio volumio[7086]: verbose: New Socket.io Connection to 192.168.1.146 from 192.168.1.171 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Sep 08 22:55:30 volumio volumio[7086]: info: MPD Permissions set Sep 08 22:55:30 volumio volumio[7086]: info: MPD Permissions set Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 22:55:30 volumio volumio[7086]: verbose: New Socket.io Connection to 192.168.1.146 from 192.168.1.171 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 7 Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:30 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 22:55:30 volumio volumio[7086]: info: Received Get System Info Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 22:55:30 volumio volumio[7086]: info: Discovery: Getting this device information Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:30 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:30 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:30 volumio volumio[7086]: info: Listing playlists Sep 08 22:55:30 volumio volumio[7086]: info: Received Get System Info Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 22:55:30 volumio volumio[7086]: info: Discovery: Getting this device information Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:30 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:30 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:30 volumio volumio[7086]: info: Received Get System Info Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 22:55:30 volumio volumio[7086]: info: Discovery: Getting this device information Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:30 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:30 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:30 volumio volumio[7086]: info: Received Get System Info Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 22:55:30 volumio volumio[7086]: info: Discovery: Getting this device information Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:30 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:30 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:30 volumio volumio[7086]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 08 22:55:30 volumio volumio[7086]: info: VolumeController:: Volume=0 Mute =false Sep 08 22:55:30 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:55:30 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:30 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 08 22:55:30 volumio volumio[7086]: info: Starting Shairport Sync Sep 08 22:55:30 volumio volumio[7086]: info: Starting Shairport Sync Sep 08 22:55:30 volumio sudo[7215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 22:55:30 volumio sudo[7215]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:30 volumio volumio[7086]: info: Starting Shairport Sync Sep 08 22:55:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 22:55:30 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 22:55:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 22:55:30 volumio sudo[7220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 22:55:30 volumio sudo[7220]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 22:55:30 volumio sudo[7215]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:30 volumio sudo[7217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 22:55:30 volumio sudo[7217]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 22:55:30 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 22:55:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:30 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 22:55:30 volumio sudo[7220]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 22:55:30 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 08 22:55:30 volumio volumio[7086]: info: Discovery: adding 876276c4-a573-4aa2-b5cf-d3871f3c4e65 Sep 08 22:55:30 volumio volumio[7086]: info: Discovery: Found device Volumio Sep 08 22:55:30 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:30 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 22:55:30 volumio volumio[7086]: info: Discovery: this is already registered, 876276c4-a573-4aa2-b5cf-d3871f3c4e65 Sep 08 22:55:30 volumio volumio[7086]: info: Discovery: Found device Volumio Sep 08 22:55:30 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:30 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:30 volumio mpd[7184]: Sep 08 22:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 08 22:55:31 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 22:55:31 volumio sudo[7217]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:31 volumio systemd[1]: Started Music Player Daemon. Sep 08 22:55:31 volumio sudo[7155]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:31 volumio sudo[7164]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:31 volumio volumio[7086]: info: Shairport-Sync Started Sep 08 22:55:31 volumio volumio[7086]: Error adding Membership: Error: addMembership EINVAL Sep 08 22:55:31 volumio volumio[7086]: info: Shairport-Sync Started Sep 08 22:55:31 volumio volumio[7086]: info: Completed starting Core Plugins Sep 08 22:55:31 volumio volumio[7086]: info: ------------------------------------------- Sep 08 22:55:31 volumio volumio[7086]: info: ----- MyVolumio plugins startup ---- Sep 08 22:55:31 volumio volumio[7086]: info: ------------------------------------------- Sep 08 22:55:31 volumio volumio[7086]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 08 22:55:31 volumio volumio[7086]: info: Shairport-Sync Started Sep 08 22:55:31 volumio volumio[7086]: info: MPD running with PID7184 Sep 08 22:55:31 volumio volumio[7086]: ,establishing connection Sep 08 22:55:31 volumio volumio[7086]: error: MPD error: The expression evaluated to a falsy value: Sep 08 22:55:31 volumio volumio[7086]: assert.ok(self.idling) Sep 08 22:55:31 volumio volumio[7086]: error: The expression evaluated to a falsy value: Sep 08 22:55:31 volumio volumio[7086]: assert.ok(self.idling) Sep 08 22:55:31 volumio volumio[7086]: error: MPD error: The expression evaluated to a falsy value: Sep 08 22:55:31 volumio volumio[7086]: assert.ok(self.idling) Sep 08 22:55:31 volumio volumio[7086]: error: The expression evaluated to a falsy value: Sep 08 22:55:31 volumio volumio[7086]: assert.ok(self.idling) Sep 08 22:55:31 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:55:31 volumio volumio[7086]: error: updateQueue error: null Sep 08 22:55:31 volumio volumio-remote-updater[712]: [2024-09-08 22:55:31] [connect] Successful connection Sep 08 22:55:31 volumio volumio-remote-updater[712]: [2024-09-08 22:55:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725807331 101 Sep 08 22:55:31 volumio volumio[7086]: 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: 9 Sep 08 22:55:31 volumio volumio[7086]: info: Volumio called home Sep 08 22:55:34 volumio volumio[7086]: info: Discovery: Browse raised the following error Error: dns service error: unknown Sep 08 22:55:35 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:55:35 volumio volumio[7086]: 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: 10 Sep 08 22:55:35 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 22:55:35 volumio volumio[7086]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 22:55:35 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 22:55:35 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:35 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:35 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:35 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:35 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 08 22:55:35 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 22:55:35 volumio volumio[7086]: info: Received Get System Info Sep 08 22:55:35 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 22:55:35 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 22:55:35 volumio volumio[7086]: info: Discovery: Getting this device information Sep 08 22:55:35 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:35 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:35 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 22:55:35 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:35 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:35 volumio volumio[7086]: info: Listing playlists Sep 08 22:55:36 volumio volumio[7086]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 08 22:55:38 volumio sudo[7237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 22:55:38 volumio sudo[7237]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:38 volumio sudo[7237]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:38 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 08 22:55:38 volumio sudo[7240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 22:55:38 volumio sudo[7240]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:38 volumio sudo[7240]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:38 volumio sudo[7243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 08 22:55:38 volumio sudo[7243]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:38 volumio sudo[7243]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:38 volumio volumio[7086]: info: Upmpdcli Daemon Started Sep 08 22:55:39 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:55:39 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 08 22:55:39 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 08 22:55:40 volumio volumio[7086]: info: Adding plugin bluetooth to MyMusic Plugins Sep 08 22:55:40 volumio volumio[7086]: info: Adding plugin multiroom to MyMusic Plugins Sep 08 22:55:40 volumio volumio[7086]: info: Adding plugin metavolumio to MyMusic Plugins Sep 08 22:55:40 volumio volumio[7086]: info: Adding plugin cd_controller to MyMusic Plugins Sep 08 22:55:40 volumio volumio[7086]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 08 22:55:40 volumio volumio[7086]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 08 22:55:40 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:40 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:55:40 volumio volumio[7086]: info: Starting MyVolumio Remote Streaming Endpoints Sep 08 22:55:40 volumio volumio[7086]: info: MyVolumio not started Sep 08 22:55:40 volumio volumio[7086]: info: Initializing device activation check Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 08 22:55:40 volumio volumio[7086]: info: Starting Streaming Service Transparent Proxy Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 08 22:55:40 volumio volumio[7086]: info: Streaming services startup Sep 08 22:55:40 volumio volumio[7086]: info: Starting Streaming Daemon Sep 08 22:55:40 volumio sudo[7253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 08 22:55:40 volumio sudo[7253]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 22:55:40 volumio sudo[7253]: pam_unix(sudo:session): session closed for user root Sep 08 22:55:40 volumio volumio[7086]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 08 22:55:40 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 08 22:55:40 volumio volumio[7086]: error: Cannot start Volumio Streaming Daemon Sep 08 22:55:40 volumio volumio[7086]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 08 22:55:40 volumio volumio[7086]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 08 22:55:41 volumio volumio[7086]: STREAMING PROXY: Starting server on port 3245 Sep 08 22:55:41 volumio volumio[7086]: Node JS runtime: 14 Sep 08 22:55:43 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:55:47 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:55:51 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:55:55 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:55:55 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 08 22:55:55 volumio volumio[7086]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Sep 08 22:55:55 volumio volumio[7086]: info: CoreCommandRouter::volumioGetState Sep 08 22:55:55 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:55:59 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:56:00 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 22:56:00 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:56:00 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 22:56:00 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:56:00 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:56:00 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 08 22:56:00 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 08 22:56:00 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 08 22:56:00 volumio volumio[7086]: info: CoreCommandRouter::volumioGetBrowseSources Sep 08 22:56:00 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 22:56:00 volumio volumio-remote-updater[712]: No test mode Sep 08 22:56:00 volumio volumio-remote-updater[712]: No alpha test mode Sep 08 22:56:00 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 08 22:56:00 volumio volumio[7086]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 08 22:56:00 volumio volumio[7086]: info: Completed starting MyVolumio Plugin Sep 08 22:56:00 volumio volumio[7086]: info: BOOT COMPLETED Sep 08 22:56:00 volumio volumio[7086]: [Metrics] CommandRouter: 33s 306.05ms Sep 08 22:56:00 volumio volumio[7086]: info: CoreCommandRouter::volumiosetStartupVolume Sep 08 22:56:00 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 22:56:00 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 22:56:00 volumio volumio[7086]: info: CoreCommandRouter::Close All Modals sent Sep 08 22:56:00 volumio volumio[7086]: info: CoreCommandRouter::Close All Modals sent Sep 08 22:56:01 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 08 22:56:01 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 08 22:56:01 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 08 22:56:02 volumio volumio[7086]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 08 22:56:02 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 08 22:56:03 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:56:07 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:56:11 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:56:15 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:56:15 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Sep 08 22:56:15 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Sep 08 22:56:16 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Sep 08 22:56:16 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Sep 08 22:56:16 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Sep 08 22:56:16 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Sep 08 22:56:16 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Sep 08 22:56:16 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Sep 08 22:56:19 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 22:56:19 volumio volumio[7086]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 08 22:56:19 volumio volumio[7086]: verbose: ControllerMpd::sendMpdCommand update Sep 08 22:56:19 volumio volumio[7086]: verbose: ControllerMpd::sendMpdCommand status Sep 08 22:56:19 volumio volumio[7086]: info: Sep 08 22:56:19 volumio volumio[7086]: ---------------------------- MPD announces state update: update Sep 08 22:56:19 volumio volumio[7086]: info: sendMpdCommand update took 8 milliseconds Sep 08 22:56:19 volumio volumio[7086]: info: ControllerMpd::getState Sep 08 22:56:19 volumio volumio[7086]: verbose: ControllerMpd::sendMpdCommand status Sep 08 22:56:19 volumio volumio[7086]: verbose: ControllerMpd::sendMpdCommand status Sep 08 22:56:19 volumio volumio[7086]: info: Sep 08 22:56:19 volumio volumio[7086]: ---------------------------- MPD announces state update: update Sep 08 22:56:19 volumio volumio[7086]: info: ControllerMpd::getState Sep 08 22:56:19 volumio volumio[7086]: verbose: ControllerMpd::sendMpdCommand status Sep 08 22:56:19 volumio volumio[7086]: verbose: ControllerMpd::sendMpdCommand status Sep 08 22:56:19 volumio volumio[7086]: info: Sep 08 22:56:19 volumio volumio[7086]: ---------------------------- MPD announces state update: update Sep 08 22:56:19 volumio volumio[7086]: info: ControllerMpd::getState Sep 08 22:56:19 volumio volumio[7086]: verbose: ControllerMpd::sendMpdCommand status Sep 08 22:56:19 volumio volumio[7086]: info: Sep 08 22:56:19 volumio volumio[7086]: ---------------------------- MPD announces state update: update Sep 08 22:56:19 volumio volumio[7086]: info: sendMpdCommand status took 19 milliseconds Sep 08 22:56:19 volumio volumio[7086]: info: ControllerMpd::getState Sep 08 22:56:19 volumio volumio[7086]: verbose: ControllerMpd::sendMpdCommand status Sep 08 22:56:19 volumio volumio[7086]: info: sendMpdCommand status took 14 milliseconds Sep 08 22:56:19 volumio volumio[7086]: info: sendMpdCommand status took 13 milliseconds Sep 08 22:56:19 volumio volumio[7086]: info: sendMpdCommand status took 9 milliseconds Sep 08 22:56:19 volumio volumio[7086]: info: sendMpdCommand status took 8 milliseconds Sep 08 22:56:19 volumio volumio[7086]: info: sendMpdCommand status took 7 milliseconds Sep 08 22:56:19 volumio volumio[7086]: verbose: ControllerMpd::parseState Sep 08 22:56:19 volumio volumio[7086]: info: Command Router : Notfying DB Updatetrue Sep 08 22:56:19 volumio volumio[7086]: verbose: ControllerMpd::parseState Sep 08 22:56:19 volumio volumio[7086]: verbose: ControllerMpd::parseState Sep 08 22:56:19 volumio volumio[7086]: info: Command Router : Notfying DB Updatefalse Sep 08 22:56:19 volumio volumio[7086]: info: CoreCommandRouter::Close All Modals sent Sep 08 22:56:19 volumio volumio[7086]: verbose: ControllerMpd::parseState Sep 08 22:56:19 volumio volumio[7086]: verbose: ControllerMpd::parseState Sep 08 22:56:19 volumio volumio[7086]: info: Command Router : Notfying DB Updatefalse Sep 08 22:56:19 volumio volumio[7086]: info: CoreCommandRouter::Close All Modals sent Sep 08 22:56:19 volumio volumio[7086]: verbose: ControllerMpd::parseState Sep 08 22:56:19 volumio volumio[7086]: info: ControllerMpd::pushState Sep 08 22:56:19 volumio volumio[7086]: info: CoreCommandRouter::servicePushState Sep 08 22:56:19 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:19 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:19 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:19 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:19 volumio volumio[7086]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 08 22:56:19 volumio volumio[7086]: verbose: CURRENT POSITION 0 Sep 08 22:56:19 volumio volumio[7086]: info: CoreStateMachine::syncState stateService stop Sep 08 22:56:19 volumio volumio[7086]: info: CoreStateMachine::syncState currentStatus stop Sep 08 22:56:19 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:19 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:19 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:19 volumio volumio[7086]: info: No code Sep 08 22:56:19 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:19 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:19 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:19 volumio volumio[7086]: info: ControllerMpd::pushState Sep 08 22:56:19 volumio volumio[7086]: info: CoreCommandRouter::servicePushState Sep 08 22:56:19 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:19 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:19 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:19 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:19 volumio volumio[7086]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 08 22:56:19 volumio volumio[7086]: verbose: CURRENT POSITION 0 Sep 08 22:56:19 volumio volumio[7086]: info: CoreStateMachine::syncState stateService stop Sep 08 22:56:19 volumio volumio[7086]: info: CoreStateMachine::syncState currentStatus stop Sep 08 22:56:19 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:19 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:19 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:19 volumio volumio[7086]: info: No code Sep 08 22:56:19 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:19 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:19 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:19 volumio volumio[7086]: info: ControllerMpd::pushState Sep 08 22:56:19 volumio volumio[7086]: info: CoreCommandRouter::servicePushState Sep 08 22:56:19 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:19 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:19 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:19 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:19 volumio volumio[7086]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 08 22:56:19 volumio volumio[7086]: verbose: CURRENT POSITION 0 Sep 08 22:56:19 volumio volumio[7086]: info: CoreStateMachine::syncState stateService stop Sep 08 22:56:19 volumio volumio[7086]: info: CoreStateMachine::syncState currentStatus stop Sep 08 22:56:19 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:19 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:19 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:19 volumio volumio[7086]: info: No code Sep 08 22:56:19 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:19 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:19 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:20 volumio volumio[7086]: info: ------------------------------ 186ms Sep 08 22:56:20 volumio volumio[7086]: info: ------------------------------ 179ms Sep 08 22:56:20 volumio volumio[7086]: info: ------------------------------ 175ms Sep 08 22:56:20 volumio volumio[7086]: verbose: ControllerMpd::sendMpdCommand status Sep 08 22:56:20 volumio volumio[7086]: info: Sep 08 22:56:20 volumio volumio[7086]: ---------------------------- MPD announces state update: update Sep 08 22:56:20 volumio volumio[7086]: info: ControllerMpd::getState Sep 08 22:56:20 volumio volumio[7086]: verbose: ControllerMpd::sendMpdCommand status Sep 08 22:56:20 volumio volumio[7086]: verbose: ControllerMpd::sendMpdCommand status Sep 08 22:56:20 volumio volumio[7086]: info: Sep 08 22:56:20 volumio volumio[7086]: ---------------------------- MPD announces state update: update Sep 08 22:56:20 volumio volumio[7086]: info: ControllerMpd::getState Sep 08 22:56:20 volumio volumio[7086]: verbose: ControllerMpd::sendMpdCommand status Sep 08 22:56:20 volumio volumio[7086]: info: sendMpdCommand status took 176 milliseconds Sep 08 22:56:20 volumio volumio[7086]: info: sendMpdCommand status took 175 milliseconds Sep 08 22:56:20 volumio volumio[7086]: info: sendMpdCommand status took 6 milliseconds Sep 08 22:56:20 volumio volumio[7086]: info: sendMpdCommand status took 4 milliseconds Sep 08 22:56:20 volumio volumio[7086]: info: sendMpdCommand status took 4 milliseconds Sep 08 22:56:20 volumio volumio[7086]: info: sendMpdCommand status took 3 milliseconds Sep 08 22:56:20 volumio volumio[7086]: verbose: ControllerMpd::parseState Sep 08 22:56:20 volumio volumio[7086]: info: Command Router : Notfying DB Updatefalse Sep 08 22:56:20 volumio volumio[7086]: info: CoreCommandRouter::Close All Modals sent Sep 08 22:56:20 volumio volumio[7086]: verbose: ControllerMpd::parseState Sep 08 22:56:20 volumio volumio[7086]: verbose: ControllerMpd::parseState Sep 08 22:56:20 volumio volumio[7086]: info: Command Router : Notfying DB Updatefalse Sep 08 22:56:20 volumio volumio[7086]: info: CoreCommandRouter::Close All Modals sent Sep 08 22:56:20 volumio volumio[7086]: verbose: ControllerMpd::parseState Sep 08 22:56:20 volumio volumio[7086]: info: Command Router : Notfying DB Updatefalse Sep 08 22:56:20 volumio volumio[7086]: info: CoreCommandRouter::Close All Modals sent Sep 08 22:56:20 volumio volumio[7086]: verbose: ControllerMpd::parseState Sep 08 22:56:20 volumio volumio[7086]: info: ControllerMpd::pushState Sep 08 22:56:20 volumio volumio[7086]: info: CoreCommandRouter::servicePushState Sep 08 22:56:20 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:20 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:20 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:20 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:20 volumio volumio[7086]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 08 22:56:20 volumio volumio[7086]: verbose: CURRENT POSITION 0 Sep 08 22:56:20 volumio volumio[7086]: info: CoreStateMachine::syncState stateService stop Sep 08 22:56:20 volumio volumio[7086]: info: CoreStateMachine::syncState currentStatus stop Sep 08 22:56:20 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:20 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:20 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:20 volumio volumio[7086]: info: No code Sep 08 22:56:20 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:20 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:20 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:20 volumio volumio[7086]: info: ControllerMpd::pushState Sep 08 22:56:20 volumio volumio[7086]: info: CoreCommandRouter::servicePushState Sep 08 22:56:20 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:20 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:20 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:20 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:20 volumio volumio[7086]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 08 22:56:20 volumio volumio[7086]: verbose: CURRENT POSITION 0 Sep 08 22:56:20 volumio volumio[7086]: info: CoreStateMachine::syncState stateService stop Sep 08 22:56:20 volumio volumio[7086]: info: CoreStateMachine::syncState currentStatus stop Sep 08 22:56:20 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:20 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:20 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:20 volumio volumio[7086]: info: No code Sep 08 22:56:20 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:20 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:20 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:20 volumio volumio[7086]: info: ControllerMpd::pushState Sep 08 22:56:20 volumio volumio[7086]: info: CoreCommandRouter::servicePushState Sep 08 22:56:20 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:20 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:20 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:20 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:20 volumio volumio[7086]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 08 22:56:20 volumio volumio[7086]: verbose: CURRENT POSITION 0 Sep 08 22:56:20 volumio volumio[7086]: info: CoreStateMachine::syncState stateService stop Sep 08 22:56:20 volumio volumio[7086]: info: CoreStateMachine::syncState currentStatus stop Sep 08 22:56:20 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:20 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:20 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:20 volumio volumio[7086]: info: No code Sep 08 22:56:20 volumio volumio[7086]: info: CoreStateMachine::pushState Sep 08 22:56:20 volumio volumio[7086]: info: CorePlayQueue::getTrack 0 Sep 08 22:56:20 volumio volumio[7086]: info: CoreCommandRouter::volumioPushState Sep 08 22:56:20 volumio volumio[7086]: info: ------------------------------ 320ms Sep 08 22:56:20 volumio volumio[7086]: info: ------------------------------ 150ms Sep 08 22:56:20 volumio volumio[7086]: info: ------------------------------ 148ms Sep 08 22:56:20 volumio volumio[7086]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 22:56:20 volumio volumio[7086]: Error: dns service error: unknown Sep 08 22:56:20 volumio volumio[7086]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Sep 08 22:56:20 volumio volumio[7086]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Sep 08 22:56:20 volumio volumio[7086]: errorCode: -65537 Sep 08 22:56:20 volumio volumio[7086]: } Sep 08 22:56:20 volumio volumio[7086]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 22:56:21 volumio sudo[7327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-08 22:55 Sep 08 22:56:21 volumio sudo[7327]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"