-- Logs begin at Mon 2025-02-24 16:41:46 EST, end at Mon 2025-02-24 16:43:09 EST. -- Feb 24 16:42:00 coscia-volumio volumio-remote-updater[791]: [2025-02-24 16:42:00] [info] asio async_connect error: system:111 (Connection refused) Feb 24 16:42:00 coscia-volumio volumio-remote-updater[791]: [2025-02-24 16:42:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 24 16:42:00 coscia-volumio volumio-remote-updater[791]: [2025-02-24 16:42:00] [error] handle_connect error: Underlying Transport Error Feb 24 16:42:00 coscia-volumio wireless.js[805]: trying... Feb 24 16:42:00 coscia-volumio sudo[1217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 16:42:00 coscia-volumio sudo[1217]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:00 coscia-volumio sudo[1217]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:00 coscia-volumio ntpd[979]: Listen normally on 3 eth0 192.168.4.183:123 Feb 24 16:42:00 coscia-volumio ntpd[979]: new interface(s) found: waking up resolver Feb 24 16:42:01 coscia-volumio wireless.js[805]: trying... Feb 24 16:42:01 coscia-volumio sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 16:42:01 coscia-volumio sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:01 coscia-volumio sudo[1220]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:02 coscia-volumio wireless.js[805]: trying... Feb 24 16:42:02 coscia-volumio sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 16:42:02 coscia-volumio sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:02 coscia-volumio sudo[1223]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:03 coscia-volumio wireless.js[805]: trying... Feb 24 16:42:03 coscia-volumio sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 16:42:03 coscia-volumio sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:03 coscia-volumio sudo[1226]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:04 coscia-volumio wireless.js[805]: trying... Feb 24 16:42:04 coscia-volumio sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 16:42:04 coscia-volumio sudo[1229]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:04 coscia-volumio sudo[1229]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:04 coscia-volumio dhcpcd[1173]: wlan0: leased 192.168.4.182 for 12289 seconds Feb 24 16:42:04 coscia-volumio avahi-daemon[813]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.4.182. Feb 24 16:42:04 coscia-volumio dhcpcd[1173]: wlan0: adding route to 192.168.4.0/22 Feb 24 16:42:04 coscia-volumio dhcpcd[1173]: wlan0: adding default route via 192.168.4.1 Feb 24 16:42:04 coscia-volumio avahi-daemon[813]: New relevant interface wlan0.IPv4 for mDNS. Feb 24 16:42:04 coscia-volumio avahi-daemon[813]: Registering new address record for 192.168.4.182 on wlan0.IPv4. Feb 24 16:42:05 coscia-volumio wireless.js[805]: trying... Feb 24 16:42:05 coscia-volumio sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 16:42:05 coscia-volumio sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:05 coscia-volumio sudo[1252]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:05 coscia-volumio wireless.js[805]: Connected to: ----dcoscia Feb 24 16:42:05 coscia-volumio wireless.js[805]: ---- Feb 24 16:42:05 coscia-volumio sudo[1255]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 16:42:05 coscia-volumio sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:05 coscia-volumio sudo[1255]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:05 coscia-volumio wireless.js[805]: ... joined AP, wlan0 IPv4 is 192.168.4.182, ipV6 is undefined Feb 24 16:42:05 coscia-volumio wireless.js[805]: It's done! AP Feb 24 16:42:05 coscia-volumio systemd[1]: Started Wireless Services. Feb 24 16:42:05 coscia-volumio systemd[1]: Started Volumio Backend Module. Feb 24 16:42:05 coscia-volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 24 16:42:05 coscia-volumio systemd[1]: Started Volumio Cpu Tweaker. Feb 24 16:42:05 coscia-volumio systemd[1]: Reached target Multi-User System. Feb 24 16:42:05 coscia-volumio systemd[1]: Reached target Graphical Interface. Feb 24 16:42:05 coscia-volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 24 16:42:05 coscia-volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 24 16:42:05 coscia-volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 24 16:42:05 coscia-volumio volumio-cpu-tweak[1261]: Setting RT Priority for mpd Feb 24 16:42:05 coscia-volumio volumio-cpu-tweak[1261]: Setting MPD Affinity Feb 24 16:42:05 coscia-volumio volumio-cpu-tweak[1261]: pid 994's current affinity mask: f Feb 24 16:42:05 coscia-volumio volumio-cpu-tweak[1261]: pid 994's new affinity mask: 3 Feb 24 16:42:05 coscia-volumio systemd[1]: Startup finished in 10.658s (kernel) + 19.497s (userspace) = 30.156s. Feb 24 16:42:05 coscia-volumio volumio-cpu-tweak[1261]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 24 16:42:05 coscia-volumio volumio-cpu-tweak[1261]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Feb 24 16:42:05 coscia-volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Feb 24 16:42:05 coscia-volumio ntpd[979]: Listen normally on 4 wlan0 192.168.4.182:123 Feb 24 16:42:05 coscia-volumio ntpd[979]: new interface(s) found: waking up resolver Feb 24 16:42:06 coscia-volumio volumio[1260]: info: ------------------------------------------- Feb 24 16:42:06 coscia-volumio volumio[1260]: info: ----- Volumio3 ---- Feb 24 16:42:06 coscia-volumio volumio[1260]: info: ------------------------------------------- Feb 24 16:42:06 coscia-volumio volumio[1260]: info: ----- System startup ---- Feb 24 16:42:06 coscia-volumio volumio[1260]: info: ------------------------------------------- Feb 24 16:42:06 coscia-volumio volumio[1260]: info: MYVOLUMIO Environment detected Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Plugin folders cleanup Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Scanning into folder /volumio/app/plugins/ Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Scanning category audio_interface Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Scanning category miscellanea Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Scanning category music_service Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Scanning category plugins.json Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Scanning category system_controller Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Scanning category user_interface Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Scanning into folder /data/plugins/ Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Plugin folders cleanup completed Feb 24 16:42:06 coscia-volumio volumio[1260]: info: ------------------------------------------- Feb 24 16:42:06 coscia-volumio volumio[1260]: info: ----- Core plugins startup ---- Feb 24 16:42:06 coscia-volumio volumio[1260]: info: ------------------------------------------- Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Loading plugins from folder /volumio/app/plugins/ Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Adding plugin upnp to MyMusic Plugins Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Loading plugins from folder /data/plugins/ Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Loading plugin "system"... Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Loading plugin "appearance"... Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Loading plugin "network"... Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Refreshing Cached IP Addresses Feb 24 16:42:06 coscia-volumio sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 16:42:06 coscia-volumio sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:06 coscia-volumio sudo[1292]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:06 coscia-volumio sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 16:42:06 coscia-volumio sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:06 coscia-volumio sudo[1294]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Loading plugin "services"... Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Loading plugin "alsa_controller"... Feb 24 16:42:06 coscia-volumio sudo[1303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 24 16:42:06 coscia-volumio sudo[1303]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:06 coscia-volumio volumio[1260]: warn: Unable to locate the audio output device Generic I2S DAC. Please configure a valid output device. Feb 24 16:42:06 coscia-volumio volumio[1260]: Invalid card number. Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer Feb 24 16:42:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 16:42:06 coscia-volumio volumio[1260]: Invalid card number. Feb 24 16:42:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 24 16:42:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Loading plugin "wizard"... Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Loading plugin "networkfs"... Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Starting Udev Watcher for removable devices Feb 24 16:42:06 coscia-volumio sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=Coscianetnas2,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.4.125/Backup /mnt/NAS/Backup Feb 24 16:42:06 coscia-volumio sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Ignoring mount for partition: boot Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Ignoring mount for partition: volumio Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Ignoring mount for partition: volumio_data Feb 24 16:42:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Loading plugin "volumio_command_line_client"... Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Loading plugin "upnp"... Feb 24 16:42:06 coscia-volumio volumio[1260]: info: [1740433326867] Starting Upmpd Daemon Feb 24 16:42:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Loading plugin "my_music"... Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Loading plugin "mpd"... Feb 24 16:42:06 coscia-volumio kernel: Key type cifs.spnego registered Feb 24 16:42:06 coscia-volumio kernel: Key type cifs.idmap registered Feb 24 16:42:06 coscia-volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Feb 24 16:42:06 coscia-volumio kernel: CIFS: Attempting to mount //192.168.4.125/Backup Feb 24 16:42:06 coscia-volumio volumio[1260]: info: Loading plugin "upnp_browser"... Feb 24 16:42:07 coscia-volumio sudo[1326]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Loading plugin "alarm-clock"... Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Loading plugin "airplay_emulation"... Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Starting Shairport Sync Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Loading plugin "last_100"... Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Loading plugin "webradio"... Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Loading plugin "i2s_dacs"... Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Loading plugin "volumiodiscovery"... Feb 24 16:42:07 coscia-volumio volumio[1260]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 24 16:42:07 coscia-volumio node[1260]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 24 16:42:07 coscia-volumio node[1260]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 16:42:07 coscia-volumio volumio[1260]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 16:42:07 coscia-volumio volumio[1260]: *** WARNING *** For more information see Feb 24 16:42:07 coscia-volumio volumio[1260]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 24 16:42:07 coscia-volumio volumio[1260]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 16:42:07 coscia-volumio volumio[1260]: *** WARNING *** For more information see Feb 24 16:42:07 coscia-volumio node[1260]: *** WARNING *** For more information see Feb 24 16:42:07 coscia-volumio node[1260]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 24 16:42:07 coscia-volumio node[1260]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 16:42:07 coscia-volumio node[1260]: *** WARNING *** For more information see Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Discovery: Started advertising with name: Coscia-Volumio Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Loading plugin "outputs"... Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Loading plugin "albumart"... Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Plugin example_plugin is not enabled Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Loading plugin "inputs"... Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Loading plugin "updater_comm"... Feb 24 16:42:07 coscia-volumio volumio[1260]: Forking 3 albumart workers Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Plugin mpdemulation is not enabled Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Loading plugin "rest_api"... Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Loading plugin "websocket"... Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Starting Socket.io Server version 2.3.0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Loading i18n strings for locale en Feb 24 16:42:07 coscia-volumio volumio[1260]: Updating browse sources language Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::initPlayerControls Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: Express server listening on port 3000 Feb 24 16:42:07 coscia-volumio volumio[1260]: [Metrics] WebUI: 1s 587.15ms Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreStateMachine::resetVolumioState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreStateMachine::getcurrentVolume Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Volumio Network Manager: Network status updated: 3 Feb 24 16:42:07 coscia-volumio volumio[1260]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 24 16:42:07 coscia-volumio volumio[1260]: Starting albumart workers Feb 24 16:42:07 coscia-volumio volumio[1260]: Starting albumart workers Feb 24 16:42:07 coscia-volumio volumio[1260]: Starting albumart workers Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.137 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.137 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: VolumeController:: Volume=undefined Mute =false Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreStateMachine::updateTrackBlock Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrackBlock Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Feb 24 16:42:07 coscia-volumio volumio[1260]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Reloading queue from file Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 21 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 23 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreStateMachine::setRepeat null single undefined Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreStateMachine::setRandom null Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 24 16:42:07 coscia-volumio volumio[1260]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 24 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 25 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 26 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 27 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 28 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 29 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 30 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 31 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 32 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: VolumeController:: Volume=undefined Mute =false Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Setting Device type: Raspberry PI Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Completed loading Core Plugins Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Preparing to generate the ALSA configuration file Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Received Get System Info Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Discovery: Getting this device information Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Received Get System Info Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Discovery: Getting this device information Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Listing playlists Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 33 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Received Get System Info Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Discovery: Getting this device information Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Received Get System Info Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Discovery: Getting this device information Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Listing playlists Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 34 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 35 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Asound.conf file written Feb 24 16:42:07 coscia-volumio sudo[1422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 24 16:42:07 coscia-volumio sudo[1422]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:07 coscia-volumio sudo[1422]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Output device has changed, restarting MPD Feb 24 16:42:07 coscia-volumio sudo[1427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Output device has changed, restarting Shairport Sync Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:07 coscia-volumio sudo[1427]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:07 coscia-volumio sudo[1427]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:07 coscia-volumio sudo[1429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 16:42:07 coscia-volumio sudo[1429]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:07 coscia-volumio systemd[1]: Stopping Music Player Daemon... Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: ___________ START PLUGINS ___________ Feb 24 16:42:07 coscia-volumio volumio[1260]: info: ControllerMpd::onStart: Initializing MPD Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Creating MPD Configuration file Feb 24 16:42:07 coscia-volumio sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 16:42:07 coscia-volumio sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 16:42:07 coscia-volumio sudo[1436]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 16:42:07 coscia-volumio volumio[1260]: info: [1740433327783] CoreMusicLibrary::Adding element Media Servers Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:42:07 coscia-volumio sudo[1438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 16:42:07 coscia-volumio sudo[1438]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:07 coscia-volumio systemd[1]: mpd.service: Succeeded. Feb 24 16:42:07 coscia-volumio systemd[1]: Stopped Music Player Daemon. Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 16:42:07 coscia-volumio volumio[1260]: info: [1740433327818] CoreMusicLibrary::Adding element Last_100 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 16:42:07 coscia-volumio volumio[1260]: info: [1740433327819] CoreMusicLibrary::Adding element Webradio Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Initializing BBC Radios Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Volumio Calling Home Feb 24 16:42:07 coscia-volumio systemd[1]: Starting Music Player Daemon... Feb 24 16:42:07 coscia-volumio sudo[1447]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 16:42:07 coscia-volumio sudo[1447]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:07 coscia-volumio sudo[1447]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 36 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 37 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 38 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: MPD Permissions set Feb 24 16:42:07 coscia-volumio volumio[1260]: info: MPD Permissions set Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 39 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 40 Feb 24 16:42:07 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 41 Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Starting Shairport Sync Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Starting Shairport Sync Feb 24 16:42:07 coscia-volumio volumio[1260]: info: Starting Shairport Sync Feb 24 16:42:07 coscia-volumio sudo[1472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 16:42:07 coscia-volumio sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:07 coscia-volumio sudo[1474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 16:42:07 coscia-volumio sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:07 coscia-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 24 16:42:07 coscia-volumio systemd[1]: shairport-sync.service: Succeeded. Feb 24 16:42:07 coscia-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:07 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:07 coscia-volumio sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 16:42:07 coscia-volumio sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:08 coscia-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 24 16:42:08 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 42 Feb 24 16:42:08 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 43 Feb 24 16:42:08 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 44 Feb 24 16:42:08 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 45 Feb 24 16:42:08 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 46 Feb 24 16:42:08 coscia-volumio sudo[1472]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:08 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:08 coscia-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 24 16:42:08 coscia-volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 24 16:42:08 coscia-volumio systemd[1]: shairport-sync.service: Succeeded. Feb 24 16:42:08 coscia-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 24 16:42:08 coscia-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 24 16:42:08 coscia-volumio sudo[1474]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:08 coscia-volumio volumio[1260]: info: Shairport-Sync Started Feb 24 16:42:08 coscia-volumio volumio[1260]: Error adding Membership: Error: addMembership EINVAL Feb 24 16:42:08 coscia-volumio sudo[1477]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:08 coscia-volumio volumio[1260]: info: Shairport-Sync Started Feb 24 16:42:08 coscia-volumio volumio[1260]: info: Discovery: adding 47c07786-79ff-47b9-ae13-45fb03f9ca9d Feb 24 16:42:08 coscia-volumio volumio[1260]: info: Discovery: Found device Coscia-Volumio Feb 24 16:42:08 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:08 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:08 coscia-volumio volumio[1260]: info: Discovery: this is already registered, 47c07786-79ff-47b9-ae13-45fb03f9ca9d Feb 24 16:42:08 coscia-volumio volumio[1260]: info: Discovery: Found device Coscia-Volumio Feb 24 16:42:08 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:08 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:08 coscia-volumio volumio[1260]: info: Shairport-Sync Started Feb 24 16:42:08 coscia-volumio volumio[1260]: info: Volumio called home Feb 24 16:42:08 coscia-volumio mpd[1462]: Feb 24 16:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 16:42:08 coscia-volumio systemd[1]: Started Music Player Daemon. Feb 24 16:42:08 coscia-volumio sudo[1429]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:08 coscia-volumio sudo[1438]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:08 coscia-volumio volumio[1260]: info: Completed starting Core Plugins Feb 24 16:42:08 coscia-volumio volumio[1260]: info: ------------------------------------------- Feb 24 16:42:08 coscia-volumio volumio[1260]: info: ----- MyVolumio plugins startup ---- Feb 24 16:42:08 coscia-volumio volumio[1260]: info: ------------------------------------------- Feb 24 16:42:08 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 24 16:42:08 coscia-volumio volumio[1260]: error: MPD error: The expression evaluated to a falsy value: Feb 24 16:42:08 coscia-volumio volumio[1260]: assert.ok(self.idling) Feb 24 16:42:08 coscia-volumio volumio[1260]: error: The expression evaluated to a falsy value: Feb 24 16:42:08 coscia-volumio volumio[1260]: assert.ok(self.idling) Feb 24 16:42:08 coscia-volumio volumio[1260]: info: MPD running with PID1462 Feb 24 16:42:08 coscia-volumio volumio[1260]: ,establishing connection Feb 24 16:42:08 coscia-volumio volumio[1260]: error: MPD error: The expression evaluated to a falsy value: Feb 24 16:42:08 coscia-volumio volumio[1260]: assert.ok(self.idling) Feb 24 16:42:08 coscia-volumio volumio[1260]: error: The expression evaluated to a falsy value: Feb 24 16:42:08 coscia-volumio volumio[1260]: assert.ok(self.idling) Feb 24 16:42:08 coscia-volumio volumio[1260]: error: updateQueue error: null Feb 24 16:42:08 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:08 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 47 Feb 24 16:42:08 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 48 Feb 24 16:42:08 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 49 Feb 24 16:42:08 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 50 Feb 24 16:42:08 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 51 Feb 24 16:42:08 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 52 Feb 24 16:42:08 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 53 Feb 24 16:42:09 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:09 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 54 Feb 24 16:42:09 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 55 Feb 24 16:42:09 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 56 Feb 24 16:42:09 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 57 Feb 24 16:42:09 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.182 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 58 Feb 24 16:42:09 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:10 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:10 coscia-volumio volumio-remote-updater[791]: [2025-02-24 16:42:10] [connect] Successful connection Feb 24 16:42:10 coscia-volumio volumio[1260]: 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: 59 Feb 24 16:42:10 coscia-volumio volumio-remote-updater[791]: [2025-02-24 16:42:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740433330 101 Feb 24 16:42:10 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:10 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 24 16:42:10 coscia-volumio volumio[1260]: info: CoreStateMachine::getcurrentVolume Feb 24 16:42:10 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 16:42:10 coscia-volumio volumio[1260]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 24 16:42:10 coscia-volumio volumio[1260]: info: VolumeController:: Volume=undefined Mute =false Feb 24 16:42:10 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:10 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:10 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:10 coscia-volumio sudo[1303]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:10 coscia-volumio volumio[1260]: info: CoreStateMachine::updateTrackBlock Feb 24 16:42:10 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrackBlock Feb 24 16:42:10 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 16:42:10 coscia-volumio volumio[1260]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 24 16:42:10 coscia-volumio volumio[1260]: info: VolumeController:: Volume=undefined Mute =false Feb 24 16:42:10 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:10 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:10 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:11 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:11 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:12 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:12 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:13 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:13 coscia-volumio volumio[1260]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 24 16:42:13 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:14 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:14 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:14 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:15 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:15 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:15 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:15 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:15 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:15 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:16 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 24 16:42:16 coscia-volumio volumio[1260]: info: Adding plugin bluetooth to MyMusic Plugins Feb 24 16:42:16 coscia-volumio volumio[1260]: info: Adding plugin multiroom to MyMusic Plugins Feb 24 16:42:16 coscia-volumio volumio[1260]: info: Adding plugin metavolumio to MyMusic Plugins Feb 24 16:42:16 coscia-volumio volumio[1260]: info: Adding plugin cd_controller to MyMusic Plugins Feb 24 16:42:16 coscia-volumio volumio[1260]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 24 16:42:16 coscia-volumio volumio[1260]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 24 16:42:16 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:16 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:16 coscia-volumio volumio[1260]: info: Starting MyVolumio Remote Streaming Endpoints Feb 24 16:42:16 coscia-volumio volumio[1260]: info: MyVolumio login type: Token Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 24 16:42:16 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 24 16:42:17 coscia-volumio systemd[1]: systemd-fsckd.service: Succeeded. Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Starting Streaming Service Transparent Proxy Feb 24 16:42:17 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 24 16:42:17 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Streaming services startup Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Starting Streaming Daemon Feb 24 16:42:17 coscia-volumio sudo[1517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 24 16:42:17 coscia-volumio sudo[1517]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:17 coscia-volumio sudo[1517]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:17 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 24 16:42:17 coscia-volumio sudo[1525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPlay Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::play index undefined Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:17 coscia-volumio sudo[1527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::startPlaybackTimer Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:17 coscia-volumio sudo[1527]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::clearAddPlayTracks NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a Feb 24 16:42:17 coscia-volumio sudo[1525]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 16:42:17 coscia-volumio sudo[1525]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:17 coscia-volumio sudo[1527]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:17 coscia-volumio volumio[1260]: error: Cannot start Volumio Streaming Daemon Feb 24 16:42:17 coscia-volumio volumio[1260]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 24 16:42:17 coscia-volumio volumio[1260]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand stop took 8 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Feb 24 16:42:17 coscia-volumio volumio[1260]: ---------------------------- MPD announces system playlist update Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Ignoring MPD Status Update Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand clear took 2 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand add "NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a" Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Feb 24 16:42:17 coscia-volumio volumio[1260]: ---------------------------- MPD announces system playlist update Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Ignoring MPD Status Update Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Feb 24 16:42:17 coscia-volumio volumio[1260]: ---------------------------- MPD announces system playlist update Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Ignoring MPD Status Update Feb 24 16:42:17 coscia-volumio volumio[1260]: error: updateQueue error: null Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Feb 24 16:42:17 coscia-volumio volumio[1260]: ---------------------------- MPD announces system playlist update Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Ignoring MPD Status Update Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ------------------------------ 4ms Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand add "NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a" took 4 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ------------------------------ 4ms Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ------------------------------ 3ms Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand play Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Feb 24 16:42:17 coscia-volumio volumio[1260]: ---------------------------- MPD announces system playlist update Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Ignoring MPD Status Update Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Feb 24 16:42:17 coscia-volumio volumio[1260]: ---------------------------- MPD announces system playlist update Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Ignoring MPD Status Update Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ------------------------------ 2ms Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand play took 1 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ------------------------------ 1ms Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ------------------------------ 1ms Feb 24 16:42:17 coscia-volumio sudo[1533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 24 16:42:17 coscia-volumio sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:17 coscia-volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 24 16:42:17 coscia-volumio sudo[1533]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Upmpdcli Daemon Started Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Feb 24 16:42:17 coscia-volumio volumio[1260]: ---------------------------- MPD announces state update: player Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ControllerMpd::getState Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Feb 24 16:42:17 coscia-volumio volumio[1260]: ---------------------------- MPD announces state update: player Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ControllerMpd::getState Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Feb 24 16:42:17 coscia-volumio volumio[1260]: ---------------------------- MPD announces state update: player Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ControllerMpd::getState Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Feb 24 16:42:17 coscia-volumio volumio[1260]: ---------------------------- MPD announces state update: player Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand status took 6 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand status took 6 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand status took 6 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ControllerMpd::getState Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseState Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseState Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseState Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Feb 24 16:42:17 coscia-volumio volumio[1260]: ---------------------------- MPD announces state update: player Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ControllerMpd::getState Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Feb 24 16:42:17 coscia-volumio volumio[1260]: info: Feb 24 16:42:17 coscia-volumio volumio[1260]: ---------------------------- MPD announces state update: player Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ControllerMpd::getState Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand clearerror took 8 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand clearerror took 7 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand status took 7 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand status took 2 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand status took 2 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseState Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseState Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseState Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ControllerMpd::pushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::servicePushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":220,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hot N Cold","artist":"Katy Perry","album":"One of the Boys","uri":"NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a","trackType":"m4a"} Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: CURRENT POSITION 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState stateService pause Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus stop Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ControllerMpd::pushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::servicePushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":220,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hot N Cold","artist":"Katy Perry","album":"One of the Boys","uri":"NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a","trackType":"m4a"} Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: CURRENT POSITION 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState stateService pause Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus stop Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ControllerMpd::pushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::servicePushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":220,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hot N Cold","artist":"Katy Perry","album":"One of the Boys","uri":"NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a","trackType":"m4a"} Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: CURRENT POSITION 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState stateService pause Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus stop Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ------------------------------ 28ms Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ------------------------------ 27ms Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ------------------------------ 26ms Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ControllerMpd::pushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::servicePushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":220,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hot N Cold","artist":"Katy Perry","album":"One of the Boys","uri":"NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a","trackType":"m4a"} Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: CURRENT POSITION 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState stateService pause Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus stop Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:17 coscia-volumio volumio[1535]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ControllerMpd::pushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::servicePushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":220,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hot N Cold","artist":"Katy Perry","album":"One of the Boys","uri":"NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a","trackType":"m4a"} Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: CURRENT POSITION 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState stateService pause Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus stop Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ControllerMpd::pushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::servicePushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":220,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hot N Cold","artist":"Katy Perry","album":"One of the Boys","uri":"NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a","trackType":"m4a"} Feb 24 16:42:17 coscia-volumio volumio[1260]: verbose: CURRENT POSITION 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState stateService pause Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus stop Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ------------------------------ 34ms Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ------------------------------ 24ms Feb 24 16:42:17 coscia-volumio volumio[1260]: info: ------------------------------ 24ms Feb 24 16:42:17 coscia-volumio volumio[1260]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 24 16:42:17 coscia-volumio volumio[1260]: STREAMING PROXY: Starting server on port 3245 Feb 24 16:42:17 coscia-volumio volumio[1260]: Node JS runtime: 14 Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:17 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:18 coscia-volumio volumio[1260]: info: MyVolumio token set successfully Feb 24 16:42:18 coscia-volumio volumio[1260]: info: MYVOLUMIO: Adding device Feb 24 16:42:18 coscia-volumio volumio[1260]: info: MYVOLUMIO: Evaluating Server Feb 24 16:42:18 coscia-volumio volumio[1260]: info: MyVolumio Plan changed: premium Feb 24 16:42:18 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 24 16:42:18 coscia-volumio volumio[1260]: info: Removing browser output: myVolumio user plan is not superstar Feb 24 16:42:18 coscia-volumio volumio[1260]: info: Removing audio output: Feb 24 16:42:18 coscia-volumio volumio[1260]: info: MYVOLUMIO: Adding device Feb 24 16:42:18 coscia-volumio volumio[1260]: info: MYVOLUMIO: Evaluating Server Feb 24 16:42:18 coscia-volumio volumio[1260]: info: Remote config written successfully Feb 24 16:42:18 coscia-volumio volumio[1260]: info: Starting Tunnel 1 Feb 24 16:42:18 coscia-volumio volumio[1260]: info: Starting Tunnel Connection Checker Feb 24 16:42:18 coscia-volumio volumio[1260]: info: Completed starting MyVolumio Plugin Feb 24 16:42:18 coscia-volumio systemd[1]: systemd-hostnamed.service: Succeeded. Feb 24 16:42:18 coscia-volumio volumio[1260]: info: MYVolumio Device enabled Feb 24 16:42:18 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 24 16:42:18 coscia-volumio volumio[1260]: info: MyVolumio status changed Feb 24 16:42:18 coscia-volumio volumio[1260]: info: Streaming services startup Feb 24 16:42:18 coscia-volumio volumio[1260]: info: Starting Streaming Daemon Feb 24 16:42:18 coscia-volumio sudo[1593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 24 16:42:18 coscia-volumio sudo[1593]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:18 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 24 16:42:18 coscia-volumio sudo[1593]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:18 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 24 16:42:18 coscia-volumio volumio[1260]: info: [1740433338446] Starting BluetoothController Feb 24 16:42:18 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 24 16:42:18 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Feb 24 16:42:18 coscia-volumio sudo[1596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 24 16:42:18 coscia-volumio sudo[1596]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:18 coscia-volumio sudo[1596]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:18 coscia-volumio volumio[1260]: info: MRS: MultiRoom plugin initialized Feb 24 16:42:18 coscia-volumio volumio[1260]: info: MRS: STOPPING SNAPCLIENT Feb 24 16:42:18 coscia-volumio volumio[1260]: info: MRS: Snap server stop Feb 24 16:42:18 coscia-volumio sudo[1612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 24 16:42:18 coscia-volumio sudo[1612]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:18 coscia-volumio volumio[1260]: info: MRS: STOPPING volumioStreaming Feb 24 16:42:18 coscia-volumio sudo[1615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 24 16:42:18 coscia-volumio sudo[1615]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:18 coscia-volumio sudo[1612]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:18 coscia-volumio sudo[1619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 24 16:42:18 coscia-volumio sudo[1619]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:18 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 24 16:42:18 coscia-volumio sudo[1615]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:18 coscia-volumio sudo[1620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 24 16:42:18 coscia-volumio sudo[1620]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:18 coscia-volumio sudo[1620]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:18 coscia-volumio sudo[1619]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:18 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 24 16:42:18 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 24 16:42:18 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 24 16:42:18 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 24 16:42:18 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Feb 24 16:42:18 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 24 16:42:18 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 24 16:42:18 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 24 16:42:18 coscia-volumio volumio[1260]: info: Preparing to generate the ALSA configuration file Feb 24 16:42:19 coscia-volumio volumio[1260]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Reading ALSA contributions from plugins. Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Setting Geolocation for MyVolumio to us1 Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Setting Geolocation for MyVolumio to us1 Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:19 coscia-volumio volumio[1260]: info: MRS: Removed streaming files Feb 24 16:42:19 coscia-volumio volumio[1260]: info: MRS: volumioStreaming STOPPED Feb 24 16:42:19 coscia-volumio volumio[1260]: info: MRS: SNAPSERVER STOPPED Feb 24 16:42:19 coscia-volumio volumio[1260]: info: MRS: SNAPCLIENT STOPPED Feb 24 16:42:19 coscia-volumio volumio[1260]: error: Cannot start Volumio Streaming Daemon Feb 24 16:42:19 coscia-volumio volumio[1260]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 24 16:42:19 coscia-volumio volumio[1260]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Asound.conf file written Feb 24 16:42:19 coscia-volumio sudo[1627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 24 16:42:19 coscia-volumio sudo[1627]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:19 coscia-volumio sudo[1627]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Output device has changed, restarting MPD Feb 24 16:42:19 coscia-volumio sudo[1632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 16:42:19 coscia-volumio sudo[1632]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:19 coscia-volumio sudo[1632]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Output device has changed, restarting Shairport Sync Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:19 coscia-volumio sudo[1635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 16:42:19 coscia-volumio sudo[1635]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:19 coscia-volumio systemd[1]: Stopping Music Player Daemon... Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 16:42:19 coscia-volumio volumio[1260]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:19 coscia-volumio systemd[1]: mpd.service: Succeeded. Feb 24 16:42:19 coscia-volumio systemd[1]: Stopped Music Player Daemon. Feb 24 16:42:19 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 16:42:19 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Feb 24 16:42:19 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output for this device Feb 24 16:42:19 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding audio output: Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding audio output: Feb 24 16:42:19 coscia-volumio sudo[1644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Coscia-Volumio Feb 24 16:42:19 coscia-volumio sudo[1644]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:19 coscia-volumio systemd[1]: Starting Music Player Daemon... Feb 24 16:42:19 coscia-volumio volumio[1260]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:19 coscia-volumio sudo[1644]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:19 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding METAVOLUMIO REST API Endpoints Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Feb 24 16:42:19 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Feb 24 16:42:19 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Preparing CD Folders Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding CD REST API Endpoints Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Starting UDEV Watcher for CD Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Detecting CD presence with UDEV Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Feb 24 16:42:19 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding inputs REST Endpoints Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Scanning Audio Inputs Feb 24 16:42:19 coscia-volumio sudo[1640]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 16:42:19 coscia-volumio sudo[1640]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:19 coscia-volumio sudo[1640]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Checking against Known Cards name Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding Server instance for streaming Feb 24 16:42:19 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Feb 24 16:42:19 coscia-volumio volumio[1260]: error: Hi Res Audio Failed Login: Missing Login Data Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding HIGHRESAUDIO REST API Endpoints Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Feb 24 16:42:19 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Feb 24 16:42:19 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Feb 24 16:42:19 coscia-volumio volumio[1260]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Stopping AccessToken refresher cron Feb 24 16:42:19 coscia-volumio sudo[1654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 16:42:19 coscia-volumio sudo[1654]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:19 coscia-volumio volumio[1260]: info: AccessToken refresher cron started Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Stopping AccessToken refresher cron for QOBUZ Feb 24 16:42:19 coscia-volumio sudo[1654]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:19 coscia-volumio volumio[1260]: info: AccessToken refresher cron started for QOBUZ Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding TIDAL REST API Endpoints Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding QOBUZ REST API Endpoints Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Feb 24 16:42:19 coscia-volumio volumio[1260]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Bluetooth name changed to Coscia-Volumio Feb 24 16:42:19 coscia-volumio volumio[1260]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 24 16:42:19 coscia-volumio volumio[1260]: info: MPD Permissions set Feb 24 16:42:19 coscia-volumio volumio[1260]: error: Upnp client error: Error: This socket has been ended by the other party Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:19 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Starting Shairport Sync Feb 24 16:42:19 coscia-volumio sudo[1664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 16:42:19 coscia-volumio sudo[1664]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:19 coscia-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 24 16:42:19 coscia-volumio systemd[1]: shairport-sync.service: Succeeded. Feb 24 16:42:19 coscia-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 24 16:42:19 coscia-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 24 16:42:19 coscia-volumio sudo[1664]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Shairport-Sync Started Feb 24 16:42:19 coscia-volumio mpd[1652]: Feb 24 16:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 16:42:19 coscia-volumio systemd[1]: Started Music Player Daemon. Feb 24 16:42:19 coscia-volumio sudo[1635]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:19 coscia-volumio volumio[1260]: error: updateQueue error: null Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding audio output: Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding audio output: Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding audio output: Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Successfully Added MyVolumio device Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Successfully Added MyVolumio device Feb 24 16:42:19 coscia-volumio volumio[1260]: info: Adding audio output: Feb 24 16:42:20 coscia-volumio volumio[1260]: info: Updating MyVolumio device info Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:20 coscia-volumio volumio[1260]: info: Updating MyVolumio device info Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:20 coscia-volumio volumio[1260]: info: Successfully Updated MyVolumio device Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPlay Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreStateMachine::play index undefined Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreStateMachine::startPlaybackTimer Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: ControllerMpd::clearAddPlayTracks NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 16:42:20 coscia-volumio volumio[1260]: info: sendMpdCommand stop took 0 milliseconds Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 16:42:20 coscia-volumio volumio[1260]: info: Feb 24 16:42:20 coscia-volumio volumio[1260]: ---------------------------- MPD announces system playlist update Feb 24 16:42:20 coscia-volumio volumio[1260]: info: Ignoring MPD Status Update Feb 24 16:42:20 coscia-volumio volumio[1260]: info: sendMpdCommand clear took 1 milliseconds Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand add "NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a" Feb 24 16:42:20 coscia-volumio volumio[1260]: error: updateQueue error: null Feb 24 16:42:20 coscia-volumio volumio[1260]: info: ------------------------------ 0ms Feb 24 16:42:20 coscia-volumio volumio[1260]: info: sendMpdCommand add "NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a" took 0 milliseconds Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand play Feb 24 16:42:20 coscia-volumio volumio[1260]: info: Feb 24 16:42:20 coscia-volumio volumio[1260]: ---------------------------- MPD announces system playlist update Feb 24 16:42:20 coscia-volumio volumio[1260]: info: Ignoring MPD Status Update Feb 24 16:42:20 coscia-volumio volumio[1260]: info: sendMpdCommand play took 1 milliseconds Feb 24 16:42:20 coscia-volumio volumio[1260]: info: ------------------------------ 1ms Feb 24 16:42:20 coscia-volumio volumio[1260]: info: Feb 24 16:42:20 coscia-volumio volumio[1260]: ---------------------------- MPD announces state update: player Feb 24 16:42:20 coscia-volumio volumio[1260]: info: ControllerMpd::getState Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Feb 24 16:42:20 coscia-volumio volumio[1260]: info: Successfully Updated MyVolumio device Feb 24 16:42:20 coscia-volumio volumio[1260]: info: Feb 24 16:42:20 coscia-volumio volumio[1260]: ---------------------------- MPD announces state update: player Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 16:42:20 coscia-volumio volumio[1260]: info: sendMpdCommand status took 11 milliseconds Feb 24 16:42:20 coscia-volumio volumio[1260]: info: ControllerMpd::getState Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseState Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 16:42:20 coscia-volumio volumio[1260]: info: sendMpdCommand clearerror took 0 milliseconds Feb 24 16:42:20 coscia-volumio volumio[1260]: info: sendMpdCommand status took 1 milliseconds Feb 24 16:42:20 coscia-volumio volumio[1260]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseState Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Feb 24 16:42:20 coscia-volumio volumio[1260]: info: ControllerMpd::pushState Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreCommandRouter::servicePushState Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":220,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hot N Cold","artist":"Katy Perry","album":"One of the Boys","uri":"NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a","trackType":"m4a"} Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: CURRENT POSITION 0 Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState stateService pause Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus stop Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:20 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output update for this device Feb 24 16:42:20 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output Feb 24 16:42:20 coscia-volumio volumio[1260]: info: ------------------------------ 18ms Feb 24 16:42:20 coscia-volumio volumio[1260]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Feb 24 16:42:20 coscia-volumio volumio[1260]: info: ControllerMpd::pushState Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreCommandRouter::servicePushState Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":220,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hot N Cold","artist":"Katy Perry","album":"One of the Boys","uri":"NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a","trackType":"m4a"} Feb 24 16:42:20 coscia-volumio volumio[1260]: verbose: CURRENT POSITION 0 Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState stateService pause Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus stop Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:20 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:20 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output update for this device Feb 24 16:42:20 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output Feb 24 16:42:20 coscia-volumio volumio[1260]: info: ------------------------------ 12ms Feb 24 16:42:20 coscia-volumio volumio[1535]: ..................................................................++++ Feb 24 16:42:22 coscia-volumio sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 24 16:42:22 coscia-volumio sudo[1675]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:22 coscia-volumio systemd[1]: Started Volumio Bluetooth Module. Feb 24 16:42:22 coscia-volumio sudo[1675]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:22 coscia-volumio volumio[1260]: info: Volumio BT Module successfully started Feb 24 16:42:22 coscia-volumio sudo[1678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Feb 24 16:42:22 coscia-volumio sudo[1678]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:22 coscia-volumio volumiobt[1677]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Feb 24 16:42:22 coscia-volumio sudo[1678]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:22 coscia-volumio volumio[1260]: info: TidalConnect service stoped! Feb 24 16:42:22 coscia-volumio volumio[1260]: error: Upnp client error: Error: This socket has been ended by the other party Feb 24 16:42:22 coscia-volumio volumio[1260]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 24 16:42:22 coscia-volumio volumio[1260]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 24 16:42:22 coscia-volumio sudo[1690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 24 16:42:22 coscia-volumio sudo[1690]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:22 coscia-volumio systemd[1]: Started Volumio Tidal Connect Service. Feb 24 16:42:22 coscia-volumio sudo[1690]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:22 coscia-volumio volumio[1260]: info: Executing endpoint tc_getconfig Feb 24 16:42:22 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 24 16:42:22 coscia-volumio vtcs[1692]: STARTING TidalConnect services, version: 1.4.0.34 Feb 24 16:42:22 coscia-volumio vtcs[1692]: STARTED TidalConnect services. Feb 24 16:42:22 coscia-volumio volumio[1260]: info: Executing endpoint tc_connect Feb 24 16:42:22 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 24 16:42:22 coscia-volumio volumio[1260]: info: Connecting to TidalConnect Feb 24 16:42:22 coscia-volumio volumio[1260]: info: CoreCommandRouter::servicePushState Feb 24 16:42:22 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:22 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:22 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 16:42:22 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:22 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output update for this device Feb 24 16:42:22 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output Feb 24 16:42:22 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:22 coscia-volumio volumio[1260]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Feb 24 16:42:22 coscia-volumio volumio[1260]: info: CoreCommandRouter::servicePushState Feb 24 16:42:22 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:22 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:22 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:22 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output update for this device Feb 24 16:42:22 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output Feb 24 16:42:22 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:22 coscia-volumio volumio[1260]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Feb 24 16:42:23 coscia-volumio sudo[1711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Feb 24 16:42:23 coscia-volumio sudo[1711]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:23 coscia-volumio volumiobt[1677]: pulseaudio: no process found Feb 24 16:42:23 coscia-volumio sudo[1711]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:23 coscia-volumio dbus-daemon[778]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.22' (uid=1000 pid=1715 comm="/usr/bin/pulseaudio --high-priority=true ") Feb 24 16:42:23 coscia-volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Feb 24 16:42:23 coscia-volumio volumio[1260]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 16:42:23 coscia-volumio dbus-daemon[778]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Feb 24 16:42:23 coscia-volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Feb 24 16:42:23 coscia-volumio rtkit-daemon[1716]: Successfully called chroot. Feb 24 16:42:23 coscia-volumio rtkit-daemon[1716]: Successfully dropped privileges. Feb 24 16:42:23 coscia-volumio rtkit-daemon[1716]: Successfully limited resources. Feb 24 16:42:23 coscia-volumio rtkit-daemon[1716]: Running. Feb 24 16:42:23 coscia-volumio rtkit-daemon[1716]: Canary thread running. Feb 24 16:42:23 coscia-volumio rtkit-daemon[1716]: Watchdog thread running. Feb 24 16:42:23 coscia-volumio dbus-daemon[778]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.23' (uid=0 pid=1716 comm="/usr/lib/rtkit/rtkit-daemon ") Feb 24 16:42:23 coscia-volumio systemd[1]: Starting Authorization Manager... Feb 24 16:42:23 coscia-volumio volumio[1260]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 16:42:23 coscia-volumio sudo[1728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 24 16:42:23 coscia-volumio sudo[1728]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:42:23 coscia-volumio polkitd[1724]: started daemon version 0.105 using authority implementation `local' version `0.105' Feb 24 16:42:23 coscia-volumio dbus-daemon[778]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Feb 24 16:42:23 coscia-volumio systemd[1]: Started MyVolumio SSH Tunnel. Feb 24 16:42:23 coscia-volumio systemd[1]: Started Authorization Manager. Feb 24 16:42:23 coscia-volumio sudo[1728]: pam_unix(sudo:session): session closed for user root Feb 24 16:42:23 coscia-volumio volumio[1260]: info: Remote SSH Started Feb 24 16:42:23 coscia-volumio autossh[1732]: port set to 0, monitoring disabled Feb 24 16:42:23 coscia-volumio autossh[1732]: starting ssh (count 1) Feb 24 16:42:23 coscia-volumio autossh[1732]: ssh child pid is 1735 Feb 24 16:42:23 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 60 Feb 24 16:42:23 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:23 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:23 coscia-volumio pulseaudio[1715]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Feb 24 16:42:23 coscia-volumio pulseaudio[1715]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Feb 24 16:42:23 coscia-volumio bluetoothd[992]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSource Feb 24 16:42:23 coscia-volumio bluetoothd[992]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSink Feb 24 16:42:23 coscia-volumio volumiobt[1677]: Applying permissions Feb 24 16:42:23 coscia-volumio volumiobt[1677]: Setting BT discoverable and pairable Feb 24 16:42:23 coscia-volumio pulseaudio[1715]: 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 Feb 24 16:42:23 coscia-volumio kernel: Bluetooth: RFCOMM TTY layer initialized Feb 24 16:42:23 coscia-volumio kernel: Bluetooth: RFCOMM socket layer initialized Feb 24 16:42:23 coscia-volumio kernel: Bluetooth: RFCOMM ver 1.11 Feb 24 16:42:23 coscia-volumio volumiobt[1677]: Agent registered Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [117B blob data] Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [117B blob data] Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [117B blob data] Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [117B blob data] Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [117B blob data] Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [117B blob data] Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [117B blob data] Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [117B blob data] Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [117B blob data] Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [bluetooth]# -e power on Feb 24 16:42:23 coscia-volumio volumiobt[1677]: Invalid command in menu main: -e Feb 24 16:42:23 coscia-volumio volumiobt[1677]: Feb 24 16:42:23 coscia-volumio volumiobt[1677]: Use "help" for a list of available commands in a menu. Feb 24 16:42:23 coscia-volumio volumiobt[1677]: Use "menu " if you want to enter any submenu. Feb 24 16:42:23 coscia-volumio volumiobt[1677]: Use "back" if you want to return to menu main. Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [bluetooth]# agent on Feb 24 16:42:23 coscia-volumio volumiobt[1677]: Agent is already registered Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [bluetooth]# discoverable on Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [bluetooth]# pairable on Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [bluetooth]# agent NoInputNoOutput Feb 24 16:42:23 coscia-volumio volumiobt[1677]: Agent is already registered Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [bluetooth]# default-agent Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [bluetooth]# quit Feb 24 16:42:23 coscia-volumio volumiobt[1677]: [59B blob data] Feb 24 16:42:23 coscia-volumio volumiossh-tunnel[1731]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Feb 24 16:42:24 coscia-volumio volumio[1260]: info: MRS: Getting audio outputs on start Feb 24 16:42:24 coscia-volumio volumio[1260]: info: MRS: Requesting all other devices output Feb 24 16:42:25 coscia-volumio volumio[1260]: info: TidalConnect service started! Feb 24 16:42:25 coscia-volumio volumio[1260]: [Metrics] CommandRouter: 19s 192.43ms Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumiosetStartupVolume Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreCommandRouter::Close All Modals sent Feb 24 16:42:25 coscia-volumio volumio[1260]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Feb 24 16:42:25 coscia-volumio volumio[1260]: xcb_connection_has_error() returned true Feb 24 16:42:25 coscia-volumio volumio[1260]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Feb 24 16:42:25 coscia-volumio volumio[1260]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Feb 24 16:42:25 coscia-volumio volumio[1260]: aplay: main:828: audio open error: No such device Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPlay Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreStateMachine::play index undefined Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreStateMachine::startPlaybackTimer Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 24 16:42:25 coscia-volumio volumio[1260]: verbose: ControllerMpd::clearAddPlayTracks NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a Feb 24 16:42:25 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 16:42:25 coscia-volumio volumio[1260]: info: Feb 24 16:42:25 coscia-volumio volumio[1260]: ---------------------------- MPD announces state update: player Feb 24 16:42:25 coscia-volumio volumio[1260]: info: sendMpdCommand stop took 0 milliseconds Feb 24 16:42:25 coscia-volumio volumio[1260]: info: ControllerMpd::getState Feb 24 16:42:25 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Feb 24 16:42:25 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 16:42:25 coscia-volumio volumio[1260]: info: Feb 24 16:42:25 coscia-volumio volumio[1260]: ---------------------------- MPD announces system playlist update Feb 24 16:42:25 coscia-volumio volumio[1260]: info: Ignoring MPD Status Update Feb 24 16:42:25 coscia-volumio volumio[1260]: info: sendMpdCommand status took 0 milliseconds Feb 24 16:42:25 coscia-volumio volumio[1260]: info: sendMpdCommand clear took 0 milliseconds Feb 24 16:42:25 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseState Feb 24 16:42:25 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 16:42:25 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand add "NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a" Feb 24 16:42:25 coscia-volumio volumio[1260]: error: updateQueue error: null Feb 24 16:42:25 coscia-volumio volumio[1260]: info: Feb 24 16:42:25 coscia-volumio volumio[1260]: ---------------------------- MPD announces system playlist update Feb 24 16:42:25 coscia-volumio volumio[1260]: info: Ignoring MPD Status Update Feb 24 16:42:25 coscia-volumio volumio[1260]: info: ------------------------------ 0ms Feb 24 16:42:25 coscia-volumio volumio[1260]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 16:42:25 coscia-volumio volumio[1260]: info: sendMpdCommand add "NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a" took 1 milliseconds Feb 24 16:42:25 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Feb 24 16:42:25 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand play Feb 24 16:42:25 coscia-volumio volumio[1260]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 16:42:25 coscia-volumio volumio[1260]: info: ------------------------------ 2ms Feb 24 16:42:25 coscia-volumio volumio[1260]: info: ------------------------------ 1ms Feb 24 16:42:25 coscia-volumio volumio[1260]: info: sendMpdCommand play took 0 milliseconds Feb 24 16:42:25 coscia-volumio volumio[1260]: info: Feb 24 16:42:25 coscia-volumio volumio[1260]: ---------------------------- MPD announces state update: player Feb 24 16:42:25 coscia-volumio volumio[1260]: info: ControllerMpd::getState Feb 24 16:42:25 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Feb 24 16:42:25 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 16:42:25 coscia-volumio volumio[1260]: info: sendMpdCommand status took 1 milliseconds Feb 24 16:42:25 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseState Feb 24 16:42:25 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 16:42:25 coscia-volumio volumio[1260]: info: sendMpdCommand clearerror took 2 milliseconds Feb 24 16:42:25 coscia-volumio volumio[1260]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 16:42:25 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Feb 24 16:42:25 coscia-volumio volumio[1260]: info: ControllerMpd::pushState Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreCommandRouter::servicePushState Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:25 coscia-volumio volumio[1260]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":220,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hot N Cold","artist":"Katy Perry","album":"One of the Boys","uri":"NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a","trackType":"m4a"} Feb 24 16:42:25 coscia-volumio volumio[1260]: verbose: CURRENT POSITION 0 Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState stateService pause Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus stop Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 16:42:25 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:25 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output update for this device Feb 24 16:42:25 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output Feb 24 16:42:25 coscia-volumio volumio[1260]: info: ------------------------------ 8ms Feb 24 16:42:26 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 24 16:42:26 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 16:42:26 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPlay Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreStateMachine::play index undefined Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreStateMachine::startPlaybackTimer Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::clearAddPlayTracks NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 16:42:27 coscia-volumio volumio[1260]: info: Feb 24 16:42:27 coscia-volumio volumio[1260]: ---------------------------- MPD announces state update: player Feb 24 16:42:27 coscia-volumio volumio[1260]: info: sendMpdCommand stop took 0 milliseconds Feb 24 16:42:27 coscia-volumio volumio[1260]: info: ControllerMpd::getState Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 16:42:27 coscia-volumio volumio[1260]: info: Feb 24 16:42:27 coscia-volumio volumio[1260]: ---------------------------- MPD announces system playlist update Feb 24 16:42:27 coscia-volumio volumio[1260]: info: Ignoring MPD Status Update Feb 24 16:42:27 coscia-volumio volumio[1260]: info: sendMpdCommand status took 1 milliseconds Feb 24 16:42:27 coscia-volumio volumio[1260]: info: sendMpdCommand clear took 1 milliseconds Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseState Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand add "NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a" Feb 24 16:42:27 coscia-volumio volumio[1260]: error: updateQueue error: null Feb 24 16:42:27 coscia-volumio volumio[1260]: info: Feb 24 16:42:27 coscia-volumio volumio[1260]: ---------------------------- MPD announces system playlist update Feb 24 16:42:27 coscia-volumio volumio[1260]: info: Ignoring MPD Status Update Feb 24 16:42:27 coscia-volumio volumio[1260]: info: ------------------------------ 0ms Feb 24 16:42:27 coscia-volumio volumio[1260]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 16:42:27 coscia-volumio volumio[1260]: info: sendMpdCommand add "NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a" took 0 milliseconds Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand play Feb 24 16:42:27 coscia-volumio volumio[1260]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 16:42:27 coscia-volumio volumio[1260]: info: ------------------------------ 2ms Feb 24 16:42:27 coscia-volumio volumio[1260]: info: ------------------------------ 1ms Feb 24 16:42:27 coscia-volumio volumio[1260]: info: sendMpdCommand play took 0 milliseconds Feb 24 16:42:27 coscia-volumio volumio[1260]: info: Feb 24 16:42:27 coscia-volumio volumio[1260]: ---------------------------- MPD announces state update: player Feb 24 16:42:27 coscia-volumio volumio[1260]: info: ControllerMpd::getState Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Feb 24 16:42:27 coscia-volumio volumio[1260]: info: Feb 24 16:42:27 coscia-volumio volumio[1260]: ---------------------------- MPD announces state update: player Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 16:42:27 coscia-volumio volumio[1260]: info: sendMpdCommand status took 2 milliseconds Feb 24 16:42:27 coscia-volumio volumio[1260]: info: ControllerMpd::getState Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseState Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 16:42:27 coscia-volumio volumio[1260]: info: sendMpdCommand clearerror took 0 milliseconds Feb 24 16:42:27 coscia-volumio volumio[1260]: info: sendMpdCommand status took 1 milliseconds Feb 24 16:42:27 coscia-volumio volumio[1260]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseState Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Feb 24 16:42:27 coscia-volumio volumio[1260]: info: ControllerMpd::pushState Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreCommandRouter::servicePushState Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":220,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hot N Cold","artist":"Katy Perry","album":"One of the Boys","uri":"NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a","trackType":"m4a"} Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: CURRENT POSITION 0 Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState stateService pause Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus stop Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:27 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output update for this device Feb 24 16:42:27 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output Feb 24 16:42:27 coscia-volumio volumio[1260]: info: ------------------------------ 8ms Feb 24 16:42:27 coscia-volumio volumio[1260]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Feb 24 16:42:27 coscia-volumio volumio[1260]: info: ControllerMpd::pushState Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreCommandRouter::servicePushState Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":220,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hot N Cold","artist":"Katy Perry","album":"One of the Boys","uri":"NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a","trackType":"m4a"} Feb 24 16:42:27 coscia-volumio volumio[1260]: verbose: CURRENT POSITION 0 Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState stateService pause Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus stop Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:27 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:27 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output update for this device Feb 24 16:42:27 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output Feb 24 16:42:27 coscia-volumio volumio[1260]: info: ------------------------------ 11ms Feb 24 16:42:28 coscia-volumio systemd[1]: systemd-timedated.service: Succeeded. Feb 24 16:42:29 coscia-volumio volumio[1535]: .................................................................................................................................................................................++++ Feb 24 16:42:29 coscia-volumio volumio[1535]: e is 65537 (0x010001) Feb 24 16:42:29 coscia-volumio volumio[1535]: writing RSA key Feb 24 16:42:29 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPlay Feb 24 16:42:29 coscia-volumio volumio[1260]: info: CoreStateMachine::play index undefined Feb 24 16:42:29 coscia-volumio volumio[1260]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 16:42:29 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:29 coscia-volumio volumio[1260]: info: CoreStateMachine::startPlaybackTimer Feb 24 16:42:29 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:29 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 16:42:29 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:42:29 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 24 16:42:29 coscia-volumio volumio[1260]: verbose: ControllerMpd::clearAddPlayTracks NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a Feb 24 16:42:29 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 16:42:29 coscia-volumio volumio[1260]: info: Feb 24 16:42:29 coscia-volumio volumio[1260]: ---------------------------- MPD announces state update: player Feb 24 16:42:29 coscia-volumio volumio[1260]: info: sendMpdCommand stop took 1 milliseconds Feb 24 16:42:29 coscia-volumio volumio[1260]: info: ControllerMpd::getState Feb 24 16:42:29 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Feb 24 16:42:29 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 16:42:29 coscia-volumio volumio[1260]: info: Feb 24 16:42:29 coscia-volumio volumio[1260]: ---------------------------- MPD announces system playlist update Feb 24 16:42:29 coscia-volumio volumio[1260]: info: Ignoring MPD Status Update Feb 24 16:42:29 coscia-volumio volumio[1260]: info: sendMpdCommand status took 1 milliseconds Feb 24 16:42:29 coscia-volumio volumio[1260]: info: sendMpdCommand clear took 1 milliseconds Feb 24 16:42:29 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseState Feb 24 16:42:29 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 16:42:29 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand add "NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a" Feb 24 16:42:29 coscia-volumio volumio[1260]: error: updateQueue error: null Feb 24 16:42:29 coscia-volumio volumio[1260]: info: Feb 24 16:42:29 coscia-volumio volumio[1260]: ---------------------------- MPD announces system playlist update Feb 24 16:42:29 coscia-volumio volumio[1260]: info: Ignoring MPD Status Update Feb 24 16:42:29 coscia-volumio volumio[1260]: info: ------------------------------ 0ms Feb 24 16:42:29 coscia-volumio volumio[1260]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 16:42:29 coscia-volumio volumio[1260]: info: sendMpdCommand add "NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a" took 1 milliseconds Feb 24 16:42:29 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Feb 24 16:42:29 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand play Feb 24 16:42:29 coscia-volumio volumio[1260]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 16:42:29 coscia-volumio volumio[1260]: info: ------------------------------ 2ms Feb 24 16:42:29 coscia-volumio volumio[1260]: info: ------------------------------ 1ms Feb 24 16:42:29 coscia-volumio volumio[1260]: info: sendMpdCommand play took 0 milliseconds Feb 24 16:42:29 coscia-volumio volumio[1260]: info: Feb 24 16:42:29 coscia-volumio volumio[1260]: ---------------------------- MPD announces state update: player Feb 24 16:42:29 coscia-volumio volumio[1260]: info: ControllerMpd::getState Feb 24 16:42:29 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Feb 24 16:42:29 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 16:42:29 coscia-volumio volumio[1260]: info: sendMpdCommand status took 2 milliseconds Feb 24 16:42:29 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseState Feb 24 16:42:29 coscia-volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 16:42:29 coscia-volumio volumio[1260]: info: sendMpdCommand clearerror took 1 milliseconds Feb 24 16:42:29 coscia-volumio volumio[1260]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 16:42:29 coscia-volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Feb 24 16:42:29 coscia-volumio volumio[1260]: info: ControllerMpd::pushState Feb 24 16:42:29 coscia-volumio volumio[1260]: info: CoreCommandRouter::servicePushState Feb 24 16:42:29 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:29 coscia-volumio volumio[1260]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":220,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hot N Cold","artist":"Katy Perry","album":"One of the Boys","uri":"NAS/Backup/NetBakData/Admin@CNSERVER2/Disk F/Documents/Backup/cosciaka/My Music/iTunes/iTunes Music/Katy Perry/One of the Boys/07 Hot N Cold.m4a","trackType":"m4a"} Feb 24 16:42:29 coscia-volumio volumio[1260]: verbose: CURRENT POSITION 0 Feb 24 16:42:29 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState stateService pause Feb 24 16:42:29 coscia-volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus stop Feb 24 16:42:29 coscia-volumio volumio[1260]: info: CoreStateMachine::pushState Feb 24 16:42:29 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:29 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Feb 24 16:42:29 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output update for this device Feb 24 16:42:29 coscia-volumio volumio[1260]: info: MRS: Pushing multiroomSync output Feb 24 16:42:29 coscia-volumio volumio[1260]: info: ------------------------------ 9ms Feb 24 16:42:31 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 24 16:42:31 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 61 Feb 24 16:42:31 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:42:31 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:42:32 coscia-volumio volumio[1260]: info: BOOT COMPLETED Feb 24 16:42:36 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:36 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 16:42:36 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:36 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 16:42:36 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 24 16:42:36 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 24 16:42:36 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 24 16:42:36 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 24 16:42:36 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 16:42:36 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 16:42:37 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 16:42:37 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 16:42:37 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 24 16:42:37 coscia-volumio volumio-remote-updater[791]: No test mode Feb 24 16:42:37 coscia-volumio volumio-remote-updater[791]: No alpha test mode Feb 24 16:42:37 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 24 16:42:38 coscia-volumio volumio[1260]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 24 16:42:38 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 24 16:42:52 coscia-volumio ntpd[979]: Soliciting pool server 137.190.2.4 Feb 24 16:42:53 coscia-volumio ntpd[979]: Soliciting pool server 144.202.66.214 Feb 24 16:42:53 coscia-volumio ntpd[979]: Soliciting pool server 162.159.200.123 Feb 24 16:42:54 coscia-volumio ntpd[979]: Soliciting pool server 72.30.35.88 Feb 24 16:42:54 coscia-volumio ntpd[979]: Soliciting pool server 172.234.37.140 Feb 24 16:42:55 coscia-volumio ntpd[979]: Soliciting pool server 104.131.139.195 Feb 24 16:42:55 coscia-volumio ntpd[979]: Soliciting pool server 23.150.41.123 Feb 24 16:42:55 coscia-volumio ntpd[979]: Soliciting pool server 155.248.196.28 Feb 24 16:42:56 coscia-volumio ntpd[979]: Soliciting pool server 71.123.46.186 Feb 24 16:42:56 coscia-volumio ntpd[979]: Soliciting pool server 23.155.40.38 Feb 24 16:42:56 coscia-volumio ntpd[979]: Soliciting pool server 198.206.187.98 Feb 24 16:42:57 coscia-volumio ntpd[979]: Soliciting pool server 51.81.226.229 Feb 24 16:42:57 coscia-volumio ntpd[979]: Soliciting pool server 208.67.72.43 Feb 24 16:42:57 coscia-volumio ntpd[979]: Soliciting pool server 198.46.254.130 Feb 24 16:42:58 coscia-volumio ntpd[979]: Soliciting pool server 142.202.190.19 Feb 24 16:42:58 coscia-volumio ntpd[979]: Soliciting pool server 207.192.69.118 Feb 24 16:42:58 coscia-volumio ntpd[979]: Soliciting pool server 69.89.207.99 Feb 24 16:42:58 coscia-volumio ntpd[979]: Soliciting pool server 129.250.35.250 Feb 24 16:42:58 coscia-volumio ntpd[979]: Soliciting pool server 168.235.69.132 Feb 24 16:43:01 coscia-volumio ntpd[979]: receive: Unexpected origin timestamp 0xeb676664.d88a1f18 does not match aorg 0000000000.00000000 from server@23.150.41.123 xmt 0xeb676665.8bca13ea Feb 24 16:43:01 coscia-volumio ntpd[979]: receive: Unexpected origin timestamp 0xeb676664.d887d5e7 does not match aorg 0000000000.00000000 from server@198.206.187.98 xmt 0xeb676665.8fe6ec5e Feb 24 16:43:01 coscia-volumio ntpd[979]: receive: Unexpected origin timestamp 0xeb676664.d8895114 does not match aorg 0000000000.00000000 from server@104.131.139.195 xmt 0xeb676665.90136b6d Feb 24 16:43:01 coscia-volumio ntpd[979]: receive: Unexpected origin timestamp 0xeb676664.d88c13f3 does not match aorg 0000000000.00000000 from server@137.190.2.4 xmt 0xeb676665.91ded3ee Feb 24 16:43:04 coscia-volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 16:43:04 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 24 16:43:05 coscia-volumio volumio[1260]: Disk list : [ { device: 'nvme0n1', name: 'NVMe', size: '931.5G' } ] Feb 24 16:43:05 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 24 16:43:05 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 24 16:43:05 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 24 16:43:05 coscia-volumio volumio[1260]: info: Received Get System Version Feb 24 16:43:05 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::Reload Ui Feb 24 16:43:06 coscia-volumio sudo[1884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 16:43:06 coscia-volumio sudo[1884]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:06 coscia-volumio sudo[1884]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:06 coscia-volumio sudo[1887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 16:43:06 coscia-volumio sudo[1887]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:06 coscia-volumio sudo[1887]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:06 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 24 16:43:06 coscia-volumio sudo[1890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 16:43:06 coscia-volumio sudo[1890]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:06 coscia-volumio sudo[1890]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:06 coscia-volumio sudo[1892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 16:43:06 coscia-volumio sudo[1892]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:06 coscia-volumio sudo[1892]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 24 16:43:06 coscia-volumio volumio[1260]: xcb_connection_has_error() returned true Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Received Get System Info Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Discovery: Getting this device information Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 16:43:06 coscia-volumio volumio-remote-updater[791]: No test mode Feb 24 16:43:06 coscia-volumio volumio-remote-updater[791]: No alpha test mode Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 24 16:43:06 coscia-volumio volumio[1260]: verbose: New Socket.io Connection to 192.168.4.183 from 192.168.4.137 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Retrieving Cloud Streaming UI Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Getting Tidal Cloud Configuration Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Getting Qobuz Cloud Configuration Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Asking plugin for UI Config Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Getting Spotify Cloud Configuration Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Asking plugin for UI Config Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Saving Spotify Acccount Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Got it Feb 24 16:43:06 coscia-volumio volumio[1260]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Got Tidal Cloud Configuration Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Got it Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 24 16:43:06 coscia-volumio volumio[1260]: xcb_connection_has_error() returned true Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Received Get System Info Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Discovery: Getting this device information Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Retrieving Cloud Streaming UI Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Getting Tidal Cloud Configuration Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Getting Qobuz Cloud Configuration Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Asking plugin for UI Config Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Getting Spotify Cloud Configuration Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Asking plugin for UI Config Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Saving Spotify Acccount Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Got it Feb 24 16:43:06 coscia-volumio volumio[1260]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Got Tidal Cloud Configuration Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Got it Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 16:43:06 coscia-volumio volumio-remote-updater[791]: No test mode Feb 24 16:43:06 coscia-volumio volumio-remote-updater[791]: No alpha test mode Feb 24 16:43:06 coscia-volumio volumio[1260]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 24 16:43:06 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 24 16:43:07 coscia-volumio volumio[1260]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 24 16:43:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 24 16:43:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 24 16:43:07 coscia-volumio volumio[1260]: info: Loading i18n strings for locale en Feb 24 16:43:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 24 16:43:07 coscia-volumio volumio[1260]: info: Setting timezone to America/New_York Feb 24 16:43:07 coscia-volumio sudo[1918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 24 16:43:07 coscia-volumio sudo[1918]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:07 coscia-volumio sudo[1918]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:07 coscia-volumio sudo[1921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 24 16:43:07 coscia-volumio sudo[1921]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:07 coscia-volumio sudo[1921]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:07 coscia-volumio sudo[1924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 24 16:43:07 coscia-volumio sudo[1924]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:07 coscia-volumio sudo[1924]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:07 coscia-volumio sudo[1927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 24 16:43:07 coscia-volumio sudo[1927]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:07 coscia-volumio dbus-daemon[778]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.46' (uid=0 pid=1928 comm="/usr/bin/timedatectl set-timezone America/New_York") Feb 24 16:43:07 coscia-volumio systemd[1]: Starting Time & Date Service... Feb 24 16:43:07 coscia-volumio dbus-daemon[778]: [system] Successfully activated service 'org.freedesktop.timedate1' Feb 24 16:43:07 coscia-volumio systemd[1]: Started Time & Date Service. Feb 24 16:43:07 coscia-volumio sudo[1927]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:07 coscia-volumio volumio[1260]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 16:43:07 coscia-volumio volumio[1260]: Updating browse sources language Feb 24 16:43:07 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:43:08 coscia-volumio volumio[1260]: info: Fetching Streaming Services browse cache Feb 24 16:43:08 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 24 16:43:08 coscia-volumio volumio[1260]: info: Loading i18n strings for locale en Feb 24 16:43:08 coscia-volumio volumio[1260]: Updating browse sources language Feb 24 16:43:08 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:43:08 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 24 16:43:08 coscia-volumio volumio[1260]: info: Setting timezone to Etc/GMT+5 Feb 24 16:43:08 coscia-volumio sudo[1931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 24 16:43:08 coscia-volumio sudo[1931]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:08 coscia-volumio sudo[1931]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:08 coscia-volumio sudo[1934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Etc/GMT+5 /etc/localtime Feb 24 16:43:08 coscia-volumio sudo[1934]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:08 coscia-volumio sudo[1934]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:08 coscia-volumio sudo[1937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 24 16:43:08 coscia-volumio sudo[1937]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:08 coscia-volumio sudo[1937]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:08 coscia-volumio sudo[1940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Etc/GMT+5 Feb 24 16:43:08 coscia-volumio sudo[1940]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:08 coscia-volumio systemd-timedated[1929]: Failed to set time zone: Permission denied Feb 24 16:43:08 coscia-volumio sudo[1940]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:08 coscia-volumio volumio[1260]: Failed to set time zone: Access denied Feb 24 16:43:08 coscia-volumio volumio[1260]: info: Could not set timezone, retrying Feb 24 16:43:08 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 16:43:08 coscia-volumio volumio[1260]: info: Fetching Streaming Services browse cache Feb 24 16:43:08 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 16:43:09 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 24 16:43:09 coscia-volumio volumio[1260]: info: Loading i18n strings for locale en Feb 24 16:43:09 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 24 16:43:09 coscia-volumio volumio[1260]: info: Setting timezone to America/New_York Feb 24 16:43:09 coscia-volumio sudo[1943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 24 16:43:09 coscia-volumio sudo[1943]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:09 coscia-volumio sudo[1943]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:09 coscia-volumio sudo[1946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 24 16:43:09 coscia-volumio sudo[1946]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:09 coscia-volumio sudo[1946]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:09 coscia-volumio sudo[1949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 24 16:43:09 coscia-volumio sudo[1949]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:09 coscia-volumio sudo[1949]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:09 coscia-volumio sudo[1952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 24 16:43:09 coscia-volumio sudo[1952]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:09 coscia-volumio systemd-timedated[1929]: Failed to set time zone: Permission denied Feb 24 16:43:09 coscia-volumio sudo[1952]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:09 coscia-volumio volumio[1260]: Failed to set time zone: Access denied Feb 24 16:43:09 coscia-volumio volumio[1260]: info: Could not set timezone, retrying Feb 24 16:43:09 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 24 16:43:09 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 24 16:43:09 coscia-volumio sudo[1955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 24 16:43:09 coscia-volumio sudo[1955]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:09 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 16:43:09 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 16:43:09 coscia-volumio volumio[1260]: Updating browse sources language Feb 24 16:43:09 coscia-volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 16:43:09 coscia-volumio sudo[1958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Etc/GMT+5 Feb 24 16:43:09 coscia-volumio sudo[1958]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 16:43:09 coscia-volumio systemd-timedated[1929]: Failed to set time zone: Permission denied Feb 24 16:43:09 coscia-volumio sudo[1958]: pam_unix(sudo:session): session closed for user root Feb 24 16:43:09 coscia-volumio volumio[1260]: Failed to set time zone: Access denied Feb 24 16:43:09 coscia-volumio volumio[1260]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 16:43:09 coscia-volumio volumio[1260]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Etc/GMT+5' Feb 24 16:43:09 coscia-volumio volumio[1260]: Failed to set time zone: Access denied Feb 24 16:43:09 coscia-volumio volumio[1260]: at checkExecSyncError (child_process.js:616:11) Feb 24 16:43:09 coscia-volumio volumio[1260]: at execSync (child_process.js:652:15) Feb 24 16:43:09 coscia-volumio volumio[1260]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9) Feb 24 16:43:09 coscia-volumio volumio[1260]: at listOnTimeout (internal/timers.js:554:17) Feb 24 16:43:09 coscia-volumio volumio[1260]: at processTimers (internal/timers.js:497:7) { Feb 24 16:43:09 coscia-volumio volumio[1260]: status: 1, Feb 24 16:43:09 coscia-volumio volumio[1260]: signal: null, Feb 24 16:43:09 coscia-volumio volumio[1260]: output: [ null, '', 'Failed to set time zone: Access denied\n' ], Feb 24 16:43:09 coscia-volumio volumio[1260]: pid: 1957, Feb 24 16:43:09 coscia-volumio volumio[1260]: stdout: '', Feb 24 16:43:09 coscia-volumio volumio[1260]: stderr: 'Failed to set time zone: Access denied\n' Feb 24 16:43:09 coscia-volumio volumio[1260]: } Feb 24 16:43:09 coscia-volumio volumio[1260]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 16:43:09 coscia-volumio sudo[1969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-24 16:42 Feb 24 16:43:09 coscia-volumio sudo[1969]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"