-- Logs begin at Wed 2025-04-16 14:30:29 MSK, end at Wed 2025-04-16 14:32:31 MSK. -- Apr 16 14:31:07 volumio systemd-timedated[1161]: Changed local time to Wed Apr 16 14:31:07 2025 Apr 16 14:31:07 volumio sudo[1153]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:07 volumio volumio-time-update[700]: volumio-time-update-util: System time updated successfully. Apr 16 14:31:07 volumio systemd[1]: Started Volumio Time Update Utility. Apr 16 14:31:07 volumio ntpd[1038]: Soliciting pool server 46.160.198.122 Apr 16 14:31:07 volumio ntpd[1038]: Soliciting pool server 90.188.236.148 Apr 16 14:31:07 volumio volumio[1049]: info: ------------------------------------------- Apr 16 14:31:07 volumio volumio[1049]: info: ----- Volumio3 ---- Apr 16 14:31:07 volumio volumio[1049]: info: ------------------------------------------- Apr 16 14:31:07 volumio volumio[1049]: info: ----- System startup ---- Apr 16 14:31:07 volumio volumio[1049]: info: ------------------------------------------- Apr 16 14:31:08 volumio volumio[1049]: info: MYVOLUMIO Environment detected Apr 16 14:31:08 volumio volumio[1049]: info: Plugin folders cleanup Apr 16 14:31:08 volumio volumio[1049]: info: Scanning into folder /volumio/app/plugins/ Apr 16 14:31:08 volumio volumio[1049]: info: Scanning category audio_interface Apr 16 14:31:08 volumio volumio[1049]: info: Scanning category miscellanea Apr 16 14:31:08 volumio volumio[1049]: info: Scanning category music_service Apr 16 14:31:08 volumio volumio[1049]: info: Scanning category plugins.json Apr 16 14:31:08 volumio volumio[1049]: info: Scanning category system_controller Apr 16 14:31:08 volumio volumio[1049]: info: Scanning category user_interface Apr 16 14:31:08 volumio volumio[1049]: info: Scanning into folder /data/plugins/ Apr 16 14:31:08 volumio volumio[1049]: info: Plugin folders cleanup completed Apr 16 14:31:08 volumio volumio[1049]: info: ------------------------------------------- Apr 16 14:31:08 volumio volumio[1049]: info: ----- Core plugins startup ---- Apr 16 14:31:08 volumio volumio[1049]: info: ------------------------------------------- Apr 16 14:31:08 volumio volumio[1049]: info: Loading plugins from folder /volumio/app/plugins/ Apr 16 14:31:08 volumio volumio[1049]: info: Adding plugin upnp to MyMusic Plugins Apr 16 14:31:08 volumio volumio[1049]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 16 14:31:08 volumio volumio[1049]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 16 14:31:08 volumio volumio[1049]: info: Loading plugins from folder /data/plugins/ Apr 16 14:31:08 volumio volumio[1049]: info: Loading plugin "system"... Apr 16 14:31:08 volumio volumio[1049]: info: Loading plugin "appearance"... Apr 16 14:31:08 volumio ntpd[1038]: Soliciting pool server 46.188.16.150 Apr 16 14:31:08 volumio ntpd[1038]: Soliciting pool server 95.79.30.113 Apr 16 14:31:08 volumio nmbd[812]: [2025/04/16 14:31:08.780512, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 16 14:31:08 volumio systemd[1]: Started Samba NMB Daemon. Apr 16 14:31:08 volumio nmbd[812]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 16 14:31:08 volumio systemd[1]: Starting Samba Winbind Daemon... Apr 16 14:31:08 volumio nmbd[812]: [2025/04/16 14:31:08.954558, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 16 14:31:08 volumio nmbd[812]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.111.131 for name WORKGROUP<1d>. Apr 16 14:31:08 volumio nmbd[812]: This response was from IP 192.168.111.2, reporting an IP address of 192.168.111.2. Apr 16 14:31:09 volumio winbindd[1175]: [2025/04/16 14:31:09.274198, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 16 14:31:09 volumio winbindd[1175]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 16 14:31:09 volumio winbindd[1175]: [2025/04/16 14:31:09.326687, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 16 14:31:09 volumio winbindd[1175]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 16 14:31:09 volumio systemd[1]: Started Samba Winbind Daemon. Apr 16 14:31:09 volumio systemd[1]: Starting Samba SMB Daemon... Apr 16 14:31:09 volumio ntpd[1038]: Soliciting pool server 93.95.98.77 Apr 16 14:31:09 volumio ntpd[1038]: Soliciting pool server 92.255.126.12 Apr 16 14:31:09 volumio ntpd[1038]: Soliciting pool server 82.146.53.58 Apr 16 14:31:09 volumio volumio[1049]: info: Loading plugin "network"... Apr 16 14:31:09 volumio volumio[1049]: info: Refreshing Cached IP Addresses Apr 16 14:31:09 volumio sudo[1185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 16 14:31:09 volumio sudo[1185]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:09 volumio sudo[1185]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:09 volumio sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 14:31:09 volumio sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:09 volumio sudo[1187]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:09 volumio volumio[1049]: info: Loading plugin "services"... Apr 16 14:31:09 volumio volumio[1049]: info: Loading plugin "alsa_controller"... Apr 16 14:31:09 volumio sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 16 14:31:09 volumio sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:10 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 16 14:31:10 volumio volumio[1049]: info: Loading plugin "wizard"... Apr 16 14:31:10 volumio volumio[1049]: info: Loading plugin "networkfs"... Apr 16 14:31:10 volumio volumio[1049]: info: Starting Udev Watcher for removable devices Apr 16 14:31:10 volumio volumio[1049]: info: Ignoring mount for partition: boot Apr 16 14:31:10 volumio volumio[1049]: info: Ignoring mount for partition: volumio Apr 16 14:31:10 volumio volumio[1049]: info: Ignoring mount for partition: volumio_data Apr 16 14:31:10 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 16 14:31:10 volumio volumio[1049]: info: Loading plugin "volumio_command_line_client"... Apr 16 14:31:10 volumio smbd[1180]: [2025/04/16 14:31:10.240841, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 16 14:31:10 volumio systemd[1]: Started Samba SMB Daemon. Apr 16 14:31:10 volumio systemd[1]: Reached target Multi-User System. Apr 16 14:31:10 volumio systemd[1]: Reached target Graphical Interface. Apr 16 14:31:10 volumio smbd[1180]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 16 14:31:10 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 16 14:31:10 volumio volumio[1049]: info: Loading plugin "upnp"... Apr 16 14:31:10 volumio volumio[1049]: info: [1744803070262] Starting Upmpd Daemon Apr 16 14:31:10 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 16 14:31:10 volumio volumio[1049]: info: Loading plugin "my_music"... Apr 16 14:31:10 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 16 14:31:10 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 16 14:31:10 volumio volumio[1049]: info: Loading plugin "mpd"... Apr 16 14:31:10 volumio systemd[1]: Startup finished in 10.831s (kernel) + 27.265s (userspace) = 38.096s. Apr 16 14:31:10 volumio ntpd[1038]: Soliciting pool server 51.250.110.169 Apr 16 14:31:10 volumio ntpd[1038]: Soliciting pool server 151.0.2.53 Apr 16 14:31:10 volumio ntpd[1038]: Soliciting pool server 92.241.18.100 Apr 16 14:31:10 volumio volumio[1049]: info: Loading plugin "upnp_browser"... Apr 16 14:31:10 volumio volumio[1049]: info: Loading plugin "alarm-clock"... Apr 16 14:31:10 volumio volumio[1049]: info: Loading plugin "airplay_emulation"... Apr 16 14:31:10 volumio volumio[1049]: info: Starting Shairport Sync Apr 16 14:31:10 volumio volumio[1049]: info: Loading plugin "last_100"... Apr 16 14:31:10 volumio volumio[1049]: info: Loading plugin "webradio"... Apr 16 14:31:10 volumio volumio[1049]: info: Loading plugin "i2s_dacs"... Apr 16 14:31:10 volumio volumio[1049]: info: I2S DAC not set, start Auto-detection Apr 16 14:31:10 volumio volumio[1049]: info: Loading plugin "volumiodiscovery"... Apr 16 14:31:10 volumio volumio[1049]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 16 14:31:10 volumio volumio[1049]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 16 14:31:10 volumio volumio[1049]: *** WARNING *** For more information see Apr 16 14:31:10 volumio volumio[1049]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 16 14:31:10 volumio volumio[1049]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 16 14:31:10 volumio volumio[1049]: *** WARNING *** For more information see Apr 16 14:31:10 volumio node[1049]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 16 14:31:10 volumio node[1049]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 16 14:31:10 volumio node[1049]: *** WARNING *** For more information see Apr 16 14:31:10 volumio node[1049]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 16 14:31:10 volumio node[1049]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 16 14:31:10 volumio node[1049]: *** WARNING *** For more information see Apr 16 14:31:10 volumio volumio[1049]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 16 14:31:10 volumio volumio[1049]: info: Discovery: Started advertising with name: Volumio Apr 16 14:31:10 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 16 14:31:10 volumio volumio[1049]: info: Loading plugin "outputs"... Apr 16 14:31:10 volumio volumio[1049]: info: Loading plugin "albumart"... Apr 16 14:31:11 volumio volumio[1049]: info: Plugin example_plugin is not enabled Apr 16 14:31:11 volumio volumio[1049]: info: Loading plugin "inputs"... Apr 16 14:31:11 volumio volumio[1049]: info: Loading plugin "updater_comm"... Apr 16 14:31:11 volumio volumio[1049]: Forking 1 albumart workers Apr 16 14:31:11 volumio volumio[1049]: info: Plugin mpdemulation is not enabled Apr 16 14:31:11 volumio volumio[1049]: info: Loading plugin "rest_api"... Apr 16 14:31:11 volumio volumio[1049]: info: Loading plugin "websocket"... Apr 16 14:31:11 volumio volumio[1049]: info: Starting Socket.io Server version 2.3.0 Apr 16 14:31:11 volumio volumio[1049]: info: Loading i18n strings for locale ru Apr 16 14:31:11 volumio volumio[1049]: Updating browse sources language Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 14:31:11 volumio volumio[1049]: Starting albumart workers Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::initPlayerControls Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: Express server listening on port 3000 Apr 16 14:31:11 volumio volumio[1049]: [Metrics] WebUI: 4s 177.48ms Apr 16 14:31:11 volumio volumio[1049]: info: Setting Device type: x86 Apr 16 14:31:11 volumio volumio[1049]: info: CoreStateMachine::resetVolumioState Apr 16 14:31:11 volumio volumio[1049]: info: CoreStateMachine::getcurrentVolume Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::volumioRetrievevolume Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 16 14:31:11 volumio volumio[1049]: info: Completed loading Core Plugins Apr 16 14:31:11 volumio volumio[1049]: info: Preparing to generate the ALSA configuration file Apr 16 14:31:11 volumio volumio[1049]: info: Volumio Network Manager: Network status updated: 2 Apr 16 14:31:11 volumio volumio[1049]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 16 14:31:11 volumio ntpd[1038]: Soliciting pool server 92.255.126.1 Apr 16 14:31:11 volumio ntpd[1038]: Soliciting pool server 92.255.126.11 Apr 16 14:31:11 volumio volumio[1049]: info: VolumeController:: Volume=60 Mute =false Apr 16 14:31:11 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:11 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:11 volumio volumio[1049]: info: CoreStateMachine::updateTrackBlock Apr 16 14:31:11 volumio volumio[1049]: info: CorePlayQueue::getTrackBlock Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::volumioRetrievevolume Apr 16 14:31:11 volumio volumio[1049]: info: Reloading queue from file Apr 16 14:31:11 volumio volumio[1049]: info: Asound.conf file unchanged, so no further update is needed Apr 16 14:31:11 volumio volumio[1049]: info: Output device has changed, restarting MPD Apr 16 14:31:11 volumio volumio[1049]: info: Output device has changed, restarting Shairport Sync Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 14:31:11 volumio sudo[1250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 16 14:31:11 volumio sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:11 volumio sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 16 14:31:11 volumio sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:11 volumio sudo[1250]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:11 volumio systemd[1]: Stopping Music Player Daemon... Apr 16 14:31:11 volumio volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 16 14:31:11 volumio volumio[1049]: info: ___________ START PLUGINS ___________ Apr 16 14:31:11 volumio volumio[1049]: info: ControllerMpd::onStart: Initializing MPD Apr 16 14:31:11 volumio volumio[1049]: info: Creating MPD Configuration file Apr 16 14:31:11 volumio sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 14:31:11 volumio sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 16 14:31:11 volumio volumio[1049]: info: [1744803071655] CoreMusicLibrary::Adding element Медиа-серверы Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 14:31:11 volumio sudo[1259]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:11 volumio sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 16 14:31:11 volumio sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 16 14:31:11 volumio volumio[1049]: info: [1744803071723] CoreMusicLibrary::Adding element Last_100 Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 16 14:31:11 volumio volumio[1049]: info: [1744803071724] CoreMusicLibrary::Adding element Webradio Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 16 14:31:11 volumio volumio[1049]: info: Initializing BBC Radios Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: Volumio Calling Home Apr 16 14:31:11 volumio systemd[1]: mpd.service: Succeeded. Apr 16 14:31:11 volumio systemd[1]: Stopped Music Player Daemon. Apr 16 14:31:11 volumio systemd[1]: Starting Music Player Daemon... Apr 16 14:31:11 volumio volumio[1049]: info: CoreStateMachine::setRepeat false single undefined Apr 16 14:31:11 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:11 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:11 volumio volumio[1049]: info: CoreStateMachine::setRandom true Apr 16 14:31:11 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:11 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:11 volumio sudo[1282]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 16 14:31:11 volumio sudo[1282]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:11 volumio volumio[1049]: info: MPD Permissions set Apr 16 14:31:11 volumio volumio[1049]: info: MPD Permissions set Apr 16 14:31:11 volumio sudo[1282]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:11 volumio volumio[1049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 16 14:31:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:12 volumio volumio[1049]: info: VolumeController:: Volume=60 Mute =false Apr 16 14:31:12 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:12 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:12 volumio volumio[1049]: info: Volumio called home Apr 16 14:31:12 volumio volumio[1049]: info: Starting Shairport Sync Apr 16 14:31:12 volumio volumio[1049]: info: Starting Shairport Sync Apr 16 14:31:12 volumio sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 14:31:12 volumio sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:12 volumio volumio[1049]: info: Starting Shairport Sync Apr 16 14:31:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 16 14:31:12 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 16 14:31:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 16 14:31:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 16 14:31:12 volumio sudo[1300]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:12 volumio volumio[1049]: info: Discovery: adding 1c434002-bb81-4121-8ddb-22a9c4e45f9e Apr 16 14:31:12 volumio volumio[1049]: info: Discovery: Found device Volumio Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:31:12 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:12 volumio sudo[1303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 14:31:12 volumio sudo[1303]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:12 volumio sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 14:31:12 volumio sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 16 14:31:12 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 16 14:31:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 16 14:31:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 16 14:31:12 volumio sudo[1306]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:12 volumio sudo[1303]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:31:12 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 14:31:12 volumio volumio[1049]: info: Discovery: Getting this device information Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:31:12 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 14:31:12 volumio volumio[1049]: info: Shairport-Sync Started Apr 16 14:31:12 volumio volumio[1049]: Error adding Membership: Error: addMembership EINVAL Apr 16 14:31:12 volumio volumio[1049]: info: Shairport-Sync Started Apr 16 14:31:12 volumio volumio[1049]: verbose: New Socket.io Connection to 192.168.111.131:3000 from 192.168.111.157 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:31:12 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:12 volumio mpd[1286]: Apr 16 14:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 16 14:31:12 volumio systemd[1]: Started Music Player Daemon. Apr 16 14:31:12 volumio sudo[1261]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:12 volumio sudo[1252]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 16 14:31:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 16 14:31:12 volumio volumio[1049]: info: Completed starting Core Plugins Apr 16 14:31:12 volumio volumio[1049]: info: ------------------------------------------- Apr 16 14:31:12 volumio volumio[1049]: info: ----- MyVolumio plugins startup ---- Apr 16 14:31:12 volumio volumio[1049]: info: ------------------------------------------- Apr 16 14:31:12 volumio volumio[1049]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 16 14:31:12 volumio volumio[1049]: info: MPD running with PID1286 Apr 16 14:31:12 volumio volumio[1049]: ,establishing connection Apr 16 14:31:12 volumio ntpd[1038]: Soliciting pool server 195.90.182.235 Apr 16 14:31:12 volumio ntpd[1038]: Soliciting pool server 2a00:18c0:1:1::8 Apr 16 14:31:12 volumio volumio[1049]: error: MPD error: The expression evaluated to a falsy value: Apr 16 14:31:12 volumio volumio[1049]: assert.ok(self.idling) Apr 16 14:31:12 volumio volumio[1049]: error: The expression evaluated to a falsy value: Apr 16 14:31:12 volumio volumio[1049]: assert.ok(self.idling) Apr 16 14:31:12 volumio volumio[1049]: error: MPD error: The expression evaluated to a falsy value: Apr 16 14:31:12 volumio volumio[1049]: assert.ok(self.idling) Apr 16 14:31:12 volumio volumio[1049]: error: The expression evaluated to a falsy value: Apr 16 14:31:12 volumio volumio[1049]: assert.ok(self.idling) Apr 16 14:31:12 volumio volumio[1049]: error: updateQueue error: null Apr 16 14:31:13 volumio sudo[1199]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:13 volumio volumio-remote-updater[696]: [2025-04-16 14:31:13] [connect] Successful connection Apr 16 14:31:13 volumio volumio-remote-updater[696]: [2025-04-16 14:31:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1744803073 101 Apr 16 14:31:13 volumio volumio[1049]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Apr 16 14:31:15 volumio sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 16 14:31:15 volumio sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:15 volumio sudo[1320]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:15 volumio sudo[1322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 14:31:15 volumio sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:15 volumio sudo[1322]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:16 volumio volumio[1049]: verbose: New Socket.io Connection to 192.168.111.131 from 192.168.111.157 UA: Mozilla/5.0 (Linux; Android 14; RMX3393 Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/134.0.6998.135 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Apr 16 14:31:16 volumio volumio[1049]: info: CoreCommandRouter::volumioGetVisibleSources Apr 16 14:31:16 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 14:31:16 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 16 14:31:16 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:31:16 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:16 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 16 14:31:16 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 16 14:31:16 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 16 14:31:16 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 16 14:31:16 volumio sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 16 14:31:16 volumio sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:16 volumio sudo[1326]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:16 volumio sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 14:31:16 volumio sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:16 volumio sudo[1329]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:16 volumio volumio[1049]: verbose: New Socket.io Connection to 192.168.111.131 from 192.168.111.157 UA: Mozilla/5.0 (Linux; Android 14; RMX3393 Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/134.0.6998.135 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Apr 16 14:31:16 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:31:16 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:16 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 14:31:16 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 14:31:16 volumio volumio[1049]: info: Discovery: Getting this device information Apr 16 14:31:16 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:31:16 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:16 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 14:31:17 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 16 14:31:17 volumio volumio[1049]: info: Listing playlists Apr 16 14:31:17 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 16 14:31:17 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 16 14:31:17 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 16 14:31:17 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 16 14:31:17 volumio volumio[1049]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 16 14:31:19 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 16 14:31:20 volumio sudo[1349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 16 14:31:20 volumio sudo[1349]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:20 volumio sudo[1349]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:20 volumio sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 14:31:20 volumio sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:20 volumio sudo[1351]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:20 volumio sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 16 14:31:20 volumio sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:20 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 16 14:31:20 volumio sudo[1355]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:20 volumio volumio[1049]: info: Upmpdcli Daemon Started Apr 16 14:31:20 volumio volumio[1357]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 16 14:31:21 volumio sh[611]: timed out Apr 16 14:31:21 volumio sh[611]: dhcpcd exited Apr 16 14:31:21 volumio dhcpcd[669]: timed out Apr 16 14:31:21 volumio dhcpcd[669]: dhcpcd exited Apr 16 14:31:21 volumio sh[611]: ifup: failed to bring up eth0 Apr 16 14:31:21 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 16 14:31:21 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 16 14:31:21 volumio volumio[1049]: info: Adding plugin bluetooth to MyMusic Plugins Apr 16 14:31:21 volumio volumio[1049]: info: Adding plugin multiroom to MyMusic Plugins Apr 16 14:31:21 volumio volumio[1049]: info: Adding plugin metavolumio to MyMusic Plugins Apr 16 14:31:21 volumio volumio[1049]: info: Adding plugin cd_controller to MyMusic Plugins Apr 16 14:31:21 volumio volumio[1049]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 16 14:31:21 volumio volumio[1049]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 16 14:31:21 volumio volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 16 14:31:21 volumio volumio[1357]: .....................................................++++ Apr 16 14:31:22 volumio volumio[1049]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 16 14:31:22 volumio volumio[1049]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 16 14:31:22 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 14:31:22 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 14:31:22 volumio volumio[1049]: info: Starting MyVolumio Remote Streaming Endpoints Apr 16 14:31:22 volumio volumio[1049]: info: MyVolumio not started Apr 16 14:31:22 volumio volumio[1049]: info: Initializing device activation check Apr 16 14:31:22 volumio volumio[1049]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 16 14:31:22 volumio volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 16 14:31:22 volumio volumio[1357]: ........................................................++++ Apr 16 14:31:22 volumio volumio[1357]: e is 65537 (0x010001) Apr 16 14:31:22 volumio volumio[1357]: writing RSA key Apr 16 14:31:22 volumio volumio[1049]: info: Starting Streaming Service Transparent Proxy Apr 16 14:31:22 volumio volumio[1049]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 16 14:31:22 volumio volumio[1049]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 16 14:31:22 volumio volumio[1049]: info: Streaming services startup Apr 16 14:31:22 volumio volumio[1049]: info: Starting Streaming Daemon Apr 16 14:31:22 volumio sudo[1393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 16 14:31:22 volumio sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:22 volumio volumio[1049]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 16 14:31:22 volumio sudo[1393]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:22 volumio volumio[1049]: error: Cannot start Volumio Streaming Daemon Apr 16 14:31:22 volumio volumio[1049]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 16 14:31:22 volumio volumio[1049]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 16 14:31:23 volumio volumio[1049]: STREAMING PROXY: Starting server on port 3245 Apr 16 14:31:23 volumio volumio[1049]: Node JS runtime: 14 Apr 16 14:31:27 volumio volumio[1049]: info: CoreCommandRouter::volumioPlay Apr 16 14:31:27 volumio volumio[1049]: info: CoreStateMachine::play index undefined Apr 16 14:31:27 volumio volumio[1049]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 14:31:27 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:27 volumio volumio[1049]: info: CoreStateMachine::startPlaybackTimer Apr 16 14:31:27 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:27 volumio volumio[1049]: info: [1744803087431] ControllerUPNPBrowser::clearAddPlayTrack Apr 16 14:31:27 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 14:31:27 volumio volumio[1049]: info: sendMpdCommand stop took 1 milliseconds Apr 16 14:31:27 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 14:31:27 volumio volumio[1049]: info: sendMpdCommand clear took 0 milliseconds Apr 16 14:31:27 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.111.154:8200/MediaItems/2167.flac" Apr 16 14:31:27 volumio volumio[1049]: info: Apr 16 14:31:27 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:31:27 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:31:27 volumio volumio[1049]: info: Apr 16 14:31:27 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:31:27 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:31:27 volumio volumio[1049]: info: Apr 16 14:31:27 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:31:27 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:31:30 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.111.154:8200/MediaItems/2167.flac" Apr 16 14:31:30 volumio volumio[1049]: error: updateQueue error: null Apr 16 14:31:30 volumio volumio[1049]: error: updateQueue error: null Apr 16 14:31:30 volumio volumio[1049]: error: updateQueue error: null Apr 16 14:31:30 volumio volumio[1049]: info: ------------------------------ 3071ms Apr 16 14:31:30 volumio volumio[1049]: info: ------------------------------ 3070ms Apr 16 14:31:30 volumio volumio[1049]: info: ------------------------------ 3070ms Apr 16 14:31:30 volumio volumio[1049]: Upnp client error: Error: This socket has been ended by the other party Apr 16 14:31:30 volumio volumio[1049]: info: Apr 16 14:31:30 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:31:30 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:31:30 volumio volumio[1049]: info: sendMpdCommand add "http://192.168.111.154:8200/MediaItems/2167.flac" took 2 milliseconds Apr 16 14:31:30 volumio volumio[1049]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 16 14:31:30 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand play Apr 16 14:31:30 volumio volumio[1049]: info: Apr 16 14:31:30 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:31:30 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:31:30 volumio volumio[1049]: info: Apr 16 14:31:30 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:31:30 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:31:30 volumio volumio[1049]: info: ------------------------------ 5ms Apr 16 14:31:30 volumio volumio[1049]: info: sendMpdCommand play took 5 milliseconds Apr 16 14:31:30 volumio volumio[1049]: info: ------------------------------ 5ms Apr 16 14:31:30 volumio volumio[1049]: info: ------------------------------ 5ms Apr 16 14:31:33 volumio volumio[1049]: info: Apr 16 14:31:33 volumio volumio[1049]: ---------------------------- MPD announces state update: player Apr 16 14:31:33 volumio volumio[1049]: info: ControllerMpd::getState Apr 16 14:31:33 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand status Apr 16 14:31:33 volumio volumio[1049]: info: Apr 16 14:31:33 volumio volumio[1049]: ---------------------------- MPD announces state update: player Apr 16 14:31:33 volumio volumio[1049]: info: ControllerMpd::getState Apr 16 14:31:33 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand status Apr 16 14:31:33 volumio volumio[1049]: info: Apr 16 14:31:33 volumio volumio[1049]: ---------------------------- MPD announces state update: player Apr 16 14:31:33 volumio volumio[1049]: info: ControllerMpd::getState Apr 16 14:31:33 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand status Apr 16 14:31:33 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 16 14:31:33 volumio volumio[1049]: info: sendMpdCommand status took 5 milliseconds Apr 16 14:31:33 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 16 14:31:33 volumio volumio[1049]: info: sendMpdCommand status took 5 milliseconds Apr 16 14:31:33 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 16 14:31:33 volumio volumio[1049]: info: sendMpdCommand status took 4 milliseconds Apr 16 14:31:33 volumio volumio[1049]: verbose: ControllerMpd::parseState Apr 16 14:31:33 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 14:31:33 volumio volumio[1049]: verbose: ControllerMpd::parseState Apr 16 14:31:33 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 14:31:33 volumio volumio[1049]: verbose: ControllerMpd::parseState Apr 16 14:31:33 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 14:31:33 volumio volumio[1049]: info: sendMpdCommand clearerror took 6 milliseconds Apr 16 14:31:33 volumio volumio[1049]: info: sendMpdCommand clearerror took 5 milliseconds Apr 16 14:31:33 volumio volumio[1049]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 16 14:31:33 volumio volumio[1049]: verbose: ControllerMpd::parseTrackInfo Apr 16 14:31:33 volumio volumio[1049]: verbose: ControllerMpd::parseTrackInfo Apr 16 14:31:33 volumio volumio[1049]: verbose: ControllerMpd::parseTrackInfo Apr 16 14:31:33 volumio volumio[1049]: info: ControllerMpd::pushState Apr 16 14:31:33 volumio volumio[1049]: info: CoreCommandRouter::servicePushState Apr 16 14:31:33 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:33 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 14:31:33 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:33 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:33 volumio volumio[1049]: 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":"2167.flac","artist":null,"album":null,"uri":"http://192.168.111.154:8200/MediaItems/2167.flac","trackType":"flac"} Apr 16 14:31:33 volumio volumio[1049]: verbose: CURRENT POSITION 0 Apr 16 14:31:33 volumio volumio[1049]: info: CoreStateMachine::syncState stateService stop Apr 16 14:31:33 volumio volumio[1049]: info: CoreStateMachine::syncState currentStatus stop Apr 16 14:31:33 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:33 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:33 volumio volumio[1049]: info: No code Apr 16 14:31:33 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:33 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:33 volumio volumio[1049]: info: ControllerMpd::pushState Apr 16 14:31:33 volumio volumio[1049]: info: CoreCommandRouter::servicePushState Apr 16 14:31:33 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:33 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:33 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:33 volumio volumio[1049]: 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":"2167.flac","artist":null,"album":null,"uri":"http://192.168.111.154:8200/MediaItems/2167.flac","trackType":"flac"} Apr 16 14:31:33 volumio volumio[1049]: verbose: CURRENT POSITION 0 Apr 16 14:31:33 volumio volumio[1049]: info: CoreStateMachine::syncState stateService stop Apr 16 14:31:33 volumio volumio[1049]: info: CoreStateMachine::syncState currentStatus stop Apr 16 14:31:33 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:33 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:33 volumio volumio[1049]: info: No code Apr 16 14:31:33 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:33 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:33 volumio volumio[1049]: info: ControllerMpd::pushState Apr 16 14:31:33 volumio volumio[1049]: info: CoreCommandRouter::servicePushState Apr 16 14:31:33 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:33 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:33 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:33 volumio volumio[1049]: 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":"2167.flac","artist":null,"album":null,"uri":"http://192.168.111.154:8200/MediaItems/2167.flac","trackType":"flac"} Apr 16 14:31:33 volumio volumio[1049]: verbose: CURRENT POSITION 0 Apr 16 14:31:33 volumio volumio[1049]: info: CoreStateMachine::syncState stateService stop Apr 16 14:31:33 volumio volumio[1049]: info: CoreStateMachine::syncState currentStatus stop Apr 16 14:31:33 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:33 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:33 volumio volumio[1049]: info: No code Apr 16 14:31:33 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:33 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:33 volumio volumio[1049]: info: ------------------------------ 37ms Apr 16 14:31:33 volumio volumio[1049]: info: ------------------------------ 36ms Apr 16 14:31:33 volumio volumio[1049]: info: ------------------------------ 36ms Apr 16 14:31:35 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 16 14:31:35 volumio volumio[1049]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 16 14:31:35 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 16 14:31:35 volumio volumio[1049]: info: Received Get System Version Apr 16 14:31:35 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 16 14:31:35 volumio volumio[1049]: info: Received Get System Info Apr 16 14:31:35 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 14:31:35 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 14:31:35 volumio volumio[1049]: info: Discovery: Getting this device information Apr 16 14:31:35 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:31:35 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 14:31:35 volumio volumio[1049]: info: CoreCommandRouter::volumioPlay Apr 16 14:31:35 volumio volumio[1049]: info: CoreStateMachine::play index undefined Apr 16 14:31:35 volumio volumio[1049]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 14:31:35 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:35 volumio volumio[1049]: info: CoreStateMachine::startPlaybackTimer Apr 16 14:31:35 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:35 volumio volumio[1049]: info: [1744803095185] ControllerUPNPBrowser::clearAddPlayTrack Apr 16 14:31:35 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 14:31:35 volumio volumio[1049]: info: sendMpdCommand stop took 1 milliseconds Apr 16 14:31:35 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 14:31:35 volumio volumio[1049]: info: sendMpdCommand clear took 0 milliseconds Apr 16 14:31:35 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.111.154:8200/MediaItems/2167.flac" Apr 16 14:31:35 volumio volumio[1049]: info: Apr 16 14:31:35 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:31:35 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:31:35 volumio volumio[1049]: info: Apr 16 14:31:35 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:31:35 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:31:35 volumio volumio[1049]: info: Apr 16 14:31:35 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:31:35 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:31:37 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 16 14:31:37 volumio volumio[1049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 16 14:31:37 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:31:37 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:37 volumio systemd[1]: systemd-timedated.service: Succeeded. Apr 16 14:31:38 volumio volumio[1049]: info: CoreCommandRouter::volumioGetQueue Apr 16 14:31:38 volumio volumio[1049]: info: CoreStateMachine::getQueue Apr 16 14:31:38 volumio volumio[1049]: info: CorePlayQueue::getQueue Apr 16 14:31:38 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.111.154:8200/MediaItems/2167.flac" Apr 16 14:31:38 volumio volumio[1049]: error: updateQueue error: null Apr 16 14:31:38 volumio volumio[1049]: info: Apr 16 14:31:38 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:31:38 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:31:38 volumio volumio[1049]: info: ------------------------------ 3062ms Apr 16 14:31:38 volumio volumio[1049]: info: sendMpdCommand add "http://192.168.111.154:8200/MediaItems/2167.flac" took 1 milliseconds Apr 16 14:31:38 volumio volumio[1049]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 16 14:31:38 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand play Apr 16 14:31:38 volumio volumio[1049]: Upnp client error: Error: This socket has been ended by the other party Apr 16 14:31:38 volumio volumio[1049]: info: Apr 16 14:31:38 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:31:38 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:31:38 volumio volumio[1049]: info: Apr 16 14:31:38 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:31:38 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:31:38 volumio volumio[1049]: info: ------------------------------ 5ms Apr 16 14:31:38 volumio volumio[1049]: info: sendMpdCommand play took 4 milliseconds Apr 16 14:31:38 volumio volumio[1049]: info: ------------------------------ 4ms Apr 16 14:31:38 volumio volumio[1049]: info: ------------------------------ 2ms Apr 16 14:31:41 volumio volumio[1049]: info: Apr 16 14:31:41 volumio volumio[1049]: ---------------------------- MPD announces state update: player Apr 16 14:31:41 volumio volumio[1049]: info: ControllerMpd::getState Apr 16 14:31:41 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand status Apr 16 14:31:41 volumio volumio[1049]: info: Apr 16 14:31:41 volumio volumio[1049]: ---------------------------- MPD announces state update: player Apr 16 14:31:41 volumio volumio[1049]: info: ControllerMpd::getState Apr 16 14:31:41 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand status Apr 16 14:31:41 volumio volumio[1049]: info: Apr 16 14:31:41 volumio volumio[1049]: ---------------------------- MPD announces state update: player Apr 16 14:31:41 volumio volumio[1049]: info: ControllerMpd::getState Apr 16 14:31:41 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand status Apr 16 14:31:41 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 16 14:31:41 volumio volumio[1049]: info: sendMpdCommand status took 4 milliseconds Apr 16 14:31:41 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 16 14:31:41 volumio volumio[1049]: info: sendMpdCommand status took 3 milliseconds Apr 16 14:31:41 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 16 14:31:41 volumio volumio[1049]: info: sendMpdCommand status took 4 milliseconds Apr 16 14:31:41 volumio volumio[1049]: verbose: ControllerMpd::parseState Apr 16 14:31:41 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 14:31:41 volumio volumio[1049]: verbose: ControllerMpd::parseState Apr 16 14:31:41 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 14:31:41 volumio volumio[1049]: verbose: ControllerMpd::parseState Apr 16 14:31:41 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 14:31:41 volumio volumio[1049]: info: sendMpdCommand clearerror took 5 milliseconds Apr 16 14:31:41 volumio volumio[1049]: info: sendMpdCommand clearerror took 4 milliseconds Apr 16 14:31:41 volumio volumio[1049]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 14:31:41 volumio volumio[1049]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 16 14:31:41 volumio volumio[1049]: verbose: ControllerMpd::parseTrackInfo Apr 16 14:31:41 volumio volumio[1049]: verbose: ControllerMpd::parseTrackInfo Apr 16 14:31:41 volumio volumio[1049]: verbose: ControllerMpd::parseTrackInfo Apr 16 14:31:41 volumio volumio[1049]: info: ControllerMpd::pushState Apr 16 14:31:41 volumio volumio[1049]: info: CoreCommandRouter::servicePushState Apr 16 14:31:41 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:41 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 14:31:41 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:41 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:41 volumio volumio[1049]: 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":"2167.flac","artist":null,"album":null,"uri":"http://192.168.111.154:8200/MediaItems/2167.flac","trackType":"flac"} Apr 16 14:31:41 volumio volumio[1049]: verbose: CURRENT POSITION 0 Apr 16 14:31:41 volumio volumio[1049]: info: CoreStateMachine::syncState stateService stop Apr 16 14:31:41 volumio volumio[1049]: info: CoreStateMachine::syncState currentStatus stop Apr 16 14:31:41 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:41 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:41 volumio volumio[1049]: info: No code Apr 16 14:31:41 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:41 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:41 volumio volumio[1049]: info: ControllerMpd::pushState Apr 16 14:31:41 volumio volumio[1049]: info: CoreCommandRouter::servicePushState Apr 16 14:31:41 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:41 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:41 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:41 volumio volumio[1049]: 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":"2167.flac","artist":null,"album":null,"uri":"http://192.168.111.154:8200/MediaItems/2167.flac","trackType":"flac"} Apr 16 14:31:41 volumio volumio[1049]: verbose: CURRENT POSITION 0 Apr 16 14:31:41 volumio volumio[1049]: info: CoreStateMachine::syncState stateService stop Apr 16 14:31:41 volumio volumio[1049]: info: CoreStateMachine::syncState currentStatus stop Apr 16 14:31:41 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:41 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:41 volumio volumio[1049]: info: No code Apr 16 14:31:41 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:41 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:41 volumio volumio[1049]: info: ControllerMpd::pushState Apr 16 14:31:41 volumio volumio[1049]: info: CoreCommandRouter::servicePushState Apr 16 14:31:41 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:41 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:41 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:41 volumio volumio[1049]: 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":"2167.flac","artist":null,"album":null,"uri":"http://192.168.111.154:8200/MediaItems/2167.flac","trackType":"flac"} Apr 16 14:31:41 volumio volumio[1049]: verbose: CURRENT POSITION 0 Apr 16 14:31:41 volumio volumio[1049]: info: CoreStateMachine::syncState stateService stop Apr 16 14:31:41 volumio volumio[1049]: info: CoreStateMachine::syncState currentStatus stop Apr 16 14:31:41 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:41 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:41 volumio volumio[1049]: info: No code Apr 16 14:31:41 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:41 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:41 volumio volumio[1049]: info: ------------------------------ 39ms Apr 16 14:31:41 volumio volumio[1049]: info: ------------------------------ 38ms Apr 16 14:31:41 volumio volumio[1049]: info: ------------------------------ 38ms Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::volumioGetBrowseSources Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 16 14:31:42 volumio volumio-remote-updater[696]: No test mode Apr 16 14:31:42 volumio volumio-remote-updater[696]: No alpha test mode Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 16 14:31:42 volumio volumio[1049]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

  • Improved translations

 

NEW ADDITIONS

  • Update TIDAL Connect SDK
","title":"Update v3.799","updateavailable":true} Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 16 14:31:42 volumio volumio[1049]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 16 14:31:42 volumio volumio[1049]: info: Completed starting MyVolumio Plugin Apr 16 14:31:42 volumio volumio[1049]: [Metrics] CommandRouter: 34s 571.17ms Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::volumiosetStartupVolume Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 14:31:42 volumio volumio[1049]: info: VolumeController:: Setting startup Volume 60 Apr 16 14:31:42 volumio volumio[1049]: info: VolumeController::SetAlsaVolume60 Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::Close All Modals sent Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::Close All Modals sent Apr 16 14:31:42 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 14:31:42 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:31:42 volumio kernel: snd_hda_intel 0000:00:1b.0: IRQ timing workaround is activated for card #0. Suggest a bigger bdl_pos_adj. Apr 16 14:31:43 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 16 14:31:43 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 16 14:31:43 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 16 14:31:45 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 16 14:31:45 volumio volumio[1049]: info: Received Get System Info Apr 16 14:31:45 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 14:31:45 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 14:31:45 volumio volumio[1049]: info: Discovery: Getting this device information Apr 16 14:31:45 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:31:45 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 14:31:46 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 14:31:46 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 14:31:46 volumio volumio[1049]: info: Discovery: Getting this device information Apr 16 14:31:46 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:31:46 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 14:31:49 volumio volumio[1049]: info: BOOT COMPLETED Apr 16 14:31:49 volumio sudo[1476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 16 14:31:49 volumio sudo[1476]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:49 volumio sudo[1476]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:49 volumio sudo[1479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 14:31:49 volumio sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:49 volumio sudo[1479]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:49 volumio volumio[1049]: verbose: New Socket.io Connection to 192.168.111.131 from 192.168.111.157 UA: Mozilla/5.0 (Linux; Android 14; RMX3393 Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/134.0.6998.135 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 16 14:31:50 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:31:50 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 16 14:31:50 volumio volumio[1049]: info: Listing playlists Apr 16 14:31:50 volumio volumio[1049]: info: CoreCommandRouter::volumioGetQueue Apr 16 14:31:50 volumio volumio[1049]: info: CoreStateMachine::getQueue Apr 16 14:31:50 volumio volumio[1049]: info: CorePlayQueue::getQueue Apr 16 14:31:55 volumio sudo[1534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 16 14:31:55 volumio sudo[1534]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:55 volumio sudo[1534]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:55 volumio sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 14:31:55 volumio sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:55 volumio sudo[1538]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:55 volumio volumio[1049]: verbose: New Socket.io Connection to 192.168.111.131 from 192.168.111.157 UA: Mozilla/5.0 (Linux; Android 14; RMX3393 Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/134.0.6998.135 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 16 14:31:55 volumio volumio[1049]: info: CoreCommandRouter::volumioGetVisibleSources Apr 16 14:31:55 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 14:31:55 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 16 14:31:55 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:31:55 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 16 14:31:55 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 16 14:31:55 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 16 14:31:55 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 16 14:31:55 volumio sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 16 14:31:55 volumio sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:55 volumio sudo[1541]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:55 volumio sudo[1544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 14:31:55 volumio sudo[1544]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 14:31:55 volumio sudo[1544]: pam_unix(sudo:session): session closed for user root Apr 16 14:31:55 volumio volumio[1049]: verbose: New Socket.io Connection to 192.168.111.131 from 192.168.111.157 UA: Mozilla/5.0 (Linux; Android 14; RMX3393 Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/134.0.6998.135 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 16 14:31:55 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:31:55 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 16 14:31:55 volumio volumio[1049]: info: Listing playlists Apr 16 14:31:55 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 16 14:31:55 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 16 14:31:55 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 16 14:31:55 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 16 14:31:59 volumio volumio[1049]: info: CoreCommandRouter::volumioPlay Apr 16 14:31:59 volumio volumio[1049]: info: CoreStateMachine::play index undefined Apr 16 14:31:59 volumio volumio[1049]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 14:31:59 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:59 volumio volumio[1049]: info: CoreStateMachine::startPlaybackTimer Apr 16 14:31:59 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:31:59 volumio volumio[1049]: info: [1744803119278] ControllerUPNPBrowser::clearAddPlayTrack Apr 16 14:31:59 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 14:31:59 volumio volumio[1049]: info: sendMpdCommand stop took 0 milliseconds Apr 16 14:31:59 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 14:31:59 volumio volumio[1049]: info: Apr 16 14:31:59 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:31:59 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:31:59 volumio volumio[1049]: info: sendMpdCommand clear took 0 milliseconds Apr 16 14:31:59 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.111.154:8200/MediaItems/2167.flac" Apr 16 14:31:59 volumio volumio[1049]: info: Apr 16 14:31:59 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:31:59 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:31:59 volumio volumio[1049]: info: Apr 16 14:31:59 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:31:59 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:32:02 volumio volumio[1049]: Upnp client error: Error: This socket has been ended by the other party Apr 16 14:32:02 volumio volumio[1049]: error: updateQueue error: null Apr 16 14:32:02 volumio volumio[1049]: error: updateQueue error: null Apr 16 14:32:02 volumio volumio[1049]: info: ------------------------------ 3062ms Apr 16 14:32:02 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.111.154:8200/MediaItems/2167.flac" Apr 16 14:32:02 volumio volumio[1049]: info: ------------------------------ 3059ms Apr 16 14:32:02 volumio volumio[1049]: info: ------------------------------ 3058ms Apr 16 14:32:02 volumio volumio[1049]: info: Apr 16 14:32:02 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:32:02 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:32:02 volumio volumio[1049]: info: sendMpdCommand add "http://192.168.111.154:8200/MediaItems/2167.flac" took 1 milliseconds Apr 16 14:32:02 volumio volumio[1049]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 16 14:32:02 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand play Apr 16 14:32:02 volumio volumio[1049]: info: Apr 16 14:32:02 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:32:02 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:32:02 volumio volumio[1049]: info: Apr 16 14:32:02 volumio volumio[1049]: ---------------------------- MPD announces system playlist update Apr 16 14:32:02 volumio volumio[1049]: info: Ignoring MPD Status Update Apr 16 14:32:02 volumio volumio[1049]: info: ------------------------------ 2ms Apr 16 14:32:02 volumio volumio[1049]: info: sendMpdCommand play took 2 milliseconds Apr 16 14:32:02 volumio volumio[1049]: info: ------------------------------ 1ms Apr 16 14:32:02 volumio volumio[1049]: info: ------------------------------ 1ms Apr 16 14:32:05 volumio volumio[1049]: info: Apr 16 14:32:05 volumio volumio[1049]: ---------------------------- MPD announces state update: player Apr 16 14:32:05 volumio volumio[1049]: info: ControllerMpd::getState Apr 16 14:32:05 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand status Apr 16 14:32:05 volumio volumio[1049]: info: Apr 16 14:32:05 volumio volumio[1049]: ---------------------------- MPD announces state update: player Apr 16 14:32:05 volumio volumio[1049]: info: ControllerMpd::getState Apr 16 14:32:05 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand status Apr 16 14:32:05 volumio volumio[1049]: info: Apr 16 14:32:05 volumio volumio[1049]: ---------------------------- MPD announces state update: player Apr 16 14:32:05 volumio volumio[1049]: info: ControllerMpd::getState Apr 16 14:32:05 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand status Apr 16 14:32:05 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 16 14:32:05 volumio volumio[1049]: info: sendMpdCommand status took 4 milliseconds Apr 16 14:32:05 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 16 14:32:05 volumio volumio[1049]: info: sendMpdCommand status took 4 milliseconds Apr 16 14:32:05 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 16 14:32:05 volumio volumio[1049]: info: sendMpdCommand status took 3 milliseconds Apr 16 14:32:05 volumio volumio[1049]: verbose: ControllerMpd::parseState Apr 16 14:32:05 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 14:32:05 volumio volumio[1049]: verbose: ControllerMpd::parseState Apr 16 14:32:05 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 14:32:05 volumio volumio[1049]: verbose: ControllerMpd::parseState Apr 16 14:32:05 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 14:32:05 volumio volumio[1049]: info: sendMpdCommand clearerror took 5 milliseconds Apr 16 14:32:05 volumio volumio[1049]: info: sendMpdCommand clearerror took 4 milliseconds Apr 16 14:32:05 volumio volumio[1049]: info: sendMpdCommand clearerror took 4 milliseconds Apr 16 14:32:05 volumio volumio[1049]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 16 14:32:05 volumio volumio[1049]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 14:32:05 volumio volumio[1049]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 16 14:32:05 volumio volumio[1049]: verbose: ControllerMpd::parseTrackInfo Apr 16 14:32:05 volumio volumio[1049]: verbose: ControllerMpd::parseTrackInfo Apr 16 14:32:05 volumio volumio[1049]: verbose: ControllerMpd::parseTrackInfo Apr 16 14:32:05 volumio volumio[1049]: info: ControllerMpd::pushState Apr 16 14:32:05 volumio volumio[1049]: info: CoreCommandRouter::servicePushState Apr 16 14:32:05 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:32:05 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 14:32:05 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:32:05 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:32:05 volumio volumio[1049]: 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":"2167.flac","artist":null,"album":null,"uri":"http://192.168.111.154:8200/MediaItems/2167.flac","trackType":"flac"} Apr 16 14:32:05 volumio volumio[1049]: verbose: CURRENT POSITION 0 Apr 16 14:32:05 volumio volumio[1049]: info: CoreStateMachine::syncState stateService stop Apr 16 14:32:05 volumio volumio[1049]: info: CoreStateMachine::syncState currentStatus stop Apr 16 14:32:05 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:32:05 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:32:05 volumio volumio[1049]: info: No code Apr 16 14:32:05 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:32:05 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:32:05 volumio volumio[1049]: info: ControllerMpd::pushState Apr 16 14:32:05 volumio volumio[1049]: info: CoreCommandRouter::servicePushState Apr 16 14:32:05 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:32:05 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:32:05 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:32:05 volumio volumio[1049]: 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":"2167.flac","artist":null,"album":null,"uri":"http://192.168.111.154:8200/MediaItems/2167.flac","trackType":"flac"} Apr 16 14:32:05 volumio volumio[1049]: verbose: CURRENT POSITION 0 Apr 16 14:32:05 volumio volumio[1049]: info: CoreStateMachine::syncState stateService stop Apr 16 14:32:05 volumio volumio[1049]: info: CoreStateMachine::syncState currentStatus stop Apr 16 14:32:05 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:32:05 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:32:05 volumio volumio[1049]: info: No code Apr 16 14:32:05 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:32:05 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:32:05 volumio volumio[1049]: info: ControllerMpd::pushState Apr 16 14:32:05 volumio volumio[1049]: info: CoreCommandRouter::servicePushState Apr 16 14:32:05 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:32:05 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:32:05 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 16 14:32:05 volumio volumio[1049]: 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":"2167.flac","artist":null,"album":null,"uri":"http://192.168.111.154:8200/MediaItems/2167.flac","trackType":"flac"} Apr 16 14:32:05 volumio volumio[1049]: verbose: CURRENT POSITION 0 Apr 16 14:32:05 volumio volumio[1049]: info: CoreStateMachine::syncState stateService stop Apr 16 14:32:05 volumio volumio[1049]: info: CoreStateMachine::syncState currentStatus stop Apr 16 14:32:05 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:32:05 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:32:05 volumio volumio[1049]: info: No code Apr 16 14:32:05 volumio volumio[1049]: info: CoreStateMachine::pushState Apr 16 14:32:05 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 16 14:32:05 volumio volumio[1049]: info: ------------------------------ 41ms Apr 16 14:32:05 volumio volumio[1049]: info: ------------------------------ 40ms Apr 16 14:32:05 volumio volumio[1049]: info: ------------------------------ 41ms Apr 16 14:32:08 volumio volumio[1049]: verbose: New Socket.io Connection to 192.168.111.131 from 192.168.111.157 UA: Mozilla/5.0 (Linux; Android 14; RMX3393 Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/134.0.6998.135 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 16 14:32:08 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:32:08 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 16 14:32:08 volumio volumio[1049]: info: Listing playlists Apr 16 14:32:12 volumio volumio[1049]: info: UPDATER: Scheduling automatic update Apr 16 14:32:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Apr 16 14:32:12 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Apr 16 14:32:12 volumio volumio[1049]: info: UPDATER: Auto update will take place at: Thu Apr 17 2025 05:20:43 GMT+0300 (Moscow Standard Time) Apr 16 14:32:13 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 16 14:32:13 volumio volumio[1049]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 16 14:32:13 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 16 14:32:13 volumio volumio[1049]: info: Received Get System Version Apr 16 14:32:13 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 16 14:32:13 volumio volumio[1049]: info: Received Get System Info Apr 16 14:32:13 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 14:32:13 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 14:32:13 volumio volumio[1049]: info: Discovery: Getting this device information Apr 16 14:32:13 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:32:13 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 14:32:16 volumio ntpd[1038]: Soliciting pool server 147.45.251.137 Apr 16 14:32:16 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 14:32:16 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 14:32:16 volumio volumio[1049]: info: Discovery: Getting this device information Apr 16 14:32:16 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 16 14:32:16 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 14:32:18 volumio volumio[1049]: info: CoreCommandRouter::volumioGetVisibleSources Apr 16 14:32:18 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 14:32:18 volumio volumio[1049]: info: CoreCommandRouter::volumioGetQueue Apr 16 14:32:18 volumio volumio[1049]: info: CoreStateMachine::getQueue Apr 16 14:32:18 volumio volumio[1049]: info: CorePlayQueue::getQueue Apr 16 14:32:19 volumio ntpd[1038]: Soliciting pool server 2a01:230:2::a5 Apr 16 14:32:23 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 16 14:32:23 volumio volumio[1049]: info: Preload queue cleared Apr 16 14:32:24 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 16 14:32:27 volumio volumio[1049]: info: Preload queue cleared Apr 16 14:32:31 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 16 14:32:31 volumio volumio[1049]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 14:32:31 volumio volumio[1049]: TypeError: Cannot read property 'length' of undefined Apr 16 14:32:31 volumio volumio[1049]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 16 14:32:31 volumio volumio[1049]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 16 14:32:31 volumio volumio[1049]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 16 14:32:31 volumio volumio[1049]: at Parser.emit (events.js:400:28) Apr 16 14:32:31 volumio volumio[1049]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 16 14:32:31 volumio volumio[1049]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 16 14:32:31 volumio volumio[1049]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 16 14:32:31 volumio volumio[1049]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 16 14:32:31 volumio volumio[1049]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 16 14:32:31 volumio volumio[1049]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 16 14:32:31 volumio volumio[1049]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 16 14:32:31 volumio volumio[1049]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 16 14:32:31 volumio volumio[1049]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 16 14:32:31 volumio volumio[1049]: at IncomingMessage.emit (events.js:412:35) Apr 16 14:32:31 volumio volumio[1049]: at endReadableNT (internal/streams/readable.js:1333:12) Apr 16 14:32:31 volumio volumio[1049]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 16 14:32:31 volumio volumio[1049]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 14:32:31 volumio sudo[1632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-16 14:31 Apr 16 14:32:31 volumio sudo[1632]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"