-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Mon 2025-12-22 14:40:02 CST. -- Dec 22 14:39:00 volumio volumio[958]: info: MyVolumio login type: Token Dec 22 14:39:03 volumio volumio[958]: info: CoreCommandRouter::volumioRemoveQueueItem Dec 22 14:39:03 volumio volumio[958]: info: CoreStateMachine::removeQueueItem Dec 22 14:39:03 volumio volumio[958]: info: CorePlayQueue::removeQueueItem 2 Dec 22 14:39:03 volumio volumio[958]: info: CorePlayQueue::saveQueue Dec 22 14:39:03 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Dec 22 14:39:03 volumio volumio[958]: info: CoreCommandRouter::volumioRemoveQueueItem Dec 22 14:39:03 volumio volumio[958]: info: CoreStateMachine::removeQueueItem Dec 22 14:39:03 volumio volumio[958]: info: CorePlayQueue::removeQueueItem 2 Dec 22 14:39:03 volumio volumio[958]: info: CorePlayQueue::saveQueue Dec 22 14:39:03 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Dec 22 14:39:03 volumio volumio[958]: info: CoreCommandRouter::volumioRemoveQueueItem Dec 22 14:39:03 volumio volumio[958]: info: CoreStateMachine::removeQueueItem Dec 22 14:39:03 volumio volumio[958]: info: CorePlayQueue::removeQueueItem 2 Dec 22 14:39:03 volumio volumio[958]: info: CorePlayQueue::saveQueue Dec 22 14:39:03 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Dec 22 14:39:03 volumio volumio[958]: info: CoreCommandRouter::volumioRemoveQueueItem Dec 22 14:39:03 volumio volumio[958]: info: CoreStateMachine::removeQueueItem Dec 22 14:39:03 volumio volumio[958]: info: CorePlayQueue::removeQueueItem 2 Dec 22 14:39:03 volumio volumio[958]: info: CorePlayQueue::saveQueue Dec 22 14:39:03 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Dec 22 14:39:08 volumio volumio[958]: info: CoreCommandRouter::volumioRandom Dec 22 14:39:08 volumio volumio[958]: info: CoreCommandRouter::writePlayerControls Dec 22 14:39:08 volumio volumio[958]: info: CorePlayQueue::getTrack 685 Dec 22 14:39:08 volumio volumio[958]: info: CoreStateMachine::setRepeat true single false Dec 22 14:39:08 volumio volumio[958]: info: CoreStateMachine::pushState Dec 22 14:39:08 volumio volumio[958]: info: CorePlayQueue::getTrack 685 Dec 22 14:39:08 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 14:39:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:08 volumio volumio[958]: info: CoreCommandRouter::volumioRandom Dec 22 14:39:08 volumio volumio[958]: info: CoreCommandRouter::writePlayerControls Dec 22 14:39:08 volumio volumio[958]: info: CorePlayQueue::getTrack 685 Dec 22 14:39:08 volumio volumio[958]: info: CoreStateMachine::setRepeat true single false Dec 22 14:39:08 volumio volumio[958]: info: CoreStateMachine::pushState Dec 22 14:39:08 volumio volumio[958]: info: CorePlayQueue::getTrack 685 Dec 22 14:39:08 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 14:39:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:10 volumio volumio[958]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 14:39:10 volumio volumio[958]: Error: connect ETIMEDOUT 157.240.17.36:443 Dec 22 14:39:10 volumio volumio[958]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 22 14:39:10 volumio volumio[958]: errno: -110, Dec 22 14:39:10 volumio volumio[958]: code: 'ETIMEDOUT', Dec 22 14:39:10 volumio volumio[958]: syscall: 'connect', Dec 22 14:39:10 volumio volumio[958]: address: '157.240.17.36', Dec 22 14:39:10 volumio volumio[958]: port: 443 Dec 22 14:39:10 volumio volumio[958]: } Dec 22 14:39:10 volumio volumio[958]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 14:39:11 volumio sudo[2720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-22 14:38 Dec 22 14:39:11 volumio sudo[2720]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:11 volumio sudo[2720]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:12 volumio volumio-remote-updater[610]: [2025-12-22 14:39:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 22 14:39:12 volumio volumio-remote-updater[610]: [2025-12-22 14:39:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 22 14:39:12 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 22 14:39:12 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 22 14:39:12 volumio systemd[1]: Started dynamicswap service. Dec 22 14:39:12 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 22 14:39:12 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 22 14:39:12 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Dec 22 14:39:12 volumio systemd[1]: Started dynamicswap service. Dec 22 14:39:12 volumio systemd[1]: Stopped Volumio Backend Module. Dec 22 14:39:12 volumio systemd[1]: Started Volumio Backend Module. Dec 22 14:39:12 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 22 14:39:15 volumio volumio[2738]: info: ------------------------------------------- Dec 22 14:39:15 volumio volumio[2738]: info: ----- Volumio3 ---- Dec 22 14:39:15 volumio volumio[2738]: info: ------------------------------------------- Dec 22 14:39:15 volumio volumio[2738]: info: ----- System startup ---- Dec 22 14:39:15 volumio volumio[2738]: info: ------------------------------------------- Dec 22 14:39:17 volumio volumio[2738]: info: MYVOLUMIO Environment detected Dec 22 14:39:17 volumio volumio[2738]: info: Plugin folders cleanup Dec 22 14:39:17 volumio volumio[2738]: info: Scanning into folder /volumio/app/plugins/ Dec 22 14:39:17 volumio volumio[2738]: info: Scanning category audio_interface Dec 22 14:39:17 volumio volumio[2738]: info: Scanning category miscellanea Dec 22 14:39:17 volumio volumio[2738]: info: Scanning category music_service Dec 22 14:39:17 volumio volumio[2738]: info: Scanning category plugins.json Dec 22 14:39:17 volumio volumio[2738]: info: Scanning category system_controller Dec 22 14:39:17 volumio volumio[2738]: info: Scanning category user_interface Dec 22 14:39:17 volumio volumio[2738]: info: Scanning into folder /data/plugins/ Dec 22 14:39:17 volumio volumio[2738]: info: Scanning category system_controller Dec 22 14:39:17 volumio volumio[2738]: info: Scanning category user_interface Dec 22 14:39:17 volumio volumio[2738]: info: Plugin folders cleanup completed Dec 22 14:39:17 volumio volumio[2738]: info: ------------------------------------------- Dec 22 14:39:17 volumio volumio[2738]: info: ----- Core plugins startup ---- Dec 22 14:39:17 volumio volumio[2738]: info: ------------------------------------------- Dec 22 14:39:17 volumio volumio[2738]: info: Loading plugins from folder /volumio/app/plugins/ Dec 22 14:39:17 volumio volumio[2738]: info: Adding plugin upnp to MyMusic Plugins Dec 22 14:39:17 volumio volumio-remote-updater[610]: [2025-12-22 14:39:17] [connect] Successful connection Dec 22 14:39:17 volumio volumio[2738]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 22 14:39:17 volumio volumio[2738]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 22 14:39:17 volumio volumio[2738]: info: Loading plugins from folder /data/plugins/ Dec 22 14:39:17 volumio volumio[2738]: info: Loading plugin "system"... Dec 22 14:39:17 volumio volumio[2738]: info: Loading plugin "appearance"... Dec 22 14:39:19 volumio volumio[2738]: info: Loading plugin "network"... Dec 22 14:39:19 volumio volumio[2738]: info: Refreshing Cached IP Addresses Dec 22 14:39:19 volumio sudo[2765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 14:39:19 volumio sudo[2765]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:19 volumio sudo[2765]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:19 volumio sudo[2767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 14:39:19 volumio sudo[2767]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:19 volumio sudo[2767]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:19 volumio volumio[2738]: info: Loading plugin "services"... Dec 22 14:39:19 volumio volumio[2738]: info: Loading plugin "alsa_controller"... Dec 22 14:39:19 volumio sudo[2776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 22 14:39:19 volumio sudo[2776]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:19 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 14:39:19 volumio volumio[2738]: info: Loading plugin "wizard"... Dec 22 14:39:19 volumio volumio[2738]: info: Loading plugin "networkfs"... Dec 22 14:39:19 volumio volumio[2738]: info: Starting Udev Watcher for removable devices Dec 22 14:39:19 volumio volumio[2738]: info: Ignoring mount for partition: boot Dec 22 14:39:19 volumio volumio[2738]: info: Ignoring mount for partition: volumio Dec 22 14:39:19 volumio volumio[2738]: info: Ignoring mount for partition: volumio_data Dec 22 14:39:19 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 14:39:19 volumio volumio[2738]: info: Loading plugin "volumio_command_line_client"... Dec 22 14:39:19 volumio volumio[2738]: info: Loading plugin "upnp"... Dec 22 14:39:19 volumio volumio[2738]: info: [1766385559706] Starting Upmpd Daemon Dec 22 14:39:19 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 14:39:19 volumio volumio[2738]: info: Loading plugin "my_music"... Dec 22 14:39:19 volumio volumio[2738]: info: Loading plugin "mpd"... Dec 22 14:39:20 volumio volumio[2738]: info: Loading plugin "upnp_browser"... Dec 22 14:39:20 volumio sudo[2776]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:20 volumio volumio[2738]: info: Loading plugin "alarm-clock"... Dec 22 14:39:21 volumio volumio[2738]: info: Loading plugin "airplay_emulation"... Dec 22 14:39:21 volumio volumio[2738]: info: Starting Shairport Sync Dec 22 14:39:21 volumio volumio[2738]: info: Loading plugin "last_100"... Dec 22 14:39:21 volumio volumio[2738]: info: Loading plugin "webradio"... Dec 22 14:39:21 volumio volumio[2738]: info: Loading plugin "i2s_dacs"... Dec 22 14:39:21 volumio volumio[2738]: info: Loading plugin "volumiodiscovery"... Dec 22 14:39:21 volumio volumio[2738]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 22 14:39:21 volumio volumio[2738]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 14:39:21 volumio volumio[2738]: *** WARNING *** For more information see Dec 22 14:39:21 volumio volumio[2738]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 22 14:39:21 volumio volumio[2738]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 14:39:21 volumio volumio[2738]: *** WARNING *** For more information see Dec 22 14:39:21 volumio node[2738]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 22 14:39:21 volumio node[2738]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 14:39:21 volumio node[2738]: *** WARNING *** For more information see Dec 22 14:39:21 volumio node[2738]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 22 14:39:21 volumio node[2738]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 14:39:21 volumio node[2738]: *** WARNING *** For more information see Dec 22 14:39:21 volumio volumio[2738]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 22 14:39:21 volumio volumio[2738]: info: Discovery: Started advertising with name: Volumio Dec 22 14:39:21 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 14:39:21 volumio volumio[2738]: info: Loading plugin "autostart"... Dec 22 14:39:21 volumio volumio[2738]: info: Applying required configuration parameters for plugin autostart Dec 22 14:39:21 volumio volumio[2738]: info: AutoStart - onVolumioStart - read config.json Dec 22 14:39:21 volumio volumio[2738]: info: Loading plugin "outputs"... Dec 22 14:39:21 volumio volumio[2738]: info: Loading plugin "albumart"... Dec 22 14:39:21 volumio volumio[2738]: info: Plugin example_plugin is not enabled Dec 22 14:39:21 volumio volumio[2738]: info: Loading plugin "inputs"... Dec 22 14:39:22 volumio volumio[2738]: info: Loading plugin "updater_comm"... Dec 22 14:39:22 volumio volumio[2738]: info: Plugin mpdemulation is not enabled Dec 22 14:39:22 volumio volumio[2738]: info: Loading plugin "rest_api"... Dec 22 14:39:22 volumio volumio[2738]: info: Loading plugin "websocket"... Dec 22 14:39:22 volumio volumio[2738]: info: Starting Socket.io Server version 2.3.0 Dec 22 14:39:22 volumio volumio[2738]: info: Loading plugin "touch_display"... Dec 22 14:39:22 volumio volumio[2738]: Forking 3 albumart workers Dec 22 14:39:23 volumio volumio[2738]: info: Applying required configuration parameters for plugin touch_display Dec 22 14:39:23 volumio volumio[2738]: info: Loading i18n strings for locale zh Dec 22 14:39:23 volumio volumio[2738]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 22 14:39:23 volumio volumio[2738]: Updating browse sources language Dec 22 14:39:23 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 14:39:23 volumio volumio[2738]: Starting albumart workers Dec 22 14:39:23 volumio volumio[2738]: Starting albumart workers Dec 22 14:39:23 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 14:39:23 volumio volumio[2738]: info: CoreCommandRouter::initPlayerControls Dec 22 14:39:23 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:23 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:23 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:23 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:23 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:23 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:23 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:23 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:23 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 14:39:23 volumio volumio[2738]: Starting albumart workers Dec 22 14:39:23 volumio volumio[2738]: Express server listening on port 3000 Dec 22 14:39:23 volumio volumio[2738]: [Metrics] WebUI: 8s 774.93ms Dec 22 14:39:23 volumio volumio[2738]: info: CoreStateMachine::resetVolumioState Dec 22 14:39:23 volumio volumio[2738]: info: CoreStateMachine::getcurrentVolume Dec 22 14:39:23 volumio volumio[2738]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 14:39:23 volumio volumio[2738]: info: Volumio Network Manager: Network status updated: 2 Dec 22 14:39:24 volumio volumio[2738]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 22 14:39:24 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:24 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:24 volumio volumio[2738]: info: CoreStateMachine::updateTrackBlock Dec 22 14:39:24 volumio volumio[2738]: info: CorePlayQueue::getTrackBlock Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 14:39:24 volumio volumio[2738]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 22 14:39:24 volumio volumio[2738]: info: Reloading queue from file Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:39:24 volumio volumio[2738]: info: CoreStateMachine::setRepeat true single undefined Dec 22 14:39:24 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:24 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:24 volumio volumio[2738]: info: CoreStateMachine::setRandom true Dec 22 14:39:24 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:24 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:24 volumio volumio[2738]: info: Setting Device type: Raspberry PI Dec 22 14:39:24 volumio volumio[2738]: info: Completed loading Core Plugins Dec 22 14:39:24 volumio volumio[2738]: info: Preparing to generate the ALSA configuration file Dec 22 14:39:24 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:24 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:24 volumio volumio[2738]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 22 14:39:24 volumio volumio[2738]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Dec 22 14:39:24 volumio volumio[2738]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 22 14:39:24 volumio volumio[2738]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 22 14:39:24 volumio volumio[2738]: info: Reading ALSA contributions from plugins. Dec 22 14:39:24 volumio volumio[2738]: info: Discovery: adding 898e9f9a-0f7b-46d0-9b6b-5789d97fabcf Dec 22 14:39:24 volumio volumio[2738]: info: Discovery: Found device Volumio Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::volumioGetState Dec 22 14:39:24 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:24 volumio volumio[2738]: info: Asound.conf file unchanged, so no further update is needed Dec 22 14:39:24 volumio volumio[2738]: info: Output device has changed, restarting MPD Dec 22 14:39:24 volumio volumio[2738]: info: Output device has changed, restarting Shairport Sync Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:39:24 volumio sudo[2865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 14:39:24 volumio sudo[2865]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:24 volumio sudo[2865]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:24 volumio sudo[2868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 14:39:24 volumio sudo[2868]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:24 volumio volumio[2738]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 14:39:24 volumio volumio[2738]: info: ___________ START PLUGINS ___________ Dec 22 14:39:24 volumio systemd[1]: Stopping Music Player Daemon... Dec 22 14:39:24 volumio volumio[2738]: info: ControllerMpd::onStart: Initializing MPD Dec 22 14:39:24 volumio volumio[2738]: info: Creating MPD Configuration file Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 14:39:24 volumio sudo[2875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 14:39:24 volumio volumio[2738]: info: [1766385564816] CoreMusicLibrary::Adding element 媒体服务器 Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 14:39:24 volumio sudo[2875]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:24 volumio sudo[2875]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:24 volumio sudo[2877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 14:39:24 volumio sudo[2877]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:39:24 volumio volumio[2738]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:39:24 volumio volumio[2738]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 14:39:24 volumio volumio[2738]: info: [1766385564968] CoreMusicLibrary::Adding element Last_100 Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 14:39:24 volumio volumio[2738]: info: [1766385564971] CoreMusicLibrary::Adding element Webradio Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 14:39:24 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 14:39:24 volumio volumio[2738]: info: Initializing BBC Radios Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: AutoStart - onStart Dec 22 14:39:25 volumio volumio[2738]: info: Loading i18n strings for locale zh Dec 22 14:39:25 volumio volumio[2738]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 14:39:25 volumio volumio[2738]: info: Volumio Calling Home Dec 22 14:39:25 volumio sudo[2900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 22 14:39:25 volumio sudo[2900]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:25 volumio systemd[1]: Reloading. Dec 22 14:39:25 volumio volumio[2738]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 22 14:39:25 volumio volumio[2738]: info: MPD Permissions set Dec 22 14:39:25 volumio volumio[2738]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:39:25 volumio volumio[2738]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::volumioGetState Dec 22 14:39:25 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 22 14:39:25 volumio volumio[2738]: info: Received Get System Info Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 14:39:25 volumio volumio[2738]: info: Discovery: Getting this device information Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::volumioGetState Dec 22 14:39:25 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::volumioGetState Dec 22 14:39:25 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:25 volumio volumio[2738]: info: Listing playlists Dec 22 14:39:25 volumio volumio[2738]: info: CoreCommandRouter::volumioGetQueue Dec 22 14:39:25 volumio volumio[2738]: info: CoreStateMachine::getQueue Dec 22 14:39:25 volumio volumio[2738]: info: CorePlayQueue::getQueue Dec 22 14:39:25 volumio volumio[2738]: info: Starting Shairport Sync Dec 22 14:39:25 volumio volumio[2738]: info: Starting Shairport Sync Dec 22 14:39:25 volumio volumio[2738]: info: Starting Shairport Sync Dec 22 14:39:25 volumio sudo[2924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 14:39:25 volumio sudo[2924]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:25 volumio sudo[2926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 14:39:25 volumio sudo[2926]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:25 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 22 14:39:26 volumio sudo[2930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 14:39:26 volumio volumio[2738]: info: CoreCommandRouter::volumioGetState Dec 22 14:39:26 volumio sudo[2930]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:26 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:26 volumio volumio[2738]: info: Volumio called home Dec 22 14:39:26 volumio systemd[1]: mpd.service: Succeeded. Dec 22 14:39:26 volumio systemd[1]: Stopped Music Player Daemon. Dec 22 14:39:26 volumio sudo[2900]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:26 volumio volumio[2738]: info: touch_display: systemctl daemon-reload succeeded. Dec 22 14:39:26 volumio sudo[2933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Dec 22 14:39:26 volumio sudo[2933]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:26 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 22 14:39:26 volumio systemd[1]: Starting Music Player Daemon... Dec 22 14:39:26 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 22 14:39:26 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 22 14:39:26 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 22 14:39:26 volumio sudo[2930]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:26 volumio volumio[2738]: info: Shairport-Sync Started Dec 22 14:39:26 volumio sudo[2924]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:26 volumio sudo[2926]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:26 volumio volumio[2738]: Error adding Membership: Error: addMembership EINVAL Dec 22 14:39:26 volumio volumio[2738]: info: Shairport-Sync Started Dec 22 14:39:26 volumio volumio[2738]: info: Shairport-Sync Started Dec 22 14:39:26 volumio sudo[2933]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:26 volumio volumio[2738]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Dec 22 14:39:26 volumio volumio[2738]: info: touch_display: Volumio Kiosk started Dec 22 14:39:26 volumio volumio[2738]: info: CoreCommandRouter::volumioGetState Dec 22 14:39:26 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:26 volumio sudo[2935]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 14:39:26 volumio sudo[2935]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:26 volumio volumio[2738]: info: CoreCommandRouter::volumioGetState Dec 22 14:39:26 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:26 volumio sudo[2935]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:27 volumio volumio[2738]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 22 14:39:27 volumio volumio[2738]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 22 14:39:27 volumio volumio[2738]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 22 14:39:27 volumio volumio[2738]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 22 14:39:29 volumio sudo[2965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 14:39:29 volumio sudo[2965]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:29 volumio sudo[2965]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:29 volumio sudo[2967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 14:39:29 volumio sudo[2967]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:29 volumio sudo[2970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 22 14:39:29 volumio sudo[2970]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:29 volumio sudo[2967]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:29 volumio sudo[2970]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:29 volumio volumio[2738]: info: Upmpdcli Daemon Started Dec 22 14:39:30 volumio volumio-remote-updater[610]: [2025-12-22 14:39:30] [connect] Successful connection Dec 22 14:39:30 volumio volumio-remote-updater[610]: [2025-12-22 14:39:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766385570 101 Dec 22 14:39:30 volumio volumio[2738]: 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: 5 Dec 22 14:39:30 volumio mpd[2942]: Dec 22 14:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 22 14:39:30 volumio systemd[1]: Started Music Player Daemon. Dec 22 14:39:30 volumio sudo[2877]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:30 volumio sudo[2868]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:30 volumio volumio[2738]: info: Completed starting Core Plugins Dec 22 14:39:30 volumio volumio[2738]: info: ------------------------------------------- Dec 22 14:39:30 volumio volumio[2738]: info: ----- MyVolumio plugins startup ---- Dec 22 14:39:30 volumio volumio[2738]: info: ------------------------------------------- Dec 22 14:39:30 volumio volumio[2738]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 22 14:39:30 volumio volumio[2738]: error: MPD error: The expression evaluated to a falsy value: Dec 22 14:39:30 volumio volumio[2738]: assert.ok(self.idling) Dec 22 14:39:30 volumio volumio[2738]: error: The expression evaluated to a falsy value: Dec 22 14:39:30 volumio volumio[2738]: assert.ok(self.idling) Dec 22 14:39:30 volumio volumio[2738]: info: MPD running with PID2942 Dec 22 14:39:30 volumio volumio[2738]: ,establishing connection Dec 22 14:39:30 volumio volumio[2738]: error: MPD error: The expression evaluated to a falsy value: Dec 22 14:39:30 volumio volumio[2738]: assert.ok(self.idling) Dec 22 14:39:30 volumio volumio[2738]: error: The expression evaluated to a falsy value: Dec 22 14:39:30 volumio volumio[2738]: assert.ok(self.idling) Dec 22 14:39:30 volumio volumio[2738]: error: updateQueue error: null Dec 22 14:39:32 volumio volumio[2738]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Doc%20Watson/Americana%20Master%20Series%3A%20Best%20of%20The%20Sugar%20Hill%20Years/6b665a3e-c3dd-4b16-9116-c55c8d7c1384.jpg' Dec 22 14:39:32 volumio volumio[2738]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Uncle%20Lucius/And%20You%20Are%20Me/393eb631-32e4-4d87-a311-584ea97d7289.jpg' Dec 22 14:39:33 volumio sudo[2981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 14:39:33 volumio sudo[2981]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:33 volumio sudo[2983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 14:39:33 volumio sudo[2983]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:33 volumio systemd[1]: Stopping MPD Monitor Service... Dec 22 14:39:33 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 22 14:39:33 volumio systemd[1]: mpd_monitor.service: Succeeded. Dec 22 14:39:33 volumio systemd[1]: Stopped MPD Monitor Service. Dec 22 14:39:33 volumio systemd[1]: Started MPD Monitor Service. Dec 22 14:39:33 volumio sudo[2983]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:33 volumio sudo[2981]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:33 volumio volumio[2738]: info: Successfully started MPD Monitor Dec 22 14:39:33 volumio mpd_monitor.sh[2986]: MPD Monitor Service: Starting MPD Monitor Service Dec 22 14:39:33 volumio volumio[2738]: info: Successfully started MPD Monitor Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 22 14:39:42 volumio volumio[2738]: info: Adding plugin bluetooth to MyMusic Plugins Dec 22 14:39:42 volumio volumio[2738]: info: Adding plugin multiroom to MyMusic Plugins Dec 22 14:39:42 volumio volumio[2738]: info: Adding plugin metavolumio to MyMusic Plugins Dec 22 14:39:42 volumio volumio[2738]: info: Adding plugin cd_controller to MyMusic Plugins Dec 22 14:39:42 volumio volumio[2738]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 22 14:39:42 volumio volumio[2738]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 22 14:39:42 volumio volumio[2738]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 22 14:39:42 volumio volumio[2738]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 22 14:39:43 volumio volumio[2738]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 22 14:39:43 volumio volumio[2738]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 22 14:39:43 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:39:43 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:39:43 volumio volumio[2738]: info: Starting MyVolumio Remote Streaming Endpoints Dec 22 14:39:43 volumio volumio[2738]: info: MyVolumio login type: Token Dec 22 14:39:43 volumio volumio[2738]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 22 14:39:43 volumio volumio[2738]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 22 14:39:45 volumio volumio[2738]: info: Starting Streaming Service Transparent Proxy Dec 22 14:39:45 volumio volumio[2738]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 22 14:39:45 volumio volumio[2738]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 22 14:39:45 volumio volumio[2738]: info: Streaming services startup Dec 22 14:39:45 volumio volumio[2738]: info: Starting Streaming Daemon Dec 22 14:39:45 volumio sudo[3048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 22 14:39:45 volumio sudo[3048]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 14:39:45 volumio volumio[2738]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 22 14:39:45 volumio sudo[3048]: pam_unix(sudo:session): session closed for user root Dec 22 14:39:45 volumio volumio[2738]: info: AutoStart - Plugin is starting Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::volumioGetQueue Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::getQueue Dec 22 14:39:45 volumio volumio[2738]: info: CorePlayQueue::getQueue Dec 22 14:39:45 volumio volumio[2738]: info: AutoStart - start playing Dec 22 14:39:45 volumio volumio[2738]: info: AutoStart - start playing with no specific position Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::volumioPlay Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::play index 0 Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::stop Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::play index undefined Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 14:39:45 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::startPlaybackTimer Dec 22 14:39:45 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::clearAddPlayTracks USB/ϵͳ/Chris Cardena、Sebastian Robertson、 Pacific Avenue - Antagonistic_L.ogg Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand stop Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 14:39:45 volumio volumio[2738]: info: Retrieving Cloud Streaming UI Dec 22 14:39:45 volumio volumio[2738]: info: sendMpdCommand stop took 44 milliseconds Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand clear Dec 22 14:39:45 volumio volumio[2738]: error: Cannot start Volumio Streaming Daemon Dec 22 14:39:45 volumio volumio[2738]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 22 14:39:45 volumio volumio[2738]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 22 14:39:45 volumio volumio[2738]: info: Dec 22 14:39:45 volumio volumio[2738]: ---------------------------- MPD announces system playlist update Dec 22 14:39:45 volumio volumio[2738]: info: Ignoring MPD Status Update Dec 22 14:39:45 volumio volumio[2738]: info: sendMpdCommand clear took 12 milliseconds Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand add "USB/ϵͳ/Chris Cardena、Sebastian Robertson、 Pacific Avenue - Antagonistic_L.ogg" Dec 22 14:39:45 volumio volumio[2738]: info: Dec 22 14:39:45 volumio volumio[2738]: ---------------------------- MPD announces system playlist update Dec 22 14:39:45 volumio volumio[2738]: info: Ignoring MPD Status Update Dec 22 14:39:45 volumio volumio[2738]: info: Dec 22 14:39:45 volumio volumio[2738]: ---------------------------- MPD announces system playlist update Dec 22 14:39:45 volumio volumio[2738]: info: Ignoring MPD Status Update Dec 22 14:39:45 volumio volumio[2738]: error: updateQueue error: null Dec 22 14:39:45 volumio volumio[2738]: info: Dec 22 14:39:45 volumio volumio[2738]: ---------------------------- MPD announces system playlist update Dec 22 14:39:45 volumio volumio[2738]: info: Ignoring MPD Status Update Dec 22 14:39:45 volumio volumio[2738]: info: ------------------------------ 13ms Dec 22 14:39:45 volumio volumio[2738]: info: sendMpdCommand add "USB/ϵͳ/Chris Cardena、Sebastian Robertson、 Pacific Avenue - Antagonistic_L.ogg" took 11 milliseconds Dec 22 14:39:45 volumio volumio[2738]: info: ------------------------------ 9ms Dec 22 14:39:45 volumio volumio[2738]: info: ------------------------------ 8ms Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand play Dec 22 14:39:45 volumio volumio[2738]: info: Dec 22 14:39:45 volumio volumio[2738]: ---------------------------- MPD announces system playlist update Dec 22 14:39:45 volumio volumio[2738]: info: Ignoring MPD Status Update Dec 22 14:39:45 volumio volumio[2738]: info: Dec 22 14:39:45 volumio volumio[2738]: ---------------------------- MPD announces system playlist update Dec 22 14:39:45 volumio volumio[2738]: info: Ignoring MPD Status Update Dec 22 14:39:45 volumio volumio[2738]: info: Dec 22 14:39:45 volumio volumio[2738]: ---------------------------- MPD announces state update: player Dec 22 14:39:45 volumio volumio[2738]: info: ------------------------------ 21ms Dec 22 14:39:45 volumio volumio[2738]: info: sendMpdCommand play took 16 milliseconds Dec 22 14:39:45 volumio volumio[2738]: info: ------------------------------ 16ms Dec 22 14:39:45 volumio volumio[2738]: info: ControllerMpd::getState Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:45 volumio volumio[2738]: info: ------------------------------ 15ms Dec 22 14:39:45 volumio volumio[2738]: info: Dec 22 14:39:45 volumio volumio[2738]: ---------------------------- MPD announces state update: player Dec 22 14:39:45 volumio volumio[2738]: info: ControllerMpd::getState Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:45 volumio volumio[2738]: info: Dec 22 14:39:45 volumio volumio[2738]: ---------------------------- MPD announces state update: player Dec 22 14:39:45 volumio volumio[2738]: info: ControllerMpd::getState Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:45 volumio volumio[2738]: info: Getting Tidal Cloud Configuration Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 14:39:45 volumio volumio[2738]: info: Getting Qobuz Cloud Configuration Dec 22 14:39:45 volumio volumio[2738]: info: Asking plugin for UI Config Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 14:39:45 volumio volumio[2738]: info: Getting Spotify Cloud Configuration Dec 22 14:39:45 volumio volumio[2738]: info: Asking plugin for UI Config Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 14:39:45 volumio volumio[2738]: info: Saving Spotify Acccount Dec 22 14:39:45 volumio volumio[2738]: info: Got Tidal Cloud Configuration Dec 22 14:39:45 volumio volumio[2738]: info: Got it Dec 22 14:39:45 volumio volumio[2738]: info: Got it Dec 22 14:39:45 volumio volumio[2738]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 22 14:39:45 volumio volumio[2738]: info: sendMpdCommand status took 34 milliseconds Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 22 14:39:45 volumio volumio[2738]: info: sendMpdCommand status took 33 milliseconds Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 22 14:39:45 volumio volumio[2738]: info: sendMpdCommand status took 33 milliseconds Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:39:45 volumio volumio[2738]: info: sendMpdCommand clearerror took 23 milliseconds Dec 22 14:39:45 volumio volumio[2738]: info: sendMpdCommand clearerror took 22 milliseconds Dec 22 14:39:45 volumio volumio[2738]: info: sendMpdCommand clearerror took 20 milliseconds Dec 22 14:39:45 volumio volumio[2738]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 22 14:39:45 volumio volumio[2738]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 22 14:39:45 volumio volumio[2738]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:39:45 volumio volumio[2738]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:39:45 volumio volumio[2738]: info: ControllerMpd::pushState Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::servicePushState Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:45 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:45 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:45 volumio volumio[2738]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Antagonistic","artist":"Chris Cardena、Sebastian Robertson、 Pacific Avenue","album":null,"uri":"USB/ϵͳ/Chris Cardena、Sebastian Robertson、 Pacific Avenue - Antagonistic_L.ogg","trackType":"ogg"} Dec 22 14:39:45 volumio volumio[2738]: verbose: CURRENT POSITION 0 Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::syncState stateService stop Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::syncState currentStatus stop Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:45 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:45 volumio volumio[2738]: info: No code Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:45 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:45 volumio volumio[2738]: info: ControllerMpd::pushState Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::servicePushState Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:45 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:45 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:45 volumio volumio[2738]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Antagonistic","artist":"Chris Cardena、Sebastian Robertson、 Pacific Avenue","album":null,"uri":"USB/ϵͳ/Chris Cardena、Sebastian Robertson、 Pacific Avenue - Antagonistic_L.ogg","trackType":"ogg"} Dec 22 14:39:45 volumio volumio[2738]: verbose: CURRENT POSITION 0 Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::syncState stateService stop Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::syncState currentStatus stop Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:45 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:45 volumio volumio[2738]: info: No code Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:45 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:45 volumio volumio[2738]: info: ControllerMpd::pushState Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::servicePushState Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:45 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:45 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:45 volumio volumio[2738]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Antagonistic","artist":"Chris Cardena、Sebastian Robertson、 Pacific Avenue","album":null,"uri":"USB/ϵͳ/Chris Cardena、Sebastian Robertson、 Pacific Avenue - Antagonistic_L.ogg","trackType":"ogg"} Dec 22 14:39:45 volumio volumio[2738]: verbose: CURRENT POSITION 0 Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::syncState stateService stop Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::syncState currentStatus stop Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:45 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:45 volumio volumio[2738]: info: No code Dec 22 14:39:45 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:45 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:45 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:45 volumio volumio[2738]: info: ------------------------------ 221ms Dec 22 14:39:45 volumio volumio[2738]: info: ------------------------------ 218ms Dec 22 14:39:45 volumio volumio[2738]: info: ------------------------------ 220ms Dec 22 14:39:46 volumio volumio[2738]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 14:39:46 volumio volumio[2738]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 22 14:39:46 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 22 14:39:46 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 22 14:39:46 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 22 14:39:46 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 22 14:39:46 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 14:39:46 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 14:39:46 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 14:39:46 volumio volumio[2738]: info: CoreCommandRouter::volumioGetBrowseSources Dec 22 14:39:46 volumio volumio[2738]: info: CoreCommandRouter::volumioGetBrowseSources Dec 22 14:39:46 volumio volumio[2738]: info: CoreCommandRouter::volumioGetBrowseSources Dec 22 14:39:47 volumio volumio[2738]: STREAMING PROXY: Starting server on port 3245 Dec 22 14:39:47 volumio volumio[2738]: Node JS runtime: 14 Dec 22 14:39:47 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 22 14:39:48 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 22 14:39:48 volumio volumio[2738]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%80%E9%83%8E/%E5%96%80%E4%BB%80%E5%99%B6%E5%B0%94%E8%83%A1%E6%9D%A8/0736c308-5a8a-49cf-bf39-39087605851c.jpg' Dec 22 14:39:49 volumio volumio[2738]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%8A%89%E5%BE%B7%E8%8F%AF/%E4%BE%86%E7%94%9F%E7%B7%A3/e78f1abc-fc6e-436f-9477-fdbce7e47acd.jpg' Dec 22 14:39:50 volumio volumio[2738]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%98%E8%8B%A5%E8%8B%B1/%E5%B0%91%E5%A5%B3%E5%B0%8F%E6%B8%94%E7%9A%84%E7%BE%8E%E4%B8%BD%E4%B8%8E%E5%93%80%E6%84%81/013b7876-a994-4bc9-972b-bac8b2a25cd7.png' Dec 22 14:39:50 volumio volumio[2738]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%98%E8%8B%A5%E8%8B%B1/%E5%9C%A8%E4%B8%80%E8%B5%B7/1f96e262-1879-4bc7-a41b-b21d40b61f5d.jpg' Dec 22 14:39:51 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 22 14:39:54 volumio volumio[2738]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%A7%9C%E8%82%B2%E6%81%92/%E5%A4%9A%E5%B9%B4%E4%BB%A5%E5%90%8E%E5%86%8D%E5%9B%9E%E9%A6%96/afda4956-039f-4296-ba47-0a8e1dfe9f60.jpg' Dec 22 14:39:55 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand rescan Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:55 volumio volumio[2738]: info: Dec 22 14:39:55 volumio volumio[2738]: ---------------------------- MPD announces state update: update Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand rescan took 6 milliseconds Dec 22 14:39:55 volumio volumio[2738]: info: ControllerMpd::getState Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:55 volumio volumio[2738]: info: Dec 22 14:39:55 volumio volumio[2738]: ---------------------------- MPD announces state update: update Dec 22 14:39:55 volumio volumio[2738]: info: ControllerMpd::getState Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:55 volumio volumio[2738]: info: Dec 22 14:39:55 volumio volumio[2738]: ---------------------------- MPD announces state update: update Dec 22 14:39:55 volumio volumio[2738]: info: ControllerMpd::getState Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:55 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 14:39:55 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:39:55 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 14:39:55 volumio volumio[2738]: info: Dec 22 14:39:55 volumio volumio[2738]: ---------------------------- MPD announces system playlist update Dec 22 14:39:55 volumio volumio[2738]: info: Ignoring MPD Status Update Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand status took 123 milliseconds Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand status took 120 milliseconds Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand status took 119 milliseconds Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand status took 104 milliseconds Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand status took 104 milliseconds Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand status took 104 milliseconds Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:55 volumio volumio[2738]: info: Command Router : Notfying DB Updatetrue Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:55 volumio volumio[2738]: info: Command Router : Notfying DB Updatetrue Dec 22 14:39:55 volumio volumio[2738]: info: CoreCommandRouter::Close All Modals sent Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:55 volumio volumio[2738]: info: Command Router : Notfying DB Updatetrue Dec 22 14:39:55 volumio volumio[2738]: info: CoreCommandRouter::Close All Modals sent Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:39:55 volumio volumio[2738]: info: Dec 22 14:39:55 volumio volumio[2738]: ---------------------------- MPD announces system playlist update Dec 22 14:39:55 volumio volumio[2738]: info: Ignoring MPD Status Update Dec 22 14:39:55 volumio volumio[2738]: info: Dec 22 14:39:55 volumio volumio[2738]: ---------------------------- MPD announces system playlist update Dec 22 14:39:55 volumio volumio[2738]: info: Ignoring MPD Status Update Dec 22 14:39:55 volumio volumio[2738]: info: MPD Database updated - AlbumList cache refreshed Dec 22 14:39:55 volumio volumio[2738]: info: Dec 22 14:39:55 volumio volumio[2738]: ---------------------------- MPD announces state update: database Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:55 volumio volumio[2738]: info: Dec 22 14:39:55 volumio volumio[2738]: ---------------------------- MPD announces state update: update Dec 22 14:39:55 volumio volumio[2738]: error: updateQueue error: null Dec 22 14:39:55 volumio volumio[2738]: error: updateQueue error: null Dec 22 14:39:55 volumio volumio[2738]: error: updateQueue error: null Dec 22 14:39:55 volumio volumio[2738]: info: ControllerMpd::getState Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:55 volumio volumio[2738]: info: ControllerMpd::getState Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:55 volumio volumio[2738]: info: ------------------------------ 595ms Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand playlistinfo took 138 milliseconds Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand playlistinfo took 92 milliseconds Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand playlistinfo took 60 milliseconds Dec 22 14:39:55 volumio volumio[2738]: info: ------------------------------ 34ms Dec 22 14:39:55 volumio volumio[2738]: info: ------------------------------ 32ms Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:39:55 volumio volumio[2738]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 22 14:39:55 volumio volumio[2738]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 22 14:39:55 volumio volumio[2738]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 22 14:39:55 volumio volumio[2738]: info: ------------------------------ 727ms Dec 22 14:39:55 volumio volumio[2738]: info: ------------------------------ 721ms Dec 22 14:39:55 volumio volumio[2738]: info: ------------------------------ 706ms Dec 22 14:39:55 volumio volumio[2738]: info: MPD Database updated - AlbumList cache refreshed Dec 22 14:39:55 volumio volumio[2738]: info: Dec 22 14:39:55 volumio volumio[2738]: ---------------------------- MPD announces state update: database Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:55 volumio volumio[2738]: info: Dec 22 14:39:55 volumio volumio[2738]: ---------------------------- MPD announces state update: update Dec 22 14:39:55 volumio volumio[2738]: info: ControllerMpd::getState Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:55 volumio volumio[2738]: info: ControllerMpd::getState Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:55 volumio volumio[2738]: info: MPD Database updated - AlbumList cache refreshed Dec 22 14:39:55 volumio volumio[2738]: info: Dec 22 14:39:55 volumio volumio[2738]: ---------------------------- MPD announces state update: database Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:55 volumio volumio[2738]: info: Dec 22 14:39:55 volumio volumio[2738]: ---------------------------- MPD announces state update: update Dec 22 14:39:55 volumio volumio[2738]: info: ControllerMpd::getState Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:55 volumio volumio[2738]: info: ControllerMpd::getState Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand status took 76 milliseconds Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand status took 73 milliseconds Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand status took 71 milliseconds Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand status took 59 milliseconds Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand status took 58 milliseconds Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand status took 56 milliseconds Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand status took 52 milliseconds Dec 22 14:39:55 volumio volumio[2738]: info: sendMpdCommand status took 50 milliseconds Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:55 volumio volumio[2738]: info: Command Router : Notfying DB Updatefalse Dec 22 14:39:55 volumio volumio[2738]: info: CoreCommandRouter::Close All Modals sent Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:55 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:56 volumio volumio[2738]: info: Command Router : Notfying DB Updatefalse Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::Close All Modals sent Dec 22 14:39:56 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:56 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:56 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:56 volumio volumio[2738]: info: Command Router : Notfying DB Updatefalse Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::Close All Modals sent Dec 22 14:39:56 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:56 volumio volumio[2738]: verbose: ControllerMpd::parseState Dec 22 14:39:56 volumio volumio[2738]: info: ControllerMpd::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::servicePushState Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: 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} Dec 22 14:39:56 volumio volumio[2738]: verbose: CURRENT POSITION 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::syncState stateService stop Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::syncState currentStatus stop Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: No code Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: ControllerMpd::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::servicePushState Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: 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} Dec 22 14:39:56 volumio volumio[2738]: verbose: CURRENT POSITION 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::syncState stateService stop Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::syncState currentStatus stop Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: No code Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: ControllerMpd::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::servicePushState Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: 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} Dec 22 14:39:56 volumio volumio[2738]: verbose: CURRENT POSITION 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::syncState stateService stop Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::syncState currentStatus stop Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: No code Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: ControllerMpd::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::servicePushState Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: 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} Dec 22 14:39:56 volumio volumio[2738]: verbose: CURRENT POSITION 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::syncState stateService stop Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::syncState currentStatus stop Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: No code Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: ControllerMpd::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::servicePushState Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: 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} Dec 22 14:39:56 volumio volumio[2738]: verbose: CURRENT POSITION 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::syncState stateService stop Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::syncState currentStatus stop Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: No code Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: ControllerMpd::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::servicePushState Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: 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} Dec 22 14:39:56 volumio volumio[2738]: verbose: CURRENT POSITION 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::syncState stateService stop Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::syncState currentStatus stop Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: No code Dec 22 14:39:56 volumio volumio[2738]: info: CoreStateMachine::pushState Dec 22 14:39:56 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::volumioPushState Dec 22 14:39:56 volumio volumio[2738]: info: ------------------------------ 542ms Dec 22 14:39:56 volumio volumio[2738]: info: ------------------------------ 543ms Dec 22 14:39:56 volumio volumio[2738]: info: ------------------------------ 527ms Dec 22 14:39:56 volumio volumio[2738]: info: ------------------------------ 528ms Dec 22 14:39:56 volumio volumio[2738]: info: ------------------------------ 523ms Dec 22 14:39:56 volumio volumio[2738]: info: ------------------------------ 523ms Dec 22 14:39:56 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 22 14:39:58 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 14:39:58 volumio volumio[2738]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 14:39:58 volumio volumio[2738]: info: CoreCommandRouter::volumioGetState Dec 22 14:39:58 volumio volumio[2738]: info: CorePlayQueue::getTrack 0 Dec 22 14:39:59 volumio volumio[2738]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 22 14:40:01 volumio volumio[2738]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 14:40:01 volumio volumio[2738]: TypeError: Cannot read property 'slice' of null Dec 22 14:40:01 volumio volumio[2738]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Dec 22 14:40:01 volumio volumio[2738]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Dec 22 14:40:01 volumio volumio[2738]: at Object.onceWrapper (events.js:422:26) Dec 22 14:40:01 volumio volumio[2738]: at ClientRequest.emit (events.js:315:20) Dec 22 14:40:01 volumio volumio[2738]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Dec 22 14:40:01 volumio volumio[2738]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Dec 22 14:40:01 volumio volumio[2738]: at TLSSocket.socketOnData (_http_client.js:509:22) Dec 22 14:40:01 volumio volumio[2738]: at TLSSocket.emit (events.js:315:20) Dec 22 14:40:01 volumio volumio[2738]: at addChunk (internal/streams/readable.js:309:12) Dec 22 14:40:01 volumio volumio[2738]: at readableAddChunk (internal/streams/readable.js:284:9) Dec 22 14:40:01 volumio volumio[2738]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Dec 22 14:40:01 volumio volumio[2738]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Dec 22 14:40:01 volumio volumio[2738]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 14:40:02 volumio sudo[3113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-22 14:39 Dec 22 14:40:02 volumio sudo[3113]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"