-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Sun 2025-07-06 17:40:24 MSK. -- Jul 06 17:39:13 volumio systemd-timedated[957]: Changed local time to Sun Jul 6 17:39:13 2025 Jul 06 17:39:13 volumio sudo[953]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:13 volumio volumio-time-update[547]: volumio-time-update-util: System time updated successfully. Jul 06 17:39:13 volumio systemd[1]: Started Volumio Time Update Utility. Jul 06 17:39:13 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 06 17:39:13 volumio ntpd[772]: Listen normally on 3 wlan0 192.168.100.4:123 Jul 06 17:39:13 volumio ntpd[772]: new interface(s) found: waking up resolver Jul 06 17:39:14 volumio nmbd[751]: [2025/07/06 17:39:14.262101, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 06 17:39:14 volumio nmbd[751]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 06 17:39:14 volumio systemd[1]: Started Samba NMB Daemon. Jul 06 17:39:14 volumio systemd[1]: Starting Samba Winbind Daemon... Jul 06 17:39:14 volumio winbindd[976]: [2025/07/06 17:39:14.526058, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 06 17:39:14 volumio winbindd[976]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 06 17:39:14 volumio winbindd[976]: [2025/07/06 17:39:14.549066, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 06 17:39:14 volumio systemd[1]: Started Samba Winbind Daemon. Jul 06 17:39:14 volumio winbindd[976]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 06 17:39:14 volumio systemd[1]: Starting Samba SMB Daemon... Jul 06 17:39:14 volumio volumio[958]: info: ------------------------------------------- Jul 06 17:39:14 volumio volumio[958]: info: ----- Volumio3 ---- Jul 06 17:39:14 volumio volumio[958]: info: ------------------------------------------- Jul 06 17:39:14 volumio volumio[958]: info: ----- System startup ---- Jul 06 17:39:14 volumio volumio[958]: info: ------------------------------------------- Jul 06 17:39:14 volumio systemd[1]: Started Samba SMB Daemon. Jul 06 17:39:14 volumio systemd[1]: Reached target Multi-User System. Jul 06 17:39:14 volumio systemd[1]: Reached target Graphical Interface. Jul 06 17:39:14 volumio smbd[989]: [2025/07/06 17:39:14.960116, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 06 17:39:14 volumio smbd[989]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 06 17:39:15 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 06 17:39:15 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 06 17:39:15 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 06 17:39:15 volumio systemd[1]: Startup finished in 11.851s (kernel) + 21.169s (userspace) = 33.020s. Jul 06 17:39:15 volumio volumio[958]: info: MYVOLUMIO Environment detected Jul 06 17:39:15 volumio volumio[958]: info: Plugin folders cleanup Jul 06 17:39:15 volumio volumio[958]: info: Scanning into folder /volumio/app/plugins/ Jul 06 17:39:15 volumio volumio[958]: info: Scanning category audio_interface Jul 06 17:39:15 volumio volumio[958]: info: Scanning category miscellanea Jul 06 17:39:15 volumio volumio[958]: info: Scanning category music_service Jul 06 17:39:15 volumio volumio[958]: info: Scanning category plugins.json Jul 06 17:39:15 volumio volumio[958]: info: Scanning category system_controller Jul 06 17:39:15 volumio volumio[958]: info: Scanning category user_interface Jul 06 17:39:15 volumio volumio[958]: info: Scanning into folder /data/plugins/ Jul 06 17:39:15 volumio volumio[958]: info: Scanning category audio_interface Jul 06 17:39:15 volumio volumio[958]: info: Scanning category music_service Jul 06 17:39:15 volumio volumio[958]: info: Scanning category system_controller Jul 06 17:39:15 volumio volumio[958]: info: Scanning category system_hardware Jul 06 17:39:15 volumio volumio[958]: info: Scanning category user_interface Jul 06 17:39:15 volumio volumio[958]: info: Plugin folders cleanup completed Jul 06 17:39:15 volumio volumio[958]: info: ------------------------------------------- Jul 06 17:39:15 volumio volumio[958]: info: ----- Core plugins startup ---- Jul 06 17:39:15 volumio volumio[958]: info: ------------------------------------------- Jul 06 17:39:15 volumio volumio[958]: info: Loading plugins from folder /volumio/app/plugins/ Jul 06 17:39:15 volumio volumio[958]: info: Adding plugin upnp to MyMusic Plugins Jul 06 17:39:15 volumio volumio[958]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 06 17:39:15 volumio volumio[958]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 06 17:39:15 volumio volumio[958]: info: Loading plugins from folder /data/plugins/ Jul 06 17:39:15 volumio volumio[958]: info: Loading plugin "system"... Jul 06 17:39:15 volumio volumio[958]: info: Loading plugin "appearance"... Jul 06 17:39:16 volumio volumio[958]: info: Loading plugin "network"... Jul 06 17:39:16 volumio volumio[958]: info: Refreshing Cached IP Addresses Jul 06 17:39:16 volumio sudo[1004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 06 17:39:16 volumio sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:16 volumio sudo[1004]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:16 volumio sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 06 17:39:16 volumio sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:16 volumio sudo[1006]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:16 volumio volumio[958]: info: Loading plugin "services"... Jul 06 17:39:16 volumio volumio[958]: info: Loading plugin "alsa_controller"... Jul 06 17:39:16 volumio sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 06 17:39:16 volumio sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:16 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 17:39:16 volumio volumio[958]: info: Loading plugin "wizard"... Jul 06 17:39:16 volumio volumio[958]: info: Loading plugin "networkfs"... Jul 06 17:39:16 volumio volumio[958]: info: Starting Udev Watcher for removable devices Jul 06 17:39:16 volumio volumio[958]: info: Ignoring mount for partition: boot Jul 06 17:39:16 volumio volumio[958]: info: Ignoring mount for partition: volumio Jul 06 17:39:16 volumio volumio[958]: info: Ignoring mount for partition: volumio_data Jul 06 17:39:16 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 06 17:39:16 volumio volumio[958]: info: Loading plugin "volumio_command_line_client"... Jul 06 17:39:16 volumio volumio[958]: info: Loading plugin "upnp"... Jul 06 17:39:16 volumio volumio[958]: info: [1751812756852] Starting Upmpd Daemon Jul 06 17:39:16 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 06 17:39:16 volumio volumio[958]: info: Loading plugin "my_music"... Jul 06 17:39:16 volumio volumio[958]: info: Loading plugin "mpd"... Jul 06 17:39:17 volumio volumio[958]: info: Loading plugin "upnp_browser"... Jul 06 17:39:17 volumio volumio[958]: info: Loading plugin "alarm-clock"... Jul 06 17:39:17 volumio volumio[958]: info: Loading plugin "airplay_emulation"... Jul 06 17:39:17 volumio volumio[958]: info: Starting Shairport Sync Jul 06 17:39:17 volumio volumio[958]: info: Loading plugin "last_100"... Jul 06 17:39:17 volumio volumio[958]: info: Loading plugin "webradio"... Jul 06 17:39:17 volumio volumio[958]: info: Loading plugin "i2s_dacs"... Jul 06 17:39:17 volumio volumio[958]: info: I2S DAC not set, start Auto-detection Jul 06 17:39:17 volumio volumio[958]: info: Loading plugin "volumiodiscovery"... Jul 06 17:39:17 volumio volumio[958]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 06 17:39:17 volumio volumio[958]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 06 17:39:17 volumio volumio[958]: *** WARNING *** For more information see Jul 06 17:39:17 volumio node[958]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 06 17:39:17 volumio volumio[958]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 06 17:39:17 volumio volumio[958]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 06 17:39:17 volumio volumio[958]: *** WARNING *** For more information see Jul 06 17:39:17 volumio node[958]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 06 17:39:17 volumio node[958]: *** WARNING *** For more information see Jul 06 17:39:17 volumio node[958]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 06 17:39:17 volumio node[958]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 06 17:39:17 volumio node[958]: *** WARNING *** For more information see Jul 06 17:39:17 volumio volumio[958]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 06 17:39:17 volumio volumio[958]: info: Discovery: Started advertising with name: Volumio Jul 06 17:39:17 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 06 17:39:17 volumio volumio[958]: info: Loading plugin "spop"... Jul 06 17:39:18 volumio sudo[1015]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:19 volumio volumio[958]: info: Loading plugin "gpio-buttons"... Jul 06 17:39:19 volumio volumio[958]: info: GPIO-Buttons initialized Jul 06 17:39:19 volumio volumio[958]: info: Loading plugin "outputs"... Jul 06 17:39:19 volumio volumio[958]: info: Loading plugin "albumart"... Jul 06 17:39:19 volumio volumio[958]: info: Plugin example_plugin is not enabled Jul 06 17:39:19 volumio volumio[958]: info: Loading plugin "inputs"... Jul 06 17:39:19 volumio volumio[958]: info: Loading plugin "updater_comm"... Jul 06 17:39:19 volumio volumio[958]: info: Plugin mpdemulation is not enabled Jul 06 17:39:19 volumio volumio[958]: info: Loading plugin "rest_api"... Jul 06 17:39:19 volumio volumio[958]: info: Loading plugin "websocket"... Jul 06 17:39:19 volumio volumio[958]: info: Starting Socket.io Server version 2.3.0 Jul 06 17:39:19 volumio volumio[958]: info: Loading plugin "fusiondsp"... Jul 06 17:39:19 volumio volumio[958]: Forking 3 albumart workers Jul 06 17:39:20 volumio volumio[958]: info: Applying required configuration parameters for plugin fusiondsp Jul 06 17:39:20 volumio volumio-remote-updater[576]: [2025-07-06 17:39:20] [connect] Successful connection Jul 06 17:39:20 volumio volumio[958]: info: Loading plugin "RoonBridge"... Jul 06 17:39:20 volumio volumio[958]: Starting albumart workers Jul 06 17:39:20 volumio volumio[958]: Starting albumart workers Jul 06 17:39:20 volumio volumio[958]: Starting albumart workers Jul 06 17:39:20 volumio volumio[958]: info: Applying required configuration parameters for plugin RoonBridge Jul 06 17:39:20 volumio volumio[958]: info: Loading plugin "minidlna"... Jul 06 17:39:20 volumio volumio[958]: info: Applying required configuration parameters for plugin minidlna Jul 06 17:39:20 volumio volumio[958]: info: Plugin pandora is not enabled Jul 06 17:39:20 volumio volumio[958]: info: Loading plugin "radio_paradise"... Jul 06 17:39:21 volumio volumio[958]: info: Applying required configuration parameters for plugin radio_paradise Jul 06 17:39:21 volumio volumio[958]: info: [1751812761052] [RadioParadise] API delay: 5 Jul 06 17:39:21 volumio volumio[958]: info: Loading plugin "yandex_music"... Jul 06 17:39:21 volumio volumio[958]: info: Applying required configuration parameters for plugin yandex_music Jul 06 17:39:21 volumio volumio[958]: info: Loading plugin "backup_restore"... Jul 06 17:39:22 volumio volumio[958]: info: Applying required configuration parameters for plugin backup_restore Jul 06 17:39:22 volumio volumio[958]: info: Loading plugin "ir_controller"... Jul 06 17:39:22 volumio volumio[958]: info: Applying required configuration parameters for plugin ir_controller Jul 06 17:39:22 volumio volumio[958]: info: Loading plugin "Systeminfo"... Jul 06 17:39:22 volumio volumio[958]: info: Loading i18n strings for locale ru Jul 06 17:39:22 volumio volumio[958]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jul 06 17:39:22 volumio volumio[958]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Jul 06 17:39:22 volumio volumio[958]: Updating browse sources language Jul 06 17:39:22 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 17:39:22 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 06 17:39:22 volumio volumio[958]: info: CoreCommandRouter::initPlayerControls Jul 06 17:39:22 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:22 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 06 17:39:22 volumio volumio[958]: Express server listening on port 3000 Jul 06 17:39:22 volumio volumio[958]: [Metrics] WebUI: 8s 827.46ms Jul 06 17:39:22 volumio volumio[958]: info: CoreStateMachine::resetVolumioState Jul 06 17:39:22 volumio volumio[958]: info: CoreStateMachine::getcurrentVolume Jul 06 17:39:22 volumio volumio[958]: info: CoreCommandRouter::volumioRetrievevolume Jul 06 17:39:22 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:22 volumio volumio[958]: info: Volumio Network Manager: Network status updated: 2 Jul 06 17:39:22 volumio volumio[958]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.3 UA: Mozilla/5.0 (Linux; Android 11; K48 Build/R01005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 06 17:39:22 volumio volumio-remote-updater[576]: [2025-07-06 17:39:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751812760 101 Jul 06 17:39:22 volumio volumio[958]: 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: 2 Jul 06 17:39:22 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 17:39:22 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 17:39:22 volumio volumio[958]: info: Discovery: Getting this device information Jul 06 17:39:22 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:22 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:22 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 17:39:23 volumio volumio[958]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 06 17:39:23 volumio volumio[958]: info: VolumeController:: Volume=100 Mute =false Jul 06 17:39:23 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:39:23 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:39:23 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jul 06 17:39:23 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::volumioRetrievevolume Jul 06 17:39:23 volumio volumio[958]: info: Reloading queue from file Jul 06 17:39:23 volumio volumio[958]: info: Setting Device type: Raspberry PI Jul 06 17:39:23 volumio volumio[958]: info: CoreStateMachine::setRepeat null single undefined Jul 06 17:39:23 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:39:23 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:39:23 volumio volumio[958]: info: CoreStateMachine::setRandom null Jul 06 17:39:23 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:39:23 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:39:23 volumio volumio[958]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 06 17:39:23 volumio volumio[958]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03112 Jul 06 17:39:23 volumio volumio[958]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 06 17:39:23 volumio volumio[958]: verbose: New Socket.io Connection to 192.168.100.4:3000 from 192.168.100.3 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Jul 06 17:39:23 volumio volumio[958]: info: VolumeController:: Volume=100 Mute =false Jul 06 17:39:23 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:39:23 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:23 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 06 17:39:23 volumio volumio[958]: info: Completed loading Core Plugins Jul 06 17:39:23 volumio volumio[958]: info: Preparing to generate the ALSA configuration file Jul 06 17:39:23 volumio volumio[958]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 06 17:39:23 volumio volumio[958]: info: Reading ALSA contributions from plugins. Jul 06 17:39:23 volumio volumio[958]: info: Asound.conf file unchanged, so no further update is needed Jul 06 17:39:23 volumio volumio[958]: info: Output device has changed, restarting MPD Jul 06 17:39:23 volumio sudo[1102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 06 17:39:23 volumio volumio[958]: info: Output device has changed, restarting Shairport Sync Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:23 volumio sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:23 volumio sudo[1102]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:23 volumio sudo[1104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 06 17:39:23 volumio sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:23 volumio systemd[1]: Stopping Music Player Daemon... Jul 06 17:39:23 volumio volumio[958]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 06 17:39:23 volumio volumio[958]: info: ___________ START PLUGINS ___________ Jul 06 17:39:23 volumio volumio[958]: info: ControllerMpd::onStart: Initializing MPD Jul 06 17:39:23 volumio volumio[958]: info: Creating MPD Configuration file Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 06 17:39:23 volumio volumio[958]: info: [1751812763322] CoreMusicLibrary::Adding element Медиа-серверы Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 17:39:23 volumio sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 06 17:39:23 volumio sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:23 volumio sudo[1111]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:23 volumio sudo[1113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 06 17:39:23 volumio sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:23 volumio volumio[958]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:23 volumio systemd[1]: mpd.service: Succeeded. Jul 06 17:39:23 volumio systemd[1]: Stopped Music Player Daemon. Jul 06 17:39:23 volumio volumio[958]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 06 17:39:23 volumio volumio[958]: info: [1751812763416] CoreMusicLibrary::Adding element Last_100 Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 06 17:39:23 volumio volumio[958]: info: [1751812763418] CoreMusicLibrary::Adding element Webradio Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 17:39:23 volumio volumio[958]: info: Initializing BBC Radios Jul 06 17:39:23 volumio systemd[1]: Starting Music Player Daemon... Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:23 volumio volumio[958]: info: Creating Spotify config file Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:23 volumio volumio[958]: info: GPIO-Buttons: Reading config and creating triggers... Jul 06 17:39:23 volumio volumio[958]: info: Loading i18n strings for locale ru Jul 06 17:39:23 volumio sudo[1120]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 06 17:39:23 volumio sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:23 volumio volumio[958]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jul 06 17:39:23 volumio volumio[958]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:23 volumio volumio[958]: info: FusionDsp - mixtype--------------------- Hardware Jul 06 17:39:23 volumio sudo[1120]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:23 volumio volumio[958]: info: Loading i18n strings for locale ru Jul 06 17:39:23 volumio volumio[958]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jul 06 17:39:23 volumio sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jul 06 17:39:23 volumio sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:23 volumio volumio[958]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Jul 06 17:39:23 volumio systemd[1]: Started RoonBridge. Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 06 17:39:23 volumio sudo[1141]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:23 volumio volumio[958]: info: [1751812763593] CoreMusicLibrary::Adding element Radio Paradise Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 17:39:23 volumio volumio[958]: Cannot find translation for source Radio Paradise Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 06 17:39:23 volumio volumio[958]: info: [1751812763599] CoreMusicLibrary::Adding element Яндекс Музыка Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 17:39:23 volumio volumio[958]: Cannot find translation for source Radio Paradise Jul 06 17:39:23 volumio volumio[958]: Cannot find translation for source Яндекс Музыка Jul 06 17:39:23 volumio volumio[958]: info: Loading i18n strings for locale ru Jul 06 17:39:23 volumio volumio[958]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jul 06 17:39:23 volumio volumio[958]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 17:39:23 volumio volumio[958]: info: Volumio Calling Home Jul 06 17:39:23 volumio volumio[958]: info: GPIO-Buttons started Jul 06 17:39:23 volumio volumio[958]: info: Preparing to generate the ALSA configuration file Jul 06 17:39:23 volumio sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Jul 06 17:39:23 volumio sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:23 volumio sudo[1160]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:23 volumio volumio[958]: info: Discovery: adding 2f1897fc-f11c-4839-8b99-0e2d95999821 Jul 06 17:39:23 volumio volumio[958]: info: Discovery: Found device Volumio Jul 06 17:39:23 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:23 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:23 volumio volumio[958]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 06 17:39:23 volumio volumio[958]: info: Reading ALSA contributions from plugins. Jul 06 17:39:24 volumio volumio[958]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Jul 06 17:39:24 volumio volumio[958]: info: minidlna: Found miniDLNA version 1.2.1 Jul 06 17:39:24 volumio volumio[958]: info: minidlna: Starting minidlna.service Jul 06 17:39:24 volumio volumio[958]: info: MPD Permissions set Jul 06 17:39:24 volumio volumio[958]: info: MPD Permissions set Jul 06 17:39:24 volumio volumio[958]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 06 17:39:24 volumio sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Jul 06 17:39:24 volumio sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:24 volumio volumio[958]: info: Spotify config file written Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:24 volumio sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 06 17:39:24 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:24 volumio sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:24 volumio sudo[1164]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:24 volumio systemd[1]: Started go-librespot Daemon. Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:24 volumio volumio[958]: info: No need to fix Spotify hosts Jul 06 17:39:24 volumio sudo[1167]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:24 volumio go-librespot[1178]: go-librespot daemon starting... Jul 06 17:39:24 volumio volumio[958]: info: minidlna: systemctl start minidlna.service succeeded. Jul 06 17:39:24 volumio volumio[958]: info: Volumio called home Jul 06 17:39:24 volumio volumio[958]: info: Starting Shairport Sync Jul 06 17:39:24 volumio volumio[958]: info: Starting Shairport Sync Jul 06 17:39:24 volumio sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 06 17:39:24 volumio sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:24 volumio volumio[958]: info: Starting Shairport Sync Jul 06 17:39:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 06 17:39:24 volumio sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 06 17:39:24 volumio sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:24 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 06 17:39:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 06 17:39:24 volumio volumio[958]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.3 UA: Mozilla/5.0 (Linux; Android 11; K48 Build/R01005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 06 17:39:24 volumio sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 06 17:39:24 volumio sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 06 17:39:24 volumio sudo[1181]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:24 volumio sudo[1183]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 06 17:39:24 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 06 17:39:24 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 06 17:39:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 06 17:39:24 volumio volumio[958]: info: ir_controller: LIRC correctly updated. Jul 06 17:39:24 volumio volumio[958]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver! Jul 06 17:39:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 06 17:39:24 volumio mpd[1139]: Jul 06 17:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 06 17:39:24 volumio sudo[1192]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:24 volumio systemd[1]: Started Music Player Daemon. Jul 06 17:39:24 volumio volumio[958]: info: Asound.conf file unchanged, so no further update is needed Jul 06 17:39:24 volumio volumio[958]: info: Output device has changed, restarting MPD Jul 06 17:39:24 volumio sudo[1113]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:24 volumio sudo[1104]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:24 volumio volumio[958]: info: Output device has changed, restarting Shairport Sync Jul 06 17:39:24 volumio sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 06 17:39:24 volumio sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:24 volumio sudo[1206]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:24 volumio sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 06 17:39:24 volumio sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:24 volumio systemd[1]: Stopping Music Player Daemon... Jul 06 17:39:24 volumio systemd[1]: mpd.service: Succeeded. Jul 06 17:39:24 volumio volumio[958]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 06 17:39:24 volumio systemd[1]: Stopped Music Player Daemon. Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:24 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:24 volumio volumio[958]: info: MPD Permissions set Jul 06 17:39:24 volumio volumio[958]: info: ir_controller: Raspberry Pi revision code: b03112 Jul 06 17:39:24 volumio systemd[1]: Starting Music Player Daemon... Jul 06 17:39:24 volumio volumio[958]: info: Completed starting Core Plugins Jul 06 17:39:24 volumio volumio[958]: info: ------------------------------------------- Jul 06 17:39:24 volumio volumio[958]: info: ----- MyVolumio plugins startup ---- Jul 06 17:39:24 volumio volumio[958]: info: ------------------------------------------- Jul 06 17:39:24 volumio volumio[958]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 06 17:39:24 volumio volumio[958]: info: Shairport-Sync Started Jul 06 17:39:24 volumio volumio[958]: Error adding Membership: Error: addMembership EINVAL Jul 06 17:39:24 volumio volumio[958]: info: Shairport-Sync Started Jul 06 17:39:24 volumio volumio[958]: info: Shairport-Sync Started Jul 06 17:39:24 volumio sudo[1216]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 06 17:39:24 volumio sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:24 volumio sudo[1216]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:24 volumio volumio[958]: error: Cannot initialize MPD Connection: MPD is not running Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:24 volumio go-librespot[1178]: time="2025-07-06T17:39:24+03:00" level=info msg="running go-librespot 0.2.0" Jul 06 17:39:24 volumio go-librespot[1178]: time="2025-07-06T17:39:24+03:00" level=debug msg="app state loaded" Jul 06 17:39:24 volumio go-librespot[1178]: time="2025-07-06T17:39:24+03:00" level=debug msg="stored credentials not found" Jul 06 17:39:24 volumio go-librespot[1178]: time="2025-07-06T17:39:24+03:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:24 volumio volumio[958]: info: Starting Shairport Sync Jul 06 17:39:24 volumio sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 06 17:39:24 volumio sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 06 17:39:24 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 06 17:39:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 06 17:39:24 volumio sudo[1231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r -1 Jul 06 17:39:24 volumio sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 06 17:39:24 volumio sudo[1231]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 06 17:39:24 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:24 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:24 volumio sudo[1228]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:24 volumio volumio[958]: info: Shairport-Sync Started Jul 06 17:39:24 volumio sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-ir gpio_pin=25 gpio_pull=up Jul 06 17:39:24 volumio sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:25 volumio kernel: Registered IR keymap rc-rc6-mce Jul 06 17:39:25 volumio sudo[1240]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:25 volumio volumio[958]: info: ir_controller: Overlay gpio-ir gpio_pin=25 gpio_pull=up loaded. Jul 06 17:39:25 volumio kernel: IR RC6 protocol handler initialized Jul 06 17:39:25 volumio RoonBridge[1145]: 00:00:00.018 Warn: get lock file path: /tmp/.rnbgem0- Jul 06 17:39:25 volumio sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jul 06 17:39:25 volumio sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:25 volumio kernel: rc rc0: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0 Jul 06 17:39:25 volumio kernel: rc rc0: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter Jul 06 17:39:25 volumio kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0/input1 Jul 06 17:39:25 volumio systemd[1]: Starting lircd(8) initialization helper tool... Jul 06 17:39:25 volumio systemd-udevd[1256]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 06 17:39:25 volumio systemd-logind[577]: Watching system buttons on /dev/input/event1 (gpio_ir_recv) Jul 06 17:39:25 volumio RoonBridge[1145]: 00:00:00.479 Trace: [childprocess] using unix child process Jul 06 17:39:25 volumio sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jul 06 17:39:25 volumio sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:25 volumio RoonBridge[1145]: 00:00:00.566 Debug: PathForResource, filename: ../.update Jul 06 17:39:25 volumio lircd-setup[1260]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Jul 06 17:39:25 volumio lircd-setup[1260]: parser = configparser.SafeConfigParser() Jul 06 17:39:25 volumio systemd[1]: lircd-setup.service: Succeeded. Jul 06 17:39:25 volumio systemd[1]: Started lircd(8) initialization helper tool. Jul 06 17:39:25 volumio RoonBridge[1145]: 00:00:00.657 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Jul 06 17:39:25 volumio RoonBridge[1145]: 00:00:00.665 Debug: PathForResource, filename: RAATServer Jul 06 17:39:25 volumio RoonBridge[1145]: 00:00:00.665 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jul 06 17:39:25 volumio RoonBridge[1145]: 00:00:00.666 Debug: PathForResource, filename: RoonBridgeHelper Jul 06 17:39:25 volumio RoonBridge[1145]: 00:00:00.666 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jul 06 17:39:25 volumio RoonBridge[1145]: Initializing Jul 06 17:39:25 volumio volumio[958]: info: camilladsp service started and running in background, instance 1 Jul 06 17:39:25 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:25 volumio systemd[1]: Starting Flexible IR remote input/output application support... Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Info: lircd: Opening log, level: Info Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Version: lircd 0.10.1 Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: System info: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l GNU/Linux Jul 06 17:39:25 volumio RoonBridge[1145]: 00:00:00.724 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jul 06 17:39:25 volumio volumio[958]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 768000 <-AAAAAAAAAAAAA Jul 06 17:39:25 volumio volumio[958]: info: FusionDsp loaded Jul 06 17:39:25 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 17:39:25 volumio RoonBridge[1145]: 00:00:00.777 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jul 06 17:39:25 volumio sudo[1284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 06 17:39:25 volumio sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:25 volumio mpd[1222]: Jul 06 17:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 06 17:39:25 volumio systemd[1]: Started Music Player Daemon. Jul 06 17:39:25 volumio sudo[1209]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:25 volumio volumio[958]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 06 17:39:25 volumio volumio[958]: info: Adding Signal Path Element [object Object] Jul 06 17:39:25 volumio volumio[958]: info: Adding fusiondspeq DSP Signal Path Element Jul 06 17:39:25 volumio volumio[958]: info: FusionDsp - ---- installed callbackRead Jul 06 17:39:25 volumio volumio[958]: info: CoreCommandRouter::volumioGetVisibleSources Jul 06 17:39:25 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 17:39:25 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:25 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:25 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 06 17:39:25 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 06 17:39:25 volumio volumio[958]: info: Received Get System Info Jul 06 17:39:25 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 17:39:25 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 17:39:25 volumio volumio[958]: info: Discovery: Getting this device information Jul 06 17:39:25 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:25 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:25 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 17:39:25 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:25 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:25 volumio systemd[1]: Started FusionDsp Daemon. Jul 06 17:39:25 volumio sudo[1284]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:25 volumio RoonBridge[1145]: Not Running (.o) Jul 06 17:39:25 volumio volumio[958]: info: Listing playlists Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Info: Initial device: /dev/lirc0 Jul 06 17:39:25 volumio lircd[1268]: lircd-0.10.1[1268]: Info: lircd: Opening log, level: Info Jul 06 17:39:25 volumio lircd[1268]: lircd-0.10.1[1268]: Notice: Using systemd fd Jul 06 17:39:25 volumio lircd[1268]: lircd-0.10.1[1268]: Warning: Running as root Jul 06 17:39:25 volumio lircd[1268]: lircd-0.10.1[1268]: Info: Using remote: lircd.conf. Jul 06 17:39:25 volumio lircd[1268]: lircd-0.10.1[1268]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jul 06 17:39:25 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:39:25 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:39:25 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Jul 06 17:39:25 volumio systemd[1]: Started Flexible IR remote input/output application support. Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Info: Initial device: /dev/lirc0 Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Info: [lirc] protocol is enabled Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: driver: default Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: output: /var/run/lirc/lircd Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: nodaemon: 1 Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: logfile: syslog Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: immediate-init: 0 Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: permission: 666 Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: driver-options: Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: listen: 0 Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: connect: (null) Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: userelease: 0 Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: effective_user: (null) Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: release_suffix: _EVUP Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: allow_simulate: 0 Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: repeat_max: 600 Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: configfile: /etc/lirc/lircd.conf Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Options: dynamic_codes: (null) Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Current driver: default Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Driver API version: 3 Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Driver version: 0.10.0 Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Info: lircd: Opening log, level: Info Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: Using systemd fd Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Warning: Running as root Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Info: Using remote: lircd.conf. Jul 06 17:39:25 volumio lircd-0.10.1[1268]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jul 06 17:39:25 volumio sudo[1254]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:25 volumio sudo[1263]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:26 volumio RoonBridge[1145]: Running Jul 06 17:39:26 volumio go-librespot[1178]: time="2025-07-06T17:39:26+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 06 17:39:26 volumio go-librespot[1178]: time="2025-07-06T17:39:26+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jul 06 17:39:26 volumio go-librespot[1178]: time="2025-07-06T17:39:26+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jul 06 17:39:26 volumio go-librespot[1178]: time="2025-07-06T17:39:26+03:00" level=info msg="zeroconf server listening on port 42201" Jul 06 17:39:26 volumio volumio[958]: error: MPD error: The expression evaluated to a falsy value: Jul 06 17:39:26 volumio volumio[958]: assert.ok(self.idling) Jul 06 17:39:26 volumio volumio[958]: error: The expression evaluated to a falsy value: Jul 06 17:39:26 volumio volumio[958]: assert.ok(self.idling) Jul 06 17:39:26 volumio volumio[958]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 06 17:39:26 volumio volumio[958]: error: updateQueue error: null Jul 06 17:39:26 volumio volumio[958]: info: ir_controller: systemctl restart lircd.service succeeded. Jul 06 17:39:26 volumio volumio[958]: info: ir_controller: systemctl restart lircd.service succeeded. Jul 06 17:39:26 volumio sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jul 06 17:39:26 volumio sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:26 volumio sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jul 06 17:39:26 volumio sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:26 volumio systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Jul 06 17:39:26 volumio volumio[958]: verbose: New Socket.io Connection to 192.168.100.4:3000 from 192.168.100.3 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jul 06 17:39:26 volumio volumio[958]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 06 17:39:26 volumio systemd[1]: Stopping Handle events from IR remotes decoded by lircd(8)... Jul 06 17:39:26 volumio systemd[1]: irexec.service: Main process exited, code=killed, status=15/TERM Jul 06 17:39:26 volumio systemd[1]: irexec.service: Succeeded. Jul 06 17:39:26 volumio sudo[1317]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:26 volumio systemd[1]: Stopped Handle events from IR remotes decoded by lircd(8). Jul 06 17:39:26 volumio systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Jul 06 17:39:26 volumio sudo[1321]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:26 volumio lircd[1268]: lircd-0.10.1[1268]: Notice: accepted new client on /var/run/lirc/lircd Jul 06 17:39:26 volumio lircd-0.10.1[1268]: Notice: accepted new client on /var/run/lirc/lircd Jul 06 17:39:26 volumio lircd[1268]: lircd-0.10.1[1268]: Info: [lirc] protocol is enabled Jul 06 17:39:26 volumio lircd-0.10.1[1268]: Info: [lirc] protocol is enabled Jul 06 17:39:26 volumio RoonBridge[1145]: 00:00:00.037 Warn: get lock file path: /tmp/.rnbhgem0- Jul 06 17:39:26 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jul 06 17:39:27 volumio sudo[1342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 06 17:39:27 volumio sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:27 volumio volumio[958]: error: updateQueue error: null Jul 06 17:39:27 volumio sudo[1342]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:27 volumio sudo[1344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 06 17:39:27 volumio sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:27 volumio sudo[1344]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:27 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:27 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:27 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 06 17:39:27 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 06 17:39:27 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:27 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:27 volumio volumio[958]: info: ir_controller: systemctl restart irexec.service succeeded. Jul 06 17:39:27 volumio volumio[958]: info: ir_controller: systemctl restart irexec.service succeeded. Jul 06 17:39:27 volumio volumio[958]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 06 17:39:27 volumio volumio[958]: info: go-librespot daemon successfully initialized Jul 06 17:39:27 volumio sudo[1349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 06 17:39:27 volumio sudo[1349]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:27 volumio sh[489]: timed out Jul 06 17:39:27 volumio sh[489]: dhcpcd exited Jul 06 17:39:27 volumio dhcpcd[525]: timed out Jul 06 17:39:27 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jul 06 17:39:27 volumio sh[489]: ifup: failed to bring up eth0 Jul 06 17:39:27 volumio dhcpcd[525]: dhcpcd exited Jul 06 17:39:27 volumio volumio[958]: info: Upmpdcli Daemon Started Jul 06 17:39:27 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 06 17:39:27 volumio sudo[1349]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:27 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 06 17:39:27 volumio volumio[1351]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 06 17:39:27 volumio volumio[958]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 06 17:39:27 volumio volumio[958]: info: FusionDsp - no preset used Jul 06 17:39:27 volumio volumio[958]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Q1.json Jul 06 17:39:28 volumio volumio[958]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 06 17:39:28 volumio volumio[958]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 06 17:39:28 volumio volumio[958]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 06 17:39:28 volumio volumio[958]: info: FusionDsp - no preset used Jul 06 17:39:28 volumio volumio[958]: info: FusionDsp - no preset used Jul 06 17:39:28 volumio volumio[958]: info: FusionDsp - no preset used Jul 06 17:39:28 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jul 06 17:39:28 volumio volumio[958]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Q1.json Jul 06 17:39:28 volumio volumio[958]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Q1.json Jul 06 17:39:28 volumio volumio[958]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Q1.json Jul 06 17:39:30 volumio volumio[958]: info: Initializing connection to go-librespot Websocket Jul 06 17:39:30 volumio go-librespot[1178]: time="2025-07-06T17:39:30+03:00" level=debug msg="new websocket client" Jul 06 17:39:30 volumio volumio[958]: info: Connection to go-librespot Websocket established Jul 06 17:39:31 volumio volumio[958]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 06 17:39:32 volumio sudo[1398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 06 17:39:32 volumio sudo[1398]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:32 volumio sudo[1398]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:32 volumio sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 06 17:39:32 volumio sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:32 volumio sudo[1400]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:33 volumio volumio[958]: info: Getting Spotify volume Jul 06 17:39:33 volumio volumio[958]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 06 17:39:33 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:33 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:33 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:39:33 volumio volumio[958]: SPOTIFY: SPOTIFY VOLUME undefined Jul 06 17:39:33 volumio volumio[958]: SPOTIFY: VOLUMIO VOLUME 100 Jul 06 17:39:33 volumio volumio[958]: info: Aligning Spotify Volume to Volumio Volume Jul 06 17:39:33 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:33 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:33 volumio volumio[958]: info: Setting Spotify Volume from Volumio: 100 Jul 06 17:39:33 volumio volumio[958]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.3 UA: Mozilla/5.0 (Linux; Android 11; K48 Build/R01005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 06 17:39:33 volumio sudo[1404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 06 17:39:33 volumio sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:33 volumio sudo[1404]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:33 volumio sudo[1407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 06 17:39:33 volumio sudo[1407]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:33 volumio sudo[1407]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 06 17:39:34 volumio volumio[958]: info: Adding plugin bluetooth to MyMusic Plugins Jul 06 17:39:34 volumio volumio[958]: info: Adding plugin multiroom to MyMusic Plugins Jul 06 17:39:34 volumio volumio[958]: info: Adding plugin metavolumio to MyMusic Plugins Jul 06 17:39:34 volumio volumio[958]: info: Adding plugin cd_controller to MyMusic Plugins Jul 06 17:39:34 volumio volumio[958]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 06 17:39:34 volumio volumio[958]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 06 17:39:34 volumio volumio[958]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 06 17:39:34 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:34 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:34 volumio volumio[958]: info: Starting MyVolumio Remote Streaming Endpoints Jul 06 17:39:34 volumio volumio[958]: info: MyVolumio login type: Token Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 06 17:39:34 volumio volumio[958]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 06 17:39:36 volumio volumio[958]: info: Starting Streaming Service Transparent Proxy Jul 06 17:39:36 volumio volumio[958]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 06 17:39:36 volumio volumio[958]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 06 17:39:36 volumio volumio[958]: info: Streaming services startup Jul 06 17:39:36 volumio volumio[958]: info: Starting Streaming Daemon Jul 06 17:39:36 volumio sudo[1419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 06 17:39:36 volumio sudo[1419]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:36 volumio volumio[958]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 06 17:39:36 volumio sudo[1419]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:36 volumio volumio[958]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Jul 06 17:39:36 volumio volumio[958]: info: Sending Spotify command with payload to local API: /player/volume Jul 06 17:39:36 volumio volumio[958]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.3 UA: Mozilla/5.0 (Linux; Android 11; K48 Build/R01005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jul 06 17:39:36 volumio volumio[958]: error: Cannot start Volumio Streaming Daemon Jul 06 17:39:36 volumio volumio[958]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 06 17:39:36 volumio volumio[958]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 06 17:39:36 volumio volumio[958]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.3 UA: Mozilla/5.0 (Linux; Android 11; K48 Build/R01005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jul 06 17:39:36 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 17:39:36 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 17:39:36 volumio volumio[958]: info: Discovery: Getting this device information Jul 06 17:39:36 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:36 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:36 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 17:39:36 volumio volumio[958]: STREAMING PROXY: Starting server on port 3245 Jul 06 17:39:36 volumio volumio[958]: Node JS runtime: 14 Jul 06 17:39:38 volumio volumio[958]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 06 17:39:39 volumio volumio[958]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.3 UA: Mozilla/5.0 (Linux; Android 11; K48 Build/R01005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jul 06 17:39:39 volumio volumio[958]: info: MyVolumio token set successfully Jul 06 17:39:39 volumio volumio[958]: info: MYVOLUMIO: Adding device Jul 06 17:39:39 volumio volumio[958]: info: MYVOLUMIO: Evaluating Server Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::volumioGetVisibleSources Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:39 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 06 17:39:39 volumio volumio[958]: info: Received Get System Info Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 17:39:39 volumio volumio[958]: info: Discovery: Getting this device information Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:39 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:39 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:39 volumio volumio[958]: info: Listing playlists Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 06 17:39:39 volumio volumio[958]: info: Received Get System Info Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 17:39:39 volumio volumio[958]: info: Discovery: Getting this device information Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:39 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::volumioGetVisibleSources Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:39 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 06 17:39:39 volumio volumio[958]: info: Received Get System Info Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 17:39:39 volumio volumio[958]: info: Discovery: Getting this device information Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:39 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 17:39:39 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:39 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:39 volumio volumio[958]: info: Listing playlists Jul 06 17:39:40 volumio volumio[958]: info: MyVolumio status changed Jul 06 17:39:40 volumio volumio[958]: info: Streaming services startup Jul 06 17:39:40 volumio volumio[958]: info: Starting Streaming Daemon Jul 06 17:39:40 volumio volumio[958]: info: Removing browser output: myVolumio user plan is not superstar Jul 06 17:39:40 volumio volumio[958]: info: Removing audio output: Jul 06 17:39:40 volumio volumio[958]: info: Stoppping Tunnel 1 Jul 06 17:39:40 volumio sudo[1459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 06 17:39:40 volumio sudo[1459]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:40 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 06 17:39:40 volumio sudo[1459]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:40 volumio sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 06 17:39:40 volumio sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 17:39:40 volumio volumio[958]: error: Cannot start Volumio Streaming Daemon Jul 06 17:39:40 volumio volumio[958]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 06 17:39:40 volumio volumio[958]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 06 17:39:40 volumio sudo[1462]: pam_unix(sudo:session): session closed for user root Jul 06 17:39:40 volumio volumio[958]: info: Remote SSH Stopped Jul 06 17:39:40 volumio volumio[958]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 06 17:39:40 volumio volumio[958]: info: FusionDsp - no preset used Jul 06 17:39:40 volumio volumio[958]: info: FusionDsp - no preset used Jul 06 17:39:40 volumio volumio[958]: info: FusionDsp - no preset used Jul 06 17:39:40 volumio volumio[958]: info: FusionDsp - no preset used Jul 06 17:39:40 volumio volumio[958]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Q1.json Jul 06 17:39:41 volumio volumio[958]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Q1.json Jul 06 17:39:41 volumio volumio[958]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Q1.json Jul 06 17:39:41 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 06 17:39:41 volumio volumio[958]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Q1.json Jul 06 17:39:41 volumio volumio[958]: info: Setting Geolocation for MyVolumio to eu5 Jul 06 17:39:41 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:41 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:41 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:41 volumio volumio[1351]: ..............................................................................................................................................................++++ Jul 06 17:39:42 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 06 17:39:42 volumio volumio[958]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 06 17:39:42 volumio volumio[958]: info: Updating MyVolumio device info Jul 06 17:39:42 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:42 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:42 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:42 volumio volumio[958]: info: CoreCommandRouter::volumioPlay Jul 06 17:39:42 volumio volumio[958]: info: CoreStateMachine::play index undefined Jul 06 17:39:42 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 17:39:42 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:42 volumio volumio[958]: info: CoreStateMachine::startPlaybackTimer Jul 06 17:39:42 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:42 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Jul 06 17:39:42 volumio volumio[958]: info: sendMpdCommand stop took 1 milliseconds Jul 06 17:39:42 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand clear Jul 06 17:39:42 volumio volumio[958]: info: sendMpdCommand clear took 0 milliseconds Jul 06 17:39:42 volumio volumio[958]: info: Jul 06 17:39:42 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jul 06 17:39:42 volumio volumio[958]: info: Ignoring MPD Status Update Jul 06 17:39:42 volumio volumio[958]: info: Jul 06 17:39:42 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jul 06 17:39:42 volumio volumio[958]: info: Ignoring MPD Status Update Jul 06 17:39:42 volumio volumio[958]: info: Jul 06 17:39:42 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jul 06 17:39:42 volumio volumio[958]: info: Ignoring MPD Status Update Jul 06 17:39:42 volumio volumio[958]: error: updateQueue error: null Jul 06 17:39:42 volumio volumio[958]: error: updateQueue error: null Jul 06 17:39:42 volumio volumio[958]: error: updateQueue error: null Jul 06 17:39:42 volumio volumio[958]: info: ------------------------------ 5ms Jul 06 17:39:42 volumio volumio[958]: info: ------------------------------ 3ms Jul 06 17:39:42 volumio volumio[958]: info: ------------------------------ 2ms Jul 06 17:39:42 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-25.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D62a4a6396e655bd27989bf15acc59c1875865f8b4cb5bde824f1cb54c2d90cb0%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6878e3cc%2F0%2F20794%2Fa1876ef3.91421766.24.17740220%2Fflac-mp4&ext=.flac" Jul 06 17:39:42 volumio volumio[958]: info: Jul 06 17:39:42 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jul 06 17:39:42 volumio volumio[958]: info: Ignoring MPD Status Update Jul 06 17:39:42 volumio volumio[958]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-25.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D62a4a6396e655bd27989bf15acc59c1875865f8b4cb5bde824f1cb54c2d90cb0%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6878e3cc%2F0%2F20794%2Fa1876ef3.91421766.24.17740220%2Fflac-mp4&ext=.flac" took 2 milliseconds Jul 06 17:39:42 volumio volumio[958]: verbose: MPD COMMAND [object Object] Jul 06 17:39:42 volumio volumio[958]: verbose: MPD COMMAND [object Object] Jul 06 17:39:42 volumio volumio[958]: verbose: MPD COMMAND [object Object] Jul 06 17:39:42 volumio volumio[958]: info: Jul 06 17:39:42 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jul 06 17:39:42 volumio volumio[958]: info: Ignoring MPD Status Update Jul 06 17:39:42 volumio volumio[958]: info: Jul 06 17:39:42 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jul 06 17:39:42 volumio volumio[958]: info: Ignoring MPD Status Update Jul 06 17:39:42 volumio volumio[958]: info: Jul 06 17:39:42 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jul 06 17:39:42 volumio volumio[958]: info: Ignoring MPD Status Update Jul 06 17:39:42 volumio volumio[958]: info: ------------------------------ 6ms Jul 06 17:39:42 volumio volumio[958]: info: ------------------------------ 3ms Jul 06 17:39:42 volumio volumio[958]: info: ------------------------------ 3ms Jul 06 17:39:42 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 06 17:39:42 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand play Jul 06 17:39:42 volumio volumio[958]: info: Jul 06 17:39:42 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jul 06 17:39:42 volumio volumio[958]: info: Ignoring MPD Status Update Jul 06 17:39:42 volumio volumio[958]: info: Jul 06 17:39:42 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jul 06 17:39:42 volumio volumio[958]: info: Ignoring MPD Status Update Jul 06 17:39:42 volumio volumio[958]: info: ------------------------------ 5ms Jul 06 17:39:42 volumio volumio[958]: info: sendMpdCommand play took 3 milliseconds Jul 06 17:39:42 volumio volumio[958]: info: ------------------------------ 2ms Jul 06 17:39:42 volumio volumio[958]: info: ------------------------------ 2ms Jul 06 17:39:43 volumio systemd[1]: systemd-timedated.service: Succeeded. Jul 06 17:39:43 volumio volumio[958]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 06 17:39:43 volumio volumio[958]: info: Jul 06 17:39:43 volumio volumio[958]: ---------------------------- MPD announces state update: player Jul 06 17:39:43 volumio volumio[958]: info: ControllerMpd::getState Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jul 06 17:39:43 volumio volumio[958]: info: Jul 06 17:39:43 volumio volumio[958]: ---------------------------- MPD announces state update: player Jul 06 17:39:43 volumio volumio[958]: info: ControllerMpd::getState Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jul 06 17:39:43 volumio volumio[958]: info: Jul 06 17:39:43 volumio volumio[958]: ---------------------------- MPD announces state update: player Jul 06 17:39:43 volumio volumio[958]: info: ControllerMpd::getState Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jul 06 17:39:43 volumio volumio[958]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 06 17:39:43 volumio volumio[958]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 06 17:39:43 volumio volumio[958]: info: camilladsp stopping service pid 1273... Jul 06 17:39:43 volumio volumio[958]: info: camilladsp service terminated, instance 1 Jul 06 17:39:43 volumio volumio[958]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 06 17:39:43 volumio volumio[958]: info: camilladsp service started and running in background, instance 1 Jul 06 17:39:43 volumio volumio[958]: info: Jul 06 17:39:43 volumio volumio[958]: ---------------------------- MPD announces state update: player Jul 06 17:39:43 volumio volumio[958]: info: sendMpdCommand status took 45 milliseconds Jul 06 17:39:43 volumio volumio[958]: info: sendMpdCommand status took 44 milliseconds Jul 06 17:39:43 volumio volumio[958]: info: sendMpdCommand status took 42 milliseconds Jul 06 17:39:43 volumio volumio[958]: info: ControllerMpd::getState Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::parseState Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::parseState Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::parseState Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 17:39:43 volumio volumio[958]: info: Jul 06 17:39:43 volumio volumio[958]: ---------------------------- MPD announces state update: player Jul 06 17:39:43 volumio volumio[958]: info: ControllerMpd::getState Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jul 06 17:39:43 volumio volumio[958]: info: Jul 06 17:39:43 volumio volumio[958]: ---------------------------- MPD announces state update: player Jul 06 17:39:43 volumio volumio[958]: info: ControllerMpd::getState Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jul 06 17:39:43 volumio volumio[958]: info: sendMpdCommand status took 9 milliseconds Jul 06 17:39:43 volumio volumio[958]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 06 17:39:43 volumio volumio[958]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 06 17:39:43 volumio volumio[958]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 06 17:39:43 volumio volumio[958]: info: sendMpdCommand status took 6 milliseconds Jul 06 17:39:43 volumio volumio[958]: info: sendMpdCommand status took 6 milliseconds Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::parseState Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::parseState Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::parseState Jul 06 17:39:43 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 17:39:43 volumio volumio[958]: info: ControllerMpd::pushState Jul 06 17:39:43 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jul 06 17:39:43 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:43 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Gloria","artist":"сборник","album":"Chillout","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-25.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D62a4a6396e655bd27989bf15acc59c1875865f8b4cb5bde824f1cb54c2d90cb0%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6878e3cc%2F0%2F20794%2Fa1876ef3.91421766.24.17740220%2Fflac-mp4&ext=.flac","trackType":"flac"} Jul 06 17:39:43 volumio volumio[958]: verbose: CURRENT POSITION 0 Jul 06 17:39:43 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jul 06 17:39:43 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jul 06 17:39:43 volumio volumio[958]: info: ControllerMpd::pushState Jul 06 17:39:43 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jul 06 17:39:43 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:43 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Gloria","artist":"сборник","album":"Chillout","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-25.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D62a4a6396e655bd27989bf15acc59c1875865f8b4cb5bde824f1cb54c2d90cb0%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6878e3cc%2F0%2F20794%2Fa1876ef3.91421766.24.17740220%2Fflac-mp4&ext=.flac","trackType":"flac"} Jul 06 17:39:43 volumio volumio[958]: verbose: CURRENT POSITION 0 Jul 06 17:39:43 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jul 06 17:39:43 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jul 06 17:39:43 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jul 06 17:39:43 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:39:43 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 17:39:43 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:39:43 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:39:43 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:39:43 volumio volumio[958]: info: ControllerMpd::pushState Jul 06 17:39:43 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jul 06 17:39:43 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:43 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Gloria","artist":"сборник","album":"Chillout","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-25.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D62a4a6396e655bd27989bf15acc59c1875865f8b4cb5bde824f1cb54c2d90cb0%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6878e3cc%2F0%2F20794%2Fa1876ef3.91421766.24.17740220%2Fflac-mp4&ext=.flac","trackType":"flac"} Jul 06 17:39:43 volumio volumio[958]: verbose: CURRENT POSITION 0 Jul 06 17:39:43 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jul 06 17:39:43 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jul 06 17:39:43 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jul 06 17:39:43 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:39:43 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:39:43 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:39:43 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:39:43 volumio volumio[958]: info: ------------------------------ 82ms Jul 06 17:39:43 volumio volumio[958]: info: ------------------------------ 105ms Jul 06 17:39:43 volumio volumio[958]: info: ------------------------------ 104ms Jul 06 17:39:44 volumio volumio[958]: info: sendMpdCommand playlistinfo took 69 milliseconds Jul 06 17:39:44 volumio volumio[958]: info: sendMpdCommand playlistinfo took 67 milliseconds Jul 06 17:39:44 volumio volumio[958]: info: sendMpdCommand playlistinfo took 68 milliseconds Jul 06 17:39:44 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jul 06 17:39:44 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jul 06 17:39:44 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jul 06 17:39:44 volumio volumio[958]: info: ControllerMpd::pushState Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jul 06 17:39:44 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:44 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Gloria","artist":"сборник","album":"Chillout","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-25.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D62a4a6396e655bd27989bf15acc59c1875865f8b4cb5bde824f1cb54c2d90cb0%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6878e3cc%2F0%2F20794%2Fa1876ef3.91421766.24.17740220%2Fflac-mp4&ext=.flac","trackType":"flac"} Jul 06 17:39:44 volumio volumio[958]: verbose: CURRENT POSITION 0 Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jul 06 17:39:44 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:39:44 volumio volumio[958]: info: ControllerMpd::pushState Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jul 06 17:39:44 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:44 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Gloria","artist":"сборник","album":"Chillout","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-25.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D62a4a6396e655bd27989bf15acc59c1875865f8b4cb5bde824f1cb54c2d90cb0%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6878e3cc%2F0%2F20794%2Fa1876ef3.91421766.24.17740220%2Fflac-mp4&ext=.flac","trackType":"flac"} Jul 06 17:39:44 volumio volumio[958]: verbose: CURRENT POSITION 0 Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jul 06 17:39:44 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:39:44 volumio volumio[958]: info: ControllerMpd::pushState Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jul 06 17:39:44 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:39:44 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Gloria","artist":"сборник","album":"Chillout","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-25.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D62a4a6396e655bd27989bf15acc59c1875865f8b4cb5bde824f1cb54c2d90cb0%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6878e3cc%2F0%2F20794%2Fa1876ef3.91421766.24.17740220%2Fflac-mp4&ext=.flac","trackType":"flac"} Jul 06 17:39:44 volumio volumio[958]: verbose: CURRENT POSITION 0 Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jul 06 17:39:44 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:39:44 volumio volumio[958]: info: ------------------------------ 137ms Jul 06 17:39:44 volumio volumio[958]: info: ------------------------------ 133ms Jul 06 17:39:44 volumio volumio[958]: info: ------------------------------ 132ms Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:39:44 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:39:44 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:39:44 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:39:44 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:39:44 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:39:44 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:39:44 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:39:44 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:39:44 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:39:44 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:39:44 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:39:44 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:39:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 06 17:39:46 volumio volumio[958]: info: MYVOLUMIO: Adding device Jul 06 17:39:46 volumio volumio[958]: info: MYVOLUMIO: Evaluating Server Jul 06 17:39:49 volumio volumio[958]: info: Setting Geolocation for MyVolumio to us3 Jul 06 17:39:49 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:49 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 06 17:39:49 volumio volumio[958]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jul 06 17:39:49 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:39:50 volumio volumio[958]: info: Updating MyVolumio device info Jul 06 17:39:50 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:51 volumio volumio[958]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 06 17:39:53 volumio volumio[958]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 06 17:39:53 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 17:39:53 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:53 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 06 17:39:54 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:54 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 06 17:39:54 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 06 17:39:54 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 06 17:39:54 volumio volumio[958]: info: CoreCommandRouter::volumioGetBrowseSources Jul 06 17:39:54 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 17:39:54 volumio volumio-remote-updater[576]: Test mode enabled Jul 06 17:39:54 volumio volumio-remote-updater[576]: No alpha test mode Jul 06 17:39:54 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 06 17:39:56 volumio volumio[958]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 06 17:39:56 volumio volumio[958]: info: Completed starting MyVolumio Plugin Jul 06 17:39:56 volumio volumio[958]: [Metrics] CommandRouter: 41s 459.34ms Jul 06 17:39:56 volumio volumio[958]: info: CoreCommandRouter::volumiosetStartupVolume Jul 06 17:39:56 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 17:39:56 volumio volumio[958]: info: VolumeController:: Setting startup Volume 100 Jul 06 17:39:56 volumio volumio[958]: info: VolumeController::SetAlsaVolume100 Jul 06 17:39:56 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 17:39:56 volumio volumio[958]: info: CoreCommandRouter::Close All Modals sent Jul 06 17:39:56 volumio volumio[958]: info: CoreCommandRouter::Close All Modals sent Jul 06 17:39:56 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:39:56 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 17:39:56 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:39:56 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:39:56 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:39:56 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:39:56 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:39:56 volumio volumio[958]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 06 17:39:56 volumio volumio[958]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 06 17:39:56 volumio volumio[958]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 06 17:39:56 volumio volumio[958]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 06 17:39:57 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 06 17:39:57 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 06 17:39:57 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 06 17:39:59 volumio volumio[958]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 06 17:39:59 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 06 17:40:03 volumio volumio[958]: info: BOOT COMPLETED Jul 06 17:40:04 volumio volumio[958]: info: CoreCommandRouter::volumioPause Jul 06 17:40:04 volumio volumio[958]: info: CoreStateMachine::pause Jul 06 17:40:04 volumio volumio[958]: info: CoreStateMachine::stPlaybackTimer Jul 06 17:40:04 volumio volumio[958]: info: CoreStateMachine::servicePause Jul 06 17:40:04 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:40:04 volumio volumio[958]: info: CoreCommandRouter::servicePause Jul 06 17:40:04 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 06 17:40:04 volumio volumio[958]: info: ControllerMpd::pause Jul 06 17:40:04 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand pause Jul 06 17:40:04 volumio volumio[958]: info: Jul 06 17:40:04 volumio volumio[958]: ---------------------------- MPD announces state update: player Jul 06 17:40:04 volumio volumio[958]: info: sendMpdCommand pause took 20 milliseconds Jul 06 17:40:04 volumio volumio[958]: info: ControllerMpd::getState Jul 06 17:40:04 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jul 06 17:40:04 volumio volumio[958]: info: Jul 06 17:40:04 volumio volumio[958]: ---------------------------- MPD announces state update: player Jul 06 17:40:04 volumio volumio[958]: info: ControllerMpd::getState Jul 06 17:40:04 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jul 06 17:40:04 volumio volumio[958]: info: Jul 06 17:40:04 volumio volumio[958]: ---------------------------- MPD announces state update: player Jul 06 17:40:04 volumio volumio[958]: info: ControllerMpd::getState Jul 06 17:40:04 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jul 06 17:40:04 volumio volumio[958]: info: sendMpdCommand status took 10 milliseconds Jul 06 17:40:04 volumio volumio[958]: info: sendMpdCommand status took 8 milliseconds Jul 06 17:40:04 volumio volumio[958]: info: sendMpdCommand status took 7 milliseconds Jul 06 17:40:04 volumio volumio[958]: verbose: ControllerMpd::parseState Jul 06 17:40:04 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 17:40:04 volumio volumio[958]: verbose: ControllerMpd::parseState Jul 06 17:40:04 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 17:40:04 volumio volumio[958]: verbose: ControllerMpd::parseState Jul 06 17:40:04 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 17:40:04 volumio volumio[958]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 06 17:40:04 volumio volumio[958]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 06 17:40:04 volumio volumio[958]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 06 17:40:04 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jul 06 17:40:04 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jul 06 17:40:04 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jul 06 17:40:04 volumio volumio[958]: info: ControllerMpd::pushState Jul 06 17:40:04 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jul 06 17:40:04 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:40:04 volumio volumio[958]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9747,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"723 Kbps","isStreaming":false,"title":"Gloria","artist":"сборник","album":"Chillout","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-25.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D62a4a6396e655bd27989bf15acc59c1875865f8b4cb5bde824f1cb54c2d90cb0%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6878e3cc%2F0%2F20794%2Fa1876ef3.91421766.24.17740220%2Fflac-mp4&ext=.flac","trackType":"flac"} Jul 06 17:40:04 volumio volumio[958]: verbose: CURRENT POSITION 0 Jul 06 17:40:04 volumio volumio[958]: info: CoreStateMachine::syncState stateService pause Jul 06 17:40:04 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus pause Jul 06 17:40:04 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:40:04 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 17:40:04 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:40:04 volumio volumio[958]: info: CoreStateMachine::stPlaybackTimer Jul 06 17:40:04 volumio volumio[958]: info: ControllerMpd::pushState Jul 06 17:40:04 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jul 06 17:40:04 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:40:04 volumio volumio[958]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9747,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"723 Kbps","isStreaming":false,"title":"Gloria","artist":"сборник","album":"Chillout","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-25.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D62a4a6396e655bd27989bf15acc59c1875865f8b4cb5bde824f1cb54c2d90cb0%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6878e3cc%2F0%2F20794%2Fa1876ef3.91421766.24.17740220%2Fflac-mp4&ext=.flac","trackType":"flac"} Jul 06 17:40:04 volumio volumio[958]: verbose: CURRENT POSITION 0 Jul 06 17:40:04 volumio volumio[958]: info: CoreStateMachine::syncState stateService pause Jul 06 17:40:04 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus pause Jul 06 17:40:04 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:40:04 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:40:04 volumio volumio[958]: info: CoreStateMachine::stPlaybackTimer Jul 06 17:40:04 volumio volumio[958]: info: ControllerMpd::pushState Jul 06 17:40:04 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jul 06 17:40:04 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:40:04 volumio volumio[958]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9747,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"723 Kbps","isStreaming":false,"title":"Gloria","artist":"сборник","album":"Chillout","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-25.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D62a4a6396e655bd27989bf15acc59c1875865f8b4cb5bde824f1cb54c2d90cb0%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6878e3cc%2F0%2F20794%2Fa1876ef3.91421766.24.17740220%2Fflac-mp4&ext=.flac","trackType":"flac"} Jul 06 17:40:04 volumio volumio[958]: verbose: CURRENT POSITION 0 Jul 06 17:40:04 volumio volumio[958]: info: CoreStateMachine::syncState stateService pause Jul 06 17:40:04 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus pause Jul 06 17:40:04 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:40:04 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:40:04 volumio volumio[958]: info: CoreStateMachine::stPlaybackTimer Jul 06 17:40:04 volumio volumio[958]: info: ------------------------------ 82ms Jul 06 17:40:04 volumio volumio[958]: info: ------------------------------ 81ms Jul 06 17:40:04 volumio volumio[958]: info: ------------------------------ 79ms Jul 06 17:40:05 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:40:05 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:40:05 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:40:05 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:40:05 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:40:05 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:40:05 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:40:05 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:40:05 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:40:05 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:40:05 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:40:05 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:40:05 volumio ntpd[772]: Soliciting pool server 192.36.143.130 Jul 06 17:40:05 volumio ntpd[772]: Soliciting pool server 217.170.87.229 Jul 06 17:40:06 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 17:40:06 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 17:40:06 volumio volumio[958]: info: Discovery: Getting this device information Jul 06 17:40:06 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:40:06 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 17:40:06 volumio ntpd[772]: Soliciting pool server 151.0.2.54 Jul 06 17:40:06 volumio ntpd[772]: Soliciting pool server 185.211.244.47 Jul 06 17:40:07 volumio volumio[958]: info: CoreCommandRouter::volumioPlay Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::play index undefined Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 17:40:07 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::startPlaybackTimer Jul 06 17:40:07 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 06 17:40:07 volumio volumio[958]: info: ControllerMpd::resume Jul 06 17:40:07 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand play Jul 06 17:40:07 volumio volumio[958]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 06 17:40:07 volumio volumio[958]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 06 17:40:07 volumio volumio[958]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 06 17:40:07 volumio volumio[958]: info: Jul 06 17:40:07 volumio volumio[958]: ---------------------------- MPD announces state update: player Jul 06 17:40:07 volumio volumio[958]: info: sendMpdCommand play took 26 milliseconds Jul 06 17:40:07 volumio volumio[958]: info: ControllerMpd::getState Jul 06 17:40:07 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jul 06 17:40:07 volumio volumio[958]: info: Jul 06 17:40:07 volumio volumio[958]: ---------------------------- MPD announces state update: player Jul 06 17:40:07 volumio volumio[958]: info: ControllerMpd::getState Jul 06 17:40:07 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jul 06 17:40:07 volumio volumio[958]: info: Jul 06 17:40:07 volumio volumio[958]: ---------------------------- MPD announces state update: player Jul 06 17:40:07 volumio volumio[958]: info: ControllerMpd::getState Jul 06 17:40:07 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jul 06 17:40:07 volumio volumio[958]: info: sendMpdCommand status took 7 milliseconds Jul 06 17:40:07 volumio volumio[958]: info: sendMpdCommand status took 3 milliseconds Jul 06 17:40:07 volumio volumio[958]: info: sendMpdCommand status took 2 milliseconds Jul 06 17:40:07 volumio volumio[958]: verbose: ControllerMpd::parseState Jul 06 17:40:07 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 17:40:07 volumio volumio[958]: verbose: ControllerMpd::parseState Jul 06 17:40:07 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 17:40:07 volumio volumio[958]: verbose: ControllerMpd::parseState Jul 06 17:40:07 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 17:40:07 volumio volumio[958]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 06 17:40:07 volumio volumio[958]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 06 17:40:07 volumio volumio[958]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 06 17:40:07 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jul 06 17:40:07 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jul 06 17:40:07 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jul 06 17:40:07 volumio volumio[958]: info: ControllerMpd::pushState Jul 06 17:40:07 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jul 06 17:40:07 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:40:07 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11234,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"723 Kbps","isStreaming":false,"title":"Gloria","artist":"сборник","album":"Chillout","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-25.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D62a4a6396e655bd27989bf15acc59c1875865f8b4cb5bde824f1cb54c2d90cb0%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6878e3cc%2F0%2F20794%2Fa1876ef3.91421766.24.17740220%2Fflac-mp4&ext=.flac","trackType":"flac"} Jul 06 17:40:07 volumio volumio[958]: verbose: CURRENT POSITION 0 Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus pause Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:40:07 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 17:40:07 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:40:07 volumio volumio[958]: info: ControllerMpd::pushState Jul 06 17:40:07 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jul 06 17:40:07 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:40:07 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11234,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"723 Kbps","isStreaming":false,"title":"Gloria","artist":"сборник","album":"Chillout","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-25.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D62a4a6396e655bd27989bf15acc59c1875865f8b4cb5bde824f1cb54c2d90cb0%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6878e3cc%2F0%2F20794%2Fa1876ef3.91421766.24.17740220%2Fflac-mp4&ext=.flac","trackType":"flac"} Jul 06 17:40:07 volumio volumio[958]: verbose: CURRENT POSITION 0 Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jul 06 17:40:07 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:40:07 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 17:40:07 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:40:07 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:40:07 volumio volumio[958]: info: ControllerMpd::pushState Jul 06 17:40:07 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jul 06 17:40:07 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jul 06 17:40:07 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11234,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"723 Kbps","isStreaming":false,"title":"Gloria","artist":"сборник","album":"Chillout","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-25.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D62a4a6396e655bd27989bf15acc59c1875865f8b4cb5bde824f1cb54c2d90cb0%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6878e3cc%2F0%2F20794%2Fa1876ef3.91421766.24.17740220%2Fflac-mp4&ext=.flac","trackType":"flac"} Jul 06 17:40:07 volumio volumio[958]: verbose: CURRENT POSITION 0 Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jul 06 17:40:07 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:40:07 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::pushState Jul 06 17:40:07 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jul 06 17:40:07 volumio volumio[958]: info: ------------------------------ 76ms Jul 06 17:40:07 volumio volumio[958]: info: ------------------------------ 67ms Jul 06 17:40:07 volumio volumio[958]: info: ------------------------------ 68ms Jul 06 17:40:07 volumio volumio[958]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 06 17:40:07 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:40:07 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:40:07 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:40:07 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:40:07 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:40:07 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:40:07 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:40:07 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:40:07 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jul 06 17:40:07 volumio volumio[958]: info: CoreStateMachine::getQueue Jul 06 17:40:07 volumio volumio[958]: info: CorePlayQueue::getQueue Jul 06 17:40:07 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:40:07 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:40:07 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:40:07 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:40:07 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 17:40:07 volumio ntpd[772]: Soliciting pool server 91.207.136.55 Jul 06 17:40:07 volumio ntpd[772]: Soliciting pool server 94.100.180.133 Jul 06 17:40:08 volumio ntpd[772]: Soliciting pool server 212.41.10.19 Jul 06 17:40:09 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 06 17:40:09 volumio volumio[958]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 06 17:40:09 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 06 17:40:09 volumio volumio[958]: info: Received Get System Version Jul 06 17:40:09 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 17:40:09 volumio volumio[958]: info: Received Get System Info Jul 06 17:40:09 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 17:40:09 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 17:40:09 volumio volumio[958]: info: Discovery: Getting this device information Jul 06 17:40:09 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:40:09 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 17:40:09 volumio ntpd[772]: Soliciting pool server 51.250.53.172 Jul 06 17:40:09 volumio ntpd[772]: Soliciting pool server 91.197.207.24 Jul 06 17:40:10 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 06 17:40:11 volumio volumio[1351]: ......................................................................................................................................................................................................................................................................................................................................++++ Jul 06 17:40:11 volumio volumio[1351]: e is 65537 (0x010001) Jul 06 17:40:11 volumio volumio[1351]: writing RSA key Jul 06 17:40:12 volumio volumio[958]: info: Received Get System Info Jul 06 17:40:12 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 17:40:12 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 17:40:12 volumio volumio[958]: info: Discovery: Getting this device information Jul 06 17:40:12 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:40:12 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 17:40:15 volumio ntpd[772]: receive: Unexpected origin timestamp 0xec15094c.a76cd84c does not match aorg 0xec15094e.a7681221 from server@217.170.87.229 xmt 0xec15094d.17481d59 Jul 06 17:40:15 volumio ntpd[772]: receive: Unexpected origin timestamp 0xec15094c.a76dd713 does not match aorg 0xec15094e.a76afad5 from server@192.36.143.130 xmt 0xec15094d.19387abf Jul 06 17:40:16 volumio ntpd[772]: receive: Unexpected origin timestamp 0xec15094d.a768bf92 does not match aorg 0xec15094f.a7683742 from server@151.0.2.54 xmt 0xec15094e.19add5ce Jul 06 17:40:18 volumio ntpd[772]: receive: Unexpected origin timestamp 0xec15094e.a7681221 does not match aorg 0xec150950.a76b5f41 from server@217.170.87.229 xmt 0xec15094f.171c915d Jul 06 17:40:18 volumio ntpd[772]: receive: Unexpected origin timestamp 0xec15094e.a76afad5 does not match aorg 0xec150950.a76c5432 from server@192.36.143.130 xmt 0xec15094f.1906b7dc Jul 06 17:40:19 volumio ntpd[772]: receive: Unexpected origin timestamp 0xec15094f.a7683742 does not match aorg 0xec150951.a7684a55 from server@151.0.2.54 xmt 0xec150950.1aa10876 Jul 06 17:40:20 volumio ntpd[772]: receive: Unexpected origin timestamp 0xec150950.a7687009 does not match aorg 0xec150952.a7689098 from server@91.207.136.55 xmt 0xec150951.1af53e2e Jul 06 17:40:20 volumio ntpd[772]: receive: Unexpected origin timestamp 0xec150951.a7684a55 does not match aorg 0xec150953.a768559c from server@151.0.2.54 xmt 0xec150952.19a65d9f Jul 06 17:40:20 volumio ntpd[772]: receive: Unexpected origin timestamp 0xec150952.a7689098 does not match aorg 0xec150954.a7694a6b from server@91.207.136.55 xmt 0xec150953.1b3f1fdf Jul 06 17:40:22 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 17:40:22 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 17:40:22 volumio volumio[958]: info: Discovery: Getting this device information Jul 06 17:40:22 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jul 06 17:40:22 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 17:40:24 volumio volumio[958]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 17:40:24 volumio volumio[958]: Error: read ECONNRESET Jul 06 17:40:24 volumio volumio[958]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Jul 06 17:40:24 volumio volumio[958]: errno: -104, Jul 06 17:40:24 volumio volumio[958]: code: 'ECONNRESET', Jul 06 17:40:24 volumio volumio[958]: syscall: 'read' Jul 06 17:40:24 volumio volumio[958]: } Jul 06 17:40:24 volumio volumio[958]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 17:40:24 volumio sudo[1737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-06 17:39 Jul 06 17:40:24 volumio sudo[1737]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"