-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-05-14 22:10:41 CEST. -- May 14 22:09:23 volumio-skloby systemd-timedated[897]: Changed local time to Wed May 14 22:09:23 2025 May 14 22:09:23 volumio-skloby sudo[895]: pam_unix(sudo:session): session closed for user root May 14 22:09:23 volumio-skloby volumio-time-update[577]: volumio-time-update-util: System time updated successfully. May 14 22:09:23 volumio-skloby systemd[1]: Started Volumio Time Update Utility. May 14 22:09:23 volumio-skloby systemd[1]: Starting Daily apt download activities... May 14 22:09:23 volumio-skloby volumio[844]: info: ------------------------------------------- May 14 22:09:23 volumio-skloby volumio[844]: info: ----- Volumio3 ---- May 14 22:09:23 volumio-skloby volumio[844]: info: ------------------------------------------- May 14 22:09:23 volumio-skloby volumio[844]: info: ----- System startup ---- May 14 22:09:23 volumio-skloby volumio[844]: info: ------------------------------------------- May 14 22:09:24 volumio-skloby ntpd[799]: Listen normally on 3 eth0 192.168.1.200:123 May 14 22:09:24 volumio-skloby ntpd[799]: new interface(s) found: waking up resolver May 14 22:09:24 volumio-skloby nmbd[796]: [2025/05/14 22:09:24.387904, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 14 22:09:24 volumio-skloby systemd[1]: Started Samba NMB Daemon. May 14 22:09:24 volumio-skloby nmbd[796]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 14 22:09:24 volumio-skloby systemd[1]: Starting Samba Winbind Daemon... May 14 22:09:24 volumio-skloby winbindd[941]: [2025/05/14 22:09:24.892429, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 14 22:09:24 volumio-skloby winbindd[941]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 14 22:09:25 volumio-skloby winbindd[941]: [2025/05/14 22:09:25.001969, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 14 22:09:25 volumio-skloby systemd[1]: Started Samba Winbind Daemon. May 14 22:09:25 volumio-skloby winbindd[941]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 14 22:09:25 volumio-skloby systemd[1]: Starting Samba SMB Daemon... May 14 22:09:25 volumio-skloby volumio[844]: info: MYVOLUMIO Environment detected May 14 22:09:25 volumio-skloby volumio[844]: info: Plugin folders cleanup May 14 22:09:25 volumio-skloby volumio[844]: info: Scanning into folder /volumio/app/plugins/ May 14 22:09:25 volumio-skloby volumio[844]: info: Scanning category audio_interface May 14 22:09:25 volumio-skloby volumio[844]: info: Scanning category miscellanea May 14 22:09:25 volumio-skloby volumio[844]: info: Scanning category music_service May 14 22:09:25 volumio-skloby volumio[844]: info: Scanning category plugins.json May 14 22:09:25 volumio-skloby volumio[844]: info: Scanning category system_controller May 14 22:09:25 volumio-skloby volumio[844]: info: Scanning category user_interface May 14 22:09:25 volumio-skloby volumio[844]: info: Scanning into folder /data/plugins/ May 14 22:09:25 volumio-skloby volumio[844]: info: Scanning category audio_interface May 14 22:09:25 volumio-skloby volumio[844]: info: Scanning category system_controller May 14 22:09:25 volumio-skloby volumio[844]: info: Scanning category system_hardware May 14 22:09:25 volumio-skloby volumio[844]: info: Plugin folders cleanup completed May 14 22:09:25 volumio-skloby volumio[844]: info: ------------------------------------------- May 14 22:09:25 volumio-skloby volumio[844]: info: ----- Core plugins startup ---- May 14 22:09:25 volumio-skloby volumio[844]: info: ------------------------------------------- May 14 22:09:25 volumio-skloby volumio[844]: info: Loading plugins from folder /volumio/app/plugins/ May 14 22:09:25 volumio-skloby volumio[844]: info: Adding plugin upnp to MyMusic Plugins May 14 22:09:25 volumio-skloby volumio[844]: info: Adding plugin airplay_emulation to MyMusic Plugins May 14 22:09:25 volumio-skloby volumio[844]: info: Adding plugin upnp_browser to MyMusic Plugins May 14 22:09:25 volumio-skloby volumio[844]: info: Loading plugins from folder /data/plugins/ May 14 22:09:25 volumio-skloby volumio[844]: info: Loading plugin "system"... May 14 22:09:25 volumio-skloby volumio[844]: info: Plugin ampswitch is not enabled May 14 22:09:25 volumio-skloby volumio[844]: info: Loading plugin "appearance"... May 14 22:09:25 volumio-skloby systemd[1]: Started Samba SMB Daemon. May 14 22:09:25 volumio-skloby systemd[1]: Reached target Multi-User System. May 14 22:09:25 volumio-skloby systemd[1]: Reached target Graphical Interface. May 14 22:09:25 volumio-skloby smbd[946]: [2025/05/14 22:09:25.756439, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 14 22:09:25 volumio-skloby smbd[946]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 14 22:09:25 volumio-skloby systemd[1]: Starting Update UTMP about System Runlevel Changes... May 14 22:09:25 volumio-skloby systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 14 22:09:25 volumio-skloby systemd[1]: Started Update UTMP about System Runlevel Changes. May 14 22:09:26 volumio-skloby systemd[1]: apt-daily.service: Succeeded. May 14 22:09:26 volumio-skloby systemd[1]: Started Daily apt download activities. May 14 22:09:26 volumio-skloby systemd[1]: Startup finished in 13.418s (kernel) + 14.801s (userspace) = 28.219s. May 14 22:09:27 volumio-skloby volumio[844]: info: Loading plugin "network"... May 14 22:09:27 volumio-skloby volumio[844]: info: Refreshing Cached IP Addresses May 14 22:09:27 volumio-skloby sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 22:09:27 volumio-skloby sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:27 volumio-skloby volumio[844]: info: Loading plugin "services"... May 14 22:09:27 volumio-skloby sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 22:09:27 volumio-skloby sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:27 volumio-skloby sudo[990]: pam_unix(sudo:session): session closed for user root May 14 22:09:27 volumio-skloby volumio[844]: info: Loading plugin "alsa_controller"... May 14 22:09:27 volumio-skloby sudo[992]: pam_unix(sudo:session): session closed for user root May 14 22:09:27 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 22:09:27 volumio-skloby volumio[844]: info: Loading plugin "wizard"... May 14 22:09:27 volumio-skloby volumio[844]: info: Loading plugin "networkfs"... May 14 22:09:27 volumio-skloby volumio[844]: info: Starting Udev Watcher for removable devices May 14 22:09:27 volumio-skloby volumio[844]: info: Ignoring mount for partition: boot May 14 22:09:27 volumio-skloby volumio[844]: info: Ignoring mount for partition: volumio May 14 22:09:27 volumio-skloby volumio[844]: info: Ignoring mount for partition: volumio_data May 14 22:09:27 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 14 22:09:27 volumio-skloby volumio[844]: info: Loading plugin "volumio_command_line_client"... May 14 22:09:27 volumio-skloby volumio[844]: info: Loading plugin "upnp"... May 14 22:09:27 volumio-skloby volumio[844]: info: [1747253367582] Starting Upmpd Daemon May 14 22:09:27 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 14 22:09:27 volumio-skloby volumio[844]: info: Loading plugin "my_music"... May 14 22:09:27 volumio-skloby volumio[844]: info: Loading plugin "mpd"... May 14 22:09:28 volumio-skloby volumio[844]: info: Loading plugin "upnp_browser"... May 14 22:09:28 volumio-skloby volumio[844]: info: Loading plugin "alarm-clock"... May 14 22:09:28 volumio-skloby volumio[844]: info: Loading plugin "airplay_emulation"... May 14 22:09:28 volumio-skloby volumio[844]: info: Starting Shairport Sync May 14 22:09:28 volumio-skloby volumio[844]: info: Loading plugin "last_100"... May 14 22:09:28 volumio-skloby volumio[844]: info: Loading plugin "webradio"... May 14 22:09:28 volumio-skloby volumio[844]: info: Loading plugin "i2s_dacs"... May 14 22:09:28 volumio-skloby volumio[844]: info: Loading plugin "volumiodiscovery"... May 14 22:09:28 volumio-skloby volumio[844]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 14 22:09:28 volumio-skloby volumio[844]: *** WARNING *** Please fix your application to use the native API of Avahi! May 14 22:09:28 volumio-skloby volumio[844]: *** WARNING *** For more information see May 14 22:09:28 volumio-skloby node[844]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 14 22:09:28 volumio-skloby volumio[844]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 14 22:09:28 volumio-skloby volumio[844]: *** WARNING *** Please fix your application to use the native API of Avahi! May 14 22:09:28 volumio-skloby volumio[844]: *** WARNING *** For more information see May 14 22:09:28 volumio-skloby node[844]: *** WARNING *** Please fix your application to use the native API of Avahi! May 14 22:09:28 volumio-skloby node[844]: *** WARNING *** For more information see May 14 22:09:28 volumio-skloby node[844]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 14 22:09:28 volumio-skloby node[844]: *** WARNING *** Please fix your application to use the native API of Avahi! May 14 22:09:28 volumio-skloby node[844]: *** WARNING *** For more information see May 14 22:09:28 volumio-skloby volumio[844]: info: Applying required configuration parameters for plugin volumiodiscovery May 14 22:09:28 volumio-skloby volumio[844]: info: Discovery: Started advertising with name: Volumio Skloby May 14 22:09:28 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 14 22:09:28 volumio-skloby volumio[844]: info: Loading plugin "outputs"... May 14 22:09:28 volumio-skloby volumio[844]: info: Loading plugin "albumart"... May 14 22:09:28 volumio-skloby volumio[844]: info: Plugin example_plugin is not enabled May 14 22:09:28 volumio-skloby volumio[844]: info: Loading plugin "inputs"... May 14 22:09:28 volumio-skloby volumio[844]: info: Loading plugin "updater_comm"... May 14 22:09:29 volumio-skloby volumio[844]: info: Plugin mpdemulation is not enabled May 14 22:09:29 volumio-skloby volumio[844]: info: Loading plugin "rest_api"... May 14 22:09:29 volumio-skloby volumio[844]: info: Loading plugin "websocket"... May 14 22:09:29 volumio-skloby volumio[844]: info: Starting Socket.io Server version 2.3.0 May 14 22:09:29 volumio-skloby volumio[844]: info: Loading plugin "fusiondsp"... May 14 22:09:29 volumio-skloby volumio[844]: Forking 3 albumart workers May 14 22:09:29 volumio-skloby sshd[1010]: Accepted password for volumio from 192.168.1.69 port 63140 ssh2 May 14 22:09:29 volumio-skloby sshd[1010]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 14 22:09:29 volumio-skloby systemd[1]: Created slice User Slice of UID 1000. May 14 22:09:29 volumio-skloby systemd[1]: Starting User Runtime Directory /run/user/1000... May 14 22:09:29 volumio-skloby systemd-logind[569]: New session 1 of user volumio. May 14 22:09:29 volumio-skloby systemd[1]: Started User Runtime Directory /run/user/1000. May 14 22:09:29 volumio-skloby systemd[1]: Starting User Manager for UID 1000... May 14 22:09:29 volumio-skloby systemd[1047]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) May 14 22:09:29 volumio-skloby volumio-remote-updater[575]: [2025-05-14 22:09:29] [connect] Successful connection May 14 22:09:30 volumio-skloby volumio[844]: info: Applying required configuration parameters for plugin fusiondsp May 14 22:09:30 volumio-skloby systemd[1047]: Listening on Sound System. May 14 22:09:30 volumio-skloby systemd[1047]: Reached target Timers. May 14 22:09:30 volumio-skloby systemd[1047]: Listening on GnuPG cryptographic agent and passphrase cache. May 14 22:09:30 volumio-skloby systemd[1047]: Listening on GnuPG cryptographic agent (ssh-agent emulation). May 14 22:09:30 volumio-skloby systemd[1047]: Starting D-Bus User Message Bus Socket. May 14 22:09:30 volumio-skloby systemd[1047]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). May 14 22:09:30 volumio-skloby systemd[1047]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). May 14 22:09:30 volumio-skloby systemd[1047]: Reached target Paths. May 14 22:09:30 volumio-skloby systemd[1047]: Listening on GnuPG network certificate management daemon. May 14 22:09:30 volumio-skloby systemd[1047]: Listening on D-Bus User Message Bus Socket. May 14 22:09:30 volumio-skloby systemd[1047]: Reached target Sockets. May 14 22:09:30 volumio-skloby systemd[1047]: Reached target Basic System. May 14 22:09:30 volumio-skloby systemd[1047]: Reached target Default. May 14 22:09:30 volumio-skloby systemd[1047]: Startup finished in 434ms. May 14 22:09:30 volumio-skloby systemd[1]: Started User Manager for UID 1000. May 14 22:09:30 volumio-skloby systemd[1]: Started Session 1 of user volumio. May 14 22:09:30 volumio-skloby volumio[844]: info: Loading plugin "ir_controller"... May 14 22:09:30 volumio-skloby volumio[844]: Starting albumart workers May 14 22:09:30 volumio-skloby volumio[844]: Starting albumart workers May 14 22:09:30 volumio-skloby volumio[844]: Starting albumart workers May 14 22:09:30 volumio-skloby volumio[844]: info: Applying required configuration parameters for plugin ir_controller May 14 22:09:30 volumio-skloby volumio[844]: info: Loading i18n strings for locale en May 14 22:09:30 volumio-skloby volumio[844]: Updating browse sources language May 14 22:09:30 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::initPlayerControls May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 22:09:31 volumio-skloby volumio[844]: Express server listening on port 3000 May 14 22:09:31 volumio-skloby volumio[844]: [Metrics] WebUI: 8s 860.94ms May 14 22:09:31 volumio-skloby volumio[844]: info: CoreStateMachine::resetVolumioState May 14 22:09:31 volumio-skloby volumio[844]: info: CoreStateMachine::getcurrentVolume May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioRetrievevolume May 14 22:09:31 volumio-skloby volumio[844]: info: Volumio Network Manager: Network status updated: 1 May 14 22:09:31 volumio-skloby volumio-remote-updater[575]: [2025-05-14 22:09:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747253369 101 May 14 22:09:31 volumio-skloby volumio[844]: 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: 1 May 14 22:09:31 volumio-skloby volumio[844]: info: Reloading queue from file May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:31 volumio-skloby volumio[844]: info: VolumeController:: Volume=30 Mute =false May 14 22:09:31 volumio-skloby volumio[844]: info: CoreStateMachine::pushState May 14 22:09:31 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioPushState May 14 22:09:31 volumio-skloby volumio[844]: info: CoreStateMachine::updateTrackBlock May 14 22:09:31 volumio-skloby volumio[844]: info: CorePlayQueue::getTrackBlock May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioRetrievevolume May 14 22:09:31 volumio-skloby volumio[844]: info: Setting Device type: Raspberry PI May 14 22:09:31 volumio-skloby volumio[844]: info: CoreStateMachine::setRepeat null single undefined May 14 22:09:31 volumio-skloby volumio[844]: info: CoreStateMachine::pushState May 14 22:09:31 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioPushState May 14 22:09:31 volumio-skloby volumio[844]: info: CoreStateMachine::setRandom false May 14 22:09:31 volumio-skloby volumio[844]: info: CoreStateMachine::pushState May 14 22:09:31 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioPushState May 14 22:09:31 volumio-skloby volumio[844]: info: Completed loading Core Plugins May 14 22:09:31 volumio-skloby volumio[844]: info: Preparing to generate the ALSA configuration file May 14 22:09:31 volumio-skloby volumio[844]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 14 22:09:31 volumio-skloby volumio[844]: info: Reading ALSA contributions from plugins. May 14 22:09:31 volumio-skloby volumio[844]: info: VolumeController:: Volume=30 Mute =false May 14 22:09:31 volumio-skloby volumio[844]: info: CoreStateMachine::pushState May 14 22:09:31 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioPushState May 14 22:09:31 volumio-skloby volumio[844]: info: Asound.conf file written May 14 22:09:31 volumio-skloby sudo[1106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 14 22:09:31 volumio-skloby sudo[1106]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:31 volumio-skloby sudo[1106]: pam_unix(sudo:session): session closed for user root May 14 22:09:31 volumio-skloby volumio[844]: info: Output device has changed, restarting MPD May 14 22:09:31 volumio-skloby volumio[844]: info: Output device has changed, restarting Shairport Sync May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:31 volumio-skloby sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 22:09:31 volumio-skloby sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:31 volumio-skloby sudo[1111]: pam_unix(sudo:session): session closed for user root May 14 22:09:31 volumio-skloby sudo[1113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 22:09:31 volumio-skloby sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:31 volumio-skloby systemd[1]: Stopping Music Player Daemon... May 14 22:09:31 volumio-skloby volumio[844]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 22:09:31 volumio-skloby volumio[844]: info: ___________ START PLUGINS ___________ May 14 22:09:31 volumio-skloby volumio[844]: info: ControllerMpd::onStart: Initializing MPD May 14 22:09:31 volumio-skloby volumio[844]: info: Creating MPD Configuration file May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 14 22:09:31 volumio-skloby volumio[844]: info: [1747253371962] CoreMusicLibrary::Adding element Media Servers May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 22:09:31 volumio-skloby sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 22:09:31 volumio-skloby sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:31 volumio-skloby sudo[1120]: pam_unix(sudo:session): session closed for user root May 14 22:09:31 volumio-skloby sudo[1122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 22:09:31 volumio-skloby sudo[1122]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:31 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:32 volumio-skloby systemd[1]: mpd.service: Succeeded. May 14 22:09:32 volumio-skloby systemd[1]: Stopped Music Player Daemon. May 14 22:09:32 volumio-skloby volumio[844]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 14 22:09:32 volumio-skloby volumio[844]: info: [1747253372100] CoreMusicLibrary::Adding element Last_100 May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 14 22:09:32 volumio-skloby volumio[844]: info: [1747253372103] CoreMusicLibrary::Adding element Webradio May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 22:09:32 volumio-skloby volumio[844]: info: Initializing BBC Radios May 14 22:09:32 volumio-skloby systemd[1]: Starting Music Player Daemon... May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: Loading i18n strings for locale en May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: FusionDsp - mixtype--------------------- Hardware May 14 22:09:32 volumio-skloby volumio[844]: info: Loading i18n strings for locale en May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 22:09:32 volumio-skloby volumio[844]: info: Volumio Calling Home May 14 22:09:32 volumio-skloby sudo[1131]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 14 22:09:32 volumio-skloby sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:32 volumio-skloby sudo[1131]: pam_unix(sudo:session): session closed for user root May 14 22:09:32 volumio-skloby volumio[844]: info: Preparing to generate the ALSA configuration file May 14 22:09:32 volumio-skloby sudo[1150]: 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 May 14 22:09:32 volumio-skloby sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:32 volumio-skloby sudo[1150]: pam_unix(sudo:session): session closed for user root May 14 22:09:32 volumio-skloby volumio[844]: info: Discovery: adding da1e217f-7b17-40a5-83ee-ab1f5ea8e8b5 May 14 22:09:32 volumio-skloby volumio[844]: info: Discovery: Found device Volumio Skloby May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:32 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:32 volumio-skloby volumio[844]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 14 22:09:32 volumio-skloby volumio[844]: info: Reading ALSA contributions from plugins. May 14 22:09:32 volumio-skloby volumio[844]: info: ir_controller: File permissions successfully set on /etc/lirc/*. May 14 22:09:32 volumio-skloby volumio[844]: info: MPD Permissions set May 14 22:09:32 volumio-skloby volumio[844]: info: MPD Permissions set May 14 22:09:32 volumio-skloby volumio[844]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:32 volumio-skloby volumio[844]: info: Volumio called home May 14 22:09:32 volumio-skloby volumio[844]: info: Starting Shairport Sync May 14 22:09:32 volumio-skloby volumio[844]: info: Starting Shairport Sync May 14 22:09:32 volumio-skloby volumio[844]: info: Starting Shairport Sync May 14 22:09:32 volumio-skloby sudo[1161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 22:09:32 volumio-skloby sudo[1161]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:32 volumio-skloby sudo[1163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 22:09:32 volumio-skloby sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:32 volumio-skloby sudo[1166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 22:09:32 volumio-skloby sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:32 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:32 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:32 volumio-skloby systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 14 22:09:32 volumio-skloby systemd[1]: shairport-sync.service: Succeeded. May 14 22:09:32 volumio-skloby systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 14 22:09:32 volumio-skloby volumio[844]: info: ir_controller: LIRC correctly updated. May 14 22:09:32 volumio-skloby volumio[844]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver! May 14 22:09:32 volumio-skloby systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 14 22:09:32 volumio-skloby sudo[1166]: pam_unix(sudo:session): session closed for user root May 14 22:09:32 volumio-skloby sudo[1163]: pam_unix(sudo:session): session closed for user root May 14 22:09:32 volumio-skloby sudo[1161]: pam_unix(sudo:session): session closed for user root May 14 22:09:33 volumio-skloby volumio[844]: info: Asound.conf file unchanged, so no further update is needed May 14 22:09:33 volumio-skloby volumio[844]: info: Output device has changed, restarting MPD May 14 22:09:33 volumio-skloby volumio[844]: info: Output device has changed, restarting Shairport Sync May 14 22:09:33 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:33 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:33 volumio-skloby sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 22:09:33 volumio-skloby sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:33 volumio-skloby sudo[1175]: pam_unix(sudo:session): session closed for user root May 14 22:09:33 volumio-skloby sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 22:09:33 volumio-skloby sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:33 volumio-skloby volumio[844]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 22:09:33 volumio-skloby systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 14 22:09:33 volumio-skloby systemd[1]: mpd.service: Succeeded. May 14 22:09:33 volumio-skloby systemd[1]: Stopped Music Player Daemon. May 14 22:09:33 volumio-skloby volumio[844]: info: ir_controller: Raspberry Pi revision code: a020d3 May 14 22:09:33 volumio-skloby volumio[844]: info: Shairport-Sync Started May 14 22:09:33 volumio-skloby volumio[844]: Error adding Membership: Error: addMembership EINVAL May 14 22:09:33 volumio-skloby volumio[844]: info: Shairport-Sync Started May 14 22:09:33 volumio-skloby volumio[844]: info: Shairport-Sync Started May 14 22:09:33 volumio-skloby systemd[1]: Starting Music Player Daemon... May 14 22:09:33 volumio-skloby volumio[844]: info: MPD Permissions set May 14 22:09:33 volumio-skloby sudo[1183]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 14 22:09:33 volumio-skloby sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:33 volumio-skloby sudo[1183]: pam_unix(sudo:session): session closed for user root May 14 22:09:33 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:33 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:33 volumio-skloby sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r -1 May 14 22:09:33 volumio-skloby sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:33 volumio-skloby sudo[1189]: pam_unix(sudo:session): session closed for user root May 14 22:09:33 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:33 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:33 volumio-skloby volumio[844]: info: Starting Shairport Sync May 14 22:09:33 volumio-skloby sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 22:09:33 volumio-skloby sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:33 volumio-skloby systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 14 22:09:33 volumio-skloby systemd[1]: shairport-sync.service: Succeeded. May 14 22:09:33 volumio-skloby systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 14 22:09:33 volumio-skloby sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-ir gpio_pin=25 gpio_pull=up May 14 22:09:33 volumio-skloby sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:33 volumio-skloby kernel: Registered IR keymap rc-rc6-mce May 14 22:09:33 volumio-skloby systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 14 22:09:33 volumio-skloby sudo[1198]: pam_unix(sudo:session): session closed for user root May 14 22:09:33 volumio-skloby kernel: IR RC6 protocol handler initialized May 14 22:09:33 volumio-skloby volumio[844]: info: Shairport-Sync Started May 14 22:09:33 volumio-skloby sudo[1203]: pam_unix(sudo:session): session closed for user root May 14 22:09:33 volumio-skloby volumio[844]: info: ir_controller: Overlay gpio-ir gpio_pin=25 gpio_pull=up loaded. May 14 22:09:33 volumio-skloby kernel: rc rc0: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0 May 14 22:09:33 volumio-skloby kernel: rc rc0: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter May 14 22:09:33 volumio-skloby kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0/input0 May 14 22:09:33 volumio-skloby sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service May 14 22:09:33 volumio-skloby sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:33 volumio-skloby systemd[1]: Starting lircd(8) initialization helper tool... May 14 22:09:33 volumio-skloby systemd-udevd[1211]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 14 22:09:33 volumio-skloby systemd-logind[569]: Watching system buttons on /dev/input/event0 (gpio_ir_recv) May 14 22:09:33 volumio-skloby sudo[1227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service May 14 22:09:33 volumio-skloby sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:34 volumio-skloby sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 22:09:34 volumio-skloby sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:34 volumio-skloby sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 22:09:34 volumio-skloby sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:34 volumio-skloby sudo[1230]: pam_unix(sudo:session): session closed for user root May 14 22:09:34 volumio-skloby sudo[1233]: pam_unix(sudo:session): session closed for user root May 14 22:09:34 volumio-skloby lircd-setup[1223]: /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. May 14 22:09:34 volumio-skloby lircd-setup[1223]: parser = configparser.SafeConfigParser() May 14 22:09:34 volumio-skloby systemd[1]: lircd-setup.service: Succeeded. May 14 22:09:34 volumio-skloby volumio[844]: info: camilladsp service started and running in background, instance 1 May 14 22:09:34 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:34 volumio-skloby systemd[1]: Started lircd(8) initialization helper tool. May 14 22:09:34 volumio-skloby volumio[844]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA May 14 22:09:34 volumio-skloby systemd[1]: Starting Flexible IR remote input/output application support... May 14 22:09:34 volumio-skloby volumio[844]: info: FusionDsp loaded May 14 22:09:34 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 22:09:34 volumio-skloby sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Info: lircd: Opening log, level: Info May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Version: lircd 0.10.1 May 14 22:09:34 volumio-skloby sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: System info: Linux volumio-skloby 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l GNU/Linux May 14 22:09:34 volumio-skloby volumio[844]: info: FusionDsp - Reporting Fusion DSP Enabled May 14 22:09:34 volumio-skloby volumio[844]: info: Adding Signal Path Element [object Object] May 14 22:09:34 volumio-skloby volumio[844]: info: Adding fusiondspeq DSP Signal Path Element May 14 22:09:34 volumio-skloby volumio[844]: info: FusionDsp - ---- installed callbackRead May 14 22:09:34 volumio-skloby volumio[844]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 14 22:09:34 volumio-skloby systemd[1]: Started FusionDsp Daemon. May 14 22:09:34 volumio-skloby sudo[1246]: pam_unix(sudo:session): session closed for user root May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Info: Initial device: /dev/lirc0 May 14 22:09:34 volumio-skloby systemd[1]: Started Flexible IR remote input/output application support. May 14 22:09:34 volumio-skloby lircd[1243]: lircd-0.10.1[1243]: Info: lircd: Opening log, level: Info May 14 22:09:34 volumio-skloby lircd[1243]: lircd-0.10.1[1243]: Notice: Using systemd fd May 14 22:09:34 volumio-skloby lircd[1243]: lircd-0.10.1[1243]: Warning: Running as root May 14 22:09:34 volumio-skloby lircd[1243]: lircd-0.10.1[1243]: Info: Using remote: Toshiba_CT-90326. May 14 22:09:34 volumio-skloby lircd[1243]: lircd-0.10.1[1243]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Info: Initial device: /dev/lirc0 May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Info: [lirc] protocol is enabled May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: driver: default May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: output: /var/run/lirc/lircd May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: nodaemon: 1 May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: logfile: syslog May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: immediate-init: 0 May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: permission: 666 May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: driver-options: May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: pidfile: /var/run/lirc/lircd.pid May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: listen: 0 May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: connect: (null) May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: userelease: 0 May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: effective_user: (null) May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: release_suffix: _EVUP May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: allow_simulate: 0 May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: repeat_max: 600 May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: configfile: /etc/lirc/lircd.conf May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Options: dynamic_codes: (null) May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Current driver: default May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Driver API version: 3 May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Driver version: 0.10.0 May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Info: lircd: Opening log, level: Info May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: Using systemd fd May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Warning: Running as root May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Info: Using remote: Toshiba_CT-90326. May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 14 22:09:34 volumio-skloby sudo[1218]: pam_unix(sudo:session): session closed for user root May 14 22:09:34 volumio-skloby sudo[1227]: pam_unix(sudo:session): session closed for user root May 14 22:09:34 volumio-skloby volumio[844]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.69 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 14 22:09:34 volumio-skloby volumio[844]: info: ir_controller: systemctl restart lircd.service succeeded. May 14 22:09:34 volumio-skloby sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service May 14 22:09:34 volumio-skloby sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:34 volumio-skloby volumio[844]: info: ir_controller: systemctl restart lircd.service succeeded. May 14 22:09:34 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetVisibleSources May 14 22:09:34 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 22:09:34 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 14 22:09:34 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:34 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:34 volumio-skloby sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service May 14 22:09:34 volumio-skloby sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:34 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 14 22:09:34 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 14 22:09:34 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 14 22:09:34 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 14 22:09:34 volumio-skloby systemd[1]: Started Handle events from IR remotes decoded by lircd(8). May 14 22:09:34 volumio-skloby sudo[1259]: pam_unix(sudo:session): session closed for user root May 14 22:09:34 volumio-skloby systemd[1]: Stopping Handle events from IR remotes decoded by lircd(8)... May 14 22:09:34 volumio-skloby systemd[1]: irexec.service: Main process exited, code=killed, status=15/TERM May 14 22:09:34 volumio-skloby systemd[1]: irexec.service: Succeeded. May 14 22:09:34 volumio-skloby systemd[1]: Stopped Handle events from IR remotes decoded by lircd(8). May 14 22:09:34 volumio-skloby systemd[1]: Started Handle events from IR remotes decoded by lircd(8). May 14 22:09:34 volumio-skloby lircd[1243]: lircd-0.10.1[1243]: Notice: accepted new client on /var/run/lirc/lircd May 14 22:09:34 volumio-skloby lircd[1243]: lircd-0.10.1[1243]: Info: [lirc] protocol is enabled May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Notice: accepted new client on /var/run/lirc/lircd May 14 22:09:34 volumio-skloby lircd-0.10.1[1243]: Info: [lirc] protocol is enabled May 14 22:09:34 volumio-skloby sudo[1263]: pam_unix(sudo:session): session closed for user root May 14 22:09:34 volumio-skloby volumio[844]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 14 22:09:34 volumio-skloby volumio[844]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 14 22:09:34 volumio-skloby volumio[844]: info: ir_controller: systemctl restart irexec.service succeeded. May 14 22:09:34 volumio-skloby sudo[1269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 22:09:34 volumio-skloby sudo[1269]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:34 volumio-skloby sudo[1267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 22:09:34 volumio-skloby sudo[1269]: pam_unix(sudo:session): session closed for user root May 14 22:09:34 volumio-skloby sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:34 volumio-skloby sudo[1267]: pam_unix(sudo:session): session closed for user root May 14 22:09:34 volumio-skloby volumio[844]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.69 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 14 22:09:35 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:35 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:35 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 14 22:09:35 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetVisibleSources May 14 22:09:35 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 22:09:35 volumio-skloby volumio[844]: info: Listing playlists May 14 22:09:35 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 14 22:09:35 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 14 22:09:35 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 14 22:09:35 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 14 22:09:35 volumio-skloby mpd[1188]: May 14 22:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 14 22:09:35 volumio-skloby systemd[1]: Started Music Player Daemon. May 14 22:09:35 volumio-skloby sudo[1113]: pam_unix(sudo:session): session closed for user root May 14 22:09:35 volumio-skloby sudo[1122]: pam_unix(sudo:session): session closed for user root May 14 22:09:35 volumio-skloby sudo[1177]: pam_unix(sudo:session): session closed for user root May 14 22:09:35 volumio-skloby volumio[844]: info: Completed starting Core Plugins May 14 22:09:35 volumio-skloby volumio[844]: info: ------------------------------------------- May 14 22:09:35 volumio-skloby volumio[844]: info: ----- MyVolumio plugins startup ---- May 14 22:09:35 volumio-skloby volumio[844]: info: ------------------------------------------- May 14 22:09:35 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Fetching plans data.... May 14 22:09:35 volumio-skloby volumio[844]: error: MPD error: The expression evaluated to a falsy value: May 14 22:09:35 volumio-skloby volumio[844]: assert.ok(self.idling) May 14 22:09:35 volumio-skloby volumio[844]: error: The expression evaluated to a falsy value: May 14 22:09:35 volumio-skloby volumio[844]: assert.ok(self.idling) May 14 22:09:35 volumio-skloby volumio[844]: error: MPD error: The expression evaluated to a falsy value: May 14 22:09:35 volumio-skloby volumio[844]: assert.ok(self.idling) May 14 22:09:35 volumio-skloby volumio[844]: error: The expression evaluated to a falsy value: May 14 22:09:35 volumio-skloby volumio[844]: assert.ok(self.idling) May 14 22:09:35 volumio-skloby volumio[844]: info: MPD running with PID1188 May 14 22:09:35 volumio-skloby volumio[844]: ,establishing connection May 14 22:09:35 volumio-skloby volumio[844]: error: MPD error: The expression evaluated to a falsy value: May 14 22:09:35 volumio-skloby volumio[844]: assert.ok(self.idling) May 14 22:09:35 volumio-skloby volumio[844]: error: The expression evaluated to a falsy value: May 14 22:09:35 volumio-skloby volumio[844]: assert.ok(self.idling) May 14 22:09:35 volumio-skloby volumio[844]: error: updateQueue error: null May 14 22:09:37 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 22:09:37 volumio-skloby volumio[844]: info: Received Get System Info May 14 22:09:37 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 22:09:37 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 22:09:37 volumio-skloby volumio[844]: info: Discovery: Getting this device information May 14 22:09:37 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:37 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:37 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 22:09:37 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 22:09:37 volumio-skloby sudo[1291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 22:09:37 volumio-skloby sudo[1291]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:37 volumio-skloby sudo[1291]: pam_unix(sudo:session): session closed for user root May 14 22:09:37 volumio-skloby sudo[1293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 22:09:37 volumio-skloby sudo[1293]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:37 volumio-skloby sudo[1293]: pam_unix(sudo:session): session closed for user root May 14 22:09:37 volumio-skloby sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 14 22:09:37 volumio-skloby sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:37 volumio-skloby systemd[1]: Started UPnP Renderer front-end to MPD. May 14 22:09:37 volumio-skloby sudo[1297]: pam_unix(sudo:session): session closed for user root May 14 22:09:37 volumio-skloby volumio[844]: info: Upmpdcli Daemon Started May 14 22:09:37 volumio-skloby volumio[1299]: Generating RSA private key, 4096 bit long modulus (2 primes) May 14 22:09:38 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 22:09:38 volumio-skloby volumio[844]: info: Received Get System Info May 14 22:09:38 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 22:09:38 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 22:09:38 volumio-skloby volumio[844]: info: Discovery: Getting this device information May 14 22:09:38 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:38 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:38 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 22:09:38 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 14 22:09:39 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 14 22:09:40 volumio-skloby volumio[844]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 14 22:09:40 volumio-skloby sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 22:09:40 volumio-skloby sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:40 volumio-skloby sudo[1316]: pam_unix(sudo:session): session closed for user root May 14 22:09:40 volumio-skloby sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 22:09:40 volumio-skloby sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:40 volumio-skloby sudo[1318]: pam_unix(sudo:session): session closed for user root May 14 22:09:41 volumio-skloby volumio[844]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.69 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 14 22:09:41 volumio-skloby sudo[1322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 22:09:41 volumio-skloby sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:41 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:41 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:41 volumio-skloby sudo[1322]: pam_unix(sudo:session): session closed for user root May 14 22:09:41 volumio-skloby sudo[1324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 22:09:41 volumio-skloby sudo[1324]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:41 volumio-skloby sudo[1324]: pam_unix(sudo:session): session closed for user root May 14 22:09:41 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 14 22:09:41 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetVisibleSources May 14 22:09:41 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 22:09:41 volumio-skloby volumio[844]: info: Listing playlists May 14 22:09:41 volumio-skloby volumio[844]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.69 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 14 22:09:41 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:41 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:41 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 14 22:09:41 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetVisibleSources May 14 22:09:41 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 22:09:41 volumio-skloby volumio[844]: info: Listing playlists May 14 22:09:42 volumio-skloby sudo[1331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 22:09:42 volumio-skloby sudo[1331]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:42 volumio-skloby sudo[1331]: pam_unix(sudo:session): session closed for user root May 14 22:09:42 volumio-skloby sudo[1333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 22:09:42 volumio-skloby sudo[1333]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:42 volumio-skloby sudo[1333]: pam_unix(sudo:session): session closed for user root May 14 22:09:42 volumio-skloby volumio[844]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.69 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 14 22:09:43 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:43 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:43 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 14 22:09:43 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetVisibleSources May 14 22:09:43 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 22:09:43 volumio-skloby volumio[844]: info: Listing playlists May 14 22:09:43 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 22:09:43 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 22:09:43 volumio-skloby volumio[844]: info: Received Get System Info May 14 22:09:43 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 22:09:43 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 22:09:43 volumio-skloby volumio[844]: info: Discovery: Getting this device information May 14 22:09:43 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:43 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:43 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 14 22:09:43 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 14 22:09:43 volumio-skloby volumio[844]: info: Adding plugin bluetooth to MyMusic Plugins May 14 22:09:43 volumio-skloby volumio[844]: info: Adding plugin multiroom to MyMusic Plugins May 14 22:09:44 volumio-skloby volumio[844]: info: Adding plugin metavolumio to MyMusic Plugins May 14 22:09:44 volumio-skloby volumio[844]: info: Adding plugin cd_controller to MyMusic Plugins May 14 22:09:44 volumio-skloby volumio[844]: info: Adding plugin qobuzconnect to MyMusic Plugins May 14 22:09:44 volumio-skloby volumio[844]: info: Adding plugin smart_inputs to MyMusic Plugins May 14 22:09:44 volumio-skloby volumio[844]: info: Adding plugin tidalconnect to MyMusic Plugins May 14 22:09:44 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 14 22:09:44 volumio-skloby systemd[1]: systemd-fsckd.service: Succeeded. May 14 22:09:45 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 14 22:09:45 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 14 22:09:45 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:45 volumio-skloby volumio[844]: info: Starting MyVolumio Remote Streaming Endpoints May 14 22:09:45 volumio-skloby volumio[844]: info: MyVolumio login type: Token May 14 22:09:45 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 14 22:09:45 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 14 22:09:46 volumio-skloby volumio[844]: info: Starting Streaming Service Transparent Proxy May 14 22:09:46 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 14 22:09:46 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 14 22:09:46 volumio-skloby volumio[844]: info: Streaming services startup May 14 22:09:46 volumio-skloby volumio[844]: info: Starting Streaming Daemon May 14 22:09:46 volumio-skloby sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 14 22:09:46 volumio-skloby sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:46 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 14 22:09:46 volumio-skloby sudo[1343]: pam_unix(sudo:session): session closed for user root May 14 22:09:46 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 14 22:09:46 volumio-skloby volumio[844]: info: MyVolumio login type: Token May 14 22:09:46 volumio-skloby volumio[844]: info: CoreCommandRouter::Close All Modals sent May 14 22:09:46 volumio-skloby volumio[844]: error: Cannot start Volumio Streaming Daemon May 14 22:09:46 volumio-skloby volumio[844]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 14 22:09:46 volumio-skloby volumio[844]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 14 22:09:47 volumio-skloby sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 22:09:47 volumio-skloby sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:47 volumio-skloby sudo[1351]: pam_unix(sudo:session): session closed for user root May 14 22:09:47 volumio-skloby sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 22:09:47 volumio-skloby sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:47 volumio-skloby sudo[1354]: pam_unix(sudo:session): session closed for user root May 14 22:09:47 volumio-skloby volumio[844]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.69 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 14 22:09:47 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetVisibleSources May 14 22:09:47 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 22:09:47 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 14 22:09:47 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:47 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:47 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 14 22:09:47 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 14 22:09:47 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 14 22:09:47 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 14 22:09:47 volumio-skloby sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 22:09:47 volumio-skloby sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:47 volumio-skloby sudo[1357]: pam_unix(sudo:session): session closed for user root May 14 22:09:47 volumio-skloby sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 22:09:47 volumio-skloby sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:47 volumio-skloby sudo[1359]: pam_unix(sudo:session): session closed for user root May 14 22:09:47 volumio-skloby volumio[844]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.69 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 14 22:09:47 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:47 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:47 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 14 22:09:47 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetVisibleSources May 14 22:09:47 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 22:09:47 volumio-skloby volumio[1299]: ................................................................................++++ May 14 22:09:47 volumio-skloby volumio[844]: info: Listing playlists May 14 22:09:47 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 14 22:09:47 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 14 22:09:47 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 14 22:09:47 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 14 22:09:48 volumio-skloby volumio[844]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 14 22:09:48 volumio-skloby volumio[844]: STREAMING PROXY: Starting server on port 3245 May 14 22:09:48 volumio-skloby volumio[844]: Node JS runtime: 14 May 14 22:09:48 volumio-skloby systemd[1]: systemd-hostnamed.service: Succeeded. May 14 22:09:49 volumio-skloby volumio[844]: info: MyVolumio token set successfully May 14 22:09:49 volumio-skloby volumio[844]: info: MYVOLUMIO: Adding device May 14 22:09:49 volumio-skloby volumio[844]: info: MYVOLUMIO: Evaluating Server May 14 22:09:49 volumio-skloby volumio[844]: info: MyVolumio Plan changed: premium May 14 22:09:49 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 14 22:09:49 volumio-skloby volumio[844]: info: Removing browser output: myVolumio user plan is not superstar May 14 22:09:49 volumio-skloby volumio[844]: info: Removing audio output: May 14 22:09:49 volumio-skloby volumio[844]: info: MYVOLUMIO: Adding device May 14 22:09:49 volumio-skloby volumio[844]: info: MYVOLUMIO: Evaluating Server May 14 22:09:49 volumio-skloby volumio[844]: info: Remote config written successfully May 14 22:09:49 volumio-skloby volumio[844]: info: Starting Tunnel 1 May 14 22:09:49 volumio-skloby volumio[844]: info: Starting Tunnel Connection Checker May 14 22:09:49 volumio-skloby volumio[844]: info: Completed starting MyVolumio Plugin May 14 22:09:49 volumio-skloby volumio[844]: info: MYVolumio Device enabled May 14 22:09:50 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 14 22:09:50 volumio-skloby volumio[844]: info: MyVolumio status changed May 14 22:09:50 volumio-skloby volumio[844]: info: Streaming services startup May 14 22:09:50 volumio-skloby volumio[844]: info: Starting Streaming Daemon May 14 22:09:50 volumio-skloby sudo[1413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 14 22:09:50 volumio-skloby sudo[1413]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:50 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 14 22:09:50 volumio-skloby sudo[1413]: pam_unix(sudo:session): session closed for user root May 14 22:09:50 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 14 22:09:50 volumio-skloby volumio[844]: info: [1747253390304] Starting BluetoothController May 14 22:09:50 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 14 22:09:52 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom May 14 22:09:52 volumio-skloby sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 14 22:09:52 volumio-skloby sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:52 volumio-skloby sudo[1416]: pam_unix(sudo:session): session closed for user root May 14 22:09:52 volumio-skloby volumio[844]: info: MRS: MultiRoom plugin initialized May 14 22:09:52 volumio-skloby volumio[844]: info: MRS: STOPPING SNAPCLIENT May 14 22:09:52 volumio-skloby volumio[844]: info: MRS: Snap server stop May 14 22:09:52 volumio-skloby sudo[1432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 14 22:09:52 volumio-skloby sudo[1432]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:52 volumio-skloby volumio[844]: info: MRS: STOPPING volumioStreaming May 14 22:09:52 volumio-skloby sudo[1434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 14 22:09:52 volumio-skloby sudo[1434]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:52 volumio-skloby sudo[1432]: pam_unix(sudo:session): session closed for user root May 14 22:09:52 volumio-skloby sudo[1438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 14 22:09:52 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 14 22:09:52 volumio-skloby sudo[1438]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:52 volumio-skloby sudo[1440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 14 22:09:52 volumio-skloby sudo[1440]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:52 volumio-skloby sudo[1440]: pam_unix(sudo:session): session closed for user root May 14 22:09:52 volumio-skloby sudo[1434]: pam_unix(sudo:session): session closed for user root May 14 22:09:52 volumio-skloby sudo[1438]: pam_unix(sudo:session): session closed for user root May 14 22:09:52 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 14 22:09:52 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 14 22:09:52 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 14 22:09:52 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 14 22:09:52 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio May 14 22:09:52 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 14 22:09:52 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 14 22:09:52 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 14 22:09:52 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... May 14 22:09:52 volumio-skloby volumio[844]: info: Preparing to generate the ALSA configuration file May 14 22:09:52 volumio-skloby volumio[844]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 14 22:09:52 volumio-skloby volumio[844]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 14 22:09:52 volumio-skloby volumio[844]: info: Reading ALSA contributions from plugins. May 14 22:09:52 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 22:09:52 volumio-skloby volumio[844]: info: Received Get System Info May 14 22:09:52 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 22:09:52 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 22:09:52 volumio-skloby volumio[844]: info: Discovery: Getting this device information May 14 22:09:52 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:52 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:52 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 22:09:52 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 22:09:52 volumio-skloby volumio[844]: info: Received Get System Info May 14 22:09:52 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 22:09:52 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 22:09:52 volumio-skloby volumio[844]: info: Discovery: Getting this device information May 14 22:09:52 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:52 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:52 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 22:09:52 volumio-skloby volumio[844]: info: Setting Geolocation for MyVolumio to eu6 May 14 22:09:52 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:52 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:52 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:52 volumio-skloby volumio[844]: info: Setting Geolocation for MyVolumio to eu10 May 14 22:09:52 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:52 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:52 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:52 volumio-skloby volumio[844]: info: MRS: Removed streaming files May 14 22:09:52 volumio-skloby volumio[844]: info: MRS: volumioStreaming STOPPED May 14 22:09:52 volumio-skloby volumio[844]: info: MRS: SNAPSERVER STOPPED May 14 22:09:52 volumio-skloby volumio[844]: info: MRS: SNAPCLIENT STOPPED May 14 22:09:52 volumio-skloby volumio[844]: error: Cannot start Volumio Streaming Daemon May 14 22:09:52 volumio-skloby volumio[844]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 14 22:09:52 volumio-skloby volumio[844]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 14 22:09:52 volumio-skloby volumio[844]: info: Asound.conf file written May 14 22:09:53 volumio-skloby sudo[1444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 14 22:09:53 volumio-skloby sudo[1444]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:53 volumio-skloby sudo[1444]: pam_unix(sudo:session): session closed for user root May 14 22:09:53 volumio-skloby systemd[1]: systemd-timedated.service: Succeeded. May 14 22:09:53 volumio-skloby volumio[844]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy May 14 22:09:53 volumio-skloby volumio[844]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy May 14 22:09:53 volumio-skloby volumio[844]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy May 14 22:09:53 volumio-skloby volumio[844]: info: Output device has changed, restarting MPD May 14 22:09:53 volumio-skloby sudo[1451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 22:09:53 volumio-skloby sudo[1451]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:53 volumio-skloby volumio[844]: info: Output device has changed, restarting Shairport Sync May 14 22:09:53 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:53 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:53 volumio-skloby sudo[1451]: pam_unix(sudo:session): session closed for user root May 14 22:09:53 volumio-skloby sudo[1453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 22:09:53 volumio-skloby sudo[1453]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:53 volumio-skloby systemd[1]: Stopping Music Player Daemon... May 14 22:09:53 volumio-skloby volumio[844]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 22:09:53 volumio-skloby volumio[844]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 14 22:09:53 volumio-skloby systemd[1]: mpd.service: Succeeded. May 14 22:09:53 volumio-skloby systemd[1]: Stopped Music Player Daemon. May 14 22:09:53 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:53 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 14 22:09:53 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 14 22:09:53 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 14 22:09:53 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 14 22:09:53 volumio-skloby volumio[844]: info: MRS: Pushing multiroomSync output for this device May 14 22:09:53 volumio-skloby volumio[844]: info: MRS: Pushing multiroomSync output May 14 22:09:53 volumio-skloby systemd[1]: Starting Music Player Daemon... May 14 22:09:53 volumio-skloby volumio[844]: info: Adding audio output: May 14 22:09:53 volumio-skloby sudo[1463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Skloby May 14 22:09:53 volumio-skloby sudo[1463]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:53 volumio-skloby volumio[844]: info: Adding audio output: May 14 22:09:53 volumio-skloby sudo[1463]: pam_unix(sudo:session): session closed for user root May 14 22:09:53 volumio-skloby volumio[844]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 14 22:09:53 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:53 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 14 22:09:53 volumio-skloby volumio[844]: info: Adding METAVOLUMIO REST API Endpoints May 14 22:09:53 volumio-skloby volumio[844]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 14 22:09:53 volumio-skloby volumio[844]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 14 22:09:53 volumio-skloby volumio[844]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 14 22:09:53 volumio-skloby volumio[844]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 14 22:09:53 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 14 22:09:53 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 14 22:09:53 volumio-skloby volumio[844]: info: Preparing CD Folders May 14 22:09:53 volumio-skloby volumio[844]: info: Adding CD REST API Endpoints May 14 22:09:53 volumio-skloby volumio[844]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 14 22:09:53 volumio-skloby volumio[844]: info: Starting UDEV Watcher for CD May 14 22:09:53 volumio-skloby volumio[844]: info: Detecting CD presence with UDEV May 14 22:09:53 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 14 22:09:53 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 14 22:09:53 volumio-skloby volumio[844]: info: Adding inputs REST Endpoints May 14 22:09:53 volumio-skloby volumio[844]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 14 22:09:53 volumio-skloby volumio[844]: info: Scanning Audio Inputs May 14 22:09:53 volumio-skloby sudo[1459]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 14 22:09:53 volumio-skloby sudo[1459]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:53 volumio-skloby volumio[844]: info: Checking against Known Cards name May 14 22:09:53 volumio-skloby sudo[1459]: pam_unix(sudo:session): session closed for user root May 14 22:09:53 volumio-skloby volumio[844]: info: Adding Server instance for streaming May 14 22:09:53 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 14 22:09:53 volumio-skloby volumio[844]: error: Hi Res Audio Failed Login: Missing Login Data May 14 22:09:53 volumio-skloby volumio[844]: info: Adding HIGHRESAUDIO REST API Endpoints May 14 22:09:53 volumio-skloby volumio[844]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 14 22:09:53 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 14 22:09:53 volumio-skloby volumio[844]: info: Refreshing TIDAL token May 14 22:09:53 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 14 22:09:53 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 14 22:09:53 volumio-skloby volumio[844]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect May 14 22:09:53 volumio-skloby volumio[844]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect May 14 22:09:53 volumio-skloby sudo[1473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 14 22:09:53 volumio-skloby sudo[1473]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:53 volumio-skloby volumio[844]: info: QobuzConnect: Starting Qobuz Connect socket and service May 14 22:09:53 volumio-skloby sudo[1473]: pam_unix(sudo:session): session closed for user root May 14 22:09:53 volumio-skloby volumio[844]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections May 14 22:09:53 volumio-skloby volumio[844]: info: Stopping AccessToken refresher cron for QOBUZ May 14 22:09:53 volumio-skloby sudo[1479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 14 22:09:53 volumio-skloby sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:53 volumio-skloby sudo[1479]: pam_unix(sudo:session): session closed for user root May 14 22:09:53 volumio-skloby volumio[844]: info: AccessToken refresher cron started for QOBUZ May 14 22:09:53 volumio-skloby volumio[844]: info: Adding QOBUZ REST API Endpoints May 14 22:09:53 volumio-skloby volumio[844]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 14 22:09:53 volumio-skloby sudo[1482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 14 22:09:53 volumio-skloby sudo[1482]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:53 volumio-skloby volumio[844]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 14 22:09:53 volumio-skloby volumio[844]: info: Bluetooth name changed to Volumio Skloby May 14 22:09:53 volumio-skloby volumio[844]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 14 22:09:53 volumio-skloby volumio[844]: info: MPD Permissions set May 14 22:09:53 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:53 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:53 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:53 volumio-skloby volumio[844]: info: Starting Shairport Sync May 14 22:09:53 volumio-skloby systemd[1]: Started Volumio Qobuz Connect Service. May 14 22:09:53 volumio-skloby sudo[1482]: pam_unix(sudo:session): session closed for user root May 14 22:09:53 volumio-skloby sudo[1490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 22:09:53 volumio-skloby volumio[844]: info: Preparing to generate the ALSA configuration file May 14 22:09:53 volumio-skloby sudo[1490]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:53 volumio-skloby systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 14 22:09:53 volumio-skloby systemd[1]: shairport-sync.service: Succeeded. May 14 22:09:53 volumio-skloby systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 14 22:09:53 volumio-skloby systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 14 22:09:53 volumio-skloby sudo[1490]: pam_unix(sudo:session): session closed for user root May 14 22:09:53 volumio-skloby volumio[844]: info: Updating MyVolumio device info May 14 22:09:53 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:53 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:53 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:53 volumio-skloby volumio[844]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 14 22:09:53 volumio-skloby volumio[844]: info: Reading ALSA contributions from plugins. May 14 22:09:53 volumio-skloby volumio[844]: info: Shairport-Sync Started May 14 22:09:53 volumio-skloby volumio[844]: info: Updating MyVolumio device info May 14 22:09:53 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:54 volumio-skloby volumio[844]: info: Executing endpoint qc_getconfig May 14 22:09:54 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 14 22:09:54 volumio-skloby qobuz-connect[1484]: 20250514 22:09:54.031 [1484.1484] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 14 22:09:54 volumio-skloby volumio[844]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 14 22:09:54 volumio-skloby volumio[844]: info: QobuzConnect: QOBUZ Connect daemon connected May 14 22:09:54 volumio-skloby volumio[844]: info: Asound.conf file written May 14 22:09:54 volumio-skloby sudo[1497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 14 22:09:54 volumio-skloby sudo[1497]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:54 volumio-skloby sudo[1497]: pam_unix(sudo:session): session closed for user root May 14 22:09:54 volumio-skloby qobuz-connect[1484]: 20250514 22:09:54.171 [1484.1484] INFO VolumeManager: [0xa16998]: Setting new playback volume: 75 May 14 22:09:54 volumio-skloby qobuz-connect[1484]: 20250514 22:09:54.172 [1484.1484] INFO VolumeManager: [0xa16998]: Setting new mute state: 0 May 14 22:09:54 volumio-skloby qobuz-connect[1484]: 20250514 22:09:54.172 [1484.1484] INFO QobuzConnect: [0xa17368]: Client initialized! May 14 22:09:54 volumio-skloby qobuz-connect[1484]: 20250514 22:09:54.172 [1484.1484] INFO SampleApp: Starting Avahi advertising, name: Volumio Skloby, service name: _qobuz-connect._tcp May 14 22:09:54 volumio-skloby qobuz-connect[1484]: 20250514 22:09:54.187 [1484.1484] INFO LocalConfigManager: [0xa16278]: Starting Local Configuration server May 14 22:09:54 volumio-skloby qobuz-connect[1484]: 20250514 22:09:54.187 [1484.1484] INFO SampleApp: Starting Local configuration server May 14 22:09:54 volumio-skloby qobuz-connect[1484]: 20250514 22:09:54.188 [1484.1484] INFO SampleApp: Connected to UNIX socket client 0xa0bf60 May 14 22:09:54 volumio-skloby qobuz-connect[1484]: 20250514 22:09:54.188 [1484.1484] INFO SampleApp: Playback volume changed: 75 May 14 22:09:54 volumio-skloby volumio[844]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy May 14 22:09:54 volumio-skloby volumio[844]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy May 14 22:09:54 volumio-skloby volumio[844]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy May 14 22:09:54 volumio-skloby volumio[844]: info: Output device has changed, restarting MPD May 14 22:09:54 volumio-skloby sudo[1503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 22:09:54 volumio-skloby volumio[844]: info: Output device has changed, restarting Shairport Sync May 14 22:09:54 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:54 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:54 volumio-skloby sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:54 volumio-skloby sudo[1503]: pam_unix(sudo:session): session closed for user root May 14 22:09:54 volumio-skloby sudo[1505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 22:09:54 volumio-skloby sudo[1505]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:54 volumio-skloby systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 14 22:09:54 volumio-skloby systemd[1]: mpd.service: Succeeded. May 14 22:09:54 volumio-skloby systemd[1]: Stopped Music Player Daemon. May 14 22:09:54 volumio-skloby volumio[844]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 22:09:54 volumio-skloby volumio[844]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 14 22:09:54 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:54 volumio-skloby volumio[844]: info: QobuzConnect: setDeactiveState invoked May 14 22:09:54 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:54 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:54 volumio-skloby volumio[844]: info: Preparing to generate the ALSA configuration file May 14 22:09:54 volumio-skloby systemd[1]: Starting Music Player Daemon... May 14 22:09:54 volumio-skloby volumio[844]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 14 22:09:54 volumio-skloby volumio[844]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 14 22:09:54 volumio-skloby volumio[844]: info: Reading ALSA contributions from plugins. May 14 22:09:54 volumio-skloby sudo[1518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 14 22:09:54 volumio-skloby sudo[1518]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:54 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:54 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:54 volumio-skloby sudo[1518]: pam_unix(sudo:session): session closed for user root May 14 22:09:54 volumio-skloby sudo[1511]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 14 22:09:54 volumio-skloby sudo[1511]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:54 volumio-skloby sudo[1520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 14 22:09:54 volumio-skloby sudo[1520]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:54 volumio-skloby sudo[1511]: pam_unix(sudo:session): session closed for user root May 14 22:09:54 volumio-skloby volumio[844]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 14 22:09:54 volumio-skloby volumio[844]: info: MPD Permissions set May 14 22:09:54 volumio-skloby systemd[1]: Stopping Volumio Qobuz Connect Service... May 14 22:09:54 volumio-skloby qobuz-connect[1484]: 20250514 22:09:54.577 [1484.1484] INFO SampleApp: Stopping Local configuration server May 14 22:09:54 volumio-skloby qobuz-connect[1484]: 20250514 22:09:54.588 [1484.1484] INFO SampleApp: shat down connection on UNIX socket May 14 22:09:54 volumio-skloby systemd[1]: qobuz-connect.service: Succeeded. May 14 22:09:54 volumio-skloby systemd[1]: Stopped Volumio Qobuz Connect Service. May 14 22:09:54 volumio-skloby systemd[1]: Started Volumio Qobuz Connect Service. May 14 22:09:54 volumio-skloby sudo[1520]: pam_unix(sudo:session): session closed for user root May 14 22:09:54 volumio-skloby volumio[844]: info: Successfully Added MyVolumio device May 14 22:09:54 volumio-skloby volumio[844]: info: Successfully Added MyVolumio device May 14 22:09:54 volumio-skloby volumio[844]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 14 22:09:54 volumio-skloby volumio[844]: info: QobuzConnect: setDeactiveState invoked May 14 22:09:54 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:54 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:54 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:54 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:54 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:54 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:54 volumio-skloby volumio[844]: info: Executing endpoint qc_getconfig May 14 22:09:54 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 14 22:09:54 volumio-skloby qobuz-connect[1524]: 20250514 22:09:54.764 [1524.1524] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 14 22:09:54 volumio-skloby volumio[844]: info: Starting Shairport Sync May 14 22:09:54 volumio-skloby qobuz-connect[1524]: 20250514 22:09:54.773 [1524.1524] INFO VolumeManager: [0x17fc998]: Setting new playback volume: 75 May 14 22:09:54 volumio-skloby qobuz-connect[1524]: 20250514 22:09:54.774 [1524.1524] INFO VolumeManager: [0x17fc998]: Setting new mute state: 0 May 14 22:09:54 volumio-skloby qobuz-connect[1524]: 20250514 22:09:54.775 [1524.1524] INFO QobuzConnect: [0x17fd368]: Client initialized! May 14 22:09:54 volumio-skloby qobuz-connect[1524]: 20250514 22:09:54.775 [1524.1524] INFO SampleApp: Starting Avahi advertising, name: Volumio Skloby, service name: _qobuz-connect._tcp May 14 22:09:54 volumio-skloby qobuz-connect[1524]: 20250514 22:09:54.795 [1524.1524] INFO LocalConfigManager: [0x17fc278]: Starting Local Configuration server May 14 22:09:54 volumio-skloby qobuz-connect[1524]: 20250514 22:09:54.795 [1524.1524] INFO SampleApp: Starting Local configuration server May 14 22:09:54 volumio-skloby qobuz-connect[1524]: 20250514 22:09:54.797 [1524.1524] INFO SampleApp: Connected to UNIX socket client 0x17f1f60 May 14 22:09:54 volumio-skloby volumio[844]: info: Access Token successfully retrieved May 14 22:09:54 volumio-skloby volumio[844]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 14 22:09:54 volumio-skloby volumio[844]: info: QobuzConnect: QOBUZ Connect daemon connected May 14 22:09:54 volumio-skloby sudo[1535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 22:09:54 volumio-skloby sudo[1535]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:54 volumio-skloby volumio[844]: info: Asound.conf file written May 14 22:09:54 volumio-skloby systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 14 22:09:54 volumio-skloby systemd[1]: shairport-sync.service: Succeeded. May 14 22:09:54 volumio-skloby systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 14 22:09:54 volumio-skloby qobuz-connect[1524]: 20250514 22:09:54.915 [1524.1524] INFO SampleApp: Playback volume changed: 75 May 14 22:09:54 volumio-skloby sudo[1539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 14 22:09:54 volumio-skloby sudo[1539]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:54 volumio-skloby sudo[1542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 14 22:09:54 volumio-skloby sudo[1542]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:54 volumio-skloby sudo[1542]: pam_unix(sudo:session): session closed for user root May 14 22:09:54 volumio-skloby systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 14 22:09:54 volumio-skloby sudo[1535]: pam_unix(sudo:session): session closed for user root May 14 22:09:55 volumio-skloby volumio[844]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy May 14 22:09:55 volumio-skloby volumio[844]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy May 14 22:09:55 volumio-skloby volumio[844]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy May 14 22:09:55 volumio-skloby volumio[844]: info: Output device has changed, restarting MPD May 14 22:09:55 volumio-skloby systemd[1]: Started MyVolumio SSH Tunnel. May 14 22:09:55 volumio-skloby sudo[1539]: pam_unix(sudo:session): session closed for user root May 14 22:09:55 volumio-skloby autossh[1548]: port set to 0, monitoring disabled May 14 22:09:55 volumio-skloby autossh[1548]: starting ssh (count 1) May 14 22:09:55 volumio-skloby autossh[1548]: ssh child pid is 1553 May 14 22:09:55 volumio-skloby volumio[844]: info: Output device has changed, restarting Shairport Sync May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:55 volumio-skloby sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 22:09:55 volumio-skloby sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:55 volumio-skloby sudo[1554]: pam_unix(sudo:session): session closed for user root May 14 22:09:55 volumio-skloby sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 22:09:55 volumio-skloby sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:55 volumio-skloby volumio[844]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 22:09:55 volumio-skloby volumio[844]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:55 volumio-skloby systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 14 22:09:55 volumio-skloby systemd[1]: mpd.service: Succeeded. May 14 22:09:55 volumio-skloby systemd[1]: Stopped Music Player Daemon. May 14 22:09:55 volumio-skloby volumio[844]: info: QobuzConnect: setDeactiveState invoked May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:55 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:55 volumio-skloby systemd[1]: Starting Music Player Daemon... May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:55 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:55 volumio-skloby volumio[844]: info: Successfully Updated MyVolumio device May 14 22:09:55 volumio-skloby sudo[1570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 14 22:09:55 volumio-skloby sudo[1570]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:55 volumio-skloby volumio[844]: info: Successfully Updated MyVolumio device May 14 22:09:55 volumio-skloby volumio[844]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 14 22:09:55 volumio-skloby volumio[844]: info: MPD Permissions set May 14 22:09:55 volumio-skloby volumio[844]: info: Remote SSH Started May 14 22:09:55 volumio-skloby sudo[1570]: pam_unix(sudo:session): session closed for user root May 14 22:09:55 volumio-skloby sudo[1572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 14 22:09:55 volumio-skloby sudo[1572]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:55 volumio-skloby volumiossh-tunnel[1547]: Warning: Permanently added '[eu10.myvolumio.org]:2222,[46.101.141.203]:2222' (RSA) to the list of known hosts. May 14 22:09:55 volumio-skloby systemd[1]: Stopping Volumio Qobuz Connect Service... May 14 22:09:55 volumio-skloby sudo[1565]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 14 22:09:55 volumio-skloby sudo[1565]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:55 volumio-skloby qobuz-connect[1524]: 20250514 22:09:55.407 [1524.1524] INFO SampleApp: Stopping Local configuration server May 14 22:09:55 volumio-skloby sudo[1565]: pam_unix(sudo:session): session closed for user root May 14 22:09:55 volumio-skloby qobuz-connect[1524]: 20250514 22:09:55.417 [1524.1524] INFO SampleApp: shat down connection on UNIX socket May 14 22:09:55 volumio-skloby systemd[1]: qobuz-connect.service: Succeeded. May 14 22:09:55 volumio-skloby systemd[1]: Stopped Volumio Qobuz Connect Service. May 14 22:09:55 volumio-skloby systemd[1]: Started Volumio Qobuz Connect Service. May 14 22:09:55 volumio-skloby sudo[1572]: pam_unix(sudo:session): session closed for user root May 14 22:09:55 volumio-skloby volumio[844]: info: Shairport-Sync Started May 14 22:09:55 volumio-skloby volumio[844]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 14 22:09:55 volumio-skloby volumio[844]: info: QobuzConnect: setDeactiveState invoked May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:55 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:55 volumio-skloby volumio[844]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 14 22:09:55 volumio-skloby volumio[844]: info: Executing endpoint qc_getconfig May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 14 22:09:55 volumio-skloby qobuz-connect[1576]: 20250514 22:09:55.643 [1576.1576] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 14 22:09:55 volumio-skloby qobuz-connect[1576]: 20250514 22:09:55.652 [1576.1576] INFO VolumeManager: [0x1337998]: Setting new playback volume: 75 May 14 22:09:55 volumio-skloby qobuz-connect[1576]: 20250514 22:09:55.653 [1576.1576] INFO VolumeManager: [0x1337998]: Setting new mute state: 0 May 14 22:09:55 volumio-skloby qobuz-connect[1576]: 20250514 22:09:55.653 [1576.1576] INFO QobuzConnect: [0x1338368]: Client initialized! May 14 22:09:55 volumio-skloby qobuz-connect[1576]: 20250514 22:09:55.654 [1576.1576] INFO SampleApp: Starting Avahi advertising, name: Volumio Skloby, service name: _qobuz-connect._tcp May 14 22:09:55 volumio-skloby qobuz-connect[1576]: 20250514 22:09:55.670 [1576.1576] INFO LocalConfigManager: [0x1337278]: Starting Local Configuration server May 14 22:09:55 volumio-skloby qobuz-connect[1576]: 20250514 22:09:55.671 [1576.1576] INFO SampleApp: Starting Local configuration server May 14 22:09:55 volumio-skloby qobuz-connect[1576]: 20250514 22:09:55.672 [1576.1576] INFO SampleApp: Connected to UNIX socket client 0x132cf60 May 14 22:09:55 volumio-skloby volumio[844]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 14 22:09:55 volumio-skloby volumio[844]: info: QobuzConnect: QOBUZ Connect daemon connected May 14 22:09:55 volumio-skloby volumio[844]: info: Starting Shairport Sync May 14 22:09:55 volumio-skloby sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 22:09:55 volumio-skloby sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:55 volumio-skloby systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 14 22:09:55 volumio-skloby systemd[1]: shairport-sync.service: Succeeded. May 14 22:09:55 volumio-skloby systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 14 22:09:55 volumio-skloby qobuz-connect[1576]: 20250514 22:09:55.793 [1576.1576] INFO SampleApp: Playback volume changed: 75 May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:55 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:55 volumio-skloby systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 14 22:09:55 volumio-skloby sudo[1584]: pam_unix(sudo:session): session closed for user root May 14 22:09:55 volumio-skloby volumio[844]: info: Successfully retrieved User Session From TIDAL May 14 22:09:55 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:55 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:55 volumio-skloby volumio[844]: info: Shairport-Sync Started May 14 22:09:56 volumio-skloby volumio[844]: info: Successfully retrieved User Subscription From TIDAL May 14 22:09:56 volumio-skloby volumio[844]: info: Adding TIDAL to Browse Sources May 14 22:09:56 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 14 22:09:56 volumio-skloby volumio[844]: info: [1747253396318] CoreMusicLibrary::Adding element TIDAL May 14 22:09:56 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 22:09:56 volumio-skloby volumio[844]: Cannot find translation for source TIDAL May 14 22:09:56 volumio-skloby volumio[844]: info: Stopping AccessToken refresher cron May 14 22:09:56 volumio-skloby volumio[844]: info: AccessToken refresher cron started May 14 22:09:56 volumio-skloby volumio[844]: info: Adding TIDAL REST API Endpoints May 14 22:09:56 volumio-skloby volumio[844]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 14 22:09:56 volumio-skloby sudo[1590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 14 22:09:56 volumio-skloby sudo[1590]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:56 volumio-skloby systemd[1]: Started Volumio Bluetooth Module. May 14 22:09:56 volumio-skloby sudo[1590]: pam_unix(sudo:session): session closed for user root May 14 22:09:56 volumio-skloby sudo[1593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* May 14 22:09:56 volumio-skloby sudo[1593]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:56 volumio-skloby volumio[844]: info: Volumio BT Module successfully started May 14 22:09:56 volumio-skloby volumiobt[1592]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory May 14 22:09:56 volumio-skloby sudo[1593]: pam_unix(sudo:session): session closed for user root May 14 22:09:56 volumio-skloby volumio[844]: info: TidalConnect service stoped! May 14 22:09:56 volumio-skloby volumio[844]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 14 22:09:56 volumio-skloby volumio[844]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 14 22:09:56 volumio-skloby sudo[1605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 14 22:09:56 volumio-skloby sudo[1605]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:56 volumio-skloby systemd[1]: Started Volumio Tidal Connect Service. May 14 22:09:56 volumio-skloby sudo[1605]: pam_unix(sudo:session): session closed for user root May 14 22:09:57 volumio-skloby mpd[1575]: May 14 22:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 14 22:09:57 volumio-skloby systemd[1]: Started Music Player Daemon. May 14 22:09:57 volumio-skloby sudo[1557]: pam_unix(sudo:session): session closed for user root May 14 22:09:57 volumio-skloby sudo[1453]: pam_unix(sudo:session): session closed for user root May 14 22:09:57 volumio-skloby sudo[1505]: pam_unix(sudo:session): session closed for user root May 14 22:09:57 volumio-skloby volumio[844]: error: MPD error: The expression evaluated to a falsy value: May 14 22:09:57 volumio-skloby volumio[844]: assert.ok(self.idling) May 14 22:09:57 volumio-skloby volumio[844]: error: The expression evaluated to a falsy value: May 14 22:09:57 volumio-skloby volumio[844]: assert.ok(self.idling) May 14 22:09:57 volumio-skloby volumio[844]: error: MPD error: The expression evaluated to a falsy value: May 14 22:09:57 volumio-skloby volumio[844]: assert.ok(self.idling) May 14 22:09:57 volumio-skloby volumio[844]: error: The expression evaluated to a falsy value: May 14 22:09:57 volumio-skloby volumio[844]: assert.ok(self.idling) May 14 22:09:57 volumio-skloby volumio[844]: error: updateQueue error: null May 14 22:09:57 volumio-skloby volumio[844]: info: Executing endpoint tc_getconfig May 14 22:09:57 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 14 22:09:57 volumio-skloby vtcs[1607]: STARTING TidalConnect services, version: 1.4.0.34 May 14 22:09:57 volumio-skloby vtcs[1607]: STARTED TidalConnect services. May 14 22:09:57 volumio-skloby volumio[844]: info: Executing endpoint tc_connect May 14 22:09:57 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 14 22:09:57 volumio-skloby volumio[844]: info: Connecting to TidalConnect May 14 22:09:57 volumio-skloby volumio[844]: info: CoreCommandRouter::servicePushState May 14 22:09:57 volumio-skloby volumio[844]: info: CoreStateMachine::pushState May 14 22:09:57 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:57 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioPushState May 14 22:09:57 volumio-skloby volumio[844]: info: MRS: Pushing multiroomSync output update for this device May 14 22:09:57 volumio-skloby volumio[844]: info: MRS: Pushing multiroomSync output May 14 22:09:57 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:57 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:57 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:57 volumio-skloby volumio[844]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect May 14 22:09:57 volumio-skloby volumio[844]: info: CoreCommandRouter::servicePushState May 14 22:09:57 volumio-skloby volumio[844]: info: CoreStateMachine::pushState May 14 22:09:57 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:57 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioPushState May 14 22:09:57 volumio-skloby volumio[844]: info: MRS: Pushing multiroomSync output update for this device May 14 22:09:57 volumio-skloby volumio[844]: info: MRS: Pushing multiroomSync output May 14 22:09:57 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:57 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:57 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:57 volumio-skloby volumio[844]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect May 14 22:09:57 volumio-skloby sudo[1642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio May 14 22:09:57 volumio-skloby sudo[1642]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 22:09:57 volumio-skloby volumiobt[1592]: pulseaudio: no process found May 14 22:09:57 volumio-skloby sudo[1642]: pam_unix(sudo:session): session closed for user root May 14 22:09:57 volumio-skloby dbus-daemon[561]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.30' (uid=1000 pid=1646 comm="/usr/bin/pulseaudio --high-priority=true ") May 14 22:09:57 volumio-skloby systemd[1]: Starting RealtimeKit Scheduling Policy Service... May 14 22:09:57 volumio-skloby volumio[844]: ------------------------------------ BT MESSAGE: BT STATUS: running May 14 22:09:57 volumio-skloby dbus-daemon[561]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' May 14 22:09:57 volumio-skloby systemd[1]: Started RealtimeKit Scheduling Policy Service. May 14 22:09:57 volumio-skloby rtkit-daemon[1647]: Successfully called chroot. May 14 22:09:57 volumio-skloby rtkit-daemon[1647]: Successfully dropped privileges. May 14 22:09:57 volumio-skloby rtkit-daemon[1647]: Successfully limited resources. May 14 22:09:57 volumio-skloby rtkit-daemon[1647]: Running. May 14 22:09:57 volumio-skloby rtkit-daemon[1647]: Canary thread running. May 14 22:09:57 volumio-skloby rtkit-daemon[1647]: Watchdog thread running. May 14 22:09:57 volumio-skloby dbus-daemon[561]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.31' (uid=0 pid=1647 comm="/usr/lib/rtkit/rtkit-daemon ") May 14 22:09:57 volumio-skloby systemd[1]: Starting Authorization Manager... May 14 22:09:57 volumio-skloby volumio[844]: ------------------------------------ BT MESSAGE: BT STATUS: running May 14 22:09:57 volumio-skloby polkitd[1651]: started daemon version 0.105 using authority implementation `local' version `0.105' May 14 22:09:57 volumio-skloby dbus-daemon[561]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 14 22:09:57 volumio-skloby systemd[1]: Started Authorization Manager. May 14 22:09:58 volumio-skloby pulseaudio[1646]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 14 22:09:58 volumio-skloby pulseaudio[1646]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 14 22:09:58 volumio-skloby volumiobt[1592]: Applying permissions May 14 22:09:58 volumio-skloby volumiobt[1592]: Setting BT discoverable and pairable May 14 22:09:58 volumio-skloby bluetoothd[675]: Endpoint registered: sender=:1.51 path=/MediaEndpoint/A2DPSource May 14 22:09:58 volumio-skloby volumio[844]: info: MRS: Getting audio outputs on start May 14 22:09:58 volumio-skloby volumio[844]: info: MRS: Requesting all other devices output May 14 22:09:58 volumio-skloby bluetoothd[675]: Endpoint registered: sender=:1.51 path=/MediaEndpoint/A2DPSink May 14 22:09:58 volumio-skloby pulseaudio[1646]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files May 14 22:09:58 volumio-skloby kernel: Bluetooth: RFCOMM TTY layer initialized May 14 22:09:58 volumio-skloby kernel: Bluetooth: RFCOMM socket layer initialized May 14 22:09:58 volumio-skloby kernel: Bluetooth: RFCOMM ver 1.11 May 14 22:09:58 volumio-skloby volumiobt[1592]: [CHG] Controller B8:27:EB:9F:74:B8 Class: 0x0008041c May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [91B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [55B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [117B blob data] May 14 22:09:58 volumio-skloby volumiobt[1592]: [bluetooth]# -e power on May 14 22:09:58 volumio-skloby volumiobt[1592]: Invalid command in menu main: -e May 14 22:09:58 volumio-skloby volumiobt[1592]: May 14 22:09:58 volumio-skloby volumiobt[1592]: Use "help" for a list of available commands in a menu. May 14 22:09:58 volumio-skloby volumiobt[1592]: Use "menu " if you want to enter any submenu. May 14 22:09:58 volumio-skloby volumiobt[1592]: Use "back" if you want to return to menu main. May 14 22:09:58 volumio-skloby volumiobt[1592]: [bluetooth]# agent on May 14 22:09:58 volumio-skloby volumiobt[1592]: Agent is already registered May 14 22:09:58 volumio-skloby volumiobt[1592]: [bluetooth]# discoverable on May 14 22:09:58 volumio-skloby volumiobt[1592]: [bluetooth]# pairable on May 14 22:09:58 volumio-skloby volumiobt[1592]: [bluetooth]# agent NoInputNoOutput May 14 22:09:58 volumio-skloby volumiobt[1592]: Agent is already registered May 14 22:09:58 volumio-skloby volumiobt[1592]: [bluetooth]# default-agent May 14 22:09:58 volumio-skloby volumiobt[1592]: [bluetooth]# quit May 14 22:09:58 volumio-skloby volumiobt[1592]: [59B blob data] May 14 22:09:58 volumio-skloby volumio[1299]: ..............................................................................++++ May 14 22:09:58 volumio-skloby volumio[1299]: e is 65537 (0x010001) May 14 22:09:58 volumio-skloby volumio[1299]: writing RSA key May 14 22:09:59 volumio-skloby volumio[844]: info: TidalConnect service started! May 14 22:09:59 volumio-skloby volumio[844]: [Metrics] CommandRouter: 36s 334.02ms May 14 22:09:59 volumio-skloby volumio[844]: info: CoreCommandRouter::volumiosetStartupVolume May 14 22:09:59 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:09:59 volumio-skloby volumio[844]: info: VolumeController:: Setting startup Volume 20 May 14 22:09:59 volumio-skloby volumio[844]: info: VolumeController::SetAlsaVolume20 May 14 22:09:59 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:09:59 volumio-skloby volumio[844]: info: CoreCommandRouter::Close All Modals sent May 14 22:09:59 volumio-skloby volumio[844]: info: CoreCommandRouter::Close All Modals sent May 14 22:09:59 volumio-skloby volumio[844]: info: CoreStateMachine::pushState May 14 22:09:59 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:09:59 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 22:09:59 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioPushState May 14 22:09:59 volumio-skloby volumio[844]: info: MRS: Pushing multiroomSync output update for this device May 14 22:09:59 volumio-skloby volumio[844]: info: MRS: Pushing multiroomSync output May 14 22:09:59 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:09:59 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:10:00 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 14 22:10:00 volumio-skloby volumio[844]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 14 22:10:00 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:10:00 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:10:00 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 14 22:10:00 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 14 22:10:00 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 14 22:10:02 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 22:10:02 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 22:10:02 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 14 22:10:02 volumio-skloby volumio-remote-updater[575]: Test mode enabled May 14 22:10:02 volumio-skloby volumio-remote-updater[575]: No alpha test mode May 14 22:10:02 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 14 22:10:02 volumio-skloby volumio[844]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 14 22:10:02 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 14 22:10:05 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:10:05 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 22:10:05 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 14 22:10:05 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 14 22:10:05 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 14 22:10:05 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetBrowseSources May 14 22:10:05 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 22:10:05 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 14 22:10:05 volumio-skloby volumio[844]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 14 22:10:05 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 14 22:10:05 volumio-skloby volumio[844]: info: Received Get System Version May 14 22:10:05 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 22:10:05 volumio-skloby volumio[844]: info: Received Get System Info May 14 22:10:05 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 22:10:05 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 22:10:05 volumio-skloby volumio[844]: info: Discovery: Getting this device information May 14 22:10:05 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:10:05 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:10:05 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 22:10:06 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 22:10:06 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 14 22:10:06 volumio-skloby volumio[844]: info: BOOT COMPLETED May 14 22:10:11 volumio-skloby volumio[844]: info: Enabling plugin ampswitch May 14 22:10:11 volumio-skloby volumio[844]: info: Loading plugin "ampswitch"... May 14 22:10:12 volumio-skloby volumio[844]: info: PLUGIN START: ampswitch May 14 22:10:12 volumio-skloby volumio[844]: info: Done. May 14 22:10:12 volumio-skloby volumio[844]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 14 22:10:12 volumio-skloby volumio[844]: info: CoreCommandRouter::volumioGetState May 14 22:10:12 volumio-skloby volumio[844]: info: CorePlayQueue::getTrack 0 May 14 22:10:12 volumio-skloby volumio[844]: info: [ASDebug] CurState: stop PrevState: na May 14 22:10:12 volumio-skloby volumio[844]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 14 22:10:12 volumio-skloby volumio[844]: info: [ASDebug] CurState: stop PrevState: na May 14 22:10:12 volumio-skloby volumio[844]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 14 22:10:13 volumio-skloby volumio[844]: info: [ASDebug] Togle GPIO: OFF May 14 22:10:13 volumio-skloby volumio[844]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 22:10:13 volumio-skloby volumio[844]: info: [ASDebug] Setting UI defaults May 14 22:10:13 volumio-skloby volumio[844]: info: [ASDebug] Port: 24 May 14 22:10:13 volumio-skloby volumio[844]: info: [ASDebug] Inverted: false May 14 22:10:13 volumio-skloby volumio[844]: info: [ASDebug] Latched: false May 14 22:10:13 volumio-skloby volumio[844]: info: [ASDebug] On pulse width: 500 May 14 22:10:13 volumio-skloby volumio[844]: info: [ASDebug] Off pulse width: 500 May 14 22:10:16 volumio-skloby volumiologrotate[574]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 14 22:10:16 volumio-skloby volumiologrotate[574]: ls: cannot access 'SKLOBY': No such file or directory May 14 22:10:23 volumio-skloby ntpd[799]: Soliciting pool server 212.127.78.21 May 14 22:10:24 volumio-skloby ntpd[799]: Soliciting pool server 89.161.47.136 May 14 22:10:25 volumio-skloby ntpd[799]: Soliciting pool server 85.115.212.254 May 14 22:10:26 volumio-skloby ntpd[799]: Soliciting pool server 85.202.58.8 May 14 22:10:26 volumio-skloby ntpd[799]: Soliciting pool server 89.250.197.242 May 14 22:10:26 volumio-skloby ntpd[799]: Soliciting pool server 178.215.228.24 May 14 22:10:27 volumio-skloby ntpd[799]: Soliciting pool server 89.25.168.148 May 14 22:10:27 volumio-skloby ntpd[799]: Soliciting pool server 162.159.200.1 May 14 22:10:28 volumio-skloby ntpd[799]: Soliciting pool server 91.212.242.21 May 14 22:10:28 volumio-skloby ntpd[799]: Soliciting pool server 91.212.242.20 May 14 22:10:29 volumio-skloby ntpd[799]: Soliciting pool server 77.252.226.67 May 14 22:10:29 volumio-skloby ntpd[799]: Soliciting pool server 51.68.141.5 May 14 22:10:29 volumio-skloby ntpd[799]: Soliciting pool server 213.222.217.11 May 14 22:10:30 volumio-skloby ntpd[799]: Soliciting pool server 109.206.205.233 May 14 22:10:30 volumio-skloby ntpd[799]: Soliciting pool server 2a03:7580:1:217::11 May 14 22:10:31 volumio-skloby ntpd[799]: receive: Unexpected origin timestamp 0xebcf7736.45ae89f8 does not match aorg 0000000000.00000000 from server@91.212.242.20 xmt 0xebcf7737.5faea9c4 May 14 22:10:31 volumio-skloby ntpd[799]: receive: Unexpected origin timestamp 0xebcf7736.45b57b3a does not match aorg 0000000000.00000000 from server@85.115.212.254 xmt 0xebcf7737.5f4f8425 May 14 22:10:31 volumio-skloby ntpd[799]: Soliciting pool server 185.79.243.74 May 14 22:10:32 volumio-skloby ntpd[799]: Soliciting pool server 94.240.10.55 May 14 22:10:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 22:10:32 volumio-skloby volumio[844]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 14 22:10:33 volumio-skloby ntpd[799]: Soliciting pool server 192.86.14.67 May 14 22:10:38 volumio-skloby volumio[844]: info: [ASDebug] Port: 24 May 14 22:10:38 volumio-skloby volumio[844]: info: [ASDebug] Inverted: false May 14 22:10:38 volumio-skloby volumio[844]: info: [ASDebug] Delay: 720 May 14 22:10:40 volumio-skloby volumio[844]: info: Starting Uninstall of plugin system_controller - ampswitch May 14 22:10:40 volumio-skloby volumio[844]: info: Uninstalling plugin ampswitch May 14 22:10:40 volumio-skloby volumio[844]: info: [ASDebug] Port: 24 May 14 22:10:40 volumio-skloby volumio[844]: info: [ASDebug] Inverted: false May 14 22:10:40 volumio-skloby volumio[844]: info: [ASDebug] Delay: 720 May 14 22:10:40 volumio-skloby volumio[844]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 22:10:40 volumio-skloby volumio[844]: Error: EBADF: bad file descriptor, close May 14 22:10:40 volumio-skloby volumio[844]: at Object.closeSync (fs.js:439:3) May 14 22:10:40 volumio-skloby volumio[844]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) May 14 22:10:40 volumio-skloby volumio[844]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) May 14 22:10:40 volumio-skloby volumio[844]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) May 14 22:10:40 volumio-skloby volumio[844]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) May 14 22:10:40 volumio-skloby volumio[844]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) May 14 22:10:40 volumio-skloby volumio[844]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 14 22:10:40 volumio-skloby volumio[844]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) May 14 22:10:40 volumio-skloby volumio[844]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) May 14 22:10:40 volumio-skloby volumio[844]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) May 14 22:10:40 volumio-skloby volumio[844]: at Socket.emit (events.js:315:20) May 14 22:10:40 volumio-skloby volumio[844]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 14 22:10:40 volumio-skloby volumio[844]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { May 14 22:10:40 volumio-skloby volumio[844]: errno: -9, May 14 22:10:40 volumio-skloby volumio[844]: syscall: 'close', May 14 22:10:40 volumio-skloby volumio[844]: code: 'EBADF' May 14 22:10:40 volumio-skloby volumio[844]: } May 14 22:10:40 volumio-skloby volumio[844]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 22:10:41 volumio-skloby sudo[1806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-14 22:09 May 14 22:10:41 volumio-skloby sudo[1806]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"