-- Logs begin at Thu 2019-02-14 13:11:58 +03, end at Sat 2025-05-24 14:17:52 +03. -- May 24 14:16:51 volumiopi4 systemd-timedated[1008]: Changed local time to Sat May 24 14:16:51 2025 May 24 14:16:51 volumiopi4 sudo[1006]: pam_unix(sudo:session): session closed for user root May 24 14:16:51 volumiopi4 systemd[1]: Starting Daily man-db regeneration... May 24 14:16:51 volumiopi4 volumio-time-update[594]: volumio-time-update-util: System time updated successfully. May 24 14:16:51 volumiopi4 systemd[1]: Starting Daily apt download activities... May 24 14:16:51 volumiopi4 systemd[1]: Started Volumio Time Update Utility. May 24 14:16:51 volumiopi4 ntpd[787]: Listen normally on 3 wlan0 192.168.1.59:123 May 24 14:16:51 volumiopi4 ntpd[787]: new interface(s) found: waking up resolver May 24 14:16:52 volumiopi4 nmbd[753]: [2025/05/24 14:16:52.005754, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 24 14:16:52 volumiopi4 systemd[1]: Started Samba NMB Daemon. May 24 14:16:52 volumiopi4 nmbd[753]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 24 14:16:52 volumiopi4 nmbd[753]: [2025/05/24 14:16:52.041138, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 24 14:16:52 volumiopi4 nmbd[753]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.59 for name WORKGROUP<1d>. May 24 14:16:52 volumiopi4 nmbd[753]: This response was from IP 192.168.1.6, reporting an IP address of 192.168.1.6. May 24 14:16:52 volumiopi4 nmbd[753]: [2025/05/24 14:16:52.041329, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 24 14:16:52 volumiopi4 nmbd[753]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.1.59 for name WORKGROUP<1d>. May 24 14:16:52 volumiopi4 nmbd[753]: This response was from IP 192.168.1.6, reporting an IP address of 192.168.1.6. May 24 14:16:52 volumiopi4 systemd[1]: Starting Samba Winbind Daemon... May 24 14:16:52 volumiopi4 winbindd[1032]: [2025/05/24 14:16:52.407069, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 24 14:16:52 volumiopi4 winbindd[1032]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 24 14:16:52 volumiopi4 systemd[1]: Started Samba Winbind Daemon. May 24 14:16:52 volumiopi4 winbindd[1032]: [2025/05/24 14:16:52.446773, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 24 14:16:52 volumiopi4 winbindd[1032]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 24 14:16:52 volumiopi4 systemd[1]: Starting Samba SMB Daemon... May 24 14:16:52 volumiopi4 systemd[1]: man-db.service: Succeeded. May 24 14:16:52 volumiopi4 systemd[1]: Started Daily man-db regeneration. May 24 14:16:52 volumiopi4 volumio[984]: info: ------------------------------------------- May 24 14:16:52 volumiopi4 volumio[984]: info: ----- Volumio3 ---- May 24 14:16:52 volumiopi4 volumio[984]: info: ------------------------------------------- May 24 14:16:52 volumiopi4 volumio[984]: info: ----- System startup ---- May 24 14:16:52 volumiopi4 volumio[984]: info: ------------------------------------------- May 24 14:16:52 volumiopi4 volumio-remote-updater[583]: [2025-05-24 14:16:52] [connect] Successful connection May 24 14:16:52 volumiopi4 smbd[1037]: [2025/05/24 14:16:52.902843, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 24 14:16:52 volumiopi4 systemd[1]: Started Samba SMB Daemon. May 24 14:16:52 volumiopi4 smbd[1037]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 24 14:16:52 volumiopi4 systemd[1]: Reached target Multi-User System. May 24 14:16:52 volumiopi4 systemd[1]: Reached target Graphical Interface. May 24 14:16:52 volumiopi4 systemd[1]: Starting Update UTMP about System Runlevel Changes... May 24 14:16:53 volumiopi4 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 24 14:16:53 volumiopi4 systemd[1]: Started Update UTMP about System Runlevel Changes. May 24 14:16:53 volumiopi4 systemd[1]: apt-daily.service: Succeeded. May 24 14:16:53 volumiopi4 systemd[1]: Started Daily apt download activities. May 24 14:16:53 volumiopi4 systemd[1]: Starting Daily apt upgrade and clean activities... May 24 14:16:53 volumiopi4 volumio[984]: info: MYVOLUMIO Environment detected May 24 14:16:53 volumiopi4 volumio[984]: info: Plugin folders cleanup May 24 14:16:53 volumiopi4 volumio[984]: info: Scanning into folder /volumio/app/plugins/ May 24 14:16:53 volumiopi4 volumio[984]: info: Scanning category audio_interface May 24 14:16:53 volumiopi4 volumio[984]: info: Scanning category miscellanea May 24 14:16:53 volumiopi4 volumio[984]: info: Scanning category music_service May 24 14:16:53 volumiopi4 volumio[984]: info: Scanning category plugins.json May 24 14:16:53 volumiopi4 volumio[984]: info: Scanning category system_controller May 24 14:16:53 volumiopi4 volumio[984]: info: Scanning category user_interface May 24 14:16:53 volumiopi4 volumio[984]: info: Scanning into folder /data/plugins/ May 24 14:16:53 volumiopi4 volumio[984]: info: Scanning category miscellanea May 24 14:16:53 volumiopi4 volumio[984]: info: Scanning category music_service May 24 14:16:53 volumiopi4 volumio[984]: info: Scanning category user_interface May 24 14:16:53 volumiopi4 volumio[984]: info: Plugin folders cleanup completed May 24 14:16:53 volumiopi4 volumio[984]: info: ------------------------------------------- May 24 14:16:53 volumiopi4 volumio[984]: info: ----- Core plugins startup ---- May 24 14:16:53 volumiopi4 volumio[984]: info: ------------------------------------------- May 24 14:16:53 volumiopi4 volumio[984]: info: Loading plugins from folder /volumio/app/plugins/ May 24 14:16:53 volumiopi4 volumio[984]: info: Adding plugin upnp to MyMusic Plugins May 24 14:16:53 volumiopi4 volumio[984]: info: Adding plugin airplay_emulation to MyMusic Plugins May 24 14:16:53 volumiopi4 volumio[984]: info: Adding plugin upnp_browser to MyMusic Plugins May 24 14:16:53 volumiopi4 volumio[984]: info: Loading plugins from folder /data/plugins/ May 24 14:16:53 volumiopi4 volumio[984]: info: Loading plugin "system"... May 24 14:16:53 volumiopi4 volumio[984]: info: Loading plugin "appearance"... May 24 14:16:54 volumiopi4 systemd[1]: apt-daily-upgrade.service: Succeeded. May 24 14:16:54 volumiopi4 systemd[1]: Started Daily apt upgrade and clean activities. May 24 14:16:54 volumiopi4 systemd[1]: Startup finished in 12.121s (kernel) + 28.100s (userspace) = 40.221s. May 24 14:16:54 volumiopi4 volumio[984]: info: Loading plugin "network"... May 24 14:16:54 volumiopi4 volumio[984]: info: Refreshing Cached IP Addresses May 24 14:16:54 volumiopi4 sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 14:16:54 volumiopi4 sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:16:54 volumiopi4 sudo[1140]: pam_unix(sudo:session): session closed for user root May 24 14:16:54 volumiopi4 sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 14:16:54 volumiopi4 sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:16:54 volumiopi4 sudo[1142]: pam_unix(sudo:session): session closed for user root May 24 14:16:54 volumiopi4 volumio[984]: info: Loading plugin "services"... May 24 14:16:54 volumiopi4 volumio[984]: info: Loading plugin "alsa_controller"... May 24 14:16:54 volumiopi4 sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 24 14:16:54 volumiopi4 sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:16:54 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 14:16:54 volumiopi4 volumio[984]: info: Loading plugin "wizard"... May 24 14:16:55 volumiopi4 volumio[984]: info: Loading plugin "networkfs"... May 24 14:16:55 volumiopi4 volumio[984]: info: Starting Udev Watcher for removable devices May 24 14:16:55 volumiopi4 sudo[1166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=gjljyeuekztn,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.6/music /mnt/NAS/disk1 May 24 14:16:55 volumiopi4 sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:16:55 volumiopi4 volumio[984]: info: Ignoring mount for partition: boot May 24 14:16:55 volumiopi4 volumio[984]: info: Ignoring mount for partition: volumio May 24 14:16:55 volumiopi4 volumio[984]: info: Ignoring mount for partition: volumio_data May 24 14:16:55 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 14:16:55 volumiopi4 volumio[984]: info: Loading plugin "volumio_command_line_client"... May 24 14:16:55 volumiopi4 volumio[984]: info: Loading plugin "upnp"... May 24 14:16:55 volumiopi4 volumio[984]: info: [1748085415165] Starting Upmpd Daemon May 24 14:16:55 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 14:16:55 volumiopi4 volumio[984]: info: Loading plugin "my_music"... May 24 14:16:55 volumiopi4 volumio[984]: info: Loading plugin "mpd"... May 24 14:16:55 volumiopi4 kernel: Key type cifs.spnego registered May 24 14:16:55 volumiopi4 kernel: Key type cifs.idmap registered May 24 14:16:55 volumiopi4 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. May 24 14:16:55 volumiopi4 kernel: CIFS: Attempting to mount //192.168.1.6/music May 24 14:16:55 volumiopi4 volumio[984]: info: Loading plugin "upnp_browser"... May 24 14:16:55 volumiopi4 volumio[984]: info: Loading plugin "alarm-clock"... May 24 14:16:55 volumiopi4 volumio[984]: info: Loading plugin "airplay_emulation"... May 24 14:16:55 volumiopi4 volumio[984]: info: Starting Shairport Sync May 24 14:16:55 volumiopi4 volumio[984]: info: Loading plugin "last_100"... May 24 14:16:55 volumiopi4 volumio[984]: info: Loading plugin "webradio"... May 24 14:16:56 volumiopi4 volumio[984]: info: Loading plugin "i2s_dacs"... May 24 14:16:56 volumiopi4 volumio[984]: info: I2S DAC not set, start Auto-detection May 24 14:16:56 volumiopi4 volumio[984]: info: Loading plugin "volumiodiscovery"... May 24 14:16:56 volumiopi4 volumio[984]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 14:16:56 volumiopi4 volumio[984]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 14:16:56 volumiopi4 volumio[984]: *** WARNING *** For more information see May 24 14:16:56 volumiopi4 node[984]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 14:16:56 volumiopi4 volumio[984]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 14:16:56 volumiopi4 volumio[984]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 14:16:56 volumiopi4 volumio[984]: *** WARNING *** For more information see May 24 14:16:56 volumiopi4 node[984]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 14:16:56 volumiopi4 node[984]: *** WARNING *** For more information see May 24 14:16:56 volumiopi4 node[984]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 14:16:56 volumiopi4 node[984]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 14:16:56 volumiopi4 node[984]: *** WARNING *** For more information see May 24 14:16:56 volumiopi4 volumio[984]: info: Applying required configuration parameters for plugin volumiodiscovery May 24 14:16:56 volumiopi4 volumio[984]: info: Discovery: Started advertising with name: VolumioPI4 May 24 14:16:56 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 14:16:56 volumiopi4 volumio[984]: info: Loading plugin "now_playing"... May 24 14:16:56 volumiopi4 sudo[1166]: pam_unix(sudo:session): session closed for user root May 24 14:16:57 volumiopi4 volumio[984]: info: Loading plugin "outputs"... May 24 14:16:57 volumiopi4 volumio[984]: info: Loading plugin "albumart"... May 24 14:16:57 volumiopi4 volumio[984]: info: Plugin example_plugin is not enabled May 24 14:16:57 volumiopi4 volumio[984]: info: Loading plugin "inputs"... May 24 14:16:57 volumiopi4 volumio[984]: info: Loading plugin "updater_comm"... May 24 14:16:57 volumiopi4 volumio[984]: info: Plugin mpdemulation is not enabled May 24 14:16:57 volumiopi4 volumio[984]: info: Loading plugin "rest_api"... May 24 14:16:57 volumiopi4 volumio[984]: info: Loading plugin "websocket"... May 24 14:16:57 volumiopi4 volumio[984]: info: Starting Socket.io Server version 2.3.0 May 24 14:16:57 volumiopi4 volumio[984]: info: Loading plugin "m3u_importer"... May 24 14:16:57 volumiopi4 volumio[984]: Forking 3 albumart workers May 24 14:16:57 volumiopi4 volumio[984]: info: Applying required configuration parameters for plugin m3u_importer May 24 14:16:57 volumiopi4 volumio[984]: info: Loading plugin "yandex_music"... May 24 14:16:58 volumiopi4 volumio[984]: Starting albumart workers May 24 14:16:58 volumiopi4 volumio[984]: Starting albumart workers May 24 14:16:58 volumiopi4 volumio[984]: Starting albumart workers May 24 14:16:58 volumiopi4 systemd[1]: systemd-fsckd.service: Succeeded. May 24 14:16:58 volumiopi4 volumio[984]: info: Applying required configuration parameters for plugin yandex_music May 24 14:16:58 volumiopi4 volumio[984]: info: Loading plugin "touch_display"... May 24 14:16:59 volumiopi4 sudo[1151]: pam_unix(sudo:session): session closed for user root May 24 14:16:59 volumiopi4 volumio[984]: info: Applying required configuration parameters for plugin touch_display May 24 14:16:59 volumiopi4 volumio[984]: info: Loading i18n strings for locale ru May 24 14:16:59 volumiopi4 volumio[984]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 24 14:16:59 volumiopi4 volumio[984]: Updating browse sources language May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::initPlayerControls May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 14:16:59 volumiopi4 volumio[984]: Express server listening on port 3000 May 24 14:16:59 volumiopi4 volumio[984]: [Metrics] WebUI: 7s 742.75ms May 24 14:16:59 volumiopi4 volumio[984]: info: CoreStateMachine::resetVolumioState May 24 14:16:59 volumiopi4 volumio[984]: info: CoreStateMachine::getcurrentVolume May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioRetrievevolume May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:16:59 volumiopi4 volumio[984]: info: Volumio Network Manager: Network status updated: 2 May 24 14:16:59 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:16:59 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:16:59 volumiopi4 volumio[984]: info: CoreStateMachine::updateTrackBlock May 24 14:16:59 volumiopi4 volumio[984]: info: CorePlayQueue::getTrackBlock May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioRetrievevolume May 24 14:16:59 volumiopi4 volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 24 14:16:59 volumiopi4 volumio[984]: info: Reloading queue from file May 24 14:16:59 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:16:59 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:16:59 volumiopi4 volumio[984]: info: Setting Device type: Raspberry PI May 24 14:16:59 volumiopi4 volumio[984]: info: CoreStateMachine::setRepeat true single undefined May 24 14:16:59 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:16:59 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:16:59 volumiopi4 volumio[984]: info: CoreStateMachine::setRandom true May 24 14:16:59 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:16:59 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:16:59 volumiopi4 volumio[984]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 24 14:16:59 volumiopi4 volumio[984]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03112 May 24 14:16:59 volumiopi4 volumio[984]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 24 14:16:59 volumiopi4 volumio[984]: info: Completed loading Core Plugins May 24 14:16:59 volumiopi4 volumio[984]: info: Preparing to generate the ALSA configuration file May 24 14:16:59 volumiopi4 volumio[984]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 24 14:16:59 volumiopi4 volumio[984]: info: Reading ALSA contributions from plugins. May 24 14:16:59 volumiopi4 volumio[984]: info: Asound.conf file unchanged, so no further update is needed May 24 14:16:59 volumiopi4 volumio[984]: info: Output device has changed, restarting MPD May 24 14:16:59 volumiopi4 sudo[1267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 14:16:59 volumiopi4 volumio[984]: info: Output device has changed, restarting Shairport Sync May 24 14:16:59 volumiopi4 sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:16:59 volumiopi4 sudo[1267]: pam_unix(sudo:session): session closed for user root May 24 14:16:59 volumiopi4 sudo[1269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 14:16:59 volumiopi4 sudo[1269]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:16:59 volumiopi4 systemd[1]: Stopping Music Player Daemon... May 24 14:16:59 volumiopi4 volumio[984]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 14:16:59 volumiopi4 volumio[984]: info: ___________ START PLUGINS ___________ May 24 14:16:59 volumiopi4 volumio[984]: info: ControllerMpd::onStart: Initializing MPD May 24 14:16:59 volumiopi4 volumio[984]: info: Creating MPD Configuration file May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 14:16:59 volumiopi4 volumio[984]: info: [1748085419953] CoreMusicLibrary::Adding element Медиа-серверы May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 14:16:59 volumiopi4 sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 14:16:59 volumiopi4 sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:16:59 volumiopi4 sudo[1276]: pam_unix(sudo:session): session closed for user root May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:16:59 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:16:59 volumiopi4 sudo[1278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 14:16:59 volumiopi4 sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:17:00 volumiopi4 systemd[1]: mpd.service: Succeeded. May 24 14:17:00 volumiopi4 systemd[1]: Stopped Music Player Daemon. May 24 14:17:00 volumiopi4 volumio[984]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 14:17:00 volumiopi4 volumio[984]: info: [1748085420040] CoreMusicLibrary::Adding element Last_100 May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 14:17:00 volumiopi4 volumio[984]: info: [1748085420042] CoreMusicLibrary::Adding element Webradio May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 14:17:00 volumiopi4 volumio[984]: info: Initializing BBC Radios May 24 14:17:00 volumiopi4 systemd[1]: Starting Music Player Daemon... May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: [now-playing] ConfigUpdater: config is up to date. May 24 14:17:00 volumiopi4 volumio[984]: info: Loading i18n strings for locale ru May 24 14:17:00 volumiopi4 sudo[1284]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 24 14:17:00 volumiopi4 sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:17:00 volumiopi4 sudo[1284]: pam_unix(sudo:session): session closed for user root May 24 14:17:00 volumiopi4 volumio[984]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 14:17:00 volumiopi4 volumio[984]: info: [1748085420129] CoreMusicLibrary::Adding element Яндекс Музыка May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 14:17:00 volumiopi4 volumio[984]: Cannot find translation for source Яндекс Музыка May 24 14:17:00 volumiopi4 volumio[984]: info: Loading i18n strings for locale ru May 24 14:17:00 volumiopi4 volumio[984]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 14:17:00 volumiopi4 volumio[984]: info: Volumio Calling Home May 24 14:17:00 volumiopi4 sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service May 24 14:17:00 volumiopi4 sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:17:00 volumiopi4 sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 24 14:17:00 volumiopi4 sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:17:00 volumiopi4 sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service May 24 14:17:00 volumiopi4 sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:17:00 volumiopi4 sudo[1304]: pam_unix(sudo:session): session closed for user root May 24 14:17:00 volumiopi4 systemd[1]: Reloading. May 24 14:17:00 volumiopi4 volumio[984]: info: [now-playing] Fetching API key... May 24 14:17:00 volumiopi4 volumio[984]: info: [now-playing] App is listening on port 4004. May 24 14:17:00 volumiopi4 volumio[984]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds May 24 14:17:00 volumiopi4 volumio[984]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' May 24 14:17:00 volumiopi4 volumio[984]: info: touch_display: systemctl stop getty@tty1.service succeeded. May 24 14:17:00 volumiopi4 volumio[984]: info: MPD Permissions set May 24 14:17:00 volumiopi4 volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 sh[513]: timed out May 24 14:17:00 volumiopi4 dhcpcd[561]: timed out May 24 14:17:00 volumiopi4 sh[513]: dhcpcd exited May 24 14:17:00 volumiopi4 dhcpcd[561]: dhcpcd exited May 24 14:17:00 volumiopi4 sh[513]: ifup: failed to bring up eth0 May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:00 volumiopi4 volumio[984]: info: touch_display: No Raspberry Pi Foundation touch screen detected. May 24 14:17:00 volumiopi4 sudo[1335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf May 24 14:17:00 volumiopi4 sudo[1335]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:17:00 volumiopi4 sudo[1335]: pam_unix(sudo:session): session closed for user root May 24 14:17:00 volumiopi4 volumio[984]: info: Starting Shairport Sync May 24 14:17:00 volumiopi4 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. May 24 14:17:00 volumiopi4 volumio[984]: info: Starting Shairport Sync May 24 14:17:00 volumiopi4 sudo[1338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 14:17:00 volumiopi4 sudo[1338]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:17:00 volumiopi4 volumio[984]: info: Starting Shairport Sync May 24 14:17:00 volumiopi4 volumio[984]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. May 24 14:17:00 volumiopi4 sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 14:17:00 volumiopi4 sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:17:00 volumiopi4 sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 14:17:00 volumiopi4 sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:17:00 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetState May 24 14:17:00 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:00 volumiopi4 systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. May 24 14:17:00 volumiopi4 mpd[1299]: May 24 14:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 24 14:17:01 volumiopi4 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 24 14:17:01 volumiopi4 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 24 14:17:01 volumiopi4 sudo[1309]: pam_unix(sudo:session): session closed for user root May 24 14:17:01 volumiopi4 volumio[984]: info: touch_display: systemctl daemon-reload succeeded. May 24 14:17:01 volumiopi4 systemd[1]: Reloading. May 24 14:17:01 volumiopi4 volumio[984]: info: Discovery: adding e102274d-ed64-4c3e-98c3-07a12b4ec8b4 May 24 14:17:01 volumiopi4 volumio[984]: info: Discovery: Found device VolumioPI4 May 24 14:17:01 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetState May 24 14:17:01 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:01 volumiopi4 sudo[1347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 24 14:17:01 volumiopi4 sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:17:01 volumiopi4 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. May 24 14:17:01 volumiopi4 systemd[1]: Started Music Player Daemon. May 24 14:17:01 volumiopi4 sudo[1306]: pam_unix(sudo:session): session closed for user root May 24 14:17:01 volumiopi4 systemd[1]: systemd-hostnamed.service: Succeeded. May 24 14:17:01 volumiopi4 sudo[1278]: pam_unix(sudo:session): session closed for user root May 24 14:17:01 volumiopi4 sudo[1269]: pam_unix(sudo:session): session closed for user root May 24 14:17:01 volumiopi4 volumio[984]: info: touch_display: systemctl disable getty@tty1.service succeeded. May 24 14:17:01 volumiopi4 systemd[1]: Started Volumio Kiosk. May 24 14:17:01 volumiopi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 24 14:17:01 volumiopi4 sudo[1347]: pam_unix(sudo:session): session closed for user root May 24 14:17:01 volumiopi4 systemd[1]: shairport-sync.service: Succeeded. May 24 14:17:01 volumiopi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 24 14:17:01 volumiopi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 24 14:17:01 volumiopi4 sudo[1340]: pam_unix(sudo:session): session closed for user root May 24 14:17:01 volumiopi4 sudo[1343]: pam_unix(sudo:session): session closed for user root May 24 14:17:01 volumiopi4 sudo[1338]: pam_unix(sudo:session): session closed for user root May 24 14:17:01 volumiopi4 volumio[984]: error: MPD error: The expression evaluated to a falsy value: May 24 14:17:01 volumiopi4 volumio[984]: assert.ok(self.idling) May 24 14:17:01 volumiopi4 volumio[984]: error: The expression evaluated to a falsy value: May 24 14:17:01 volumiopi4 volumio[984]: assert.ok(self.idling) May 24 14:17:01 volumiopi4 volumio[984]: info: Shairport-Sync Started May 24 14:17:01 volumiopi4 volumio[984]: Error adding Membership: Error: addMembership EINVAL May 24 14:17:01 volumiopi4 volumio[984]: info: Shairport-Sync Started May 24 14:17:01 volumiopi4 volumio[984]: info: Shairport-Sync Started May 24 14:17:01 volumiopi4 volumio[984]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 24 14:17:01 volumiopi4 volumio[984]: info: touch_display: Volumio Kiosk started. May 24 14:17:01 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetState May 24 14:17:01 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:01 volumiopi4 volumio[984]: info: Completed starting Core Plugins May 24 14:17:01 volumiopi4 volumio[984]: info: ------------------------------------------- May 24 14:17:01 volumiopi4 volumio[984]: info: ----- MyVolumio plugins startup ---- May 24 14:17:01 volumiopi4 volumio[984]: info: ------------------------------------------- May 24 14:17:01 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Fetching plans data.... May 24 14:17:01 volumiopi4 volumio[984]: info: MPD running with PID1299 May 24 14:17:01 volumiopi4 volumio[984]: ,establishing connection May 24 14:17:01 volumiopi4 volumio[984]: error: updateQueue error: null May 24 14:17:01 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetState May 24 14:17:01 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:01 volumiopi4 volumio[984]: info: touch_display: X display number found: 0 May 24 14:17:01 volumiopi4 volumio[984]: error: updateQueue error: null May 24 14:17:01 volumiopi4 startx[1369]: X.Org X Server 1.20.4 May 24 14:17:01 volumiopi4 startx[1369]: X Protocol Version 11, Revision 0 May 24 14:17:01 volumiopi4 startx[1369]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian May 24 14:17:01 volumiopi4 startx[1369]: Current Operating System: Linux volumiopi4 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l May 24 14:17:01 volumiopi4 startx[1369]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1024 bcm2708_fb.fbheight=600 bcm2708_fb.fbswap=1 numa=fake=1 system_heap.max_order=0 smsc95xx.macaddr=DC:A6:32:C8:02:31 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=a0ee35d1-02bc-4547-b2c7-e6caa39d3454 imgfile=/volumio_current.sqsh bootpart=UUID=1BFB-AD60 datapart=UUID=a3c1e1ba-b424-4a77-8c81-c20d638e72d6 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no May 24 14:17:01 volumiopi4 startx[1369]: Build Date: 04 April 2023 07:50:56AM May 24 14:17:01 volumiopi4 startx[1369]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) May 24 14:17:01 volumiopi4 startx[1369]: Current version of pixman: 0.36.0 May 24 14:17:01 volumiopi4 startx[1369]: Before reporting problems, check http://wiki.x.org May 24 14:17:01 volumiopi4 startx[1369]: to make sure that you have the latest version. May 24 14:17:01 volumiopi4 startx[1369]: Markers: (--) probed, (**) from config file, (==) default setting, May 24 14:17:01 volumiopi4 startx[1369]: (++) from command line, (!!) notice, (II) informational, May 24 14:17:01 volumiopi4 startx[1369]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 24 14:17:01 volumiopi4 startx[1369]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat May 24 14:17:01 2025 May 24 14:17:01 volumiopi4 startx[1369]: (==) Using config directory: "/etc/X11/xorg.conf.d" May 24 14:17:01 volumiopi4 startx[1369]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 24 14:17:01 volumiopi4 volumio[984]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 24 14:17:01 volumiopi4 volumio[984]: info: touch_display: X display number found: 0 May 24 14:17:03 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetState May 24 14:17:03 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:03 volumiopi4 volumio[984]: info: touch_display: Setting screensaver timeout to 120 seconds. May 24 14:17:05 volumiopi4 sudo[1497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 14:17:05 volumiopi4 sudo[1497]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:17:05 volumiopi4 sudo[1497]: pam_unix(sudo:session): session closed for user root May 24 14:17:05 volumiopi4 sudo[1499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 14:17:05 volumiopi4 sudo[1499]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:17:05 volumiopi4 sudo[1499]: pam_unix(sudo:session): session closed for user root May 24 14:17:05 volumiopi4 sudo[1503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 24 14:17:05 volumiopi4 sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:17:05 volumiopi4 systemd[1]: Started UPnP Renderer front-end to MPD. May 24 14:17:05 volumiopi4 sudo[1503]: pam_unix(sudo:session): session closed for user root May 24 14:17:05 volumiopi4 volumio[984]: info: Upmpdcli Daemon Started May 24 14:17:05 volumiopi4 volumio[1505]: Generating RSA private key, 4096 bit long modulus (2 primes) May 24 14:17:05 volumiopi4 volumio[984]: info: Volumio called home May 24 14:17:06 volumiopi4 volumio[1505]: ...............++++ May 24 14:17:07 volumiopi4 volumio[984]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 24 14:17:07 volumiopi4 volumio-remote-updater[583]: [2025-05-24 14:17:07] [connect] Successful connection May 24 14:17:08 volumiopi4 volumio-remote-updater[583]: [2025-05-24 14:17:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748085427 101 May 24 14:17:08 volumiopi4 volumio[984]: 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 May 24 14:17:10 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 14:17:10 volumiopi4 volumio[984]: info: Discovery: Getting this device information May 24 14:17:10 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetState May 24 14:17:10 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:10 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 14:17:10 volumiopi4 volumio[1505]: .....................................++++ May 24 14:17:10 volumiopi4 volumio[1505]: e is 65537 (0x010001) May 24 14:17:10 volumiopi4 volumio[1505]: writing RSA key May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 24 14:17:11 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 24 14:17:12 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 24 14:17:12 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 24 14:17:12 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 24 14:17:12 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 24 14:17:12 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 24 14:17:12 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 24 14:17:12 volumiopi4 volumio[984]: info: Adding plugin bluetooth to MyMusic Plugins May 24 14:17:12 volumiopi4 volumio[984]: info: Adding plugin multiroom to MyMusic Plugins May 24 14:17:12 volumiopi4 volumio[984]: info: Adding plugin metavolumio to MyMusic Plugins May 24 14:17:12 volumiopi4 volumio[984]: info: Adding plugin cd_controller to MyMusic Plugins May 24 14:17:12 volumiopi4 volumio[984]: info: Adding plugin smart_inputs to MyMusic Plugins May 24 14:17:12 volumiopi4 volumio[984]: info: Adding plugin tidalconnect to MyMusic Plugins May 24 14:17:12 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 24 14:17:13 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 24 14:17:13 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 24 14:17:13 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:17:13 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:17:13 volumiopi4 volumio[984]: info: Starting MyVolumio Remote Streaming Endpoints May 24 14:17:13 volumiopi4 volumio[984]: info: MyVolumio login type: Token May 24 14:17:13 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 24 14:17:13 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 24 14:17:14 volumiopi4 volumio[984]: info: Starting Streaming Service Transparent Proxy May 24 14:17:14 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 24 14:17:14 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 24 14:17:14 volumiopi4 volumio[984]: info: Streaming services startup May 24 14:17:14 volumiopi4 volumio[984]: info: Starting Streaming Daemon May 24 14:17:14 volumiopi4 sudo[1859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 24 14:17:14 volumiopi4 sudo[1859]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:17:14 volumiopi4 sudo[1859]: pam_unix(sudo:session): session closed for user root May 24 14:17:14 volumiopi4 volumio[984]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 24 14:17:14 volumiopi4 volumio[984]: error: Cannot start Volumio Streaming Daemon May 24 14:17:14 volumiopi4 volumio[984]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 24 14:17:14 volumiopi4 volumio[984]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 24 14:17:14 volumiopi4 volumio[984]: verbose: New Socket.io Connection to localhost: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: 4 May 24 14:17:14 volumiopi4 volumio[984]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined May 24 14:17:14 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo May 24 14:17:14 volumiopi4 volumio[984]: info: Listing playlists May 24 14:17:14 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetQueue May 24 14:17:14 volumiopi4 volumio[984]: info: CoreStateMachine::getQueue May 24 14:17:14 volumiopi4 volumio[984]: info: CorePlayQueue::getQueue May 24 14:17:14 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetVisibleSources May 24 14:17:14 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 14:17:14 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetState May 24 14:17:14 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:15 volumiopi4 volumio[984]: STREAMING PROXY: Starting server on port 3245 May 24 14:17:15 volumiopi4 volumio[984]: Node JS runtime: 14 May 24 14:17:15 volumiopi4 volumio[984]: verbose: New Socket.io Connection to localhost: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: 5 May 24 14:17:15 volumiopi4 volumio[984]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined May 24 14:17:15 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo May 24 14:17:15 volumiopi4 volumio[984]: info: Listing playlists May 24 14:17:15 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetQueue May 24 14:17:15 volumiopi4 volumio[984]: info: CoreStateMachine::getQueue May 24 14:17:15 volumiopi4 volumio[984]: info: CorePlayQueue::getQueue May 24 14:17:15 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetVisibleSources May 24 14:17:15 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 14:17:15 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetState May 24 14:17:15 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:15 volumiopi4 volumio[984]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined May 24 14:17:15 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo May 24 14:17:15 volumiopi4 volumio[984]: info: Listing playlists May 24 14:17:15 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetQueue May 24 14:17:15 volumiopi4 volumio[984]: info: CoreStateMachine::getQueue May 24 14:17:15 volumiopi4 volumio[984]: info: CorePlayQueue::getQueue May 24 14:17:15 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetVisibleSources May 24 14:17:15 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 14:17:15 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetState May 24 14:17:15 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:15 volumiopi4 volumio[984]: error: MyVolumio Custom Token format not valid, refreshing it May 24 14:17:17 volumiopi4 volumio[984]: info: MyVolumio login type: Token May 24 14:17:18 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioToggle May 24 14:17:18 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:18 volumiopi4 volumio[984]: info: CoreStateMachine::play index undefined May 24 14:17:18 volumiopi4 volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 14:17:18 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:18 volumiopi4 volumio[984]: info: CoreStateMachine::startPlaybackTimer May 24 14:17:18 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:18 volumiopi4 volumio[984]: verbose: ControllerMpd::sendMpdCommand stop May 24 14:17:18 volumiopi4 volumio[984]: info: sendMpdCommand stop took 2 milliseconds May 24 14:17:18 volumiopi4 volumio[984]: verbose: ControllerMpd::sendMpdCommand clear May 24 14:17:18 volumiopi4 volumio[984]: info: sendMpdCommand clear took 1 milliseconds May 24 14:17:18 volumiopi4 volumio[984]: info: May 24 14:17:18 volumiopi4 volumio[984]: ---------------------------- MPD announces system playlist update May 24 14:17:18 volumiopi4 volumio[984]: info: Ignoring MPD Status Update May 24 14:17:18 volumiopi4 volumio[984]: info: May 24 14:17:18 volumiopi4 volumio[984]: ---------------------------- MPD announces system playlist update May 24 14:17:18 volumiopi4 volumio[984]: info: Ignoring MPD Status Update May 24 14:17:18 volumiopi4 volumio[984]: info: May 24 14:17:18 volumiopi4 volumio[984]: ---------------------------- MPD announces system playlist update May 24 14:17:18 volumiopi4 volumio[984]: info: Ignoring MPD Status Update May 24 14:17:18 volumiopi4 volumio[984]: error: updateQueue error: null May 24 14:17:18 volumiopi4 volumio[984]: error: updateQueue error: null May 24 14:17:18 volumiopi4 volumio[984]: error: updateQueue error: null May 24 14:17:18 volumiopi4 volumio[984]: info: ------------------------------ 6ms May 24 14:17:18 volumiopi4 volumio[984]: info: ------------------------------ 4ms May 24 14:17:18 volumiopi4 volumio[984]: info: ------------------------------ 3ms May 24 14:17:18 volumiopi4 volumio[984]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-std-32.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2a2b80372d74a7b80fe259564003dc9632bc74849ac385eb781637e1f024b577%2Clid%3D1539%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68400f14%2F0%2F13583660%2Fdd489b6a.198538428.10.134557735%2F320.mp3&ext=.mp3" May 24 14:17:18 volumiopi4 volumio[984]: info: May 24 14:17:18 volumiopi4 volumio[984]: ---------------------------- MPD announces system playlist update May 24 14:17:18 volumiopi4 volumio[984]: info: Ignoring MPD Status Update May 24 14:17:18 volumiopi4 volumio[984]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-std-32.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2a2b80372d74a7b80fe259564003dc9632bc74849ac385eb781637e1f024b577%2Clid%3D1539%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68400f14%2F0%2F13583660%2Fdd489b6a.198538428.10.134557735%2F320.mp3&ext=.mp3" took 2 milliseconds May 24 14:17:18 volumiopi4 volumio[984]: verbose: MPD COMMAND [object Object] May 24 14:17:18 volumiopi4 volumio[984]: info: May 24 14:17:18 volumiopi4 volumio[984]: ---------------------------- MPD announces system playlist update May 24 14:17:18 volumiopi4 volumio[984]: info: Ignoring MPD Status Update May 24 14:17:18 volumiopi4 volumio[984]: info: May 24 14:17:18 volumiopi4 volumio[984]: ---------------------------- MPD announces system playlist update May 24 14:17:18 volumiopi4 volumio[984]: info: Ignoring MPD Status Update May 24 14:17:18 volumiopi4 volumio[984]: info: May 24 14:17:18 volumiopi4 volumio[984]: ---------------------------- MPD announces system playlist update May 24 14:17:18 volumiopi4 volumio[984]: info: Ignoring MPD Status Update May 24 14:17:18 volumiopi4 volumio[984]: info: ------------------------------ 8ms May 24 14:17:18 volumiopi4 volumio[984]: info: ------------------------------ 5ms May 24 14:17:18 volumiopi4 volumio[984]: info: CoreStateMachine::setConsumeUpdateService mpd May 24 14:17:18 volumiopi4 volumio[984]: verbose: ControllerMpd::sendMpdCommand play May 24 14:17:18 volumiopi4 volumio[984]: info: May 24 14:17:18 volumiopi4 volumio[984]: ---------------------------- MPD announces system playlist update May 24 14:17:18 volumiopi4 volumio[984]: info: Ignoring MPD Status Update May 24 14:17:18 volumiopi4 volumio[984]: info: May 24 14:17:18 volumiopi4 volumio[984]: ---------------------------- MPD announces system playlist update May 24 14:17:18 volumiopi4 volumio[984]: info: Ignoring MPD Status Update May 24 14:17:18 volumiopi4 volumio[984]: info: ------------------------------ 6ms May 24 14:17:18 volumiopi4 volumio[984]: info: sendMpdCommand play took 4 milliseconds May 24 14:17:18 volumiopi4 volumio[984]: info: ------------------------------ 3ms May 24 14:17:18 volumiopi4 volumio[984]: info: ------------------------------ 1ms May 24 14:17:19 volumiopi4 volumio[984]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 24 14:17:20 volumiopi4 volumio[984]: info: MyVolumio token set successfully May 24 14:17:20 volumiopi4 volumio[984]: info: MYVOLUMIO: Adding device May 24 14:17:20 volumiopi4 volumio[984]: info: MYVOLUMIO: Evaluating Server May 24 14:17:20 volumiopi4 volumio[984]: info: MyVolumio status changed May 24 14:17:20 volumiopi4 volumio[984]: info: Streaming services startup May 24 14:17:20 volumiopi4 volumio[984]: info: Starting Streaming Daemon May 24 14:17:21 volumiopi4 volumio[984]: info: Removing browser output: myVolumio user plan is not superstar May 24 14:17:21 volumiopi4 volumio[984]: info: Removing audio output: May 24 14:17:21 volumiopi4 volumio[984]: info: Stoppping Tunnel 1 May 24 14:17:21 volumiopi4 sudo[1912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 24 14:17:21 volumiopi4 sudo[1912]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:17:21 volumiopi4 systemd[1]: systemd-timedated.service: Succeeded. May 24 14:17:21 volumiopi4 sudo[1915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 24 14:17:21 volumiopi4 sudo[1915]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 14:17:21 volumiopi4 sudo[1912]: pam_unix(sudo:session): session closed for user root May 24 14:17:21 volumiopi4 volumio[984]: error: Cannot start Volumio Streaming Daemon May 24 14:17:21 volumiopi4 volumio[984]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 24 14:17:21 volumiopi4 volumio[984]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 24 14:17:21 volumiopi4 sudo[1915]: pam_unix(sudo:session): session closed for user root May 24 14:17:21 volumiopi4 volumio[984]: info: Remote SSH Stopped May 24 14:17:22 volumiopi4 volumio[984]: info: May 24 14:17:22 volumiopi4 volumio[984]: ---------------------------- MPD announces state update: player May 24 14:17:22 volumiopi4 volumio[984]: info: ControllerMpd::getState May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::sendMpdCommand status May 24 14:17:22 volumiopi4 volumio[984]: info: May 24 14:17:22 volumiopi4 volumio[984]: ---------------------------- MPD announces state update: player May 24 14:17:22 volumiopi4 volumio[984]: info: ControllerMpd::getState May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::sendMpdCommand status May 24 14:17:22 volumiopi4 volumio[984]: info: May 24 14:17:22 volumiopi4 volumio[984]: ---------------------------- MPD announces state update: player May 24 14:17:22 volumiopi4 volumio[984]: info: ControllerMpd::getState May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::sendMpdCommand status May 24 14:17:22 volumiopi4 volumio[984]: info: sendMpdCommand status took 42 milliseconds May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::parseState May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 14:17:22 volumiopi4 volumio[984]: info: May 24 14:17:22 volumiopi4 volumio[984]: ---------------------------- MPD announces state update: player May 24 14:17:22 volumiopi4 volumio[984]: info: ControllerMpd::getState May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::sendMpdCommand status May 24 14:17:22 volumiopi4 volumio[984]: info: sendMpdCommand status took 43 milliseconds May 24 14:17:22 volumiopi4 volumio[984]: info: sendMpdCommand status took 42 milliseconds May 24 14:17:22 volumiopi4 volumio[984]: info: sendMpdCommand playlistinfo took 2 milliseconds May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::parseState May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::parseState May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::parseTrackInfo May 24 14:17:22 volumiopi4 volumio[984]: info: ControllerMpd::pushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::servicePushState May 24 14:17:22 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:22 volumiopi4 volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Не люби меня","artist":"CHEPIKK","album":"Не люби меня","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-std-32.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2a2b80372d74a7b80fe259564003dc9632bc74849ac385eb781637e1f024b577%2Clid%3D1539%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68400f14%2F0%2F13583660%2Fdd489b6a.198538428.10.134557735%2F320.mp3&ext=.mp3","trackType":"mp3"} May 24 14:17:22 volumiopi4 volumio[984]: verbose: CURRENT POSITION 0 May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::syncState stateService play May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::syncState currentStatus stop May 24 14:17:22 volumiopi4 volumio[984]: info: ------------------------------ 52ms May 24 14:17:22 volumiopi4 volumio[984]: info: May 24 14:17:22 volumiopi4 volumio[984]: ---------------------------- MPD announces state update: player May 24 14:17:22 volumiopi4 volumio[984]: info: ControllerMpd::getState May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::sendMpdCommand status May 24 14:17:22 volumiopi4 volumio[984]: info: May 24 14:17:22 volumiopi4 volumio[984]: ---------------------------- MPD announces state update: player May 24 14:17:22 volumiopi4 volumio[984]: info: ControllerMpd::getState May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::sendMpdCommand status May 24 14:17:22 volumiopi4 volumio[984]: info: sendMpdCommand status took 10 milliseconds May 24 14:17:22 volumiopi4 volumio[984]: info: sendMpdCommand playlistinfo took 9 milliseconds May 24 14:17:22 volumiopi4 volumio[984]: info: sendMpdCommand playlistinfo took 8 milliseconds May 24 14:17:22 volumiopi4 volumio[984]: info: sendMpdCommand status took 4 milliseconds May 24 14:17:22 volumiopi4 volumio[984]: info: sendMpdCommand status took 2 milliseconds May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::parseState May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::parseTrackInfo May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::parseTrackInfo May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::parseState May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::parseState May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 14:17:22 volumiopi4 volumio[984]: info: ControllerMpd::pushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::servicePushState May 24 14:17:22 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:22 volumiopi4 volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":148,"duration":195,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Не люби меня","artist":"CHEPIKK","album":"Не люби меня","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-std-32.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2a2b80372d74a7b80fe259564003dc9632bc74849ac385eb781637e1f024b577%2Clid%3D1539%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68400f14%2F0%2F13583660%2Fdd489b6a.198538428.10.134557735%2F320.mp3&ext=.mp3","trackType":"mp3"} May 24 14:17:22 volumiopi4 volumio[984]: verbose: CURRENT POSITION 0 May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::syncState stateService play May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::syncState currentStatus play May 24 14:17:22 volumiopi4 volumio[984]: info: Received an update from plugin. extracting info from payload May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:17:22 volumiopi4 volumio[984]: info: ControllerMpd::pushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::servicePushState May 24 14:17:22 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:22 volumiopi4 volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":205,"duration":195,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Не люби меня","artist":"CHEPIKK","album":"Не люби меня","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-std-32.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2a2b80372d74a7b80fe259564003dc9632bc74849ac385eb781637e1f024b577%2Clid%3D1539%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68400f14%2F0%2F13583660%2Fdd489b6a.198538428.10.134557735%2F320.mp3&ext=.mp3","trackType":"mp3"} May 24 14:17:22 volumiopi4 volumio[984]: verbose: CURRENT POSITION 0 May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::syncState stateService play May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::syncState currentStatus play May 24 14:17:22 volumiopi4 volumio[984]: info: Received an update from plugin. extracting info from payload May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:17:22 volumiopi4 volumio[984]: info: ------------------------------ 85ms May 24 14:17:22 volumiopi4 volumio[984]: info: ------------------------------ 85ms May 24 14:17:22 volumiopi4 volumio[984]: info: sendMpdCommand playlistinfo took 32 milliseconds May 24 14:17:22 volumiopi4 volumio[984]: info: sendMpdCommand playlistinfo took 31 milliseconds May 24 14:17:22 volumiopi4 volumio[984]: info: sendMpdCommand playlistinfo took 31 milliseconds May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::parseTrackInfo May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::parseTrackInfo May 24 14:17:22 volumiopi4 volumio[984]: verbose: ControllerMpd::parseTrackInfo May 24 14:17:22 volumiopi4 volumio[984]: info: ControllerMpd::pushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::servicePushState May 24 14:17:22 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:22 volumiopi4 volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":195,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Не люби меня","artist":"CHEPIKK","album":"Не люби меня","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-std-32.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2a2b80372d74a7b80fe259564003dc9632bc74849ac385eb781637e1f024b577%2Clid%3D1539%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68400f14%2F0%2F13583660%2Fdd489b6a.198538428.10.134557735%2F320.mp3&ext=.mp3","trackType":"mp3"} May 24 14:17:22 volumiopi4 volumio[984]: verbose: CURRENT POSITION 0 May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::syncState stateService play May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::syncState currentStatus play May 24 14:17:22 volumiopi4 volumio[984]: info: Received an update from plugin. extracting info from payload May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:17:22 volumiopi4 volumio[984]: info: ControllerMpd::pushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::servicePushState May 24 14:17:22 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:22 volumiopi4 volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":195,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Не люби меня","artist":"CHEPIKK","album":"Не люби меня","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-std-32.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2a2b80372d74a7b80fe259564003dc9632bc74849ac385eb781637e1f024b577%2Clid%3D1539%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68400f14%2F0%2F13583660%2Fdd489b6a.198538428.10.134557735%2F320.mp3&ext=.mp3","trackType":"mp3"} May 24 14:17:22 volumiopi4 volumio[984]: verbose: CURRENT POSITION 0 May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::syncState stateService play May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::syncState currentStatus play May 24 14:17:22 volumiopi4 volumio[984]: info: Received an update from plugin. extracting info from payload May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:17:22 volumiopi4 volumio[984]: info: ControllerMpd::pushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::servicePushState May 24 14:17:22 volumiopi4 volumio[984]: info: CorePlayQueue::getTrack 0 May 24 14:17:22 volumiopi4 volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":195,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Не люби меня","artist":"CHEPIKK","album":"Не люби меня","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-std-32.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2a2b80372d74a7b80fe259564003dc9632bc74849ac385eb781637e1f024b577%2Clid%3D1539%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68400f14%2F0%2F13583660%2Fdd489b6a.198538428.10.134557735%2F320.mp3&ext=.mp3","trackType":"mp3"} May 24 14:17:22 volumiopi4 volumio[984]: verbose: CURRENT POSITION 0 May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::syncState stateService play May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::syncState currentStatus play May 24 14:17:22 volumiopi4 volumio[984]: info: Received an update from plugin. extracting info from payload May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:17:22 volumiopi4 volumio[984]: info: ------------------------------ 89ms May 24 14:17:22 volumiopi4 volumio[984]: info: ------------------------------ 83ms May 24 14:17:22 volumiopi4 volumio[984]: info: ------------------------------ 84ms May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CorePlayQueue::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CorePlayQueue::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CorePlayQueue::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CorePlayQueue::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CorePlayQueue::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CorePlayQueue::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CorePlayQueue::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CorePlayQueue::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CorePlayQueue::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CoreStateMachine::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: CorePlayQueue::getQueue May 24 14:17:22 volumiopi4 volumio[984]: info: Setting Geolocation for MyVolumio to as1 May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:17:22 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:17:22 volumiopi4 volumio[984]: info: touch_display: Setting screensaver timeout to 0 seconds. May 24 14:17:23 volumiopi4 volumio[984]: info: Updating MyVolumio device info May 24 14:17:23 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:17:23 volumiopi4 volumio[984]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 24 14:17:24 volumiopi4 volumio[984]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 24 14:17:24 volumiopi4 volumio[984]: info: [now-playing] OpenWeatherMap API key obtained. May 24 14:17:27 volumiopi4 volumio[984]: info: MYVOLUMIO: Adding device May 24 14:17:27 volumiopi4 volumio[984]: info: MYVOLUMIO: Evaluating Server May 24 14:17:28 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 24 14:17:28 volumiopi4 volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 24 14:17:28 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetState May 24 14:17:29 volumiopi4 volumio[984]: info: Setting Geolocation for MyVolumio to eu10 May 24 14:17:29 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:17:30 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 14:17:30 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:17:30 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 24 14:17:30 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 24 14:17:30 volumiopi4 volumio-remote-updater[583]: No test mode May 24 14:17:30 volumiopi4 volumio-remote-updater[583]: No alpha test mode May 24 14:17:30 volumiopi4 volumio[984]: info: Updating MyVolumio device info May 24 14:17:30 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:17:31 volumiopi4 volumio[984]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 24 14:17:32 volumiopi4 volumio[984]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 24 14:17:33 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:33 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:33 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 24 14:17:33 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 24 14:17:33 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 24 14:17:33 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetBrowseSources May 24 14:17:33 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 14:17:34 volumiopi4 volumio[984]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 24 14:17:34 volumiopi4 volumio[984]: info: Completed starting MyVolumio Plugin May 24 14:17:34 volumiopi4 volumio[984]: [Metrics] CommandRouter: 41s 645.73ms May 24 14:17:34 volumiopi4 volumio[984]: info: CoreCommandRouter::volumiosetStartupVolume May 24 14:17:34 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 14:17:34 volumiopi4 volumio[984]: info: VolumeController:: Setting startup Volume 15 May 24 14:17:34 volumiopi4 volumio[984]: info: VolumeController::SetAlsaVolume15 May 24 14:17:34 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:17:34 volumiopi4 volumio[984]: info: CoreCommandRouter::Close All Modals sent May 24 14:17:34 volumiopi4 volumio[984]: info: CoreCommandRouter::Close All Modals sent May 24 14:17:34 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:17:34 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 14:17:34 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:17:34 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetQueue May 24 14:17:34 volumiopi4 volumio[984]: info: CoreStateMachine::getQueue May 24 14:17:34 volumiopi4 volumio[984]: info: CorePlayQueue::getQueue May 24 14:17:34 volumiopi4 volumio[984]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 24 14:17:34 volumiopi4 volumio[984]: aplay: main:828: audio open error: Device or resource busy May 24 14:17:35 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 24 14:17:35 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 14:17:35 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 24 14:17:38 volumiopi4 volumio[984]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Various Fixes for QOBUZ Connect
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Qobuz Connect Ready
    • \n
\n","title":"Update v3.812","updateavailable":true} May 24 14:17:38 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 24 14:17:38 volumiopi4 ntpd[787]: Soliciting pool server 178.208.255.95 May 24 14:17:39 volumiopi4 ntpd[787]: Soliciting pool server 162.159.200.1 May 24 14:17:40 volumiopi4 ntpd[787]: Soliciting pool server 89.169.135.41 May 24 14:17:40 volumiopi4 ntpd[787]: Soliciting pool server 91.206.16.3 May 24 14:17:41 volumiopi4 ntpd[787]: Soliciting pool server 93.95.100.85 May 24 14:17:41 volumiopi4 ntpd[787]: Soliciting pool server 91.201.254.110 May 24 14:17:41 volumiopi4 ntpd[787]: Soliciting pool server 45.141.102.99 May 24 14:17:41 volumiopi4 volumio[984]: info: BOOT COMPLETED May 24 14:17:41 volumiopi4 ntpd[787]: Soliciting pool server 213.108.170.26 May 24 14:17:42 volumiopi4 ntpd[787]: Soliciting pool server 45.90.217.6 May 24 14:17:42 volumiopi4 ntpd[787]: Soliciting pool server 94.141.168.72 May 24 14:17:42 volumiopi4 ntpd[787]: Soliciting pool server 185.104.249.225 May 24 14:17:42 volumiopi4 ntpd[787]: Soliciting pool server 46.138.254.88 May 24 14:17:43 volumiopi4 ntpd[787]: Soliciting pool server 45.143.94.136 May 24 14:17:43 volumiopi4 ntpd[787]: Soliciting pool server 2001:67c:205c:11::84 May 24 14:17:43 volumiopi4 ntpd[787]: Soliciting pool server 82.146.53.58 May 24 14:17:44 volumiopi4 ntpd[787]: Soliciting pool server 92.241.18.100 May 24 14:17:44 volumiopi4 ntpd[787]: Soliciting pool server 83.217.193.36 May 24 14:17:47 volumiopi4 ntpd[787]: receive: Unexpected origin timestamp 0xebdc295a.2977ea4b does not match aorg 0000000000.00000000 from server@93.95.100.85 xmt 0xebdc295b.29b96576 May 24 14:17:47 volumiopi4 ntpd[787]: receive: Unexpected origin timestamp 0xebdc295a.29792f52 does not match aorg 0000000000.00000000 from server@45.141.102.99 xmt 0xebdc295b.29a08fbe May 24 14:17:47 volumiopi4 ntpd[787]: receive: Unexpected origin timestamp 0xebdc295a.296e2845 does not match aorg 0000000000.00000000 from server@92.241.18.100 xmt 0xebdc295b.29671bec May 24 14:17:47 volumiopi4 ntpd[787]: receive: Unexpected origin timestamp 0xebdc295a.2976f99d does not match aorg 0000000000.00000000 from server@91.201.254.110 xmt 0xebdc295b.2a255544 May 24 14:17:47 volumiopi4 ntpd[787]: receive: Unexpected origin timestamp 0xebdc295a.2971cd07 does not match aorg 0000000000.00000000 from server@45.143.94.136 xmt 0xebdc295b.2aa7a842 May 24 14:17:47 volumiopi4 ntpd[787]: receive: Unexpected origin timestamp 0xebdc295a.29751771 does not match aorg 0000000000.00000000 from server@213.108.170.26 xmt 0xebdc295b.2af0ae53 May 24 14:17:47 volumiopi4 ntpd[787]: receive: Unexpected origin timestamp 0xebdc295a.297bc445 does not match aorg 0000000000.00000000 from server@178.208.255.95 xmt 0xebdc295b.2ac51538 May 24 14:17:50 volumiopi4 volumio[984]: info: VolumeController::SetAlsaVolume74 May 24 14:17:50 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:17:50 volumiopi4 volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 14:17:50 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:17:50 volumiopi4 volumio[984]: info: VolumeController::SetAlsaVolume74 May 24 14:17:50 volumiopi4 volumio[984]: info: CoreStateMachine::pushState May 24 14:17:50 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioPushState May 24 14:17:50 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetQueue May 24 14:17:50 volumiopi4 volumio[984]: info: CoreStateMachine::getQueue May 24 14:17:50 volumiopi4 volumio[984]: info: CorePlayQueue::getQueue May 24 14:17:50 volumiopi4 volumio[984]: info: CoreCommandRouter::volumioGetQueue May 24 14:17:50 volumiopi4 volumio[984]: info: CoreStateMachine::getQueue May 24 14:17:50 volumiopi4 volumio[984]: info: CorePlayQueue::getQueue May 24 14:17:52 volumiopi4 volumio[984]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 14:17:52 volumiopi4 volumio[984]: Error: read ECONNRESET May 24 14:17:52 volumiopi4 volumio[984]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { May 24 14:17:52 volumiopi4 volumio[984]: errno: -104, May 24 14:17:52 volumiopi4 volumio[984]: code: 'ECONNRESET', May 24 14:17:52 volumiopi4 volumio[984]: syscall: 'read' May 24 14:17:52 volumiopi4 volumio[984]: } May 24 14:17:52 volumiopi4 volumio[984]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 14:17:52 volumiopi4 sudo[2109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 14:16 May 24 14:17:52 volumiopi4 sudo[2109]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"