-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Mon 2026-01-12 20:48:14 EET. -- Jan 12 20:47:23 volumio systemd-timedated[1121]: Changed local time to Mon Jan 12 20:47:23 2026 Jan 12 20:47:23 volumio sudo[1119]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:23 volumio volumio-time-update[785]: volumio-time-update-util: System time updated successfully. Jan 12 20:47:23 volumio systemd[1]: Started Volumio Time Update Utility. Jan 12 20:47:23 volumio systemd[1]: Reached target Multi-User System. Jan 12 20:47:23 volumio systemd[1]: Reached target Graphical Interface. Jan 12 20:47:23 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 12 20:47:23 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 12 20:47:23 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 12 20:47:23 volumio systemd[1]: Startup finished in 11.914s (kernel) + 14.593s (userspace) = 26.507s. Jan 12 20:47:23 volumio volumio[1030]: info: Loading plugin "network"... Jan 12 20:47:23 volumio volumio[1030]: info: Refreshing Cached IP Addresses Jan 12 20:47:23 volumio sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 20:47:23 volumio sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:23 volumio sudo[1129]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:23 volumio sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 20:47:23 volumio sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:23 volumio sudo[1131]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:23 volumio volumio[1030]: info: Loading plugin "services"... Jan 12 20:47:23 volumio volumio[1030]: info: Loading plugin "alsa_controller"... Jan 12 20:47:23 volumio sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 12 20:47:23 volumio sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:23 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 20:47:23 volumio volumio[1030]: info: Loading plugin "wizard"... Jan 12 20:47:23 volumio volumio[1030]: info: Loading plugin "networkfs"... Jan 12 20:47:23 volumio volumio[1030]: info: Starting Udev Watcher for removable devices Jan 12 20:47:24 volumio volumio[1030]: info: Ignoring mount for partition: boot Jan 12 20:47:24 volumio volumio[1030]: info: Ignoring mount for partition: volumio Jan 12 20:47:24 volumio volumio[1030]: info: Ignoring mount for partition: volumio_data Jan 12 20:47:24 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 20:47:24 volumio volumio[1030]: info: Loading plugin "volumio_command_line_client"... Jan 12 20:47:24 volumio volumio[1030]: info: Loading plugin "upnp"... Jan 12 20:47:24 volumio volumio[1030]: info: [1768243644025] Starting Upmpd Daemon Jan 12 20:47:24 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 20:47:24 volumio volumio[1030]: info: Loading plugin "my_music"... Jan 12 20:47:24 volumio volumio[1030]: info: Loading plugin "mpd"... Jan 12 20:47:24 volumio volumio[1030]: info: Loading plugin "upnp_browser"... Jan 12 20:47:24 volumio volumio[1030]: info: Starting UPNP Browser Jan 12 20:47:24 volumio volumio[1030]: info: Loading plugin "alarm-clock"... Jan 12 20:47:24 volumio volumio[1030]: info: Loading plugin "airplay_emulation"... Jan 12 20:47:24 volumio volumio[1030]: info: Starting Shairport Sync Jan 12 20:47:24 volumio volumio[1030]: info: Loading plugin "last_100"... Jan 12 20:47:24 volumio volumio[1030]: info: Loading plugin "webradio"... Jan 12 20:47:24 volumio volumio[1030]: info: Loading plugin "i2s_dacs"... Jan 12 20:47:24 volumio volumio[1030]: info: Loading plugin "volumiodiscovery"... Jan 12 20:47:24 volumio volumio[1030]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 12 20:47:24 volumio volumio[1030]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 20:47:24 volumio volumio[1030]: *** WARNING *** For more information see Jan 12 20:47:24 volumio volumio[1030]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 12 20:47:24 volumio volumio[1030]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 20:47:24 volumio volumio[1030]: *** WARNING *** For more information see Jan 12 20:47:24 volumio node[1030]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 12 20:47:24 volumio node[1030]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 20:47:24 volumio node[1030]: *** WARNING *** For more information see Jan 12 20:47:24 volumio node[1030]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 12 20:47:24 volumio node[1030]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 20:47:24 volumio node[1030]: *** WARNING *** For more information see Jan 12 20:47:24 volumio volumio[1030]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 12 20:47:24 volumio volumio[1030]: info: Discovery: Started advertising with name: Volumio Jan 12 20:47:24 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 20:47:24 volumio volumio[1030]: info: Loading plugin "outputs"... Jan 12 20:47:24 volumio volumio[1030]: info: Loading plugin "albumart"... Jan 12 20:47:24 volumio volumio[1030]: info: Plugin example_plugin is not enabled Jan 12 20:47:24 volumio volumio[1030]: info: Loading plugin "inputs"... Jan 12 20:47:24 volumio volumio[1030]: info: Loading plugin "updater_comm"... Jan 12 20:47:24 volumio kernel: hwmon hwmon1: Voltage normalised Jan 12 20:47:25 volumio volumio[1030]: info: Plugin mpdemulation is not enabled Jan 12 20:47:25 volumio volumio[1030]: info: Loading plugin "rest_api"... Jan 12 20:47:25 volumio volumio[1030]: info: Loading plugin "websocket"... Jan 12 20:47:25 volumio volumio[1030]: info: Starting Socket.io Server version 2.3.0 Jan 12 20:47:25 volumio volumio[1030]: info: Loading plugin "radio_paradise"... Jan 12 20:47:25 volumio volumio[1030]: Forking 3 albumart workers Jan 12 20:47:25 volumio volumio-remote-updater[771]: [2026-01-12 20:47:25] [connect] Successful connection Jan 12 20:47:25 volumio volumio[1030]: info: Applying required configuration parameters for plugin radio_paradise Jan 12 20:47:25 volumio volumio[1030]: info: [1768243645575] [RadioParadise] API delay: 5 Jan 12 20:47:25 volumio volumio[1030]: info: Loading plugin "touch_display"... Jan 12 20:47:25 volumio volumio[1030]: Starting albumart workers Jan 12 20:47:25 volumio volumio[1030]: Starting albumart workers Jan 12 20:47:25 volumio volumio[1030]: Starting albumart workers Jan 12 20:47:25 volumio volumio[1030]: info: Applying required configuration parameters for plugin touch_display Jan 12 20:47:26 volumio volumio[1030]: info: Loading i18n strings for locale en Jan 12 20:47:26 volumio volumio[1030]: Updating browse sources language Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::initPlayerControls Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: Express server listening on port 3000 Jan 12 20:47:26 volumio volumio[1030]: [Metrics] WebUI: 5s 131.30ms Jan 12 20:47:26 volumio volumio[1030]: info: CoreStateMachine::resetVolumioState Jan 12 20:47:26 volumio volumio[1030]: info: CoreStateMachine::getcurrentVolume Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::volumioRetrievevolume Jan 12 20:47:26 volumio volumio[1030]: info: CoreStateMachine::pushState Jan 12 20:47:26 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jan 12 20:47:26 volumio volumio[1030]: info: Volumio Network Manager: Network status updated: 1 Jan 12 20:47:26 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 1 Jan 12 20:47:26 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 2 Jan 12 20:47:26 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 3 Jan 12 20:47:26 volumio volumio-remote-updater[771]: [2026-01-12 20:47:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1768243645 101 Jan 12 20:47:26 volumio volumio[1030]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Jan 12 20:47:26 volumio volumio[1030]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 12 20:47:26 volumio volumio[1030]: info: Reloading queue from file Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: info: CoreStateMachine::setRepeat null single undefined Jan 12 20:47:26 volumio volumio[1030]: info: CoreStateMachine::pushState Jan 12 20:47:26 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jan 12 20:47:26 volumio volumio[1030]: info: CoreStateMachine::setRandom null Jan 12 20:47:26 volumio volumio[1030]: info: CoreStateMachine::pushState Jan 12 20:47:26 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jan 12 20:47:26 volumio volumio[1030]: info: Setting Device type: Raspberry PI Jan 12 20:47:26 volumio volumio[1030]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 12 20:47:26 volumio volumio[1030]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Jan 12 20:47:26 volumio volumio[1030]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:26 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:26 volumio volumio[1030]: info: Completed loading Core Plugins Jan 12 20:47:26 volumio volumio[1030]: info: Preparing to generate the ALSA configuration file Jan 12 20:47:26 volumio volumio[1030]: info: Received Get System Info Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 20:47:26 volumio volumio[1030]: info: Discovery: Getting this device information Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:26 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:26 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:26 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:26 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 20:47:26 volumio volumio[1030]: info: Received Get System Info Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 20:47:26 volumio volumio[1030]: info: Discovery: Getting this device information Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:26 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:26 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:26 volumio volumio[1030]: info: Listing playlists Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 20:47:26 volumio volumio[1030]: info: Asound.conf file unchanged, so no further update is needed Jan 12 20:47:26 volumio volumio[1030]: info: Output device has changed, restarting MPD Jan 12 20:47:26 volumio volumio[1030]: info: Output device has changed, restarting Shairport Sync Jan 12 20:47:26 volumio sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 20:47:26 volumio sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:26 volumio sudo[1220]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:26 volumio sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 20:47:26 volumio sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:26 volumio volumio[1030]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 20:47:26 volumio volumio[1030]: info: ___________ START PLUGINS ___________ Jan 12 20:47:26 volumio volumio[1030]: info: ControllerMpd::onStart: Initializing MPD Jan 12 20:47:26 volumio volumio[1030]: info: Creating MPD Configuration file Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 20:47:26 volumio volumio[1030]: info: [1768243646841] CoreMusicLibrary::Adding element Media Servers Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 20:47:26 volumio sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 20:47:26 volumio sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:26 volumio volumio[1030]: info: UPNP Browser: Client initialized successfully Jan 12 20:47:26 volumio sudo[1230]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:26 volumio sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 20:47:26 volumio sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:26 volumio systemd[1]: Listening on mpd.socket. Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:26 volumio systemd[1]: Starting Music Player Daemon... Jan 12 20:47:26 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 12 20:47:26 volumio systemd[1]: mpd.service: Succeeded. Jan 12 20:47:26 volumio systemd[1]: Stopped Music Player Daemon. Jan 12 20:47:26 volumio systemd[1]: Starting Music Player Daemon... Jan 12 20:47:26 volumio volumio[1030]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 20:47:26 volumio volumio[1030]: info: [1768243646926] CoreMusicLibrary::Adding element Last_100 Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 20:47:26 volumio volumio[1030]: info: [1768243646929] CoreMusicLibrary::Adding element Webradio Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 20:47:26 volumio volumio[1030]: info: Initializing BBC Radios Jan 12 20:47:26 volumio sudo[1239]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 20:47:26 volumio sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:26 volumio sudo[1239]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 12 20:47:26 volumio sudo[1239]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 20:47:26 volumio volumio[1030]: info: [1768243646989] CoreMusicLibrary::Adding element Radio Paradise Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 20:47:26 volumio volumio[1030]: Cannot find translation for source Radio Paradise Jan 12 20:47:26 volumio volumio[1030]: info: Loading i18n strings for locale en Jan 12 20:47:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 20:47:27 volumio volumio[1030]: info: Volumio Calling Home Jan 12 20:47:27 volumio sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 12 20:47:27 volumio sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:27 volumio sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 12 20:47:27 volumio sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:27 volumio sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 12 20:47:27 volumio sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 6 Jan 12 20:47:27 volumio systemd[1]: Reloading. Jan 12 20:47:27 volumio volumio[1030]: info: Discovery: adding a6bd597b-094d-4182-af51-aa8232ab9307 Jan 12 20:47:27 volumio volumio[1030]: info: Discovery: Found device Volumio Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:27 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:27 volumio volumio[1030]: info: Discovery: this is already registered, a6bd597b-094d-4182-af51-aa8232ab9307 Jan 12 20:47:27 volumio volumio[1030]: info: Discovery: Found device Volumio Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:27 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:27 volumio volumio[1030]: info: touch_display: Backlight interface detected. Jan 12 20:47:27 volumio volumio[1030]: info: MPD Permissions set Jan 12 20:47:27 volumio volumio[1030]: info: MPD Permissions set Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 7 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 8 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 9 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 10 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 11 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 12 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 14 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 15 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 16 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 17 Jan 12 20:47:27 volumio volumio[1030]: info: Volumio called home Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 18 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 19 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 20 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 21 Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 20:47:27 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 12 20:47:27 volumio sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Jan 12 20:47:27 volumio sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:27 volumio volumio[1030]: info: touch_display: Raspberry Pi Foundation touch screen detected. Jan 12 20:47:27 volumio sudo[1289]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:27 volumio sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jan 12 20:47:27 volumio sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:27 volumio sudo[1292]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:27 volumio sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 20:47:27 volumio sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 22 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 23 Jan 12 20:47:27 volumio sudo[1294]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 24 Jan 12 20:47:27 volumio sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 20:47:27 volumio sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:27 volumio volumio[1030]: info: Starting Shairport Sync Jan 12 20:47:27 volumio sudo[1297]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:27 volumio volumio[1030]: info: Starting Shairport Sync Jan 12 20:47:27 volumio volumio[1030]: info: Starting Shairport Sync Jan 12 20:47:27 volumio sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 20:47:27 volumio sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:27 volumio sudo[1303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 20:47:27 volumio sudo[1303]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:27 volumio sudo[1142]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:27 volumio sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 20:47:27 volumio sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 25 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 26 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 27 Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:27 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:27 volumio volumio[1030]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jan 12 20:47:27 volumio volumio[1030]: info: touch_display: File permissions for backlight brightness control set. Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 28 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 29 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 30 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 31 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 32 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 33 Jan 12 20:47:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.5 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.2 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 34 Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:27 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 20:47:27 volumio volumio[1030]: info: Received Get System Info Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 20:47:27 volumio volumio[1030]: info: Discovery: Getting this device information Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:27 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:27 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:27 volumio volumio[1030]: info: Listing playlists Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 12 20:47:27 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 20:47:27 volumio sudo[1265]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:27 volumio volumio[1030]: info: touch_display: systemctl daemon-reload succeeded. Jan 12 20:47:27 volumio systemd[1]: Reloading. Jan 12 20:47:27 volumio sudo[1311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 12 20:47:27 volumio sudo[1311]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:28 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 12 20:47:28 volumio sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 20:47:28 volumio sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:28 volumio sudo[1328]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:28 volumio sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 20:47:28 volumio sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:28 volumio sudo[1330]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:28 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.5 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.2 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 35 Jan 12 20:47:28 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 20:47:28 volumio volumio[1030]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 20:47:28 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 20:47:28 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:28 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:28 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 12 20:47:28 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 20:47:28 volumio volumio[1030]: info: Received Get System Info Jan 12 20:47:28 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 20:47:28 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 20:47:28 volumio volumio[1030]: info: Discovery: Getting this device information Jan 12 20:47:28 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:28 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:28 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 20:47:28 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:28 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:28 volumio volumio[1030]: info: Listing playlists Jan 12 20:47:28 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 12 20:47:28 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 20:47:28 volumio sudo[1261]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:28 volumio volumio[1030]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jan 12 20:47:28 volumio sudo[1311]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:28 volumio volumio[1030]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Jan 12 20:47:28 volumio volumio[1030]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Jan 12 20:47:28 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 12 20:47:28 volumio systemd[1]: Starting Daily apt download activities... Jan 12 20:47:28 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 12 20:47:28 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 12 20:47:28 volumio sudo[1259]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:28 volumio volumio[1030]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 12 20:47:28 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 12 20:47:28 volumio sudo[1301]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:28 volumio sudo[1303]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:28 volumio sudo[1306]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:28 volumio volumio[1030]: info: Shairport-Sync Started Jan 12 20:47:28 volumio volumio[1030]: Error adding Membership: Error: addMembership EINVAL Jan 12 20:47:28 volumio volumio[1030]: info: Shairport-Sync Started Jan 12 20:47:28 volumio volumio[1030]: info: Shairport-Sync Started Jan 12 20:47:28 volumio systemd[1]: apt-daily.service: Succeeded. Jan 12 20:47:28 volumio systemd[1]: Started Daily apt download activities. Jan 12 20:47:29 volumio mpd[1246]: Jan 12 20:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 12 20:47:29 volumio systemd[1]: Started Music Player Daemon. Jan 12 20:47:29 volumio sudo[1222]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:29 volumio sudo[1232]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:29 volumio volumio[1030]: info: Completed starting Core Plugins Jan 12 20:47:29 volumio volumio[1030]: info: ------------------------------------------- Jan 12 20:47:29 volumio volumio[1030]: info: ----- MyVolumio plugins startup ---- Jan 12 20:47:29 volumio volumio[1030]: info: ------------------------------------------- Jan 12 20:47:29 volumio volumio[1030]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 12 20:47:29 volumio volumio[1030]: error: MPD error: The expression evaluated to a falsy value: Jan 12 20:47:29 volumio volumio[1030]: assert.ok(self.idling) Jan 12 20:47:29 volumio volumio[1030]: error: The expression evaluated to a falsy value: Jan 12 20:47:29 volumio volumio[1030]: assert.ok(self.idling) Jan 12 20:47:29 volumio volumio[1030]: error: updateQueue error: null Jan 12 20:47:29 volumio volumio[1030]: info: MPD running with PID1246 Jan 12 20:47:29 volumio volumio[1030]: ,establishing connection Jan 12 20:47:29 volumio volumio[1030]: error: updateQueue error: null Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:29 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 20:47:29 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.19 UA: Mozilla/5.0 (X11; Linux x86_64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 36 Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:29 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:29 volumio volumio[1030]: info: Received Get System Info Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 20:47:29 volumio volumio[1030]: info: Discovery: Getting this device information Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:29 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:29 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:29 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:29 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 20:47:29 volumio volumio[1030]: info: Received Get System Info Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 20:47:29 volumio volumio[1030]: info: Discovery: Getting this device information Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:29 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:29 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:29 volumio volumio[1030]: info: Listing playlists Jan 12 20:47:29 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 12 20:47:30 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 20:47:30 volumio volumio[1030]: info: Received Get System Info Jan 12 20:47:30 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 20:47:30 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 20:47:30 volumio volumio[1030]: info: Discovery: Getting this device information Jan 12 20:47:30 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:30 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:30 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 20:47:30 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 20:47:31 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 12 20:47:31 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 20:47:31 volumio volumio[1030]: info: Received Get System Info Jan 12 20:47:31 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 20:47:31 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 20:47:31 volumio volumio[1030]: info: Discovery: Getting this device information Jan 12 20:47:31 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:31 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:31 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 20:47:31 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 20:47:31 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 12 20:47:32 volumio sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 12 20:47:32 volumio sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:32 volumio sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 12 20:47:32 volumio sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:32 volumio systemd[1]: Started MPD Monitor Service. Jan 12 20:47:32 volumio sudo[1358]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:32 volumio volumio[1030]: info: Successfully started MPD Monitor Jan 12 20:47:32 volumio systemd[1]: Stopping MPD Monitor Service... Jan 12 20:47:32 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 12 20:47:32 volumio systemd[1]: mpd_monitor.service: Succeeded. Jan 12 20:47:32 volumio systemd[1]: Stopped MPD Monitor Service. Jan 12 20:47:32 volumio systemd[1]: Started MPD Monitor Service. Jan 12 20:47:32 volumio sudo[1360]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:32 volumio volumio[1030]: info: Successfully started MPD Monitor Jan 12 20:47:32 volumio mpd_monitor.sh[1364]: MPD Monitor Service: Starting MPD Monitor Service Jan 12 20:47:33 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 20:47:33 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jan 12 20:47:33 volumio volumio[1030]: info: CoreStateMachine::getQueue Jan 12 20:47:33 volumio volumio[1030]: info: CorePlayQueue::getQueue Jan 12 20:47:34 volumio sudo[1383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 20:47:34 volumio sudo[1383]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:34 volumio sudo[1383]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:34 volumio sudo[1385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 20:47:34 volumio sudo[1385]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:34 volumio sudo[1385]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:34 volumio sudo[1389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 12 20:47:34 volumio sudo[1389]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:34 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 12 20:47:34 volumio sudo[1389]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:34 volumio volumio[1030]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 12 20:47:34 volumio volumio[1030]: info: Upmpdcli Daemon Started Jan 12 20:47:34 volumio volumio[1391]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 12 20:47:34 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 20:47:35 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 20:47:35 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 20:47:37 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 12 20:47:37 volumio volumio[1030]: info: Adding plugin bluetooth to MyMusic Plugins Jan 12 20:47:37 volumio volumio[1030]: info: Adding plugin multiroom to MyMusic Plugins Jan 12 20:47:37 volumio volumio[1030]: info: Adding plugin metavolumio to MyMusic Plugins Jan 12 20:47:37 volumio volumio[1030]: info: Adding plugin cd_controller to MyMusic Plugins Jan 12 20:47:37 volumio volumio[1030]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 12 20:47:37 volumio volumio[1030]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 12 20:47:37 volumio volumio[1030]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 12 20:47:37 volumio volumio[1030]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 12 20:47:38 volumio volumio[1030]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 12 20:47:38 volumio volumio[1030]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 12 20:47:38 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:38 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:38 volumio volumio[1030]: info: Starting MyVolumio Remote Streaming Endpoints Jan 12 20:47:38 volumio volumio[1030]: info: MyVolumio login type: Token Jan 12 20:47:38 volumio volumio[1030]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 12 20:47:38 volumio volumio[1030]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 12 20:47:39 volumio volumio[1030]: info: Starting Streaming Service Transparent Proxy Jan 12 20:47:39 volumio volumio[1030]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 12 20:47:39 volumio volumio[1030]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 12 20:47:39 volumio volumio[1030]: info: Streaming services startup Jan 12 20:47:39 volumio volumio[1030]: info: Starting Streaming Daemon Jan 12 20:47:39 volumio sudo[1414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 20:47:39 volumio sudo[1414]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:39 volumio sudo[1414]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:39 volumio volumio[1030]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 12 20:47:39 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 20:47:39 volumio volumio[1030]: error: Cannot start Volumio Streaming Daemon Jan 12 20:47:39 volumio volumio[1030]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 20:47:39 volumio volumio[1030]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 20:47:39 volumio volumio[1030]: error: MyVolumio Custom Token format not valid, refreshing it Jan 12 20:47:39 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 20:47:39 volumio volumio[1030]: STREAMING PROXY: Starting server on port 3245 Jan 12 20:47:39 volumio volumio[1030]: Node JS runtime: 14 Jan 12 20:47:40 volumio volumio[1030]: info: MyVolumio login type: Token Jan 12 20:47:40 volumio volumio[1030]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 12 20:47:41 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jan 12 20:47:41 volumio volumio[1030]: info: MyVolumio token set successfully Jan 12 20:47:41 volumio volumio[1030]: info: MYVOLUMIO: Adding device Jan 12 20:47:41 volumio volumio[1030]: info: MYVOLUMIO: Evaluating Server Jan 12 20:47:42 volumio volumio[1030]: info: MyVolumio Plan changed: premium Jan 12 20:47:42 volumio volumio[1030]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jan 12 20:47:42 volumio volumio[1030]: info: Removing browser output: myVolumio user plan is not superstar Jan 12 20:47:42 volumio volumio[1030]: info: Removing audio output: Jan 12 20:47:42 volumio volumio[1030]: info: MYVOLUMIO: Adding device Jan 12 20:47:42 volumio volumio[1030]: info: MYVOLUMIO: Evaluating Server Jan 12 20:47:42 volumio volumio[1030]: info: Remote config written successfully Jan 12 20:47:42 volumio volumio[1030]: info: Starting Tunnel 1 Jan 12 20:47:42 volumio volumio[1030]: info: Starting Tunnel Connection Checker Jan 12 20:47:42 volumio volumio[1030]: info: Completed starting MyVolumio Plugin Jan 12 20:47:42 volumio volumio[1030]: info: MYVolumio Device enabled Jan 12 20:47:42 volumio volumio[1030]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 12 20:47:42 volumio volumio[1030]: info: MyVolumio status changed Jan 12 20:47:42 volumio volumio[1030]: info: Streaming services startup Jan 12 20:47:42 volumio volumio[1030]: info: Starting Streaming Daemon Jan 12 20:47:42 volumio volumio[1030]: info: Setting Geolocation for MyVolumio to eu5 Jan 12 20:47:42 volumio sudo[1460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 20:47:42 volumio sudo[1460]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:42 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:42 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:42 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:42 volumio sudo[1460]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:42 volumio volumio[1030]: error: Cannot start Volumio Streaming Daemon Jan 12 20:47:42 volumio volumio[1030]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 20:47:42 volumio volumio[1030]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 20:47:42 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 12 20:47:42 volumio volumio[1030]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 12 20:47:42 volumio volumio[1030]: info: [1768243662639] Starting BluetoothController Jan 12 20:47:42 volumio volumio[1030]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 12 20:47:43 volumio volumio[1030]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jan 12 20:47:43 volumio sudo[1463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 12 20:47:43 volumio sudo[1463]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:43 volumio sudo[1463]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:43 volumio volumio[1030]: info: MRS: MultiRoom plugin initialized Jan 12 20:47:43 volumio volumio[1030]: info: MRS: STOPPING SNAPCLIENT Jan 12 20:47:43 volumio volumio[1030]: info: MRS: Snap server stop Jan 12 20:47:43 volumio sudo[1479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 12 20:47:43 volumio sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:43 volumio volumio[1030]: info: MRS: STOPPING volumioStreaming Jan 12 20:47:43 volumio sudo[1482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 12 20:47:43 volumio sudo[1482]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:43 volumio sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 12 20:47:43 volumio sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:43 volumio sudo[1479]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:43 volumio sudo[1482]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:43 volumio sudo[1485]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:43 volumio volumio[1030]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 12 20:47:43 volumio sudo[1496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 12 20:47:43 volumio sudo[1496]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:43 volumio sudo[1496]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:43 volumio volumio[1030]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jan 12 20:47:44 volumio volumio[1030]: info: Preparing to generate the ALSA configuration file Jan 12 20:47:44 volumio volumio[1030]: info: Updating MyVolumio device info Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:44 volumio volumio[1030]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 12 20:47:44 volumio volumio[1030]: info: Reading ALSA contributions from plugins. Jan 12 20:47:44 volumio volumio[1030]: info: Setting Geolocation for MyVolumio to eu5 Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:44 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:44 volumio volumio[1030]: info: MRS: Removed streaming files Jan 12 20:47:44 volumio volumio[1030]: info: MRS: volumioStreaming STOPPED Jan 12 20:47:44 volumio volumio[1030]: info: MRS: SNAPSERVER STOPPED Jan 12 20:47:44 volumio volumio[1030]: info: MRS: SNAPCLIENT STOPPED Jan 12 20:47:44 volumio volumio[1030]: info: Asound.conf file written Jan 12 20:47:44 volumio sudo[1505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 12 20:47:44 volumio sudo[1505]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:44 volumio sudo[1505]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:44 volumio volumio[1030]: info: Output device has changed, restarting MPD Jan 12 20:47:44 volumio sudo[1510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 20:47:44 volumio sudo[1510]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:44 volumio volumio[1030]: info: Output device has changed, restarting Shairport Sync Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:44 volumio sudo[1510]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:44 volumio sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 20:47:44 volumio sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:44 volumio systemd[1]: Stopping Music Player Daemon... Jan 12 20:47:44 volumio volumio[1030]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 20:47:44 volumio volumio[1030]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 12 20:47:44 volumio volumio[1030]: info: MRS: Pushing multiroomSync output for this device Jan 12 20:47:44 volumio volumio[1030]: info: MRS: Pushing multiroomSync output Jan 12 20:47:44 volumio volumio[1030]: info: Adding audio output: Jan 12 20:47:44 volumio volumio[1030]: info: Adding audio output: Jan 12 20:47:44 volumio sudo[1521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Jan 12 20:47:44 volumio sudo[1521]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:44 volumio volumio[1030]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:44 volumio sudo[1521]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 12 20:47:44 volumio volumio[1030]: info: Adding METAVOLUMIO REST API Endpoints Jan 12 20:47:44 volumio volumio[1030]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 12 20:47:44 volumio volumio[1030]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 12 20:47:44 volumio volumio[1030]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 12 20:47:44 volumio volumio[1030]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 12 20:47:44 volumio volumio[1030]: info: Preparing CD Folders Jan 12 20:47:44 volumio volumio[1030]: info: Adding CD REST API Endpoints Jan 12 20:47:44 volumio volumio[1030]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jan 12 20:47:44 volumio volumio[1030]: info: Starting UDEV Watcher for CD Jan 12 20:47:44 volumio volumio[1030]: info: Detecting CD presence with UDEV Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 12 20:47:44 volumio volumio[1030]: info: Adding inputs REST Endpoints Jan 12 20:47:44 volumio volumio[1030]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jan 12 20:47:44 volumio volumio[1030]: info: Scanning Audio Inputs Jan 12 20:47:44 volumio systemd[1]: mpd.service: Succeeded. Jan 12 20:47:44 volumio systemd[1]: Stopped Music Player Daemon. Jan 12 20:47:44 volumio volumio[1030]: info: Checking against Known Cards name Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 20:47:44 volumio volumio[1030]: info: [1768243664561] CoreMusicLibrary::Adding element HiFiBerry ADC Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 20:47:44 volumio volumio[1030]: Cannot find translation for source Radio Paradise Jan 12 20:47:44 volumio volumio[1030]: Cannot find translation for source HiFiBerry ADC Jan 12 20:47:44 volumio volumio[1030]: info: Checking against Known Cards name Jan 12 20:47:44 volumio volumio[1030]: info: Adding Server instance for streaming Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 12 20:47:44 volumio volumio[1030]: error: Hi Res Audio Failed Login: Missing Login Data Jan 12 20:47:44 volumio volumio[1030]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 12 20:47:44 volumio volumio[1030]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 12 20:47:44 volumio volumio[1030]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 12 20:47:44 volumio systemd[1]: Starting Music Player Daemon... Jan 12 20:47:44 volumio volumio[1030]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jan 12 20:47:44 volumio volumio[1030]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jan 12 20:47:44 volumio sudo[1530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 12 20:47:44 volumio sudo[1530]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:44 volumio volumio[1030]: info: QobuzConnect: Starting Qobuz Connect socket and service Jan 12 20:47:44 volumio sudo[1530]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:44 volumio sudo[1528]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 20:47:44 volumio sudo[1537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 12 20:47:44 volumio sudo[1528]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:44 volumio sudo[1537]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:44 volumio sudo[1528]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:44 volumio volumio[1030]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jan 12 20:47:44 volumio volumio[1030]: info: Adding TIDAL REST API Endpoints Jan 12 20:47:44 volumio volumio[1030]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 12 20:47:44 volumio volumio[1030]: info: Stopping AccessToken refresher cron for QOBUZ Jan 12 20:47:44 volumio sudo[1537]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:44 volumio volumio[1030]: info: AccessToken refresher cron started for QOBUZ Jan 12 20:47:44 volumio volumio[1030]: info: Adding QOBUZ REST API Endpoints Jan 12 20:47:44 volumio volumio[1030]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 12 20:47:44 volumio volumio[1030]: info: Successfully Added MyVolumio device Jan 12 20:47:44 volumio sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 12 20:47:44 volumio sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:44 volumio volumio[1030]: info: Bluetooth name changed to Volumio Jan 12 20:47:44 volumio volumio[1030]: info: MPD Permissions set Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:44 volumio volumio[1030]: info: Starting Shairport Sync Jan 12 20:47:44 volumio sudo[1550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 20:47:44 volumio sudo[1550]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:44 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jan 12 20:47:44 volumio sudo[1541]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:44 volumio volumio[1030]: info: MRS: Found cast device: SDSTB01-31cbbb3fda8dcf22ff5ae5c014e657a9 Jan 12 20:47:44 volumio volumio[1030]: info: Adding audio output: Jan 12 20:47:44 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 12 20:47:44 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 12 20:47:44 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 12 20:47:44 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 12 20:47:44 volumio sudo[1550]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:44 volumio volumio[1030]: info: Shairport-Sync Started Jan 12 20:47:44 volumio volumio[1030]: info: Executing endpoint qc_getconfig Jan 12 20:47:44 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 12 20:47:44 volumio qobuz-connect[1547]: 20260112 20:47:44.921 [1547.1547] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 12 20:47:44 volumio volumio[1030]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 12 20:47:44 volumio volumio[1030]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 12 20:47:44 volumio volumio[1030]: info: Successfully Added MyVolumio device Jan 12 20:47:45 volumio qobuz-connect[1547]: 20260112 20:47:45.000 [1547.1547] INFO VolumeManager: [0x1abf898]: Setting new playback volume: 75 Jan 12 20:47:45 volumio qobuz-connect[1547]: 20260112 20:47:45.000 [1547.1547] INFO VolumeManager: [0x1abf898]: Setting new mute state: 0 Jan 12 20:47:45 volumio qobuz-connect[1547]: 20260112 20:47:45.000 [1547.1547] INFO QobuzConnect: [0x1ac0268]: Client initialized! Jan 12 20:47:45 volumio qobuz-connect[1547]: 20260112 20:47:45.000 [1547.1547] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jan 12 20:47:45 volumio qobuz-connect[1547]: 20260112 20:47:45.007 [1547.1547] INFO LocalConfigManager: [0x1abf178]: Starting Local Configuration server Jan 12 20:47:45 volumio qobuz-connect[1547]: 20260112 20:47:45.007 [1547.1547] INFO SampleApp: Starting Local configuration server Jan 12 20:47:45 volumio qobuz-connect[1547]: 20260112 20:47:45.008 [1547.1547] INFO SampleApp: Connected to UNIX socket client 0x1ab4f60 Jan 12 20:47:45 volumio volumio[1030]: info: Successfully Updated MyVolumio device Jan 12 20:47:45 volumio qobuz-connect[1547]: 20260112 20:47:45.071 [1547.1547] INFO SampleApp: Playback volume changed: 75 Jan 12 20:47:45 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:45 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:45 volumio volumio[1030]: info: Updating MyVolumio device info Jan 12 20:47:45 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:45 volumio mpd[1540]: Jan 12 20:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 12 20:47:45 volumio systemd[1]: Started Music Player Daemon. Jan 12 20:47:45 volumio sudo[1513]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:45 volumio volumio[1030]: error: updateQueue error: null Jan 12 20:47:45 volumio volumio[1030]: info: Successfully Updated MyVolumio device Jan 12 20:47:46 volumio volumio[1030]: info: Executing endpoint metavolumio Jan 12 20:47:46 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 12 20:47:46 volumio volumio[1391]: .......................................................................................................................................++++ Jan 12 20:47:46 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 20:47:46 volumio volumio[1030]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 12 20:47:46 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 12 20:47:46 volumio volumio[1030]: info: Received Get System Version Jan 12 20:47:46 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 20:47:46 volumio volumio[1030]: info: Received Get System Info Jan 12 20:47:46 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 20:47:46 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 20:47:46 volumio volumio[1030]: info: Discovery: Getting this device information Jan 12 20:47:46 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:46 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:46 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 20:47:47 volumio sudo[1566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 12 20:47:47 volumio sudo[1566]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:47 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jan 12 20:47:47 volumio sudo[1566]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:47 volumio volumio[1030]: info: Remote SSH Started Jan 12 20:47:47 volumio autossh[1569]: port set to 0, monitoring disabled Jan 12 20:47:47 volumio autossh[1569]: starting ssh (count 1) Jan 12 20:47:47 volumio autossh[1569]: ssh child pid is 1572 Jan 12 20:47:47 volumio volumio[1030]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 37 Jan 12 20:47:47 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:47 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:47 volumio volumiossh-tunnel[1568]: Warning: Permanently added '[eu5.myvolumio.org]:2222,[185.14.184.111]:2222' (RSA) to the list of known hosts. Jan 12 20:47:47 volumio sudo[1574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 12 20:47:47 volumio sudo[1574]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:47 volumio systemd[1]: Started Volumio Bluetooth Module. Jan 12 20:47:47 volumio sudo[1574]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:47 volumio volumio[1030]: info: Volumio BT Module successfully started Jan 12 20:47:47 volumio sudo[1577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jan 12 20:47:47 volumio sudo[1577]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:47 volumio volumiobt[1576]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jan 12 20:47:47 volumio sudo[1577]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:47 volumio volumio[1030]: info: TidalConnect service stoped! Jan 12 20:47:47 volumio volumio[1030]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 12 20:47:47 volumio volumio[1030]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 12 20:47:47 volumio sudo[1589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 12 20:47:47 volumio sudo[1589]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:47 volumio systemd[1]: Started Volumio Tidal Connect Service. Jan 12 20:47:47 volumio sudo[1589]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:47 volumio volumio[1030]: info: Executing endpoint tc_getconfig Jan 12 20:47:47 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 12 20:47:47 volumio vtcs[1591]: STARTING TidalConnect services, version: 1.5.2.56 Jan 12 20:47:47 volumio vtcs[1591]: STARTED TidalConnect services. Jan 12 20:47:48 volumio volumio[1030]: info: Executing endpoint tc_connect Jan 12 20:47:48 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 12 20:47:48 volumio volumio[1030]: info: Connecting to TidalConnect Jan 12 20:47:48 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jan 12 20:47:48 volumio volumio[1030]: info: CoreStateMachine::pushState Jan 12 20:47:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:48 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jan 12 20:47:48 volumio volumio[1030]: info: MRS: Pushing multiroomSync output update for this device Jan 12 20:47:48 volumio volumio[1030]: info: MRS: Pushing multiroomSync output Jan 12 20:47:48 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:48 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Jan 12 20:47:48 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jan 12 20:47:48 volumio volumio[1030]: info: CoreStateMachine::pushState Jan 12 20:47:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:48 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jan 12 20:47:48 volumio volumio[1030]: info: MRS: Pushing multiroomSync output update for this device Jan 12 20:47:48 volumio volumio[1030]: info: MRS: Pushing multiroomSync output Jan 12 20:47:48 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:48 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Jan 12 20:47:48 volumio sudo[1611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 12 20:47:48 volumio sudo[1611]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:48 volumio systemd[1]: Stopping MPD Monitor Service... Jan 12 20:47:48 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 12 20:47:48 volumio systemd[1]: mpd_monitor.service: Succeeded. Jan 12 20:47:48 volumio systemd[1]: Stopped MPD Monitor Service. Jan 12 20:47:48 volumio volumio[1030]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 12 20:47:48 volumio volumio[1030]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 12 20:47:48 volumio systemd[1]: Started MPD Monitor Service. Jan 12 20:47:48 volumio mpd_monitor.sh[1613]: MPD Monitor Service: Starting MPD Monitor Service Jan 12 20:47:48 volumio sudo[1611]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:48 volumio volumio[1030]: info: Successfully started MPD Monitor Jan 12 20:47:48 volumio sudo[1617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jan 12 20:47:48 volumio sudo[1617]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:47:48 volumio volumiobt[1576]: pulseaudio: no process found Jan 12 20:47:48 volumio sudo[1617]: pam_unix(sudo:session): session closed for user root Jan 12 20:47:48 volumio dbus-daemon[772]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.23' (uid=1000 pid=1621 comm="/usr/bin/pulseaudio --high-priority=true ") Jan 12 20:47:48 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jan 12 20:47:48 volumio volumio[1030]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 12 20:47:48 volumio dbus-daemon[772]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jan 12 20:47:48 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Jan 12 20:47:48 volumio rtkit-daemon[1622]: Successfully called chroot. Jan 12 20:47:48 volumio rtkit-daemon[1622]: Successfully dropped privileges. Jan 12 20:47:48 volumio rtkit-daemon[1622]: Successfully limited resources. Jan 12 20:47:48 volumio rtkit-daemon[1622]: Running. Jan 12 20:47:48 volumio rtkit-daemon[1622]: Watchdog thread running. Jan 12 20:47:48 volumio rtkit-daemon[1622]: Canary thread running. Jan 12 20:47:48 volumio dbus-daemon[772]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.24' (uid=0 pid=1622 comm="/usr/lib/rtkit/rtkit-daemon ") Jan 12 20:47:48 volumio systemd[1]: Starting Authorization Manager... Jan 12 20:47:48 volumio volumio[1030]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 12 20:47:48 volumio polkitd[1626]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 12 20:47:48 volumio dbus-daemon[772]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 12 20:47:48 volumio systemd[1]: Started Authorization Manager. Jan 12 20:47:49 volumio pulseaudio[1621]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 12 20:47:49 volumio pulseaudio[1621]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 12 20:47:49 volumio bluetoothd[889]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSource Jan 12 20:47:49 volumio pulseaudio[1621]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jan 12 20:47:49 volumio volumiobt[1576]: Applying permissions Jan 12 20:47:49 volumio volumiobt[1576]: Setting BT discoverable and pairable Jan 12 20:47:49 volumio bluetoothd[889]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSink Jan 12 20:47:49 volumio kernel: Bluetooth: RFCOMM TTY layer initialized Jan 12 20:47:49 volumio kernel: Bluetooth: RFCOMM socket layer initialized Jan 12 20:47:49 volumio kernel: Bluetooth: RFCOMM ver 1.11 Jan 12 20:47:49 volumio volumiobt[1576]: [96B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [77B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [77B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [78B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [78B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [33B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [95B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [55B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [bluetoothctl]> -e power on Jan 12 20:47:49 volumio volumiobt[1576]: Invalid command in menu main: -e Jan 12 20:47:49 volumio volumiobt[1576]: Jan 12 20:47:49 volumio volumiobt[1576]: Use "help" for a list of available commands in a menu. Jan 12 20:47:49 volumio volumiobt[1576]: Use "menu " if you want to enter any submenu. Jan 12 20:47:49 volumio volumiobt[1576]: Use "back" if you want to return to menu main. Jan 12 20:47:49 volumio volumiobt[1576]: [55B blob data] Jan 12 20:47:49 volumio volumiobt[1576]: [bluetoothctl]> agent on Jan 12 20:47:49 volumio volumiobt[1576]: Agent is already registered Jan 12 20:47:49 volumio volumiobt[1576]: [bluetoothctl]> discoverable on Jan 12 20:47:49 volumio volumiobt[1576]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Jan 12 20:47:49 volumio volumiobt[1576]: [bluetoothctl]> pairable on Jan 12 20:47:49 volumio volumiobt[1576]: [bluetoothctl]> agent NoInputNoOutput Jan 12 20:47:49 volumio volumiobt[1576]: Agent is already registered Jan 12 20:47:49 volumio volumiobt[1576]: [bluetoothctl]> default-agent Jan 12 20:47:49 volumio volumiobt[1576]: [bluetoothctl]> quit Jan 12 20:47:49 volumio volumiobt[1576]: [65B blob data] Jan 12 20:47:49 volumio bluetoothd[889]: src/adapter.c:store_adapter_info() Unable set contents for /usr/var/lib/bluetooth/E4:5F:01:1B:92:62/settings: (Failed to create file “/usr/var/lib/bluetooth/E4:5F:01:1B:92:62/settings.U9H1I3”: No such file or directory) Jan 12 20:47:49 volumio volumio[1030]: info: MRS: Getting audio outputs on start Jan 12 20:47:49 volumio volumio[1030]: info: MRS: Requesting all other devices output Jan 12 20:47:50 volumio volumio[1391]: ..............................................++++ Jan 12 20:47:50 volumio volumio[1391]: e is 65537 (0x010001) Jan 12 20:47:50 volumio volumio[1391]: writing RSA key Jan 12 20:47:50 volumio volumio[1030]: info: TidalConnect service started! Jan 12 20:47:50 volumio volumio[1030]: [Metrics] CommandRouter: 28s 831.84ms Jan 12 20:47:50 volumio volumio[1030]: info: CoreCommandRouter::volumiosetStartupVolume Jan 12 20:47:50 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:50 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:50 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Jan 12 20:47:50 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Jan 12 20:47:50 volumio volumio[1030]: info: Executing endpoint metavolumio Jan 12 20:47:50 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 12 20:47:50 volumio volumio[1030]: info: Executing endpoint metavolumio Jan 12 20:47:50 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 12 20:47:51 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 12 20:47:51 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 12 20:47:51 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 12 20:47:52 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:52 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:53 volumio systemd[1]: systemd-timedated.service: Succeeded. Jan 12 20:47:53 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 20:47:53 volumio volumio[1030]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 38 Jan 12 20:47:53 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jan 12 20:47:53 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jan 12 20:47:54 volumio volumio[1030]: info: Executing endpoint metavolumio Jan 12 20:47:54 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 12 20:47:55 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 20:47:55 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 12 20:47:57 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 20:47:57 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 20:47:57 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 20:47:57 volumio volumio-remote-updater[771]: No test mode Jan 12 20:47:57 volumio volumio-remote-updater[771]: No alpha test mode Jan 12 20:47:57 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 12 20:47:57 volumio volumio[1030]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 12 20:47:57 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 12 20:47:57 volumio volumio[1030]: info: BOOT COMPLETED Jan 12 20:47:58 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:58 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 20:47:58 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 12 20:47:58 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 12 20:47:58 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 12 20:47:58 volumio volumio[1030]: info: CoreCommandRouter::volumioGetBrowseSources Jan 12 20:47:58 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 20:48:01 volumio volumio[1030]: info: Enabling plugin touch_display Jan 12 20:48:01 volumio volumio[1030]: info: Loading plugin "touch_display"... Jan 12 20:48:01 volumio volumio[1030]: info: Applying required configuration parameters for plugin touch_display Jan 12 20:48:01 volumio volumio[1030]: info: PLUGIN START: touch_display Jan 12 20:48:01 volumio volumio[1030]: info: Loading i18n strings for locale en Jan 12 20:48:01 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 20:48:01 volumio sudo[1673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 12 20:48:01 volumio sudo[1673]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:48:01 volumio sudo[1676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 12 20:48:01 volumio sudo[1676]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:48:01 volumio volumio[1030]: info: touch_display: Backlight interface detected. Jan 12 20:48:01 volumio sudo[1679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 12 20:48:01 volumio sudo[1679]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:48:01 volumio volumio[1030]: info: touch_display: Raspberry Pi Foundation touch screen detected. Jan 12 20:48:01 volumio sudo[1682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jan 12 20:48:01 volumio sudo[1682]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:48:01 volumio systemd[1]: Reloading. Jan 12 20:48:01 volumio sudo[1682]: pam_unix(sudo:session): session closed for user root Jan 12 20:48:01 volumio volumio[1030]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jan 12 20:48:01 volumio sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Jan 12 20:48:01 volumio sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:48:01 volumio sudo[1685]: pam_unix(sudo:session): session closed for user root Jan 12 20:48:01 volumio volumio[1030]: info: touch_display: File permissions for backlight brightness control set. Jan 12 20:48:01 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 12 20:48:01 volumio sudo[1676]: pam_unix(sudo:session): session closed for user root Jan 12 20:48:01 volumio volumio[1030]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jan 12 20:48:01 volumio systemd[1]: Reloading. Jan 12 20:48:01 volumio sudo[1673]: pam_unix(sudo:session): session closed for user root Jan 12 20:48:01 volumio volumio[1030]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 12 20:48:01 volumio volumio[1030]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 12 20:48:02 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 12 20:48:02 volumio sudo[1679]: pam_unix(sudo:session): session closed for user root Jan 12 20:48:02 volumio volumio[1030]: info: touch_display: systemctl daemon-reload succeeded. Jan 12 20:48:02 volumio sudo[1716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 12 20:48:02 volumio sudo[1716]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:48:02 volumio sudo[1716]: pam_unix(sudo:session): session closed for user root Jan 12 20:48:02 volumio volumio[1030]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Jan 12 20:48:02 volumio volumio[1030]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Jan 12 20:48:02 volumio volumio[1030]: info: Error: Error Jan 12 20:48:05 volumio volumio[1030]: info: Enabling plugin touch_display Jan 12 20:48:05 volumio volumio[1030]: info: Loading plugin "touch_display"... Jan 12 20:48:05 volumio volumio[1030]: info: Applying required configuration parameters for plugin touch_display Jan 12 20:48:05 volumio volumio[1030]: info: PLUGIN START: touch_display Jan 12 20:48:05 volumio volumio[1030]: info: Loading i18n strings for locale en Jan 12 20:48:05 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 20:48:05 volumio sudo[1733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 12 20:48:05 volumio sudo[1733]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:48:05 volumio volumio[1030]: info: touch_display: Backlight interface detected. Jan 12 20:48:05 volumio sudo[1735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 12 20:48:05 volumio sudo[1735]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:48:05 volumio sudo[1738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 12 20:48:05 volumio sudo[1738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:48:05 volumio volumio[1030]: info: touch_display: Raspberry Pi Foundation touch screen detected. Jan 12 20:48:05 volumio sudo[1742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jan 12 20:48:05 volumio sudo[1742]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:48:05 volumio sudo[1733]: pam_unix(sudo:session): session closed for user root Jan 12 20:48:05 volumio sudo[1742]: pam_unix(sudo:session): session closed for user root Jan 12 20:48:05 volumio volumio[1030]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jan 12 20:48:05 volumio systemd[1]: Reloading. Jan 12 20:48:05 volumio sudo[1745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Jan 12 20:48:05 volumio sudo[1745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:48:05 volumio volumio[1030]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 12 20:48:05 volumio sudo[1745]: pam_unix(sudo:session): session closed for user root Jan 12 20:48:05 volumio volumio[1030]: info: touch_display: File permissions for backlight brightness control set. Jan 12 20:48:06 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 12 20:48:06 volumio systemd[1]: Reloading. Jan 12 20:48:06 volumio sudo[1738]: pam_unix(sudo:session): session closed for user root Jan 12 20:48:06 volumio volumio[1030]: info: touch_display: systemctl daemon-reload succeeded. Jan 12 20:48:06 volumio sudo[1763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 12 20:48:06 volumio sudo[1763]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:48:06 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 12 20:48:06 volumio sudo[1735]: pam_unix(sudo:session): session closed for user root Jan 12 20:48:06 volumio volumio[1030]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jan 12 20:48:06 volumio volumio[1030]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 12 20:48:06 volumio sudo[1763]: pam_unix(sudo:session): session closed for user root Jan 12 20:48:06 volumio volumio[1030]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Jan 12 20:48:06 volumio volumio[1030]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Jan 12 20:48:06 volumio volumio[1030]: info: Error: Error Jan 12 20:48:14 volumio volumio[1030]: info: Starting Uninstall of plugin user_interface - touch_display Jan 12 20:48:14 volumio volumio[1030]: info: Uninstalling plugin touch_display Jan 12 20:48:14 volumio sudo[1838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jan 12 20:48:14 volumio sudo[1838]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:48:14 volumio sudo[1838]: pam_unix(sudo:session): session closed for user root Jan 12 20:48:14 volumio sudo[1841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Jan 12 20:48:14 volumio sudo[1841]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:48:14 volumio sudo[1844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Jan 12 20:48:14 volumio sudo[1844]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:48:14 volumio volumio[1030]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jan 12 20:48:14 volumio volumio[1030]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 20:48:14 volumio systemd[1]: Created slice system-getty.slice. Jan 12 20:48:14 volumio volumio[1030]: TypeError: Cannot read property 'split' of undefined Jan 12 20:48:14 volumio volumio[1030]: at /data/plugins/user_interface/touch_display/index.js:1258:37 Jan 12 20:48:14 volumio volumio[1030]: at ChildProcess.exithandler (child_process.js:299:7) Jan 12 20:48:14 volumio volumio[1030]: at ChildProcess.emit (events.js:315:20) Jan 12 20:48:14 volumio volumio[1030]: at maybeClose (internal/child_process.js:1048:16) Jan 12 20:48:14 volumio volumio[1030]: at Socket. (internal/child_process.js:439:11) Jan 12 20:48:14 volumio volumio[1030]: at Socket.emit (events.js:315:20) Jan 12 20:48:14 volumio volumio[1030]: at Pipe. (net.js:673:12) Jan 12 20:48:14 volumio volumio[1030]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 20:48:14 volumio sudo[1847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Jan 12 20:48:14 volumio sudo[1847]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 20:48:14 volumio systemd[1]: Started Getty on tty1. Jan 12 20:48:14 volumio sudo[1841]: pam_unix(sudo:session): session closed for user root Jan 12 20:48:14 volumio systemd[1]: Reloading. Jan 12 20:48:14 volumio sudo[1847]: pam_unix(sudo:session): session closed for user root Jan 12 20:48:14 volumio sudo[1872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-12 20:47 Jan 12 20:48:14 volumio sudo[1872]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"