Jan 11 09:08:03 studio ntpd[1039]: CLOCK: time stepped by 29.877447 Jan 11 09:08:03 studio ntpd[1039]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jan 11 09:08:04 studio volumio[1198]: info: Loading plugin "outputs"... Jan 11 09:08:04 studio volumio[1198]: info: Loading plugin "albumart"... Jan 11 09:08:04 studio volumio[1198]: info: Plugin example_plugin is not enabled Jan 11 09:08:04 studio volumio[1198]: info: Loading plugin "inputs"... Jan 11 09:08:04 studio volumio[1198]: info: Loading plugin "updater_comm"... Jan 11 09:08:04 studio volumio[1198]: info: Plugin mpdemulation is not enabled Jan 11 09:08:04 studio volumio[1198]: info: Loading plugin "rest_api"... Jan 11 09:08:04 studio volumio[1198]: info: Loading plugin "websocket"... Jan 11 09:08:04 studio volumio[1198]: info: Starting Socket.io Server version 1.7.4 Jan 11 09:08:04 studio volumio[1198]: info: Loading plugin "allo_relay_attenuator"... Jan 11 09:08:05 studio volumio[1198]: info: Applying required configuration parameters for plugin allo_relay_attenuator Jan 11 09:08:05 studio volumio[1198]: info: Loading plugin "gpio_control"... Jan 11 09:08:05 studio volumio[1380]: Forking 3 albumart workers Jan 11 09:08:06 studio volumio[1198]: gpiox_arm32 V2.2.1 Jan 11 09:08:08 studio volumio[1198]: info: Applying required configuration parameters for plugin gpio_control Jan 11 09:08:08 studio volumio[1198]: info: Loading plugin "Systeminfo"... Jan 11 09:08:10 studio volumio[1198]: info: Loading plugin "ferrum_streaming_control_technology"... Jan 11 09:08:11 studio volumio[1392]: Starting albumart workers Jan 11 09:08:11 studio volumio[1390]: Starting albumart workers Jan 11 09:08:11 studio volumio[1391]: Starting albumart workers Jan 11 09:08:12 studio volumio[1198]: info: Applying required configuration parameters for plugin ferrum_streaming_control_technology Jan 11 09:08:12 studio volumio[1198]: info: Loading plugin "touch_display"... Jan 11 09:08:13 studio volumio[1198]: info: Applying required configuration parameters for plugin touch_display Jan 11 09:08:14 studio systemd[1]: systemd-fsckd.service: Deactivated successfully. Jan 11 09:08:14 studio volumio-remote-updater[818]: [2026-01-11 09:08:14] [connect] Successful connection Jan 11 09:08:14 studio volumio[1198]: info: Loading i18n strings for locale sk Jan 11 09:08:14 studio volumio[1198]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 11 09:08:14 studio volumio[1198]: Updating browse sources language Jan 11 09:08:14 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 09:08:15 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 09:08:15 studio volumio[1198]: info: CoreCommandRouter::initPlayerControls Jan 11 09:08:15 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:15 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:15 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:15 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:15 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:15 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:15 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:15 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:15 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 09:08:15 studio volumio[1198]: Express server listening on port 3000 Jan 11 09:08:15 studio volumio[1198]: [Metrics] WebUI: 19s 614.62ms Jan 11 09:08:15 studio volumio[1198]: info: CoreStateMachine::resetVolumioState Jan 11 09:08:15 studio volumio[1198]: info: CoreStateMachine::getcurrentVolume Jan 11 09:08:15 studio volumio[1198]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 09:08:15 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:15 studio sudo[1461]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 09:08:15 studio sudo[1461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:15 studio sudo[1463]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 09:08:15 studio sudo[1463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:15 studio volumio[1198]: info: Cannot read play queue from file Jan 11 09:08:15 studio sudo[1461]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:15 studio sudo[1463]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:15 studio volumio[1198]: info: Volumio Network Manager: Network status updated: 1 Jan 11 09:08:15 studio volumio-remote-updater[818]: [2026-01-11 09:08:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1768118894 101 Jan 11 09:08:15 studio volumio[1198]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Jan 11 09:08:16 studio volumio[1198]: info: VolumeController:: Volume=86 Mute =false Jan 11 09:08:16 studio volumio[1198]: info: CoreStateMachine::pushState Jan 11 09:08:16 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:16 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 09:08:16 studio volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 11 09:08:16 studio volumio[1198]: info: CoreStateMachine::updateTrackBlock Jan 11 09:08:16 studio volumio[1198]: info: CorePlayQueue::getTrackBlock Jan 11 09:08:16 studio volumio[1198]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 09:08:16 studio volumio[1198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 11 09:08:16 studio volumio[1198]: info: CoreStateMachine::setRepeat null single undefined Jan 11 09:08:16 studio volumio[1198]: info: CoreStateMachine::pushState Jan 11 09:08:16 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:16 studio volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 11 09:08:16 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:16 studio volumio[1198]: info: Setting Device type: Raspberry PI Jan 11 09:08:16 studio volumio[1198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 11 09:08:16 studio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jan 11 09:08:16 studio volumio[1198]: info: VolumeController:: Volume=86 Mute =false Jan 11 09:08:16 studio volumio[1198]: info: CoreStateMachine::pushState Jan 11 09:08:16 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:16 studio volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 11 09:08:16 studio volumio[1198]: info: Completed loading Core Plugins Jan 11 09:08:16 studio volumio[1198]: info: Preparing to generate the ALSA configuration file Jan 11 09:08:16 studio volumio[1198]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 11 09:08:16 studio volumio[1198]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Jan 11 09:08:16 studio volumio[1198]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 11 09:08:16 studio sudo[1483]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 11 09:08:16 studio sudo[1483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:16 studio volumio[1198]: info: Discovery: adding 66372c03-c720-4dbf-be75-edcf2fd7c545 Jan 11 09:08:16 studio volumio[1198]: info: Discovery: Found device Studio Jan 11 09:08:16 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:16 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:16 studio volumio[1198]: info: Discovery: this is already registered, 66372c03-c720-4dbf-be75-edcf2fd7c545 Jan 11 09:08:16 studio volumio[1198]: info: Discovery: Found device Studio Jan 11 09:08:16 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:16 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:16 studio volumio[1198]: info: Asound.conf file unchanged, so no further update is needed Jan 11 09:08:16 studio volumio[1198]: info: Output device has changed, restarting MPD Jan 11 09:08:16 studio volumio[1198]: info: Output device has changed, restarting Shairport Sync Jan 11 09:08:16 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:16 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:16 studio sudo[1487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 09:08:16 studio sudo[1487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:16 studio sudo[1487]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:16 studio volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 09:08:16 studio volumio[1198]: info: ___________ START PLUGINS ___________ Jan 11 09:08:16 studio sudo[1489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 09:08:16 studio sudo[1489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:16 studio volumio[1198]: info: ControllerMpd::onStart: Initializing MPD Jan 11 09:08:16 studio volumio[1198]: info: Creating MPD Configuration file Jan 11 09:08:16 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 09:08:16 studio volumio[1198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 09:08:16 studio volumio[1198]: info: [1768118896986] CoreMusicLibrary::Adding element Mediálne servery Jan 11 09:08:16 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 09:08:17 studio volumio[1198]: info: UPNP Browser: Client initialized successfully Jan 11 09:08:17 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:17 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:17 studio sudo[1498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 09:08:17 studio sudo[1498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:17 studio sudo[1496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 09:08:17 studio sudo[1496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:17 studio sudo[1496]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:17 studio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 11 09:08:17 studio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 11 09:08:17 studio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Jan 11 09:08:17 studio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 09:08:17 studio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 11 09:08:17 studio sudo[1483]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:17 studio volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 09:08:17 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:17 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:17 studio systemd[1]: mpd.service: Deactivated successfully. Jan 11 09:08:17 studio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 11 09:08:17 studio systemd[1]: mpd.socket: Deactivated successfully. Jan 11 09:08:17 studio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 11 09:08:17 studio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 11 09:08:17 studio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 11 09:08:17 studio volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 09:08:17 studio volumio[1198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 09:08:17 studio volumio[1198]: info: [1768118897255] CoreMusicLibrary::Adding element Last_100 Jan 11 09:08:17 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 09:08:17 studio volumio[1198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 09:08:17 studio volumio[1198]: info: [1768118897258] CoreMusicLibrary::Adding element Webradio Jan 11 09:08:17 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 09:08:17 studio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 11 09:08:17 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 09:08:17 studio volumio[1198]: info: Initializing BBC Radios Jan 11 09:08:17 studio sudo[1523]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 09:08:17 studio sudo[1523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 11 09:08:17 studio sudo[1527]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 11 09:08:17 studio sudo[1523]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:17 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 09:08:17 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:17 studio volumio[1198]: info: Creating Spotify config file Jan 11 09:08:17 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:17 studio volumio[1198]: info: Allo Relay Attenuator: Starting plugin Jan 11 09:08:17 studio sudo[1540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp /tmp/fn-rattenu.service /lib/systemd/system/fn-rattenu.service Jan 11 09:08:17 studio sudo[1540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:17 studio fsct-plugin[1198]: Starting service tasks Jan 11 09:08:17 studio fsct-plugin[1198]: Service tasks started successfully Jan 11 09:08:17 studio fsct-plugin[1198]: Failed to initialize device 1d6b:0002: BOS descriptor not available, unsupported usb version 2.00 Jan 11 09:08:17 studio fsct-plugin[1198]: Failed to initialize device 046d:c52b: BOS descriptor not available, unsupported usb version 2.00 Jan 11 09:08:17 studio fsct-plugin[1198]: Failed to initialize device 2109:3431: IO error -> Permission denied (os error 13) Jan 11 09:08:17 studio fsct-plugin[1198]: Failed to initialize device 1d6b:0003: IO error -> Permission denied (os error 13) Jan 11 09:08:17 studio volumio[1198]: info: Loading i18n strings for locale sk Jan 11 09:08:17 studio sudo[1540]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:17 studio volumio[1198]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 11 09:08:18 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 09:08:18 studio volumio[1198]: info: Volumio Calling Home Jan 11 09:08:18 studio sudo[1545]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Jan 11 09:08:18 studio sudo[1545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:18 studio sudo[1553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 11 09:08:18 studio sudo[1553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:18 studio sudo[1545]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:18 studio sudo[1551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 11 09:08:18 studio sudo[1555]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 11 09:08:18 studio sudo[1555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:18 studio sudo[1551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:18 studio systemd[1]: Reloading. Jan 11 09:08:19 studio systemd[1]: Reloading. Jan 11 09:08:19 studio sudo[1553]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:20 studio volumio[1198]: info: touch_display: No backlight interface detected. Jan 11 09:08:20 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:20 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:20 studio volumio[1198]: info: FSCT Started Jan 11 09:08:20 studio volumio[1198]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jan 11 09:08:20 studio volumio[1198]: info: MPD Permissions set Jan 11 09:08:20 studio volumio[1198]: info: MPD Permissions set Jan 11 09:08:20 studio volumio[1198]: info: Upmpdcli Daemon Started Jan 11 09:08:20 studio sudo[1611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 11 09:08:20 studio sudo[1611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:21 studio sudo[1555]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:21 studio systemd[1]: Reloading. Jan 11 09:08:21 studio volumio[1198]: info: Volumio called home Jan 11 09:08:21 studio sudo[1609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Jan 11 09:08:21 studio sudo[1609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:21 studio volumio[1198]: info: Spotify config file written Jan 11 09:08:21 studio sudo[1616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 11 09:08:21 studio sudo[1616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:21 studio sudo[1609]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:21 studio volumio[1198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 11 09:08:21 studio volumio[1198]: info: touch_display: systemctl daemon-reload succeeded. Jan 11 09:08:21 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:21 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:21 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:21 studio sudo[1636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 11 09:08:21 studio sudo[1636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:21 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:21 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:21 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:21 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:21 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:21 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:21 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:21 studio mpd[1528]: 2026-01-11T09:08:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 09:08:22 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:22 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:22 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:22 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:22 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:22 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:22 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:22 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:22 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:22 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:22 studio volumio[1198]: info: No need to fix Spotify hosts Jan 11 09:08:22 studio systemd[1]: Started mpd.service - Music Player Daemon. Jan 11 09:08:22 studio sudo[1489]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:22 studio sudo[1611]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:22 studio sudo[1551]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:22 studio sudo[1498]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:22 studio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 09:08:22 studio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 09:08:22 studio sudo[1636]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:22 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:22 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:22 studio volumio[1198]: info: touch_display: IframeKeyboardBridge extension installed successfully Jan 11 09:08:22 studio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 09:08:22 studio go-librespot[1651]: go-librespot daemon starting... Jan 11 09:08:22 studio sudo[1616]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:22 studio volumio[1198]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Jan 11 09:08:22 studio volumio[1198]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Jan 11 09:08:22 studio volumio[1198]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 11 09:08:22 studio volumio[1198]: info: Allo Relay Attenuator: Service file updated, IR disabled Jan 11 09:08:22 studio volumio[1198]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jan 11 09:08:22 studio volumio[1198]: info: Starting Shairport Sync Jan 11 09:08:22 studio sudo[1656]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fn-rattenu.service Jan 11 09:08:22 studio sudo[1656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:22 studio sudo[1663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jan 11 09:08:22 studio sudo[1663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:22 studio sudo[1663]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:22 studio volumio[1198]: info: Starting Shairport Sync Jan 11 09:08:22 studio sudo[1668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 09:08:22 studio sudo[1668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:22 studio volumio[1198]: info: Starting Shairport Sync Jan 11 09:08:22 studio sudo[1671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 09:08:22 studio sudo[1671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:22 studio sudo[1674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 09:08:22 studio sudo[1674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:22 studio systemd[1]: Started fn-rattenu.service - Allo Relay Attenuator Daemon. Jan 11 09:08:22 studio fn-rattenu[1672]: Error: Writing to I2C Jan 11 09:08:22 studio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 11 09:08:22 studio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 11 09:08:22 studio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 09:08:22 studio systemd[1]: shairport-sync.service: Consumed 1.828s CPU time. Jan 11 09:08:22 studio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 09:08:22 studio sudo[1671]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:22 studio sudo[1656]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:22 studio sudo[1668]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:22 studio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 11 09:08:22 studio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 11 09:08:22 studio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 09:08:22 studio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 09:08:22 studio sudo[1674]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:22 studio go-librespot[1652]: time="2026-01-11T09:08:22+01:00" level=info msg="running go-librespot 0.6.2" Jan 11 09:08:22 studio go-librespot[1652]: time="2026-01-11T09:08:22+01:00" level=debug msg="app state loaded" Jan 11 09:08:22 studio go-librespot[1652]: time="2026-01-11T09:08:22+01:00" level=debug msg="stored credentials not found" Jan 11 09:08:22 studio go-librespot[1652]: time="2026-01-11T09:08:22+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 09:08:22 studio volumio[1198]: error: MPD error: The expression evaluated to a falsy value: Jan 11 09:08:22 studio volumio[1198]: assert.ok(self.idling) Jan 11 09:08:22 studio volumio[1198]: error: The expression evaluated to a falsy value: Jan 11 09:08:22 studio volumio[1198]: assert.ok(self.idling) Jan 11 09:08:23 studio volumio[1198]: info: MPD running with PID1528 Jan 11 09:08:23 studio volumio[1198]: ,establishing connection Jan 11 09:08:23 studio volumio[1198]: error: updateQueue error: null Jan 11 09:08:23 studio go-librespot[1652]: time="2026-01-11T09:08:23+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 09:08:23 studio go-librespot[1652]: time="2026-01-11T09:08:23+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 09:08:23 studio go-librespot[1652]: time="2026-01-11T09:08:23+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 09:08:23 studio go-librespot[1652]: time="2026-01-11T09:08:23+01:00" level=info msg="zeroconf server listening on port 46063" Jan 11 09:08:23 studio volumio[1198]: info: Shairport-Sync Started Jan 11 09:08:23 studio volumio[1198]: Error adding Membership: Error: addMembership EINVAL Jan 11 09:08:23 studio volumio[1198]: info: Shairport-Sync Started Jan 11 09:08:23 studio volumio[1198]: info: Shairport-Sync Started Jan 11 09:08:23 studio volumio[1198]: info: Allo Relay Attenuator: Daemon started Jan 11 09:08:23 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:23 studio volumio[1198]: info: Allo Relay Attenuator: Registering volume override for card 0 Jan 11 09:08:23 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Jan 11 09:08:23 studio volumio[1198]: info: Setting Device Volume Override Jan 11 09:08:23 studio volumio[1198]: info: Applying Volume Override Jan 11 09:08:23 studio volumio[1198]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 11 09:08:23 studio volumio[1198]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI Out Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 11 09:08:23 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 11 09:08:23 studio volumio[1198]: info: Enabling external Volume Control Jan 11 09:08:23 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: allo_relay_attenuator , updateVolumeSettings Jan 11 09:08:23 studio volumio[1198]: info: Allo Relay Attenuator: updateVolumeSettings called Jan 11 09:08:23 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: allo_relay_attenuator , retrievevolume Jan 11 09:08:23 studio sudo[1702]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/fn-rattenuc -c GET_VOLUME Jan 11 09:08:23 studio sudo[1702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:23 studio sudo[1702]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:23 studio sudo[1706]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/fn-rattenuc -c GET_MUTE Jan 11 09:08:23 studio sudo[1706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:23 studio sudo[1706]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:23 studio volumio[1198]: info: Allo Relay Attenuator: retrievevolume - hardware=31, ui=31, muted=false Jan 11 09:08:23 studio volumio[1198]: info: Allo Relay Attenuator: Volume override registered successfully Jan 11 09:08:23 studio volumio[1198]: info: Completed starting Core Plugins Jan 11 09:08:23 studio volumio[1198]: info: ------------------------------------------- Jan 11 09:08:23 studio volumio[1198]: info: ----- MyVolumio plugins startup ---- Jan 11 09:08:23 studio volumio[1198]: info: ------------------------------------------- Jan 11 09:08:23 studio volumio[1198]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 11 09:08:23 studio volumio[1198]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jan 11 09:08:24 studio volumio[1198]: error: updateQueue error: null Jan 11 09:08:24 studio volumio[1198]: verbose: New Socket.io Connection to 192.168.0.139:3000 from 192.168.0.211 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:08:24 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:24 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:24 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:08:24 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:24 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:08:24 studio volumio[1198]: verbose: New Socket.io Connection to 192.168.0.139:3000 from 192.168.0.211 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:08:24 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:24 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 11 09:08:24 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 11 09:08:24 studio sudo[1714]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 11 09:08:25 studio sudo[1714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:25 studio sudo[1718]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 11 09:08:25 studio sudo[1724]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 11 09:08:25 studio sudo[1718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:25 studio sudo[1724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:25 studio sudo[1718]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:25 studio sudo[1714]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:25 studio sudo[1732]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 09:08:25 studio sudo[1732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:25 studio sudo[1724]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:25 studio sudo[1731]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 11 09:08:25 studio sudo[1732]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:25 studio sudo[1738]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 09:08:25 studio sudo[1738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:25 studio sudo[1731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:25 studio sudo[1731]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:25 studio sudo[1738]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:25 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 09:08:25 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 09:08:25 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:08:25 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:08:25 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:08:25 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:25 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:25 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:08:25 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:25 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:25 studio sudo[1746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 09:08:25 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:08:25 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:08:25 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:08:25 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:25 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:25 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:08:25 studio volumio[1198]: info: go-librespot daemon successfully initialized Jan 11 09:08:25 studio sudo[1746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:25 studio volumio[1198]: verbose: New Socket.io Connection to 192.168.0.139:3000 from 192.168.0.211 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jan 11 09:08:25 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 09:08:25 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 09:08:25 studio sudo[1748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 09:08:25 studio sudo[1748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:25 studio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 11 09:08:25 studio mpd_monitor.sh[1750]: MPD Monitor Service: Starting MPD Monitor Service Jan 11 09:08:25 studio sudo[1746]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:25 studio volumio[1198]: info: Successfully started MPD Monitor Jan 11 09:08:25 studio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 11 09:08:25 studio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 11 09:08:25 studio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 11 09:08:25 studio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 11 09:08:25 studio mpd_monitor.sh[1757]: MPD Monitor Service: Starting MPD Monitor Service Jan 11 09:08:25 studio sudo[1756]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/fn-rattenuc -c SET_VOLUME=31 Jan 11 09:08:25 studio sudo[1756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:25 studio sudo[1748]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:26 studio volumio[1198]: info: Successfully started MPD Monitor Jan 11 09:08:26 studio fn-rattenu[1672]: Error: Writing to I2C Jan 11 09:08:26 studio sudo[1756]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:26 studio volumio[1198]: info: Allo Relay Attenuator: Startup volume set to 31 Jan 11 09:08:26 studio volumio[1198]: info: CoreStateMachine::pushState Jan 11 09:08:26 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:26 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 09:08:26 studio volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 11 09:08:26 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 09:08:26 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 09:08:27 studio sudo[1767]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 09:08:27 studio sudo[1769]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 09:08:27 studio sudo[1769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:27 studio sudo[1767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:27 studio sudo[1767]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:27 studio sudo[1769]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:27 studio volumio[1198]: verbose: New Socket.io Connection to 192.168.0.139 from 192.168.0.211 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Jan 11 09:08:27 studio volumio[1198]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 11 09:08:28 studio sudo[1773]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 09:08:28 studio sudo[1773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:28 studio sudo[1775]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 09:08:28 studio sudo[1775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:28 studio sudo[1775]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:28 studio sudo[1773]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:28 studio volumio[1198]: verbose: New Socket.io Connection to 192.168.0.139 from 192.168.0.211 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:28 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 11 09:08:28 studio volumio[1198]: info: Received Get System Info Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:08:28 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:28 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:28 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:28 studio volumio[1198]: info: Listing playlists Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 09:08:28 studio volumio[1198]: info: Initializing connection to go-librespot Websocket Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:08:28 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:28 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:08:28 studio go-librespot[1652]: time="2026-01-11T09:08:28+01:00" level=debug msg="new websocket client" Jan 11 09:08:28 studio volumio[1198]: info: Connection to go-librespot Websocket established Jan 11 09:08:28 studio volumio[1198]: verbose: New Socket.io Connection to 192.168.0.139:3000 from 192.168.0.211 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 09:08:28 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 09:08:29 studio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 11 09:08:29 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 09:08:29 studio volumio[1198]: info: Received Get System Info Jan 11 09:08:29 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:08:29 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:08:29 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:08:29 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:29 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:29 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:08:30 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 11 09:08:30 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 11 09:08:30 studio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 11 09:08:30 studio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 11 09:08:31 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 09:08:31 studio volumio[1198]: info: Received Get System Info Jan 11 09:08:31 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:08:31 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:08:31 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:08:31 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:31 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:31 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:08:31 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 09:08:31 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 11 09:08:32 studio volumio[1198]: info: Getting Spotify volume Jan 11 09:08:32 studio volumio[1198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 11 09:08:32 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:32 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:32 studio volumio[1198]: SPOTIFY: RECEIVED VOLUMIO VOLUME 31 Jan 11 09:08:32 studio volumio[1198]: SPOTIFY: SPOTIFY VOLUME undefined Jan 11 09:08:32 studio volumio[1198]: SPOTIFY: VOLUMIO VOLUME 31 Jan 11 09:08:32 studio volumio[1198]: info: Aligning Spotify Volume to Volumio Volume Jan 11 09:08:32 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:32 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:32 studio volumio[1198]: info: Setting Spotify Volume from Volumio: 31 Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 11 09:08:32 studio volumio[1198]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 11 09:08:32 studio volumio[1198]: info: Adding plugin bluetooth to MyMusic Plugins Jan 11 09:08:32 studio volumio[1198]: info: Adding plugin multiroom to MyMusic Plugins Jan 11 09:08:32 studio volumio[1198]: info: Adding plugin metavolumio to MyMusic Plugins Jan 11 09:08:32 studio volumio[1198]: info: Adding plugin cd_controller to MyMusic Plugins Jan 11 09:08:32 studio volumio[1198]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 11 09:08:32 studio volumio[1198]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 11 09:08:33 studio volumio[1198]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 11 09:08:33 studio volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 11 09:08:36 studio volumio[1198]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 11 09:08:36 studio volumio[1198]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 11 09:08:36 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:36 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:36 studio volumio[1198]: info: Starting MyVolumio Remote Streaming Endpoints Jan 11 09:08:36 studio volumio[1198]: info: MyVolumio login type: Token Jan 11 09:08:36 studio volumio[1198]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 11 09:08:36 studio volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 11 09:08:40 studio volumio[1198]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 11 09:08:40 studio volumio[1198]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 11 09:08:40 studio volumio[1198]: info: Streaming services startup Jan 11 09:08:40 studio volumio[1198]: info: Starting Streaming Daemon Jan 11 09:08:41 studio volumio[1198]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 11 09:08:41 studio sudo[1824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 11 09:08:41 studio sudo[1824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:41 studio sudo[1824]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:41 studio volumio[1198]: SPOTIFY: SETTING SPOTIFY VOLUME 31 Jan 11 09:08:41 studio volumio[1198]: info: Sending Spotify command with payload to local API: /player/volume Jan 11 09:08:41 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 09:08:41 studio volumio[1198]: info: Enabling plugin touch_display Jan 11 09:08:41 studio volumio[1198]: info: Loading plugin "touch_display"... Jan 11 09:08:41 studio volumio[1198]: info: Applying required configuration parameters for plugin touch_display Jan 11 09:08:41 studio volumio[1198]: info: PLUGIN START: touch_display Jan 11 09:08:41 studio volumio[1198]: info: Loading i18n strings for locale sk Jan 11 09:08:41 studio volumio[1198]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 11 09:08:41 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 09:08:41 studio sudo[1842]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Jan 11 09:08:41 studio sudo[1842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:41 studio sudo[1842]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:41 studio sudo[1845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 11 09:08:41 studio volumio[1198]: error: Cannot start Volumio Streaming Daemon Jan 11 09:08:41 studio sudo[1847]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 11 09:08:41 studio sudo[1847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:41 studio volumio[1198]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 11 09:08:41 studio volumio[1198]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 11 09:08:41 studio sudo[1845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:41 studio sudo[1849]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 11 09:08:41 studio sudo[1849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:41 studio systemd[1]: Reloading. Jan 11 09:08:42 studio volumio[1198]: info: touch_display: No backlight interface detected. Jan 11 09:08:42 studio sudo[1855]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Jan 11 09:08:42 studio sudo[1855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:42 studio sudo[1855]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:42 studio volumio[1198]: info: touch_display: IframeKeyboardBridge extension installed successfully Jan 11 09:08:43 studio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 09:08:43 studio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 09:08:43 studio sudo[1875]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 09:08:43 studio sudo[1875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:43 studio sudo[1877]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 09:08:43 studio sudo[1877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:43 studio sudo[1875]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:43 studio sudo[1847]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:43 studio systemd[1]: Reloading. Jan 11 09:08:43 studio sudo[1877]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:43 studio volumio[1198]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jan 11 09:08:43 studio sudo[1891]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jan 11 09:08:43 studio sudo[1891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:43 studio sudo[1891]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:43 studio volumio[1198]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jan 11 09:08:43 studio volumio[1198]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jan 11 09:08:44 studio volumio[1198]: verbose: New Socket.io Connection to 192.168.0.139 from 192.168.0.211 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Jan 11 09:08:44 studio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 09:08:44 studio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 09:08:44 studio sudo[1849]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:44 studio sudo[1845]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:44 studio volumio[1198]: info: touch_display: systemctl daemon-reload succeeded. Jan 11 09:08:44 studio volumio[1198]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 11 09:08:44 studio volumio[1198]: info: Received Get System Info Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:08:44 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:44 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:44 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:44 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 11 09:08:44 studio volumio[1198]: info: Received Get System Info Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:08:44 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:44 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:44 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:44 studio sudo[1901]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 11 09:08:44 studio sudo[1901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:44 studio volumio[1198]: info: Listing playlists Jan 11 09:08:44 studio sudo[1901]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:44 studio sudo[1907]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 09:08:44 studio sudo[1907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:44 studio sudo[1904]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 09:08:44 studio sudo[1904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:44 studio sudo[1904]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:44 studio sudo[1907]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:44 studio volumio[1198]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 11 09:08:44 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 11 09:08:45 studio volumio[1198]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Jan 11 09:08:45 studio volumio[1198]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Jan 11 09:08:45 studio volumio[1198]: info: Error: Error Jan 11 09:08:45 studio volumio[1198]: verbose: New Socket.io Connection to 192.168.0.139 from 192.168.0.211 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Jan 11 09:08:45 studio volumio[1198]: info: Received Get System Info Jan 11 09:08:45 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:08:45 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:08:45 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:08:45 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:45 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:45 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:08:45 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:45 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:45 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 09:08:45 studio volumio[1198]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 09:08:45 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 09:08:45 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:45 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:45 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 11 09:08:45 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 11 09:08:45 studio volumio[1198]: info: Received Get System Info Jan 11 09:08:45 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:08:45 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:08:45 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:08:45 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:45 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:45 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:08:45 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:45 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:45 studio volumio[1198]: info: Listing playlists Jan 11 09:08:46 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 11 09:08:46 studio volumio[1198]: info: MyVolumio token set successfully Jan 11 09:08:46 studio volumio[1198]: info: MYVOLUMIO: Adding device Jan 11 09:08:46 studio volumio[1198]: info: MYVOLUMIO: Evaluating Server Jan 11 09:08:46 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 09:08:46 studio volumio[1198]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 11 09:08:46 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 11 09:08:46 studio volumio[1198]: info: Received Get System Version Jan 11 09:08:46 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 09:08:46 studio volumio[1198]: info: Received Get System Info Jan 11 09:08:46 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:08:46 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:08:46 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:08:46 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:46 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:46 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:08:47 studio volumio[1198]: info: MyVolumio Plan changed: premium Jan 11 09:08:47 studio volumio[1198]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jan 11 09:08:47 studio volumio[1198]: info: Removing browser output: myVolumio user plan is not superstar Jan 11 09:08:47 studio volumio[1198]: info: Removing audio output: Jan 11 09:08:47 studio volumio[1198]: info: MYVOLUMIO: Adding device Jan 11 09:08:47 studio volumio[1198]: info: MYVOLUMIO: Evaluating Server Jan 11 09:08:47 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 09:08:47 studio volumio[1198]: info: Received Get System Info Jan 11 09:08:47 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:08:47 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:08:47 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:08:47 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:47 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:47 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:08:48 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 09:08:48 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:48 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 09:08:48 studio volumio[1198]: info: Setting Geolocation for MyVolumio to eu6 Jan 11 09:08:48 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:48 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:48 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:48 studio volumio[1198]: info: Remote config written successfully Jan 11 09:08:48 studio volumio[1198]: info: Starting Tunnel 1 Jan 11 09:08:48 studio volumio[1198]: info: Starting Tunnel Connection Checker Jan 11 09:08:48 studio volumio[1198]: info: Completed starting MyVolumio Plugin Jan 11 09:08:48 studio volumio-remote-updater[818]: Test mode disabled Jan 11 09:08:48 studio volumio-remote-updater[818]: Alpha mode disabled Jan 11 09:08:48 studio volumio-remote-updater[818]: Alpha legacy test mode disabled Jan 11 09:08:48 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 11 09:08:48 studio volumio[1198]: info: Setting Geolocation for MyVolumio to eu7 Jan 11 09:08:48 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:48 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:48 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:48 studio volumio[1198]: info: MYVolumio Device disabled Jan 11 09:08:48 studio volumio[1198]: info: MyVolumio status changed Jan 11 09:08:48 studio volumio[1198]: info: Streaming services startup Jan 11 09:08:48 studio volumio[1198]: info: Starting Streaming Daemon Jan 11 09:08:48 studio volumio[1198]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 11 09:08:48 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 11 09:08:48 studio sudo[1956]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 11 09:08:48 studio sudo[1956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:49 studio sudo[1956]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:49 studio volumio[1198]: info: Successfully Added MyVolumio device Jan 11 09:08:49 studio volumio[1198]: error: Cannot start Volumio Streaming Daemon Jan 11 09:08:49 studio volumio[1198]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 11 09:08:49 studio volumio[1198]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 11 09:08:49 studio volumio[1198]: info: Successfully Added MyVolumio device Jan 11 09:08:49 studio volumio[1198]: info: Updating MyVolumio device info Jan 11 09:08:49 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:49 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:49 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:49 studio volumio[1198]: info: Updating MyVolumio device info Jan 11 09:08:49 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:49 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:49 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:49 studio volumio[1198]: info: Successfully Updated MyVolumio device Jan 11 09:08:50 studio volumio[1198]: info: Successfully Updated MyVolumio device Jan 11 09:08:51 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 09:08:51 studio upmpdcli[1972]: writing RSA key Jan 11 09:08:51 studio volumio[1198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 11 09:08:51 studio volumio[1198]: info: MYVolumio Device enabled Jan 11 09:08:51 studio volumio[1198]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 11 09:08:52 studio volumio[1198]: info: MyVolumio status changed Jan 11 09:08:52 studio volumio[1198]: info: Streaming services startup Jan 11 09:08:52 studio volumio[1198]: info: Starting Streaming Daemon Jan 11 09:08:52 studio sudo[1976]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 11 09:08:52 studio sudo[1976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:52 studio volumio[1198]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Jan 11 09:08:52 studio sudo[1976]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:52 studio volumio[1198]: error: Cannot start Volumio Streaming Daemon Jan 11 09:08:52 studio volumio[1198]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 11 09:08:52 studio volumio[1198]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 11 09:08:52 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:08:52 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:08:52 studio volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 11 09:08:53 studio volumio[1198]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Jan 11 09:08:53 studio volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 11 09:08:57 studio sudo[1982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 11 09:08:57 studio sudo[1982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:57 studio sudo[1982]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:57 studio volumio[1198]: info: MRS: MultiRoom plugin initialized Jan 11 09:08:57 studio volumio[1198]: info: MRS: STOPPING SNAPCLIENT Jan 11 09:08:57 studio volumio[1198]: info: MRS: Snap server stop Jan 11 09:08:57 studio volumio[1198]: info: MRS: STOPPING volumioStreaming Jan 11 09:08:57 studio sudo[2000]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 11 09:08:57 studio sudo[2000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:57 studio volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 11 09:08:57 studio sudo[2004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 11 09:08:57 studio sudo[2004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:57 studio sudo[2001]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 11 09:08:57 studio sudo[2001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:57 studio sudo[2000]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:57 studio volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 11 09:08:57 studio sudo[2001]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:57 studio sudo[2004]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:57 studio sudo[2006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 11 09:08:57 studio sudo[2006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:57 studio sudo[2006]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:57 studio volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 11 09:08:57 studio volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 11 09:08:58 studio volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 11 09:08:58 studio volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 11 09:08:58 studio volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 11 09:08:58 studio volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 11 09:08:58 studio volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jan 11 09:08:58 studio volumio[1198]: info: Preparing to generate the ALSA configuration file Jan 11 09:08:58 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:58 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:58 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 11 09:08:58 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 11 09:08:58 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 11 09:08:58 studio volumio[1198]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 09:08:58 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 09:08:58 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 11 09:08:58 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 09:08:58 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 09:08:58 studio volumio[1198]: info: MRS: Removed streaming files Jan 11 09:08:58 studio volumio[1198]: info: MRS: volumioStreaming STOPPED Jan 11 09:08:58 studio volumio[1198]: info: MRS: SNAPSERVER STOPPED Jan 11 09:08:58 studio volumio[1198]: info: MRS: SNAPCLIENT STOPPED Jan 11 09:08:58 studio volumio[1198]: info: Asound.conf file unchanged, so no further update is needed Jan 11 09:08:58 studio volumio[1198]: info: Output device has changed, restarting MPD Jan 11 09:08:58 studio volumio[1198]: info: Output device has changed, restarting Shairport Sync Jan 11 09:08:58 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:58 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:08:59 studio sudo[2018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 09:08:59 studio sudo[2018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:59 studio sudo[2020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 09:08:59 studio sudo[2020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:08:59 studio sudo[2018]: pam_unix(sudo:session): session closed for user root Jan 11 09:08:59 studio volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 09:08:59 studio volumio[1198]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 11 09:08:59 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:59 studio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 11 09:08:59 studio volumio[1198]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 11 09:08:59 studio volumio[1198]: ------------------------------------ BT MESSAGE: [FUNC] onStart Jan 11 09:08:59 studio volumio[1198]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Jan 11 09:08:59 studio volumio[1198]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Jan 11 09:08:59 studio volumio[1198]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Jan 11 09:08:59 studio volumio[1198]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Jan 11 09:08:59 studio volumio[1198]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jan 11 09:08:59 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 11 09:08:59 studio volumio[1198]: info: MRS: Pushing multiroomSync output for this device Jan 11 09:08:59 studio volumio[1198]: info: MRS: Pushing multiroomSync output Jan 11 09:08:59 studio bluetoothd[791]: Path / reserved for Adv Monitor app :1.17 Jan 11 09:08:59 studio bluetoothd[791]: Adv Monitor app :1.17 disconnected from D-Bus Jan 11 09:08:59 studio systemd[1]: mpd.service: Deactivated successfully. Jan 11 09:08:59 studio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 11 09:08:59 studio systemd[1]: mpd.service: Consumed 3.237s CPU time. Jan 11 09:08:59 studio systemd[1]: mpd.socket: Deactivated successfully. Jan 11 09:08:59 studio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 11 09:08:59 studio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 11 09:08:59 studio volumio[1198]: info: Adding audio output: Jan 11 09:08:59 studio volumio[1198]: info: Adding audio output: Jan 11 09:08:59 studio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 11 09:08:59 studio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 11 09:08:59 studio volumio[1198]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 11 09:08:59 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:08:59 studio volumio[1198]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 11 09:08:59 studio volumio[1198]: info: Adding METAVOLUMIO REST API Endpoints Jan 11 09:08:59 studio volumio[1198]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 11 09:08:59 studio volumio[1198]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 11 09:08:59 studio volumio[1198]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 11 09:08:59 studio volumio[1198]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 11 09:08:59 studio volumio[1198]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 11 09:08:59 studio volumio[1198]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 11 09:08:59 studio volumio[1198]: info: Preparing CD Folders Jan 11 09:08:59 studio volumio[1198]: info: Adding CD REST API Endpoints Jan 11 09:08:59 studio volumio[1198]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jan 11 09:08:59 studio volumio[1198]: info: Starting UDEV Watcher for CD Jan 11 09:08:59 studio volumio[1198]: info: Detecting CD presence with UDEV Jan 11 09:08:59 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jan 11 09:08:59 studio sudo[2033]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 09:08:59 studio sudo[2033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 11 09:08:59 studio sudo[2033]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:01 studio mpd[2037]: 2026-01-11T09:09:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 09:09:01 studio systemd[1]: Started mpd.service - Music Player Daemon. Jan 11 09:09:01 studio sudo[2020]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:04 studio volumio[1198]: warn: [cd-plugin] cdspeedctl: device or media not ready Jan 11 09:09:04 studio volumio[1198]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 11 09:09:04 studio volumio[1198]: info: Adding inputs REST Endpoints Jan 11 09:09:04 studio volumio[1198]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jan 11 09:09:04 studio volumio[1198]: info: Scanning Audio Inputs Jan 11 09:09:04 studio volumio[1198]: info: Checking against Known Cards name Jan 11 09:09:04 studio volumio[1198]: info: Adding Server instance for streaming Jan 11 09:09:04 studio volumio[1198]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 11 09:09:04 studio volumio[1198]: error: Hi Res Audio Failed Login: Missing Login Data Jan 11 09:09:04 studio volumio[1198]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 11 09:09:04 studio volumio[1198]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 11 09:09:04 studio volumio[1198]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 11 09:09:04 studio volumio[1198]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 11 09:09:04 studio volumio[1198]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 11 09:09:04 studio volumio[1198]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jan 11 09:09:04 studio volumio[1198]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jan 11 09:09:05 studio sudo[2061]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 11 09:09:05 studio sudo[2061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:05 studio volumio[1198]: info: QobuzConnect: Starting Qobuz Connect socket and service Jan 11 09:09:05 studio sudo[2061]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:05 studio volumio[1198]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jan 11 09:09:05 studio sudo[2068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 11 09:09:05 studio sudo[2068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:05 studio volumio[1198]: info: Adding TIDAL REST API Endpoints Jan 11 09:09:05 studio volumio[1198]: info: Stopping AccessToken refresher cron for QOBUZ Jan 11 09:09:05 studio sudo[2068]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:05 studio sudo[2071]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 11 09:09:05 studio sudo[2071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:05 studio volumio[1198]: info: AccessToken refresher cron started for QOBUZ Jan 11 09:09:05 studio volumio[1198]: info: Adding QOBUZ REST API Endpoints Jan 11 09:09:05 studio volumio[1198]: info: MRS: Getting audio outputs on start Jan 11 09:09:05 studio volumio[1198]: info: MRS: Requesting all other devices output Jan 11 09:09:05 studio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 11 09:09:05 studio sudo[2071]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:05 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 09:09:05 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 09:09:05 studio volumio[1198]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Jan 11 09:09:05 studio volumio[1198]: info: MPD Permissions set Jan 11 09:09:05 studio sudo[2075]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 11 09:09:05 studio sudo[2075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:05 studio sudo[2078]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 11 09:09:05 studio sudo[2078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:06 studio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Jan 11 09:09:06 studio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 09:09:06 studio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 09:09:06 studio volumiobt[2085]: INFO [BTSTART] Ensuring Bluetooth directory exists... Jan 11 09:09:06 studio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 11 09:09:06 studio sudo[2078]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:06 studio sudo[2075]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:06 studio sudo[2087]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Jan 11 09:09:06 studio sudo[2087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:06 studio sudo[2087]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:06 studio autossh[2088]: port set to 0, monitoring disabled Jan 11 09:09:06 studio autossh[2088]: starting ssh (count 1) Jan 11 09:09:06 studio autossh[2088]: ssh child pid is 2093 Jan 11 09:09:06 studio sudo[2092]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Jan 11 09:09:06 studio sudo[2092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:06 studio sudo[2092]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:06 studio volumiobt[2095]: INFO [BTSTART] Powering on Bluetooth if needed... Jan 11 09:09:06 studio bluetoothd[791]: Adv Monitor app :1.19 disconnected from D-Bus Jan 11 09:09:06 studio volumiobt[2098]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Jan 11 09:09:06 studio volumiobt[2099]: [176B blob data] Jan 11 09:09:06 studio volumiobt[2099]: [157B blob data] Jan 11 09:09:06 studio volumiobt[2099]: [157B blob data] Jan 11 09:09:06 studio volumiobt[2099]: [157B blob data] Jan 11 09:09:06 studio volumiobt[2099]: [113B blob data] Jan 11 09:09:06 studio volumiobt[2099]: [bluetoothctl]> discoverable on Jan 11 09:09:06 studio volumiobt[2099]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Jan 11 09:09:06 studio volumiobt[2099]: [bluetoothctl]> pairable on Jan 11 09:09:06 studio bluetoothd[791]: Path / reserved for Adv Monitor app :1.20 Jan 11 09:09:06 studio bluetoothd[791]: Adv Monitor app :1.20 disconnected from D-Bus Jan 11 09:09:06 studio volumiobt[2099]: [bluetoothctl]> Jan 11 09:09:06 studio volumiobt[2100]: INFO [BTSTART] Registering Bluetooth agent... Jan 11 09:09:06 studio volumiossh-tunnel[2093]: Warning: Permanently added '[eu7.myvolumio.org]:2222' (RSA) to the list of known hosts. Jan 11 09:09:06 studio volumiobt[2101]: [NEW] Media /org/bluez/hci0 Jan 11 09:09:06 studio volumiobt[2101]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Jan 11 09:09:06 studio volumiobt[2101]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Jan 11 09:09:06 studio volumiobt[2101]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Jan 11 09:09:06 studio bluetoothd[791]: Path / reserved for Adv Monitor app :1.21 Jan 11 09:09:06 studio bluetoothd[791]: Adv Monitor app :1.21 disconnected from D-Bus Jan 11 09:09:06 studio volumiobt[2102]: No agent is registered Jan 11 09:09:06 studio volumiobt[2102]: [NEW] Media /org/bluez/hci0 Jan 11 09:09:06 studio volumiobt[2102]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Jan 11 09:09:06 studio volumiobt[2102]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Jan 11 09:09:06 studio volumiobt[2102]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Jan 11 09:09:06 studio bluetoothd[791]: Adv Monitor app :1.22 disconnected from D-Bus Jan 11 09:09:06 studio volumiobt[2103]: INFO [BTSTART] Agent registered successfully. Jan 11 09:09:06 studio volumiobt[2104]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Jan 11 09:09:06 studio volumio[1198]: info: Executing endpoint qc_getconfig Jan 11 09:09:06 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 11 09:09:06 studio qobuz-connect[2073]: 20260111 09:09:06.574 [2073.2073] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 11 09:09:06 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:06 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:06 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:06 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:06 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:06 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:06 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:06 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:06 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:09:06 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:06 studio volumio[1198]: error: updateQueue error: null Jan 11 09:09:06 studio qobuz-connect[2073]: 20260111 09:09:06.691 [2073.2073] INFO VolumeManager: [0x56c268]: Setting new playback volume: 75 Jan 11 09:09:06 studio qobuz-connect[2073]: 20260111 09:09:06.691 [2073.2073] INFO VolumeManager: [0x56c268]: Setting new mute state: 0 Jan 11 09:09:06 studio qobuz-connect[2073]: 20260111 09:09:06.691 [2073.2073] INFO QobuzConnect: [0x56cc38]: Client initialized! Jan 11 09:09:06 studio qobuz-connect[2073]: 20260111 09:09:06.691 [2073.2073] INFO SampleApp: Starting Avahi advertising, name: Studio, service name: _qobuz-connect._tcp Jan 11 09:09:06 studio volumio[1198]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 11 09:09:06 studio volumio[1198]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 11 09:09:06 studio qobuz-connect[2073]: 20260111 09:09:06.707 [2073.2073] INFO LocalConfigManager: [0x56bb48]: Starting Local Configuration server Jan 11 09:09:06 studio qobuz-connect[2073]: 20260111 09:09:06.707 [2073.2073] INFO SampleApp: Starting Local configuration server Jan 11 09:09:06 studio qobuz-connect[2073]: 20260111 09:09:06.708 [2073.2073] INFO SampleApp: Connected to UNIX socket client 0x556818 Jan 11 09:09:06 studio volumio[1198]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Jan 11 09:09:06 studio volumio[1198]: info: CoreCommandRouter::volumioStop Jan 11 09:09:06 studio qobuz-connect[2073]: 20260111 09:09:06.724 [2073.2073] INFO SampleApp: Playback volume changed: 75 Jan 11 09:09:06 studio volumio[1198]: info: CoreStateMachine::stop Jan 11 09:09:06 studio volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 09:09:06 studio volumio[1198]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Jan 11 09:09:06 studio volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 09:09:06 studio volumio[1198]: info: Remote SSH Started Jan 11 09:09:07 studio volumiobt[2105]: INFO:a2dp-agent:Connecting to system bus. Jan 11 09:09:07 studio volumiobt[2105]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Jan 11 09:09:07 studio volumiobt[2105]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Jan 11 09:09:07 studio volumiobt[2105]: INFO:a2dp-agent:Registering agent. Jan 11 09:09:07 studio volumiobt[2105]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Jan 11 09:09:07 studio volumiobt[2105]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Jan 11 09:09:07 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:07 studio volumio[1198]: info: Starting Shairport Sync Jan 11 09:09:07 studio volumio[1198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 11 09:09:07 studio sudo[2117]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 09:09:07 studio sudo[2117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:07 studio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 11 09:09:07 studio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 11 09:09:07 studio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 09:09:07 studio systemd[1]: shairport-sync.service: Consumed 1.555s CPU time. Jan 11 09:09:07 studio volumio[1198]: info: Preparing to generate the ALSA configuration file Jan 11 09:09:07 studio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 09:09:07 studio sudo[2117]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:08 studio volumio[1198]: info: Shairport-Sync Started Jan 11 09:09:08 studio volumio[1198]: info: TidalConnect service stoped! Jan 11 09:09:08 studio volumio[1198]: info: Asound.conf file unchanged, so no further update is needed Jan 11 09:09:08 studio volumio[1198]: info: Output device has changed, restarting MPD Jan 11 09:09:08 studio volumio[1198]: info: Output device has changed, restarting Shairport Sync Jan 11 09:09:08 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:08 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:09:08 studio sudo[2139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 09:09:08 studio sudo[2139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:08 studio sudo[2139]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:08 studio volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 09:09:08 studio volumio[1198]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 11 09:09:08 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:08 studio sudo[2142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 09:09:08 studio sudo[2142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:08 studio volumio[1198]: info: QobuzConnect: setDeactiveState invoked Jan 11 09:09:08 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:08 studio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 11 09:09:08 studio volumio[1198]: info: Preparing to generate the ALSA configuration file Jan 11 09:09:08 studio systemd[1]: mpd.service: Deactivated successfully. Jan 11 09:09:08 studio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 11 09:09:08 studio systemd[1]: mpd.service: Consumed 1.456s CPU time. Jan 11 09:09:08 studio systemd[1]: mpd.socket: Deactivated successfully. Jan 11 09:09:08 studio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 11 09:09:08 studio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 11 09:09:08 studio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 11 09:09:08 studio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 11 09:09:08 studio volumio[1198]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 11 09:09:08 studio volumio[1198]: info: Reading ALSA contributions from plugins. Jan 11 09:09:08 studio sudo[2151]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 09:09:08 studio sudo[2151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 11 09:09:08 studio sudo[2157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 11 09:09:08 studio sudo[2157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:08 studio sudo[2151]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:09 studio volumio[1198]: info: MPD Permissions set Jan 11 09:09:09 studio sudo[2157]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:09 studio sudo[2162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 09:09:09 studio sudo[2162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:09 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:09 studio sudo[2163]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 11 09:09:09 studio sudo[2163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:09 studio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 11 09:09:09 studio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 11 09:09:09 studio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 11 09:09:09 studio qobuz-connect[2073]: 20260111 09:09:09.520 [2073.2073] INFO SampleApp: Stopping Local configuration server Jan 11 09:09:09 studio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 11 09:09:09 studio volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.jEtGjFzCERMejLI477Q2oWfq0fs2.718513872d625fc56759acaf446463b6.state.status' Jan 11 09:09:09 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:09 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:09 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:09 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:09 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:09 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:09 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:09 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:09 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:09:09 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:09 studio volumio[1198]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 11 09:09:09 studio volumio[1198]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 11 09:09:09 studio volumio[1198]: info: Starting Shairport Sync Jan 11 09:09:09 studio sudo[2176]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 09:09:09 studio sudo[2176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:10 studio sudo[2178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 11 09:09:10 studio volumio[1198]: info: Asound.conf file written Jan 11 09:09:10 studio sudo[2178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:10 studio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 11 09:09:10 studio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 11 09:09:10 studio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 09:09:10 studio systemd[1]: shairport-sync.service: Consumed 1.163s CPU time. Jan 11 09:09:10 studio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 09:09:10 studio sudo[2176]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:10 studio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jan 11 09:09:10 studio sudo[2178]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:10 studio sudo[2185]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 11 09:09:10 studio sudo[2185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:10 studio sudo[2185]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:10 studio volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jan 11 09:09:10 studio volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jan 11 09:09:10 studio volumio[1198]: info: Output device has changed, restarting MPD Jan 11 09:09:10 studio sudo[2208]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 09:09:10 studio sudo[2208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:10 studio sudo[2208]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:10 studio volumio[1198]: info: Output device has changed, restarting Shairport Sync Jan 11 09:09:10 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:10 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:09:10 studio qobuz-connect[2073]: 20260111 09:09:10.723 [2073.2073] INFO SampleApp: shat down connection on UNIX socket Jan 11 09:09:10 studio systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 11 09:09:10 studio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 11 09:09:10 studio mpd[2160]: 2026-01-11T09:09:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 09:09:10 studio sudo[2211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 09:09:10 studio sudo[2211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:10 studio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 11 09:09:10 studio systemd[1]: Started mpd.service - Music Player Daemon. Jan 11 09:09:10 studio sudo[2163]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:10 studio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 11 09:09:10 studio sudo[2142]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:10 studio sudo[2162]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:10 studio mpd_monitor.sh[2220]: MPD Monitor Service: Starting MPD Monitor Service Jan 11 09:09:10 studio volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 09:09:10 studio volumio[1198]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 11 09:09:10 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:10 studio volumio[1198]: info: QobuzConnect: setDeactiveState invoked Jan 11 09:09:10 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:10 studio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 11 09:09:10 studio systemd[1]: mpd.service: Deactivated successfully. Jan 11 09:09:10 studio volumio[1198]: error: Error starting TidalConnect Cannot read properties of undefined (reading 'sendRevoke') {"stack":"TypeError: Cannot read properties of undefined (reading 'sendRevoke')\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8524)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8816)\n at /volumio/node_modules/v-conf/index.js:166:13\n at /volumio/node_modules/multimap/index.js:140:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:139:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:163:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2112:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at process.processTicksAndRejections (node:internal/process/task_queues:77:11)"} Jan 11 09:09:10 studio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 11 09:09:10 studio systemd[1]: mpd.service: Consumed 1.570s CPU time. Jan 11 09:09:10 studio systemd[1]: mpd.socket: Deactivated successfully. Jan 11 09:09:10 studio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 11 09:09:10 studio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 11 09:09:11 studio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 11 09:09:11 studio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 11 09:09:11 studio sudo[2228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 11 09:09:11 studio sudo[2228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:11 studio sudo[2230]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 09:09:11 studio sudo[2230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 11 09:09:11 studio sudo[2230]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:11 studio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jan 11 09:09:11 studio systemd[1]: vtcs.service: Deactivated successfully. Jan 11 09:09:11 studio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jan 11 09:09:11 studio sudo[2228]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:11 studio sudo[2238]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 11 09:09:11 studio sudo[2238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:11 studio sudo[2241]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 09:09:11 studio sudo[2241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:11 studio volumio[1198]: info: MPD Permissions set Jan 11 09:09:11 studio sudo[2241]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:11 studio sudo[2238]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:11 studio sudo[2244]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 09:09:11 studio sudo[2244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:11 studio volumio[1198]: info: Successfully started MPD Monitor Jan 11 09:09:11 studio volumio[1198]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 11 09:09:11 studio volumio[1198]: info: QobuzConnect: setDeactiveState invoked Jan 11 09:09:11 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:11 studio volumio[1198]: info: Shairport-Sync Started Jan 11 09:09:11 studio sudo[2244]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:11 studio sudo[2250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 11 09:09:11 studio sudo[2250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:11 studio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 11 09:09:11 studio systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 11 09:09:11 studio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 11 09:09:11 studio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 11 09:09:11 studio sudo[2250]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:11 studio volumio[1198]: info: Executing endpoint tc_getconfig Jan 11 09:09:11 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 11 09:09:11 studio volumio[1198]: info: Executing endpoint qc_getconfig Jan 11 09:09:11 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 11 09:09:12 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:12 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:12 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:12 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:12 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:12 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:12 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:12 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:12 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:09:12 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:12 studio volumio[1198]: info: Executing endpoint qc_getconfig Jan 11 09:09:12 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 11 09:09:12 studio qobuz-connect[2257]: 20260111 09:09:12.355 [2257.2257] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 11 09:09:12 studio qobuz-connect[2257]: 20260111 09:09:12.359 [2257.2257] INFO VolumeManager: [0x985268]: Setting new playback volume: 75 Jan 11 09:09:12 studio qobuz-connect[2257]: 20260111 09:09:12.359 [2257.2257] INFO VolumeManager: [0x985268]: Setting new mute state: 0 Jan 11 09:09:12 studio qobuz-connect[2257]: 20260111 09:09:12.359 [2257.2257] INFO QobuzConnect: [0x985c38]: Client initialized! Jan 11 09:09:12 studio qobuz-connect[2257]: 20260111 09:09:12.360 [2257.2257] INFO SampleApp: Starting Avahi advertising, name: Studio, service name: _qobuz-connect._tcp Jan 11 09:09:12 studio volumio[1198]: verbose: New Socket.io Connection to 192.168.0.139 from 192.168.0.211 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Jan 11 09:09:12 studio qobuz-connect[2257]: 20260111 09:09:12.376 [2257.2257] INFO LocalConfigManager: [0x984b48]: Starting Local Configuration server Jan 11 09:09:12 studio qobuz-connect[2257]: 20260111 09:09:12.376 [2257.2257] INFO SampleApp: Starting Local configuration server Jan 11 09:09:12 studio qobuz-connect[2257]: 20260111 09:09:12.376 [2257.2257] INFO SampleApp: Connected to UNIX socket client 0x96f818 Jan 11 09:09:12 studio volumio[1198]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 11 09:09:12 studio volumio[1198]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 11 09:09:12 studio qobuz-connect[2257]: 20260111 09:09:12.504 [2257.2257] INFO SampleApp: Playback volume changed: 75 Jan 11 09:09:12 studio volumio[1198]: info: Starting Shairport Sync Jan 11 09:09:12 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:12 studio sudo[2278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 09:09:12 studio sudo[2278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:12 studio mpd[2236]: 2026-01-11T09:09:12 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 09:09:12 studio systemd[1]: Started mpd.service - Music Player Daemon. Jan 11 09:09:12 studio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 11 09:09:12 studio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 11 09:09:12 studio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 09:09:12 studio systemd[1]: shairport-sync.service: Consumed 1.180s CPU time. Jan 11 09:09:12 studio sudo[2211]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:12 studio volumio[1198]: error: updateQueue error: null Jan 11 09:09:12 studio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 09:09:12 studio sudo[2278]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:12 studio volumio[1198]: error: updateQueue error: null Jan 11 09:09:12 studio volumio[1198]: info: Shairport-Sync Started Jan 11 09:09:12 studio sudo[2299]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 09:09:12 studio sudo[2299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:13 studio sudo[2300]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 09:09:13 studio sudo[2300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:13 studio sudo[2299]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:13 studio sudo[2300]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:13 studio volumio[1198]: verbose: New Socket.io Connection to 192.168.0.139 from 192.168.0.211 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 11 09:09:13 studio volumio[1198]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 11 09:09:13 studio volumio[1198]: info: Received Get System Info Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:09:13 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:13 studio volumio[1198]: info: Listing playlists Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 11 09:09:13 studio volumio[1198]: info: TidalConnect service started! Jan 11 09:09:13 studio volumio[1198]: [Metrics] CommandRouter: 76s 852.74ms Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::volumiosetStartupVolume Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::Close All Modals sent Jan 11 09:09:13 studio volumio[1198]: info: CoreCommandRouter::Close All Modals sent Jan 11 09:09:14 studio volumio[1198]: info: TidalConnect service stoped! Jan 11 09:09:14 studio volumio[1198]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 11 09:09:14 studio volumio[1198]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 11 09:09:14 studio sudo[2314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 11 09:09:14 studio sudo[2314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:14 studio sudo[2317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 09:09:14 studio sudo[2317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:14 studio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 09:09:14 studio sudo[2314]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:14 studio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 11 09:09:14 studio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 11 09:09:14 studio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 11 09:09:14 studio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 11 09:09:14 studio mpd_monitor.sh[2322]: MPD Monitor Service: Starting MPD Monitor Service Jan 11 09:09:14 studio sudo[2317]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 09:09:14 studio volumio[1198]: info: Received Get System Info Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:09:14 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:09:14 studio volumio[1198]: info: Successfully started MPD Monitor Jan 11 09:09:14 studio volumio[1198]: info: Executing endpoint tc_getconfig Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 11 09:09:14 studio vtcs[2320]: STARTING TidalConnect services, version: 1.5.2.56 Jan 11 09:09:14 studio vtcs[2320]: STARTED TidalConnect services. Jan 11 09:09:14 studio volumio[1198]: info: Executing endpoint tc_connect Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 11 09:09:14 studio volumio[1198]: info: Connecting to TidalConnect Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::servicePushState Jan 11 09:09:14 studio volumio[1198]: info: CoreStateMachine::pushState Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 11 09:09:14 studio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Jan 11 09:09:14 studio volumio[1198]: info: MRS: Pushing multiroomSync output Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:14 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:09:14 studio volumio[1198]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jan 11 09:09:14 studio volumio[1198]: verbose: CURRENT POSITION 0 Jan 11 09:09:14 studio volumio[1198]: info: CoreStateMachine::syncState stateService stop Jan 11 09:09:14 studio volumio[1198]: info: CoreStateMachine::syncState currentStatus stop Jan 11 09:09:14 studio volumio[1198]: info: CoreStateMachine::pushState Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 09:09:14 studio volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 11 09:09:14 studio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Jan 11 09:09:15 studio volumio[1198]: info: MRS: Pushing multiroomSync output Jan 11 09:09:15 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:15 studio volumio[1198]: info: No code Jan 11 09:09:15 studio volumio[1198]: info: CoreStateMachine::pushState Jan 11 09:09:15 studio volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 11 09:09:15 studio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Jan 11 09:09:15 studio volumio[1198]: info: MRS: Pushing multiroomSync output Jan 11 09:09:15 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:15 studio volumio[1198]: info: CoreCommandRouter::servicePushState Jan 11 09:09:15 studio volumio[1198]: info: CoreStateMachine::pushState Jan 11 09:09:15 studio volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 11 09:09:15 studio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Jan 11 09:09:15 studio volumio[1198]: info: MRS: Pushing multiroomSync output Jan 11 09:09:15 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:15 studio volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 11 09:09:15 studio volumio[1198]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jan 11 09:09:15 studio volumio[1198]: verbose: CURRENT POSITION 0 Jan 11 09:09:15 studio volumio[1198]: info: CoreStateMachine::syncState stateService stop Jan 11 09:09:15 studio volumio[1198]: info: CoreStateMachine::syncState currentStatus stop Jan 11 09:09:15 studio volumio[1198]: info: CoreStateMachine::pushState Jan 11 09:09:15 studio volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 11 09:09:15 studio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Jan 11 09:09:15 studio volumio[1198]: info: MRS: Pushing multiroomSync output Jan 11 09:09:15 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:15 studio volumio[1198]: info: No code Jan 11 09:09:15 studio volumio[1198]: info: CoreStateMachine::pushState Jan 11 09:09:15 studio volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 11 09:09:15 studio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Jan 11 09:09:15 studio volumio[1198]: info: MRS: Pushing multiroomSync output Jan 11 09:09:15 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:15 studio volumio[1198]: SPOTIFY: RECEIVED VOLUMIO VOLUME 31 Jan 11 09:09:15 studio volumio[1198]: SPOTIFY: RECEIVED VOLUMIO VOLUME 31 Jan 11 09:09:15 studio volumio[1198]: SPOTIFY: RECEIVED VOLUMIO VOLUME 31 Jan 11 09:09:15 studio volumio[1198]: SPOTIFY: RECEIVED VOLUMIO VOLUME 31 Jan 11 09:09:15 studio volumio[1198]: SPOTIFY: RECEIVED VOLUMIO VOLUME 31 Jan 11 09:09:15 studio volumio[1198]: SPOTIFY: RECEIVED VOLUMIO VOLUME 31 Jan 11 09:09:15 studio volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.jEtGjFzCERMejLI477Q2oWfq0fs2.718513872d625fc56759acaf446463b6.state.status' Jan 11 09:09:15 studio volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.jEtGjFzCERMejLI477Q2oWfq0fs2.718513872d625fc56759acaf446463b6.state.status' Jan 11 09:09:15 studio volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.jEtGjFzCERMejLI477Q2oWfq0fs2.718513872d625fc56759acaf446463b6.state.status' Jan 11 09:09:15 studio volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.jEtGjFzCERMejLI477Q2oWfq0fs2.718513872d625fc56759acaf446463b6.state.status' Jan 11 09:09:15 studio sudo[2344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 09:09:15 studio sudo[2344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:16 studio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 11 09:09:16 studio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 11 09:09:16 studio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 11 09:09:16 studio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 11 09:09:16 studio sudo[2344]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:16 studio mpd_monitor.sh[2346]: MPD Monitor Service: Starting MPD Monitor Service Jan 11 09:09:16 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 09:09:16 studio volumio[1198]: info: Received Get System Info Jan 11 09:09:16 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 09:09:16 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 09:09:16 studio volumio[1198]: info: Discovery: Getting this device information Jan 11 09:09:16 studio volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 11 09:09:16 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 09:09:16 studio volumio[1198]: info: Successfully started MPD Monitor Jan 11 09:09:17 studio volumio[1198]: info: TidalConnect service started! Jan 11 09:09:20 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 09:09:20 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 11 09:09:20 studio volumio[1198]: info: BOOT COMPLETED Jan 11 09:09:23 studio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 09:09:26 studio volumio[1198]: info: Starting Uninstall of plugin user_interface - touch_display Jan 11 09:09:26 studio volumio[1198]: info: Uninstalling plugin touch_display Jan 11 09:09:26 studio volumio[1198]: info: touch_display: Un-/commenting gpu_mem settings in /boot/config.txt. Jan 11 09:09:26 studio volumio[1198]: info: touch_display: Using /boot/config.txt instead of /boot/userconfig.txt. Jan 11 09:09:26 studio sudo[2366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jan 11 09:09:26 studio sudo[2366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:26 studio sudo[2366]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:26 studio volumio[1198]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jan 11 09:09:26 studio volumio[1198]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 09:09:26 studio sudo[2367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Jan 11 09:09:26 studio sudo[2367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:26 studio sudo[2370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Jan 11 09:09:26 studio volumio[1198]: TypeError: Cannot read properties of undefined (reading 'split') Jan 11 09:09:26 studio volumio[1198]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Jan 11 09:09:26 studio volumio[1198]: at ChildProcess.exithandler (node:child_process:413:7) Jan 11 09:09:26 studio volumio[1198]: at ChildProcess.emit (node:events:514:28) Jan 11 09:09:26 studio volumio[1198]: at maybeClose (node:internal/child_process:1105:16) Jan 11 09:09:26 studio volumio[1198]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Jan 11 09:09:26 studio sudo[2370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:26 studio volumio[1198]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 09:09:26 studio sudo[2371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Jan 11 09:09:26 studio sudo[2371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 09:09:26 studio systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jan 11 09:09:26 studio systemd[1]: Started getty@tty1.service - Getty on tty1. Jan 11 09:09:26 studio sudo[2367]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:26 studio sudo[2371]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:26 studio systemd[1]: Reloading. Jan 11 09:09:27 studio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 09:09:27 studio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 09:09:27 studio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 09:09:27 studio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 09:09:27 studio sudo[2370]: pam_unix(sudo:session): session closed for user root Jan 11 09:09:29 studio sudo[2408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 09:08' Jan 11 09:09:29 studio sudo[2408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"