-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Tue 2026-02-17 20:55:17 UTC. -- Feb 17 20:54:00 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:00 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 20:54:00 volumio volumio[918]: info: Reloading queue from file Feb 17 20:54:00 volumio volumio[918]: error: updateQueue error: null Feb 17 20:54:00 volumio volumio[918]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 17 20:54:00 volumio volumio[918]: info: VolumeController:: Volume=undefined Mute =false Feb 17 20:54:00 volumio volumio[918]: info: CoreStateMachine::pushState Feb 17 20:54:00 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 17 20:54:00 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 20:54:00 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 17 20:54:00 volumio volumio[918]: info: VolumeController:: Volume=undefined Mute =false Feb 17 20:54:00 volumio volumio[918]: info: CoreStateMachine::pushState Feb 17 20:54:00 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 17 20:54:00 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 17 20:54:00 volumio volumio[918]: info: CoreStateMachine::updateTrackBlock Feb 17 20:54:00 volumio volumio[918]: info: CorePlayQueue::getTrackBlock Feb 17 20:54:00 volumio volumio[918]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 20:54:00 volumio volumio[918]: info: CoreStateMachine::setRepeat false single undefined Feb 17 20:54:00 volumio volumio[918]: info: CoreStateMachine::pushState Feb 17 20:54:00 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 17 20:54:00 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 20:54:00 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 17 20:54:00 volumio volumio[918]: info: CoreStateMachine::setRandom null Feb 17 20:54:00 volumio volumio[918]: info: CoreStateMachine::pushState Feb 17 20:54:00 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 17 20:54:00 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 17 20:54:00 volumio volumio[918]: info: Setting Device type: Raspberry PI Feb 17 20:54:00 volumio volumio[918]: error: updateQueue error: null Feb 17 20:54:00 volumio volumio[918]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 17 20:54:00 volumio volumio[918]: Starting albumart workers Feb 17 20:54:00 volumio volumio[918]: info: Completed loading Core Plugins Feb 17 20:54:00 volumio volumio[918]: info: Preparing to generate the ALSA configuration file Feb 17 20:54:01 volumio volumio[918]: Starting albumart workers Feb 17 20:54:01 volumio volumio[918]: Starting albumart workers Feb 17 20:54:01 volumio wpa_supplicant[758]: wlan0: Failed to initiate sched scan Feb 17 20:54:01 volumio volumio[918]: info: VolumeController:: Volume=undefined Mute =false Feb 17 20:54:01 volumio volumio[918]: info: CoreStateMachine::pushState Feb 17 20:54:01 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 17 20:54:01 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 17 20:54:01 volumio volumio[918]: info: Discovery: adding a4b9f159-8a86-4858-9202-4eba1f24858d Feb 17 20:54:01 volumio volumio[918]: info: mDNS: Found device Volumio Feb 17 20:54:01 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 17 20:54:01 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 17 20:54:01 volumio volumio-remote-updater[454]: [2026-02-17 20:54:01] [connect] Successful connection Feb 17 20:54:02 volumio volumio[918]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/system_controller/system/config.json' Feb 17 20:54:02 volumio volumio[918]: verbose: New Socket.io Connection to 192.168.0.97 from 192.168.0.98 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Total Clients: 4 Feb 17 20:54:02 volumio volumio-remote-updater[454]: [2026-02-17 20:54:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1771361641 101 Feb 17 20:54:02 volumio volumio[918]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 5 Feb 17 20:54:02 volumio volumio[918]: info: Asound.conf file unchanged, so no further update is needed Feb 17 20:54:02 volumio volumio[918]: info: Output device has changed, restarting MPD Feb 17 20:54:02 volumio volumio[918]: info: Output device has changed, restarting Shairport Sync Feb 17 20:54:02 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 20:54:02 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:02 volumio sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 20:54:02 volumio sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:02 volumio sudo[1046]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:02 volumio sudo[1048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 20:54:02 volumio sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:02 volumio systemd[1]: Stopping Music Player Daemon... Feb 17 20:54:02 volumio systemd[1]: mpd.service: Succeeded. Feb 17 20:54:02 volumio systemd[1]: Stopped Music Player Daemon. Feb 17 20:54:02 volumio volumio[918]: Home directory not accessible: Permission denied Feb 17 20:54:02 volumio volumio[918]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Feb 17 20:54:02 volumio volumio[918]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Feb 17 20:54:02 volumio volumio[918]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 20:54:02 volumio volumio[918]: Home directory not accessible: Permission denied Feb 17 20:54:02 volumio volumio[918]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Feb 17 20:54:02 volumio volumio[918]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Feb 17 20:54:02 volumio systemd[1]: Starting Music Player Daemon... Feb 17 20:54:02 volumio volumio[918]: info: ___________ START PLUGINS ___________ Feb 17 20:54:02 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 20:54:02 volumio volumio[918]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 20:54:02 volumio volumio[918]: info: [1771361642545] CoreMusicLibrary::Adding element Media Servers Feb 17 20:54:02 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 20:54:02 volumio volumio[918]: Cannot find translation for sourceMedia Servers Feb 17 20:54:02 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 20:54:02 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:02 volumio volumio[918]: Home directory not accessible: Permission denied Feb 17 20:54:02 volumio volumio[918]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Feb 17 20:54:02 volumio volumio[918]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Feb 17 20:54:02 volumio volumio[918]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 20:54:02 volumio volumio[918]: Home directory not accessible: Permission denied Feb 17 20:54:02 volumio volumio[918]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Feb 17 20:54:02 volumio volumio[918]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Feb 17 20:54:02 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 20:54:02 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:02 volumio volumio[918]: Home directory not accessible: Permission denied Feb 17 20:54:02 volumio volumio[918]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Feb 17 20:54:02 volumio volumio[918]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Feb 17 20:54:02 volumio volumio[918]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 20:54:02 volumio volumio[918]: Home directory not accessible: Permission denied Feb 17 20:54:02 volumio volumio[918]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Feb 17 20:54:02 volumio volumio[918]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Feb 17 20:54:02 volumio volumio[918]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 20:54:02 volumio volumio[918]: info: [1771361642937] CoreMusicLibrary::Adding element Last_100 Feb 17 20:54:02 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 20:54:02 volumio volumio[918]: Cannot find translation for sourceMedia Servers Feb 17 20:54:02 volumio volumio[918]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 20:54:02 volumio volumio[918]: info: [1771361642945] CoreMusicLibrary::Adding element Webradio Feb 17 20:54:02 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 20:54:02 volumio volumio[918]: Cannot find translation for sourceMedia Servers Feb 17 20:54:02 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 20:54:02 volumio volumio[918]: info: Volumio Calling Home Feb 17 20:54:03 volumio volumio[918]: info: Completed starting Core Plugins Feb 17 20:54:03 volumio volumio[918]: info: ------------------------------------------- Feb 17 20:54:03 volumio volumio[918]: info: ----- MyVolumio plugins startup ---- Feb 17 20:54:03 volumio volumio[918]: info: ------------------------------------------- Feb 17 20:54:03 volumio volumio[918]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 17 20:54:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 17 20:54:03 volumio volumio[918]: info: Listing playlists Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 17 20:54:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 17 20:54:03 volumio volumio[918]: info: Listing playlists Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 17 20:54:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 17 20:54:03 volumio volumio[918]: info: Listing playlists Feb 17 20:54:03 volumio volumio[918]: info: MPD Permissions set Feb 17 20:54:03 volumio volumio[918]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/plugins.json' Feb 17 20:54:03 volumio volumio[918]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Feb 17 20:54:03 volumio volumio[918]: info: Volumio called home Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 20:54:04 volumio sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 20:54:04 volumio sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:04 volumio sudo[1090]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:04 volumio sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 20:54:04 volumio sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:04 volumio sudo[1092]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:04 volumio sudo[1096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=john,password=Caistor69,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //RASPBERRYPI/public /mnt/NAS/public Feb 17 20:54:04 volumio sudo[1096]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:04 volumio sudo[1096]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:04 volumio volumio[918]: info: Starting Shairport Sync Feb 17 20:54:04 volumio volumio[918]: info: Starting Shairport Sync Feb 17 20:54:04 volumio volumio[918]: info: Starting Shairport Sync Feb 17 20:54:04 volumio sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 20:54:04 volumio sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:04 volumio sudo[1103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 20:54:04 volumio sudo[1103]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:04 volumio volumio[918]: info: Cannot mount NAS public at system boot, trial number 2 ,retrying in 5 seconds Feb 17 20:54:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 17 20:54:04 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 17 20:54:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 17 20:54:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 17 20:54:04 volumio sudo[1101]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:04 volumio sudo[1106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 20:54:04 volumio sudo[1106]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 17 20:54:04 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 17 20:54:04 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 17 20:54:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 17 20:54:04 volumio volumio[918]: info: Shairport-Sync Started Feb 17 20:54:04 volumio volumio[918]: Error adding Membership: Error: addMembership EINVAL Feb 17 20:54:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 17 20:54:04 volumio sudo[1103]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 17 20:54:04 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 17 20:54:04 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 17 20:54:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 17 20:54:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 17 20:54:04 volumio sudo[1106]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:04 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 17 20:54:04 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 17 20:54:04 volumio sudo[1112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 17 20:54:04 volumio sudo[1112]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:05 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 17 20:54:05 volumio sudo[1112]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:05 volumio volumio[918]: info: Shairport-Sync Started Feb 17 20:54:05 volumio volumio[918]: info: Shairport-Sync Started Feb 17 20:54:05 volumio volumio[918]: info: Upmpdcli Daemon Started Feb 17 20:54:05 volumio mpd[1082]: Feb 17 20:54 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 20:54:05 volumio mpd[1082]: Feb 17 20:54 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 17 20:54:05 volumio mpd[1082]: Feb 17 20:54 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 17 20:54:05 volumio systemd[1]: Started Music Player Daemon. Feb 17 20:54:05 volumio sudo[1048]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:05 volumio volumio[918]: error: updateQueue error: null Feb 17 20:54:05 volumio volumio[1117]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 17 20:54:06 volumio wpa_supplicant[758]: wlan0: Failed to initiate sched scan Feb 17 20:54:09 volumio sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=john,password=Caistor69,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //RASPBERRYPI/public /mnt/NAS/public Feb 17 20:54:09 volumio sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:09 volumio sudo[1137]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:09 volumio volumio[918]: info: Cannot mount NAS public at system boot, trial number 3 ,retrying in 5 seconds Feb 17 20:54:10 volumio volumio[918]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 20:54:10 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 17 20:54:10 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Feb 17 20:54:10 volumio sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Feb 17 20:54:10 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:10 volumio sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 17 20:54:10 volumio sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:10 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:10 volumio sudo[1149]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:10 volumio sudo[1158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 17 20:54:10 volumio sudo[1158]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:10 volumio sudo[1158]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:10 volumio sudo[1163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 17 20:54:10 volumio sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:10 volumio sudo[1163]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:10 volumio sudo[1169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 20:54:10 volumio sudo[1169]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:10 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 17 20:54:10 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 17 20:54:10 volumio sudo[1169]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:11 volumio sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 20:54:11 volumio sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:11 volumio sudo[1172]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:11 volumio sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 17 20:54:11 volumio sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 17 20:54:11 volumio volumio[918]: info: Adding plugin bluetooth to MyMusic Plugins Feb 17 20:54:11 volumio volumio[918]: info: Adding plugin cd_controller to MyMusic Plugins Feb 17 20:54:11 volumio volumio[918]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 17 20:54:11 volumio volumio[918]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 17 20:54:11 volumio volumio[918]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 17 20:54:11 volumio sudo[1176]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:12 volumio wpa_supplicant[758]: wlan0: Failed to initiate sched scan Feb 17 20:54:14 volumio volumio[918]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 17 20:54:14 volumio volumio[918]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 17 20:54:14 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:14 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:14 volumio volumio[918]: info: Starting MyVolumio Remote Streaming Endpoints Feb 17 20:54:14 volumio volumio[918]: info: MyVolumio login type: Token Feb 17 20:54:14 volumio volumio[918]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 17 20:54:14 volumio volumio[918]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 17 20:54:14 volumio volumio[1117]: ...............................++++ Feb 17 20:54:14 volumio volumio[918]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 17 20:54:14 volumio volumio[918]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 17 20:54:14 volumio volumio[918]: info: Streaming services startup Feb 17 20:54:14 volumio volumio[918]: info: Starting Streaming Daemon Feb 17 20:54:14 volumio sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 20:54:14 volumio sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:14 volumio systemd[1]: Stopping Volumio Streaming Daemon... Feb 17 20:54:14 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Feb 17 20:54:14 volumio systemd[1]: volumio-streaming-daemon.service: Succeeded. Feb 17 20:54:14 volumio systemd[1]: Stopped Volumio Streaming Daemon. Feb 17 20:54:14 volumio systemd[1]: Started Volumio Streaming Daemon. Feb 17 20:54:14 volumio sudo[1179]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:14 volumio volumio-streaming-daemon[1185]: ############################ Feb 17 20:54:14 volumio volumio-streaming-daemon[1185]: # Volumio Streaming Daemon # Feb 17 20:54:14 volumio volumio-streaming-daemon[1185]: # Running on port 7777 # Feb 17 20:54:14 volumio volumio-streaming-daemon[1185]: ############################ Feb 17 20:54:14 volumio volumio-streaming-daemon[1185]: Environment: production Feb 17 20:54:15 volumio sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=john,password=Caistor69,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //RASPBERRYPI/public /mnt/NAS/public Feb 17 20:54:15 volumio sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:15 volumio volumio[918]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/plugins.json' Feb 17 20:54:15 volumio sudo[1192]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:15 volumio volumio[918]: info: Cannot mount NAS public at system boot, trial number 4 ,retrying in 5 seconds Feb 17 20:54:15 volumio volumio[918]: error: MyVolumio Custom Token format not valid, refreshing it Feb 17 20:54:15 volumio volumio[918]: info: MyVolumio login type: Token Feb 17 20:54:16 volumio volumio[918]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 17 20:54:16 volumio volumio[918]: info: MYVOLUMIO: Adding device Feb 17 20:54:16 volumio volumio[918]: info: MYVOLUMIO: Evaluating Server Feb 17 20:54:17 volumio wpa_supplicant[758]: wlan0: Failed to initiate sched scan Feb 17 20:54:17 volumio volumio[918]: info: [MyVolumio PluginManager] Subscribed plan changed to virtuoso Feb 17 20:54:17 volumio volumio[918]: info: Removing browser output: myVolumio user plan is not superstar Feb 17 20:54:17 volumio volumio[918]: info: Removing audio output: Feb 17 20:54:17 volumio volumio[918]: info: MYVOLUMIO: Adding device Feb 17 20:54:17 volumio volumio[918]: info: MYVOLUMIO: Evaluating Server Feb 17 20:54:17 volumio volumio[918]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/system_controller/my_volumio/config.json' Feb 17 20:54:17 volumio volumio[918]: info: Remote config written successfully Feb 17 20:54:17 volumio volumio[918]: info: Starting Tunnel 1 Feb 17 20:54:17 volumio volumio[918]: info: Starting Tunnel Connection Checker Feb 17 20:54:17 volumio volumio[918]: info: Completed starting MyVolumio Plugin Feb 17 20:54:18 volumio volumio[918]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 17 20:54:18 volumio volumio[918]: info: MyVolumio status changed Feb 17 20:54:18 volumio volumio[918]: info: Streaming services startup Feb 17 20:54:18 volumio volumio[918]: info: Re-Starting Streaming Daemon Feb 17 20:54:18 volumio volumio[918]: info: Setting Geolocation for MyVolumio to eu3 Feb 17 20:54:18 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:18 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:18 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:18 volumio volumio-streaming-daemon[1185]: Restarting daemon Feb 17 20:54:18 volumio volumio-streaming-daemon[1185]: Environment: production Feb 17 20:54:18 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 17 20:54:18 volumio sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 17 20:54:18 volumio sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:18 volumio volumio[918]: error: [MyVolumio PluginManager] Cache data is invalid! Feb 17 20:54:18 volumio volumio[918]: error: [MyVolumio PluginManager] Error trying to remove cache file! Feb 17 20:54:18 volumio systemd[1]: Started MyVolumio SSH Tunnel. Feb 17 20:54:18 volumio volumio[918]: info: Setting Geolocation for MyVolumio to eu3 Feb 17 20:54:18 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:18 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:18 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:18 volumio sudo[1239]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:18 volumio autossh[1243]: port set to 0, monitoring disabled Feb 17 20:54:18 volumio autossh[1243]: starting ssh (count 1) Feb 17 20:54:18 volumio autossh[1243]: ssh child pid is 1248 Feb 17 20:54:18 volumio volumio-streaming-daemon[1185]: Enabled services: Feb 17 20:54:18 volumio volumio-streaming-daemon[1185]: 1) tidal Feb 17 20:54:18 volumio volumio-streaming-daemon[1185]: 2) qobuz Feb 17 20:54:18 volumio volumiossh-tunnel[1242]: load pubkey "/home/volumio/.ssh/id_rsa": Permission denied Feb 17 20:54:18 volumio volumiossh-tunnel[1242]: load pubkey "/home/volumio/.ssh/id_rsa": Permission denied Feb 17 20:54:18 volumio volumiossh-tunnel[1242]: load pubkey "/home/volumio/.ssh/id_dsa": Permission denied Feb 17 20:54:18 volumio volumiossh-tunnel[1242]: load pubkey "/home/volumio/.ssh/id_dsa": Permission denied Feb 17 20:54:18 volumio volumiossh-tunnel[1242]: load pubkey "/home/volumio/.ssh/id_ecdsa": Permission denied Feb 17 20:54:18 volumio volumiossh-tunnel[1242]: load pubkey "/home/volumio/.ssh/id_ecdsa": Permission denied Feb 17 20:54:18 volumio volumiossh-tunnel[1242]: load pubkey "/home/volumio/.ssh/id_ed25519": Permission denied Feb 17 20:54:18 volumio volumiossh-tunnel[1242]: load pubkey "/home/volumio/.ssh/id_ed25519": Permission denied Feb 17 20:54:18 volumio volumiossh-tunnel[1242]: load pubkey "/home/volumio/.ssh/id_xmss": Permission denied Feb 17 20:54:18 volumio volumiossh-tunnel[1242]: load pubkey "/home/volumio/.ssh/id_xmss": Permission denied Feb 17 20:54:18 volumio volumio[1117]: .............++++ Feb 17 20:54:18 volumio volumio[1117]: e is 65537 (0x010001) Feb 17 20:54:18 volumio ntpd[629]: Soliciting pool server 131.111.8.63 Feb 17 20:54:18 volumio volumio[1117]: writing RSA key Feb 17 20:54:18 volumio volumiossh-tunnel[1242]: Warning: Permanently added '[eu1.myvolumio.org]:2222,[68.183.69.189]:2222' (RSA) to the list of known hosts. Feb 17 20:54:19 volumio volumio[918]: info: Remote SSH Started Feb 17 20:54:19 volumio volumio[918]: info: Updating MyVolumio device info Feb 17 20:54:19 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:19 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:19 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:19 volumio volumio[918]: info: Updating MyVolumio device info Feb 17 20:54:19 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:19 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:19 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:19 volumio volumio[918]: error: Failed initialization of streaming services: Error: An error occurred while configuring services daemon. Feb 17 20:54:19 volumio volumio[918]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Feb 17 20:54:19 volumio ntpd[629]: Soliciting pool server 185.83.169.27 Feb 17 20:54:19 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 17 20:54:19 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 17 20:54:20 volumio sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=john,password=Caistor69,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //RASPBERRYPI/public /mnt/NAS/public Feb 17 20:54:20 volumio sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:20 volumio sudo[1265]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:20 volumio volumio[918]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 17 20:54:20 volumio volumio[918]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! Feb 17 20:54:20 volumio volumio[918]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 17 20:54:20 volumio volumio[918]: info: [1771361660711] Starting BluetoothController Feb 17 20:54:20 volumio ntpd[629]: Soliciting pool server 63.135.73.82 Feb 17 20:54:20 volumio ntpd[629]: Soliciting pool server 185.103.119.60 Feb 17 20:54:20 volumio volumio[918]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 17 20:54:21 volumio volumio[918]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 17 20:54:21 volumio volumio[918]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 17 20:54:21 volumio volumio[918]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 17 20:54:21 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 17 20:54:21 volumio volumio[918]: error: [MyVolumio PluginManager] Could not save cache file: EACCES: permission denied, open '/data/myvolumio/cache.data' Feb 17 20:54:21 volumio volumio-streaming-daemon[1185]: Environment: production Feb 17 20:54:21 volumio volumio-streaming-daemon[1185]: Environment: production Feb 17 20:54:21 volumio volumio[918]: Current plan: virtuoso. Enabling manifest UI Feb 17 20:54:21 volumio volumio[918]: /usr/bin/touch: cannot touch '/data/manifestUI': Permission denied Feb 17 20:54:21 volumio volumio[918]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Feb 17 20:54:21 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 20:54:21 volumio volumio[918]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Feb 17 20:54:21 volumio volumio[918]: info: Preparing CD Folders Feb 17 20:54:21 volumio volumio[918]: info: Adding CD REST API Endpoints Feb 17 20:54:21 volumio volumio[918]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Feb 17 20:54:21 volumio volumio[918]: info: Starting UDEV Watcher for CD Feb 17 20:54:21 volumio volumio[918]: info: Detecting CD presence with UDEV Feb 17 20:54:21 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Feb 17 20:54:21 volumio sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Feb 17 20:54:21 volumio sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:21 volumio sudo[1273]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:21 volumio ntpd[629]: Soliciting pool server 178.215.228.24 Feb 17 20:54:21 volumio ntpd[629]: Soliciting pool server 13.42.123.235 Feb 17 20:54:22 volumio volumio[918]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Feb 17 20:54:22 volumio volumio[918]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Feb 17 20:54:22 volumio volumio[918]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Feb 17 20:54:22 volumio volumio[918]: info: Stopping AccessToken refresher cron Feb 17 20:54:22 volumio sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 17 20:54:22 volumio sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:22 volumio volumio[918]: info: AccessToken refresher cron started Feb 17 20:54:22 volumio volumio[918]: info: Stopping AccessToken refresher cron for QOBUZ Feb 17 20:54:22 volumio sudo[1276]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:22 volumio volumio[918]: info: AccessToken refresher cron started for QOBUZ Feb 17 20:54:22 volumio volumio[918]: info: Bluetooth name changed to Volumio Feb 17 20:54:22 volumio volumio[918]: error: Failed initialization of streaming services: Error: An error occurred while configuring services daemon. Feb 17 20:54:22 volumio volumio[918]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/plugins.json' Feb 17 20:54:22 volumio ntpd[629]: Soliciting pool server 82.16.107.176 Feb 17 20:54:22 volumio ntpd[629]: Soliciting pool server 162.159.200.123 Feb 17 20:54:22 volumio ntpd[629]: Soliciting pool server 176.58.115.34 Feb 17 20:54:23 volumio wpa_supplicant[758]: wlan0: Failed to initiate sched scan Feb 17 20:54:23 volumio ntpd[629]: Soliciting pool server 95.215.175.2 Feb 17 20:54:23 volumio ntpd[629]: Soliciting pool server 91.109.118.94 Feb 17 20:54:23 volumio ntpd[629]: Soliciting pool server 185.137.221.158 Feb 17 20:54:24 volumio sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 17 20:54:24 volumio sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:24 volumio systemd[1]: Started Volumio Bluetooth Module. Feb 17 20:54:24 volumio sudo[1280]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:24 volumio sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Feb 17 20:54:24 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:24 volumio volumiobt[1282]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Feb 17 20:54:24 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:24 volumio volumio[918]: info: Volumio BT Module successfully started Feb 17 20:54:24 volumio ntpd[629]: Soliciting pool server 85.121.48.64 Feb 17 20:54:24 volumio ntpd[629]: Soliciting pool server 88.80.187.85 Feb 17 20:54:24 volumio ntpd[629]: Soliciting pool server 132.226.210.133 Feb 17 20:54:25 volumio ntpd[629]: Soliciting pool server 195.171.43.10 Feb 17 20:54:25 volumio volumio[918]: info: TidalConnect service stoped! Feb 17 20:54:25 volumio volumio[918]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 17 20:54:25 volumio volumio[918]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 17 20:54:25 volumio sudo[1291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 17 20:54:25 volumio sudo[1291]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:25 volumio systemd[1]: Started Volumio Tidal Connect Service. Feb 17 20:54:25 volumio sudo[1291]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:25 volumio sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Feb 17 20:54:25 volumio sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:54:25 volumio volumiobt[1282]: pulseaudio: no process found Feb 17 20:54:25 volumio ntpd[629]: Soliciting pool server 139.162.255.65 Feb 17 20:54:25 volumio ntpd[629]: Soliciting pool server 87.106.36.214 Feb 17 20:54:25 volumio ntpd[629]: Soliciting pool server 149.22.188.7 Feb 17 20:54:25 volumio sudo[1296]: pam_unix(sudo:session): session closed for user root Feb 17 20:54:25 volumio volumiobt[1282]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Feb 17 20:54:25 volumio volumiobt[1282]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Feb 17 20:54:25 volumio volumiobt[1282]: Applying permissions Feb 17 20:54:25 volumio volumiobt[1282]: Setting BT discoverable and pairable Feb 17 20:54:26 volumio volumiobt[1282]: Agent registered Feb 17 20:54:26 volumio volumiobt[1282]: [bluetooth]# -e power on Feb 17 20:54:26 volumio volumiobt[1282]: Invalid command in menu main: -e Feb 17 20:54:26 volumio volumiobt[1282]: Feb 17 20:54:26 volumio volumiobt[1282]: Use "help" for a list of available commands in a menu. Feb 17 20:54:26 volumio volumiobt[1282]: Use "menu " if you want to enter any submenu. Feb 17 20:54:26 volumio volumiobt[1282]: Use "back" if you want to return to menu main. Feb 17 20:54:26 volumio volumiobt[1282]: [bluetooth]# agent on Feb 17 20:54:26 volumio volumiobt[1282]: Agent is already registered Feb 17 20:54:26 volumio volumiobt[1282]: [bluetooth]# discoverable on Feb 17 20:54:26 volumio volumiobt[1282]: [bluetooth]# pairable on Feb 17 20:54:26 volumio volumiobt[1282]: [bluetooth]# agent NoInputNoOutput Feb 17 20:54:26 volumio volumiobt[1282]: Agent is already registered Feb 17 20:54:26 volumio volumiobt[1282]: [bluetooth]# default-agent Feb 17 20:54:26 volumio volumiobt[1282]: [bluetooth]# quit Feb 17 20:54:26 volumio volumiobt[1282]: [59B blob data] Feb 17 20:54:26 volumio volumio[918]: info: Executing endpoint tc_getconfig Feb 17 20:54:26 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 17 20:54:26 volumio vtcs[1293]: STARTING TidalConnect services, version: 1.1.7.5 Feb 17 20:54:26 volumio vtcs[1293]: STARTED TidalConnect services. Feb 17 20:54:26 volumio volumio[918]: info: Executing endpoint tc_connect Feb 17 20:54:26 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 17 20:54:26 volumio volumio[918]: Connecting to TidalConnect. Feb 17 20:54:26 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 17 20:54:26 volumio volumio[918]: info: CoreStateMachine::pushState Feb 17 20:54:26 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 17 20:54:26 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 17 20:54:26 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 17 20:54:26 volumio volumio[918]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Feb 17 20:54:26 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 17 20:54:26 volumio volumio[918]: info: CoreStateMachine::pushState Feb 17 20:54:26 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 17 20:54:26 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 17 20:54:26 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 17 20:54:26 volumio volumio[918]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Feb 17 20:54:49 volumio ntpd[629]: receive: Unexpected origin timestamp 0xed3f5403.ea61c07f does not match aorg 0000000000.00000000 from server@91.109.118.94 xmt 0xed3f5419.0f5ccb2b Feb 17 20:54:49 volumio ntpd[629]: receive: Unexpected origin timestamp 0xed3f5403.ea384147 does not match aorg 0000000000.00000000 from server@87.106.36.214 xmt 0xed3f5419.0ff33b28 Feb 17 20:54:49 volumio ntpd[629]: receive: Unexpected origin timestamp 0xed3f5403.ea81108b does not match aorg 0000000000.00000000 from server@185.103.119.60 xmt 0xed3f5419.0ff7cfcc Feb 17 20:54:49 volumio ntpd[629]: receive: Unexpected origin timestamp 0xed3f5403.ea4f711a does not match aorg 0000000000.00000000 from server@195.171.43.10 xmt 0xed3f5419.101993f9 Feb 17 20:54:49 volumio ntpd[629]: receive: Unexpected origin timestamp 0xed3f5403.ea87a078 does not match aorg 0000000000.00000000 from server@63.135.73.82 xmt 0xed3f5419.103029b2 Feb 17 20:54:49 volumio ntpd[629]: receive: Unexpected origin timestamp 0xed3f5403.ea8b8b8b does not match aorg 0000000000.00000000 from server@185.83.169.27 xmt 0xed3f5419.102455fd Feb 17 20:54:49 volumio ntpd[629]: receive: Unexpected origin timestamp 0xed3f5403.ea563f71 does not match aorg 0000000000.00000000 from server@132.226.210.133 xmt 0xed3f5419.12333a25 Feb 17 20:54:49 volumio ntpd[629]: receive: Unexpected origin timestamp 0xed3f5403.ea7913e2 does not match aorg 0000000000.00000000 from server@82.16.107.176 xmt 0xed3f5419.11d82802 Feb 17 20:54:49 volumio ntpd[629]: receive: Unexpected origin timestamp 0xed3f5403.ea5da9b2 does not match aorg 0000000000.00000000 from server@85.121.48.64 xmt 0xed3f5419.18443802 Feb 17 20:54:49 volumio volumio[918]: info: TidalConnect service started! Feb 17 20:54:49 volumio volumio[918]: info: BOOT COMPLETED Feb 17 20:54:49 volumio volumio[918]: [Metrics] CommandRouter: 40s 654.82ms Feb 17 20:54:49 volumio volumio[918]: info: CoreCommandRouter::volumiosetStartupVolume Feb 17 20:54:49 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 20:54:49 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:49 volumio volumio[918]: info: CoreCommandRouter::Close All Modals sent Feb 17 20:54:49 volumio volumio[918]: info: CoreCommandRouter::Close All Modals sent Feb 17 20:54:49 volumio volumio[918]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Feb 17 20:54:49 volumio volumio[918]: xcb_connection_has_error() returned true Feb 17 20:54:49 volumio volumio[918]: Home directory not accessible: Permission denied Feb 17 20:54:49 volumio volumio[918]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Feb 17 20:54:49 volumio volumio[918]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Feb 17 20:54:49 volumio volumio[918]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Feb 17 20:54:49 volumio volumio[918]: aplay: main:828: audio open error: No such device Feb 17 20:54:49 volumio wpa_supplicant[758]: wlan0: Failed to initiate sched scan Feb 17 20:54:50 volumio volumio[918]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/plugins.json' Feb 17 20:54:50 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 17 20:54:50 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 17 20:54:50 volumio volumio[918]: xcb_connection_has_error() returned true Feb 17 20:54:50 volumio volumio[918]: Home directory not accessible: Permission denied Feb 17 20:54:50 volumio volumio[918]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Feb 17 20:54:50 volumio volumio[918]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Feb 17 20:54:50 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Feb 17 20:54:50 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 17 20:54:54 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 20:54:54 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 20:54:55 volumio wpa_supplicant[758]: wlan0: Failed to initiate sched scan Feb 17 20:55:00 volumio wpa_supplicant[758]: wlan0: Failed to initiate sched scan Feb 17 20:55:06 volumio wpa_supplicant[758]: wlan0: Failed to initiate sched scan Feb 17 20:55:12 volumio wpa_supplicant[758]: wlan0: Failed to initiate sched scan Feb 17 20:55:16 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Feb 17 20:55:16 volumio volumio[918]: info: Saving new wireless network Feb 17 20:55:16 volumio volumio[918]: error: Cannot write wpasupplicant.conf Error: EACCES: permission denied, open '/etc/wpa_supplicant/wpa_supplicant.conf' Feb 17 20:55:16 volumio volumio[918]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 20:55:16 volumio volumio[918]: ReferenceError: error is not defined Feb 17 20:55:16 volumio volumio[918]: at /volumio/app/plugins/system_controller/network/index.js:513:57 Feb 17 20:55:16 volumio volumio[918]: at /volumio/node_modules/graceful-fs/graceful-fs.js:144:16 Feb 17 20:55:16 volumio volumio[918]: at fs.js:1448:7 Feb 17 20:55:16 volumio volumio[918]: at FSReqCallback.oncomplete (fs.js:171:23) Feb 17 20:55:16 volumio volumio[918]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 20:55:16 volumio sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Feb 17 20:55:16 volumio sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:55:16 volumio systemd[1]: wireless.service: Killing process 758 (wpa_supplicant) with signal SIGKILL. Feb 17 20:55:16 volumio systemd[1]: wireless.service: Killing process 863 (dhcpcd) with signal SIGKILL. Feb 17 20:55:16 volumio systemd[1]: Stopping Wireless Services... Feb 17 20:55:17 volumio systemd[1]: wireless.service: Succeeded. Feb 17 20:55:17 volumio systemd[1]: Stopped Wireless Services. Feb 17 20:55:17 volumio systemd[1]: Starting Wireless Services... Feb 17 20:55:17 volumio sudo[1344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-17 20:54 Feb 17 20:55:17 volumio sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 20:55:18 volumio wireless.js[1336]: WIRELESS DAEMON: start Feb 17 20:55:18 volumio wireless.js[1336]: WIRELESS: Loaded configuration Feb 17 20:55:18 volumio wireless.js[1336]: Cleaning previous... 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="99416080645921ccfba617dc5ebd336cc3241c15" VOLUMIO_FE_VERSION="351c6a37e4b18dd5ad34c18d606320e426c0416f" VOLUMIO_FE3_VERSION="df802b99fdf1ec44e28084207a9ee32f3c490054" VOLUMIO_BE_VERSION="3d1db04b55c2f380193e6432bdc28ec28d2a7044" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumiobuster" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 23 Nov 2021 03:34:57 PM CET" VOLUMIO_VERSION="3.152" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6da9d336e5856bf700957d484b79c06e"