-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2024-12-02 22:50:44 CET. -- Dec 02 22:49:07 volumio-raspi3b systemd-timedated[1046]: Changed local time to Mon Dec 2 22:49:07 2024 Dec 02 22:49:07 volumio-raspi3b sudo[1044]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:07 volumio-raspi3b volumio-time-update[659]: volumio-time-update-util: System time updated successfully. Dec 02 22:49:07 volumio-raspi3b systemd[1]: Starting Daily man-db regeneration... Dec 02 22:49:07 volumio-raspi3b systemd[1]: Starting Daily apt download activities... Dec 02 22:49:07 volumio-raspi3b systemd[1]: Started Volumio Time Update Utility. Dec 02 22:49:07 volumio-raspi3b systemd[1]: Reached target Multi-User System. Dec 02 22:49:07 volumio-raspi3b systemd[1]: Reached target Graphical Interface. Dec 02 22:49:07 volumio-raspi3b systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 02 22:49:07 volumio-raspi3b systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 02 22:49:07 volumio-raspi3b systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 02 22:49:07 volumio-raspi3b systemd[1]: man-db.service: Succeeded. Dec 02 22:49:07 volumio-raspi3b systemd[1]: Started Daily man-db regeneration. Dec 02 22:49:07 volumio-raspi3b nmbd[785]: [2024/12/02 22:49:07.637864, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 02 22:49:07 volumio-raspi3b nmbd[785]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.48 for name WORKGROUP<1d>. Dec 02 22:49:07 volumio-raspi3b nmbd[785]: This response was from IP 192.168.2.138, reporting an IP address of 192.168.2.138. Dec 02 22:49:08 volumio-raspi3b volumio-remote-updater[671]: [2024-12-02 22:49:08] [connect] Successful connection Dec 02 22:49:09 volumio-raspi3b volumio[1011]: info: ------------------------------------------- Dec 02 22:49:09 volumio-raspi3b volumio[1011]: info: ----- Volumio3 ---- Dec 02 22:49:09 volumio-raspi3b volumio[1011]: info: ------------------------------------------- Dec 02 22:49:09 volumio-raspi3b volumio[1011]: info: ----- System startup ---- Dec 02 22:49:09 volumio-raspi3b volumio[1011]: info: ------------------------------------------- Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: MYVOLUMIO Environment detected Dec 02 22:49:12 volumio-raspi3b systemd[1]: apt-daily.service: Succeeded. Dec 02 22:49:12 volumio-raspi3b systemd[1]: Started Daily apt download activities. Dec 02 22:49:12 volumio-raspi3b systemd[1]: Starting Daily apt upgrade and clean activities... Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Plugin folders cleanup Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Scanning into folder /volumio/app/plugins/ Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Scanning category audio_interface Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Scanning category miscellanea Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Scanning category music_service Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Scanning category plugins.json Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Scanning category system_controller Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Scanning category user_interface Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Scanning into folder /data/plugins/ Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Scanning category audio_interface Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Scanning category music_service Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Scanning category system_controller Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Scanning category system_hardware Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Scanning category user_interface Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Plugin folders cleanup completed Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: ------------------------------------------- Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: ----- Core plugins startup ---- Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: ------------------------------------------- Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Loading plugins from folder /volumio/app/plugins/ Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Adding plugin upnp to MyMusic Plugins Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Loading plugins from folder /data/plugins/ Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Loading plugin "system"... Dec 02 22:49:12 volumio-raspi3b systemd[1]: systemd-fsckd.service: Succeeded. Dec 02 22:49:12 volumio-raspi3b volumio[1011]: info: Loading plugin "appearance"... Dec 02 22:49:14 volumio-raspi3b systemd[1]: apt-daily-upgrade.service: Succeeded. Dec 02 22:49:14 volumio-raspi3b systemd[1]: Started Daily apt upgrade and clean activities. Dec 02 22:49:14 volumio-raspi3b systemd[1]: Startup finished in 14.413s (kernel) + 36.388s (userspace) = 50.802s. Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: Loading plugin "network"... Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: Refreshing Cached IP Addresses Dec 02 22:49:14 volumio-raspi3b sudo[1169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 22:49:14 volumio-raspi3b sudo[1169]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:14 volumio-raspi3b sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: Loading plugin "services"... Dec 02 22:49:14 volumio-raspi3b sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: Loading plugin "alsa_controller"... Dec 02 22:49:14 volumio-raspi3b sudo[1169]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:14 volumio-raspi3b sudo[1167]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:14 volumio-raspi3b sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 02 22:49:14 volumio-raspi3b sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: Loading plugin "wizard"... Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: Loading plugin "networkfs"... Dec 02 22:49:14 volumio-raspi3b sh[523]: timed out Dec 02 22:49:14 volumio-raspi3b dhcpcd[554]: timed out Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: Starting Udev Watcher for removable devices Dec 02 22:49:14 volumio-raspi3b sh[523]: dhcpcd exited Dec 02 22:49:14 volumio-raspi3b dhcpcd[554]: dhcpcd exited Dec 02 22:49:14 volumio-raspi3b sh[523]: ifup: failed to bring up eth0 Dec 02 22:49:14 volumio-raspi3b systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 02 22:49:14 volumio-raspi3b systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: Ignoring mount for partition: boot Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: Ignoring mount for partition: volumio Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: Ignoring mount for partition: volumio_data Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: Loading plugin "volumio_command_line_client"... Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: Loading plugin "upnp"... Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: [1733176154746] Starting Upmpd Daemon Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: Loading plugin "my_music"... Dec 02 22:49:14 volumio-raspi3b volumio[1011]: info: Loading plugin "mpd"... Dec 02 22:49:14 volumio-raspi3b sudo[1194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=gizzione,password=!!Quala726509##445,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.50/Media/02-Music/Music-HighRes/HighRes-Flac /mnt/NAS/E-Home-Media-HighRes Dec 02 22:49:14 volumio-raspi3b sudo[1194]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:14 volumio-raspi3b kernel: hwmon hwmon1: Voltage normalised Dec 02 22:49:15 volumio-raspi3b kernel: Key type cifs.spnego registered Dec 02 22:49:15 volumio-raspi3b kernel: Key type cifs.idmap registered Dec 02 22:49:15 volumio-raspi3b 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. Dec 02 22:49:15 volumio-raspi3b kernel: CIFS: Attempting to mount //192.168.2.50/Media/02-Music/Music-HighRes/HighRes-Flac Dec 02 22:49:15 volumio-raspi3b kernel: cryptd: max_cpu_qlen set to 1000 Dec 02 22:49:15 volumio-raspi3b volumio[1011]: info: Loading plugin "upnp_browser"... Dec 02 22:49:15 volumio-raspi3b sudo[1176]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:15 volumio-raspi3b sudo[1194]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:15 volumio-raspi3b volumio[1011]: info: Loading plugin "alarm-clock"... Dec 02 22:49:16 volumio-raspi3b volumio[1011]: info: Loading plugin "airplay_emulation"... Dec 02 22:49:16 volumio-raspi3b volumio[1011]: info: Starting Shairport Sync Dec 02 22:49:16 volumio-raspi3b volumio[1011]: info: Loading plugin "last_100"... Dec 02 22:49:16 volumio-raspi3b volumio[1011]: info: Loading plugin "webradio"... Dec 02 22:49:16 volumio-raspi3b volumio[1011]: info: Loading plugin "i2s_dacs"... Dec 02 22:49:16 volumio-raspi3b volumio[1011]: info: Loading plugin "volumiodiscovery"... Dec 02 22:49:16 volumio-raspi3b volumio[1011]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 02 22:49:16 volumio-raspi3b volumio[1011]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 22:49:16 volumio-raspi3b volumio[1011]: *** WARNING *** For more information see Dec 02 22:49:16 volumio-raspi3b volumio[1011]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 02 22:49:16 volumio-raspi3b volumio[1011]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 22:49:16 volumio-raspi3b volumio[1011]: *** WARNING *** For more information see Dec 02 22:49:16 volumio-raspi3b node[1011]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 02 22:49:16 volumio-raspi3b node[1011]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 22:49:16 volumio-raspi3b node[1011]: *** WARNING *** For more information see Dec 02 22:49:16 volumio-raspi3b node[1011]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 02 22:49:16 volumio-raspi3b node[1011]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 22:49:16 volumio-raspi3b node[1011]: *** WARNING *** For more information see Dec 02 22:49:16 volumio-raspi3b volumio[1011]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 02 22:49:16 volumio-raspi3b volumio[1011]: info: Discovery: Started advertising with name: Volumio-Raspi3b+ Dec 02 22:49:16 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 02 22:49:16 volumio-raspi3b volumio[1011]: info: Loading plugin "squeezelite_mc"... Dec 02 22:49:17 volumio-raspi3b volumio[1011]: info: Loading plugin "now_playing"... Dec 02 22:49:18 volumio-raspi3b systemd[1]: systemd-hostnamed.service: Succeeded. Dec 02 22:49:19 volumio-raspi3b volumio[1011]: info: Loading plugin "outputs"... Dec 02 22:49:19 volumio-raspi3b volumio[1011]: info: Loading plugin "albumart"... Dec 02 22:49:19 volumio-raspi3b volumio[1011]: info: Plugin example_plugin is not enabled Dec 02 22:49:19 volumio-raspi3b volumio[1011]: info: Loading plugin "inputs"... Dec 02 22:49:19 volumio-raspi3b volumio[1011]: info: Loading plugin "updater_comm"... Dec 02 22:49:20 volumio-raspi3b volumio[1011]: info: Plugin mpdemulation is not enabled Dec 02 22:49:20 volumio-raspi3b volumio[1011]: info: Loading plugin "rest_api"... Dec 02 22:49:20 volumio-raspi3b volumio[1011]: info: Loading plugin "websocket"... Dec 02 22:49:20 volumio-raspi3b volumio[1011]: info: Starting Socket.io Server version 2.3.0 Dec 02 22:49:20 volumio-raspi3b volumio[1011]: info: Plugin fusiondsp is not enabled Dec 02 22:49:20 volumio-raspi3b volumio[1011]: info: Loading plugin "RoonBridge"... Dec 02 22:49:20 volumio-raspi3b volumio[1011]: Forking 3 albumart workers Dec 02 22:49:20 volumio-raspi3b volumio[1011]: info: Applying required configuration parameters for plugin RoonBridge Dec 02 22:49:20 volumio-raspi3b volumio[1011]: info: Loading plugin "minidlna"... Dec 02 22:49:20 volumio-raspi3b volumio[1011]: info: Applying required configuration parameters for plugin minidlna Dec 02 22:49:21 volumio-raspi3b volumio[1011]: info: Loading plugin "plexamp"... Dec 02 22:49:21 volumio-raspi3b volumio[1011]: Starting albumart workers Dec 02 22:49:21 volumio-raspi3b volumio[1011]: Starting albumart workers Dec 02 22:49:21 volumio-raspi3b volumio[1011]: Starting albumart workers Dec 02 22:49:23 volumio-raspi3b volumio-remote-updater[671]: [2024-12-02 22:49:23] [connect] Successful connection Dec 02 22:49:23 volumio-raspi3b volumio[1011]: info: Applying required configuration parameters for plugin plexamp Dec 02 22:49:23 volumio-raspi3b volumio[1011]: info: Loading plugin "volroon"... Dec 02 22:49:24 volumio-raspi3b volumio[1011]: info: Applying required configuration parameters for plugin volroon Dec 02 22:49:24 volumio-raspi3b volumio[1011]: info: Loading plugin "backup_restore"... Dec 02 22:49:24 volumio-raspi3b volumio[1011]: info: Applying required configuration parameters for plugin backup_restore Dec 02 22:49:24 volumio-raspi3b volumio[1011]: info: Plugin mpd_oled is not enabled Dec 02 22:49:24 volumio-raspi3b volumio[1011]: info: Loading plugin "Systeminfo"... Dec 02 22:49:25 volumio-raspi3b volumio[1011]: info: Loading plugin "lastfm"... Dec 02 22:49:25 volumio-raspi3b kernel: hwmon hwmon1: Undervoltage detected! Dec 02 22:49:26 volumio-raspi3b volumio[1011]: info: Plugin touch_display is not enabled Dec 02 22:49:26 volumio-raspi3b volumio[1011]: info: Loading i18n strings for locale de Dec 02 22:49:26 volumio-raspi3b volumio[1011]: Updating browse sources language Dec 02 22:49:26 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 22:49:26 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 22:49:26 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::initPlayerControls Dec 02 22:49:26 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:26 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:26 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:26 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:26 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:26 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:26 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:26 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:26 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 22:49:26 volumio-raspi3b volumio[1011]: Express server listening on port 3000 Dec 02 22:49:26 volumio-raspi3b volumio[1011]: [Metrics] WebUI: 18s 861.02ms Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreStateMachine::resetVolumioState Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreStateMachine::getcurrentVolume Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioRetrievevolume Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreStateMachine::pushState Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioPushState Dec 02 22:49:27 volumio-raspi3b sudo[1313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 02 22:49:27 volumio-raspi3b sudo[1313]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:27 volumio-raspi3b sudo[1313]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:27 volumio-raspi3b sudo[1315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 22:49:27 volumio-raspi3b sudo[1315]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: Volumio Network Manager: Network status updated: 2 Dec 02 22:49:27 volumio-raspi3b sudo[1315]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:27 volumio-raspi3b volumio-remote-updater[671]: [2024-12-02 22:49:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1733176163 101 Dec 02 22:49:27 volumio-raspi3b volumio[1011]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioGetState Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:49:27 volumio-raspi3b volumio[1011]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreStateMachine::setRepeat false single undefined Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreStateMachine::pushState Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioPushState Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreStateMachine::setRandom false Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreStateMachine::pushState Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioPushState Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: Setting Device type: Raspberry PI Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: Listing playlists Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: Listing playlists Dec 02 22:49:27 volumio-raspi3b sudo[1324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 02 22:49:27 volumio-raspi3b sudo[1324]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: Discovery: adding 3b086e49-089c-4b37-bf08-1474d0557056 Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: Discovery: Found device Volumio-Raspi3b+ Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioGetState Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:49:27 volumio-raspi3b systemd[1]: Started UPnP Renderer front-end to MPD. Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: Completed loading Core Plugins Dec 02 22:49:27 volumio-raspi3b volumio[1011]: info: Preparing to generate the ALSA configuration file Dec 02 22:49:27 volumio-raspi3b sudo[1324]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioGetState Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: Upmpdcli Daemon Started Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: Asound.conf file written Dec 02 22:49:28 volumio-raspi3b sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 02 22:49:28 volumio-raspi3b sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:28 volumio-raspi3b sudo[1340]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:28 volumio-raspi3b volumio[1326]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: Output device has changed, restarting MPD Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: Output device has changed, restarting Shairport Sync Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: volroon::Output device has changed Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: ___________ START PLUGINS ___________ Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: ControllerMpd::onStart: Initializing MPD Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: Creating MPD Configuration file Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: [1733176168524] CoreMusicLibrary::Adding element Medienserver Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: [1733176168651] CoreMusicLibrary::Adding element Last_100 Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: [1733176168655] CoreMusicLibrary::Adding element Webradio Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: Initializing BBC Radios Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Starting proxy server... Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: [now-playing] ConfigUpdater: config is up to date. Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: Loading i18n strings for locale de Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: Loading i18n strings for locale de Dec 02 22:49:28 volumio-raspi3b volumio[1011]: Updating browse sources language Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Dec 02 22:49:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: volroon::Roon API Services Started Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: [1733176169057] CoreMusicLibrary::Adding element LastFM Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 22:49:29 volumio-raspi3b volumio[1011]: Cannot find translation for source LastFM Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: [LastFM] scrobbler initiated! Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: [LastFM] extended logging: false Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: [LastFM] try scrobble stream/radio plays: true Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: [LastFM] Left init routine Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: [LastFM] Socket already connected: true Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: Volumio Calling Home Dec 02 22:49:29 volumio-raspi3b sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 02 22:49:29 volumio-raspi3b sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:29 volumio-raspi3b sudo[1381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Dec 02 22:49:29 volumio-raspi3b sudo[1381]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:29 volumio-raspi3b sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 02 22:49:29 volumio-raspi3b sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:29 volumio-raspi3b sudo[1346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 02 22:49:29 volumio-raspi3b sudo[1355]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:29 volumio-raspi3b sudo[1346]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Proxy server started on port 39441 Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: PlexAmp::Plex initialised[object Object] Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 22:49:29 volumio-raspi3b sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: [1733176169201] CoreMusicLibrary::Adding element Plex Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 22:49:29 volumio-raspi3b volumio[1011]: Cannot find translation for source LastFM Dec 02 22:49:29 volumio-raspi3b sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:29 volumio-raspi3b volumio[1011]: Cannot find translation for source Plex Dec 02 22:49:29 volumio-raspi3b sudo[1375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Dec 02 22:49:29 volumio-raspi3b sudo[1375]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:29 volumio-raspi3b sudo[1346]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: [now-playing] Fetching API key... Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioRetrievevolume Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: [now-playing] App is listening on port 4004. Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: CoreStateMachine::pushState Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioPushState Dec 02 22:49:29 volumio-raspi3b volumio[1011]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Server discovery started Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Player finder started Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:29 volumio-raspi3b systemd[1]: Started RoonBridge. Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 02 22:49:29 volumio-raspi3b sudo[1375]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:29 volumio-raspi3b systemd[1]: Stopping Music Player Daemon... Dec 02 22:49:29 volumio-raspi3b sudo[1381]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:29 volumio-raspi3b systemd[1]: mpd.service: Succeeded. Dec 02 22:49:29 volumio-raspi3b systemd[1]: Stopped Music Player Daemon. Dec 02 22:49:29 volumio-raspi3b systemd[1]: Starting Music Player Daemon... Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: minidlna: Found miniDLNA version 1.2.1 Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: minidlna: Starting minidlna.service Dec 02 22:49:29 volumio-raspi3b volumio[1011]: info: MPD Permissions set Dec 02 22:49:30 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Card 5 supports the following ALSA formats: ["S16_LE","S32_LE","SPECIAL","DSD_U32_BE"] Dec 02 22:49:30 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio-raspi3b","dsdFormat":"dop","card":"5","mixerType":"None","mixer":null} Dec 02 22:49:30 volumio-raspi3b sudo[1403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Dec 02 22:49:30 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Dec 02 22:49:30 volumio-raspi3b sudo[1403]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:30 volumio-raspi3b volumio[1011]: info: ::Roon Bridge has successfully started Dec 02 22:49:30 volumio-raspi3b volumio[1011]: info: Volumio called home Dec 02 22:49:30 volumio-raspi3b sudo[1395]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 02 22:49:30 volumio-raspi3b sudo[1395]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:30 volumio-raspi3b sudo[1395]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:30 volumio-raspi3b systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Dec 02 22:49:30 volumio-raspi3b sudo[1403]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:30 volumio-raspi3b sudo[1407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Dec 02 22:49:30 volumio-raspi3b sudo[1407]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:30 volumio-raspi3b sudo[1407]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:30 volumio-raspi3b volumio[1011]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 02 22:49:30 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:30 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:30 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:30 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:30 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:30 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:30 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:30 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:30 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:30 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:30 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:49:31 volumio-raspi3b volumio[1011]: info: [LastFM] finished init: Authenticated successfully! Dec 02 22:49:31 volumio-raspi3b volumio[1011]: info: minidlna: systemctl start minidlna.service succeeded. Dec 02 22:49:31 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Dec 02 22:49:32 volumio-raspi3b sudo[1427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Dec 02 22:49:32 volumio-raspi3b sudo[1427]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:32 volumio-raspi3b sudo[1427]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:32 volumio-raspi3b volumio[1011]: error: Upnp client error: Error: This socket has been ended by the other party Dec 02 22:49:32 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Dec 02 22:49:32 volumio-raspi3b volumio[1011]: info: Starting Shairport Sync Dec 02 22:49:32 volumio-raspi3b volumio[1011]: info: Starting Shairport Sync Dec 02 22:49:32 volumio-raspi3b sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 22:49:32 volumio-raspi3b sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:32 volumio-raspi3b volumio[1011]: info: Starting Shairport Sync Dec 02 22:49:32 volumio-raspi3b sudo[1435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 22:49:32 volumio-raspi3b sudo[1435]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:32 volumio-raspi3b systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 22:49:32 volumio-raspi3b systemd[1]: shairport-sync.service: Succeeded. Dec 02 22:49:32 volumio-raspi3b systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 02 22:49:32 volumio-raspi3b sudo[1431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Dec 02 22:49:32 volumio-raspi3b systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 22:49:32 volumio-raspi3b sudo[1431]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:32 volumio-raspi3b sudo[1439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 22:49:32 volumio-raspi3b sudo[1439]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:32 volumio-raspi3b sudo[1431]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:32 volumio-raspi3b sudo[1433]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:32 volumio-raspi3b systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 22:49:32 volumio-raspi3b sudo[1435]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:32 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioGetState Dec 02 22:49:32 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:49:32 volumio-raspi3b systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 02 22:49:32 volumio-raspi3b systemd[1]: shairport-sync.service: Succeeded. Dec 02 22:49:32 volumio-raspi3b systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 02 22:49:33 volumio-raspi3b systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 22:49:33 volumio-raspi3b sudo[1439]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:33 volumio-raspi3b volumio[1011]: info: Shairport-Sync Started Dec 02 22:49:33 volumio-raspi3b volumio[1011]: Error adding Membership: Error: addMembership EINVAL Dec 02 22:49:33 volumio-raspi3b volumio[1011]: info: Shairport-Sync Started Dec 02 22:49:33 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Dec 02 22:49:33 volumio-raspi3b sudo[1448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 02 22:49:33 volumio-raspi3b sudo[1448]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:33 volumio-raspi3b volumio[1011]: info: Shairport-Sync Started Dec 02 22:49:33 volumio-raspi3b volroon[1391]: 00:00:00.043 Warn: get lock file path: /tmp/.rnbgem0- Dec 02 22:49:33 volumio-raspi3b systemd[1]: Reloading. Dec 02 22:49:34 volumio-raspi3b mpd[1411]: Dec 02 22:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 02 22:49:34 volumio-raspi3b volumio[1011]: info: [now-playing] OpenWeatherMap API key obtained. Dec 02 22:49:34 volumio-raspi3b volumio[1011]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=51.6754119&lon=6.178837: Error: Response error: 401 - Unauthorized Dec 02 22:49:34 volumio-raspi3b volumio[1011]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Dec 02 22:49:34 volumio-raspi3b volumio[1011]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 02 22:49:34 volumio-raspi3b volumio[1011]: at async Promise.all (index 0) Dec 02 22:49:34 volumio-raspi3b volumio[1011]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Dec 02 22:49:34 volumio-raspi3b volumio[1011]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Dec 02 22:49:34 volumio-raspi3b volumio[1011]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Dec 02 22:49:34 volumio-raspi3b volumio[1011]: info: [now-playing] Fetching API key... Dec 02 22:49:34 volumio-raspi3b volumio[1011]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=51.6754119&lon=6.178837: Error: Response error: 401 - Unauthorized Dec 02 22:49:34 volumio-raspi3b volumio[1011]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Dec 02 22:49:34 volumio-raspi3b volumio[1011]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 02 22:49:34 volumio-raspi3b volumio[1011]: at async Promise.all (index 1) Dec 02 22:49:34 volumio-raspi3b volroon[1391]: 00:00:01.418 Trace: [childprocess] using unix child process Dec 02 22:49:34 volumio-raspi3b systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 02 22:49:35 volumio-raspi3b volroon[1391]: 00:00:01.782 Debug: PathForResource, filename: ../.update Dec 02 22:49:35 volumio-raspi3b volumio[1011]: Upnp client error: Error: This socket has been ended by the other party Dec 02 22:49:35 volumio-raspi3b volroon[1391]: 00:00:01.916 Debug: PathForResource, candidate: /data/plugins/music_service/volroon/RoonBridge/Bridge/../.update Dec 02 22:49:35 volumio-raspi3b volroon[1391]: 00:00:01.936 Debug: PathForResource, filename: RAATServer Dec 02 22:49:35 volumio-raspi3b volroon[1391]: 00:00:01.936 Debug: PathForResource, candidate: /data/plugins/music_service/volroon/RoonBridge/Bridge/RAATServer Dec 02 22:49:35 volumio-raspi3b volroon[1391]: 00:00:01.938 Debug: PathForResource, filename: RoonBridgeHelper Dec 02 22:49:35 volumio-raspi3b volroon[1391]: 00:00:01.938 Debug: PathForResource, candidate: /data/plugins/music_service/volroon/RoonBridge/Bridge/RoonBridgeHelper Dec 02 22:49:35 volumio-raspi3b volroon[1391]: Initializing Dec 02 22:49:35 volumio-raspi3b volroon[1391]: 00:00:02.059 Info: Starting /data/plugins/music_service/volroon/RoonBridge/Bridge/RoonBridgeHelper Dec 02 22:49:35 volumio-raspi3b volroon[1391]: 00:00:02.142 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/volroon/RoonBridge/Bridge/RAATServer Dec 02 22:49:35 volumio-raspi3b systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Dec 02 22:49:35 volumio-raspi3b volroon[1391]: Not Running (.o) Dec 02 22:49:36 volumio-raspi3b volumio[1011]: info: [now-playing] OpenWeatherMap API key obtained. Dec 02 22:49:36 volumio-raspi3b systemd[1]: Started Music Player Daemon. Dec 02 22:49:36 volumio-raspi3b sudo[1348]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:36 volumio-raspi3b sudo[1448]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:36 volumio-raspi3b sudo[1357]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:36 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 02 22:49:36 volumio-raspi3b volumio[1326]: .............................++++ Dec 02 22:49:36 volumio-raspi3b sudo[1501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 02 22:49:36 volumio-raspi3b sudo[1501]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:36 volumio-raspi3b volumio[1011]: info: MPD running with PID1411 Dec 02 22:49:36 volumio-raspi3b volumio[1011]: ,establishing connection Dec 02 22:49:36 volumio-raspi3b volroon[1391]: Running Dec 02 22:49:37 volumio-raspi3b sudo[1501]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:37 volumio-raspi3b systemd[1]: systemd-timedated.service: Succeeded. Dec 02 22:49:37 volumio-raspi3b volumio[1011]: error: MPD error: The expression evaluated to a falsy value: Dec 02 22:49:37 volumio-raspi3b volumio[1011]: assert.ok(self.idling) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: error: The expression evaluated to a falsy value: Dec 02 22:49:37 volumio-raspi3b volumio[1011]: assert.ok(self.idling) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: error: MPD error: The expression evaluated to a falsy value: Dec 02 22:49:37 volumio-raspi3b volumio[1011]: assert.ok(self.idling) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: error: The expression evaluated to a falsy value: Dec 02 22:49:37 volumio-raspi3b volumio[1011]: assert.ok(self.idling) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Dec 02 22:49:37 volumio-raspi3b volumio[1011]: error: updateQueue error: null Dec 02 22:49:37 volumio-raspi3b volumio[1011]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=51.6754119&lon=6.178837: Error: Response error: 401 - Unauthorized Dec 02 22:49:37 volumio-raspi3b volumio[1011]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: at async Promise.all (index 0) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Dec 02 22:49:37 volumio-raspi3b sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Dec 02 22:49:37 volumio-raspi3b sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: (node:1011) UnhandledPromiseRejectionWarning: Error: Response error: 401 - Unauthorized Dec 02 22:49:37 volumio-raspi3b volumio[1011]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: at async Promise.all (index 0) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Dec 02 22:49:37 volumio-raspi3b volumio[1011]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: (node:1011) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: (node:1011) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Dec 02 22:49:37 volumio-raspi3b volumio[1011]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=51.6754119&lon=6.178837: Error: Response error: 401 - Unauthorized Dec 02 22:49:37 volumio-raspi3b volumio[1011]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 02 22:49:37 volumio-raspi3b volumio[1011]: at async Promise.all (index 1) Dec 02 22:49:37 volumio-raspi3b systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Dec 02 22:49:37 volumio-raspi3b systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Dec 02 22:49:37 volumio-raspi3b kernel: process '/opt/squeezelite/squeezelite' started with executable stack Dec 02 22:49:37 volumio-raspi3b sudo[1513]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:37 volumio-raspi3b volroon[1391]: 00:00:00.047 Warn: get lock file path: /tmp/.rnbhgem0- Dec 02 22:49:37 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioGetState Dec 02 22:49:37 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:49:38 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 02 22:49:38 volumio-raspi3b sudo[1523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 02 22:49:38 volumio-raspi3b sudo[1523]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:38 volumio-raspi3b sudo[1523]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:38 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 02 22:49:38 volumio-raspi3b sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 02 22:49:38 volumio-raspi3b sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:38 volumio-raspi3b sudo[1529]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:39 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 02 22:49:39 volumio-raspi3b sudo[1534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 02 22:49:39 volumio-raspi3b sudo[1534]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:39 volumio-raspi3b sudo[1534]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:39 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 02 22:49:39 volumio-raspi3b kernel: hwmon hwmon1: Voltage normalised Dec 02 22:49:40 volumio-raspi3b sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 02 22:49:40 volumio-raspi3b sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:40 volumio-raspi3b sudo[1538]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:40 volumio-raspi3b volumio[1011]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 02 22:49:40 volumio-raspi3b sudo[1552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 02 22:49:40 volumio-raspi3b sudo[1552]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:41 volumio-raspi3b sudo[1552]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:41 volumio-raspi3b volumio[1011]: info: Completed starting Core Plugins Dec 02 22:49:41 volumio-raspi3b volumio[1011]: info: ------------------------------------------- Dec 02 22:49:41 volumio-raspi3b volumio[1011]: info: ----- MyVolumio plugins startup ---- Dec 02 22:49:41 volumio-raspi3b volumio[1011]: info: ------------------------------------------- Dec 02 22:49:41 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 02 22:49:42 volumio-raspi3b volumio[1011]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 02 22:49:44 volumio-raspi3b volumio[1326]: .......................................................++++ Dec 02 22:49:44 volumio-raspi3b volumio[1326]: e is 65537 (0x010001) Dec 02 22:49:44 volumio-raspi3b volumio[1326]: writing RSA key Dec 02 22:49:46 volumio-raspi3b kernel: hwmon hwmon1: Undervoltage detected! Dec 02 22:49:47 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioGetState Dec 02 22:49:47 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:49:48 volumio-raspi3b volumio[1011]: info: Listing playlists Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: Adding plugin bluetooth to MyMusic Plugins Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: Adding plugin multiroom to MyMusic Plugins Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: Adding plugin metavolumio to MyMusic Plugins Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: Adding plugin cd_controller to MyMusic Plugins Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 02 22:49:49 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 02 22:49:51 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 02 22:49:51 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 02 22:49:51 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:51 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:51 volumio-raspi3b volumio[1011]: info: Starting MyVolumio Remote Streaming Endpoints Dec 02 22:49:51 volumio-raspi3b volumio[1011]: info: MyVolumio login type: Token Dec 02 22:49:51 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 02 22:49:51 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 02 22:49:53 volumio-raspi3b volumio[1011]: info: Starting Streaming Service Transparent Proxy Dec 02 22:49:53 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 02 22:49:53 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 02 22:49:53 volumio-raspi3b volumio[1011]: info: Streaming services startup Dec 02 22:49:53 volumio-raspi3b volumio[1011]: info: Starting Streaming Daemon Dec 02 22:49:53 volumio-raspi3b sudo[1679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 02 22:49:53 volumio-raspi3b sudo[1679]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:53 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 02 22:49:53 volumio-raspi3b sudo[1679]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:54 volumio-raspi3b volumio[1011]: error: Cannot start Volumio Streaming Daemon Dec 02 22:49:54 volumio-raspi3b volumio[1011]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 02 22:49:54 volumio-raspi3b volumio[1011]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 02 22:49:54 volumio-raspi3b ntpd[796]: Soliciting pool server 194.50.19.204 Dec 02 22:49:54 volumio-raspi3b ntpd[796]: Soliciting pool server 194.50.19.117 Dec 02 22:49:54 volumio-raspi3b volumio[1011]: error: MyVolumio Custom Token format not valid, refreshing it Dec 02 22:49:55 volumio-raspi3b ntpd[796]: Soliciting pool server 176.9.157.155 Dec 02 22:49:55 volumio-raspi3b ntpd[796]: Soliciting pool server 213.239.234.28 Dec 02 22:49:55 volumio-raspi3b volumio[1011]: STREAMING PROXY: Starting server on port 3245 Dec 02 22:49:55 volumio-raspi3b volumio[1011]: Node JS runtime: 14 Dec 02 22:49:55 volumio-raspi3b volumio[1011]: info: MyVolumio login type: Token Dec 02 22:49:56 volumio-raspi3b ntpd[796]: Soliciting pool server 45.9.61.155 Dec 02 22:49:56 volumio-raspi3b ntpd[796]: Soliciting pool server 144.76.59.37 Dec 02 22:49:56 volumio-raspi3b ntpd[796]: Soliciting pool server 213.172.105.106 Dec 02 22:49:56 volumio-raspi3b ntpd[796]: Soliciting pool server 128.140.37.196 Dec 02 22:49:56 volumio-raspi3b volumio[1011]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 02 22:49:57 volumio-raspi3b volumio[1011]: info: MyVolumio token set successfully Dec 02 22:49:57 volumio-raspi3b volumio[1011]: info: MYVOLUMIO: Adding device Dec 02 22:49:57 volumio-raspi3b volumio[1011]: info: MYVOLUMIO: Evaluating Server Dec 02 22:49:57 volumio-raspi3b ntpd[796]: Soliciting pool server 94.16.122.152 Dec 02 22:49:57 volumio-raspi3b ntpd[796]: Soliciting pool server 94.130.23.46 Dec 02 22:49:57 volumio-raspi3b ntpd[796]: Soliciting pool server 185.11.138.90 Dec 02 22:49:57 volumio-raspi3b ntpd[796]: Soliciting pool server 90.187.112.137 Dec 02 22:49:57 volumio-raspi3b volumio[1011]: info: MyVolumio Plan changed: premium Dec 02 22:49:57 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 02 22:49:57 volumio-raspi3b volumio[1011]: info: Removing browser output: myVolumio user plan is not superstar Dec 02 22:49:57 volumio-raspi3b volumio[1011]: info: Removing audio output: Dec 02 22:49:57 volumio-raspi3b volumio[1011]: info: MYVOLUMIO: Adding device Dec 02 22:49:57 volumio-raspi3b volumio[1011]: info: MYVOLUMIO: Evaluating Server Dec 02 22:49:57 volumio-raspi3b volumio[1011]: info: Remote config written successfully Dec 02 22:49:57 volumio-raspi3b volumio[1011]: info: Starting Tunnel 1 Dec 02 22:49:57 volumio-raspi3b volumio[1011]: info: Starting Tunnel Connection Checker Dec 02 22:49:57 volumio-raspi3b volumio[1011]: info: Completed starting MyVolumio Plugin Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: MYVolumio Device enabled Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: MyVolumio status changed Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: Streaming services startup Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: Starting Streaming Daemon Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioGetState Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 02 22:49:58 volumio-raspi3b volumio[1011]: error: [MyVolumio PluginManager] Cache data is invalid! Dec 02 22:49:58 volumio-raspi3b sudo[1737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 02 22:49:58 volumio-raspi3b sudo[1737]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:49:58 volumio-raspi3b sudo[1737]: pam_unix(sudo:session): session closed for user root Dec 02 22:49:58 volumio-raspi3b volumio[1011]: error: Cannot start Volumio Streaming Daemon Dec 02 22:49:58 volumio-raspi3b volumio[1011]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 02 22:49:58 volumio-raspi3b volumio[1011]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 02 22:49:58 volumio-raspi3b ntpd[796]: Soliciting pool server 116.203.244.102 Dec 02 22:49:58 volumio-raspi3b ntpd[796]: Soliciting pool server 2001:41d0:700:49bc::4 Dec 02 22:49:58 volumio-raspi3b ntpd[796]: Soliciting pool server 148.251.5.46 Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: Setting Geolocation for MyVolumio to eu6 Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: Setting Geolocation for MyVolumio to eu4 Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:58 volumio-raspi3b kernel: hwmon hwmon1: Voltage normalised Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: Successfully Added MyVolumio device Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: Successfully Added MyVolumio device Dec 02 22:49:58 volumio-raspi3b volumio-remote-updater[671]: Test mode enabled Dec 02 22:49:58 volumio-raspi3b volumio-remote-updater[671]: No alpha test mode Dec 02 22:49:58 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 02 22:49:59 volumio-raspi3b volumio[1011]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 02 22:49:59 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 02 22:49:59 volumio-raspi3b ntpd[796]: Soliciting pool server 62.128.1.18 Dec 02 22:49:59 volumio-raspi3b ntpd[796]: Soliciting pool server 62.169.23.198 Dec 02 22:49:59 volumio-raspi3b volumio[1011]: info: Updating MyVolumio device info Dec 02 22:49:59 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:59 volumio-raspi3b volumio[1011]: info: Updating MyVolumio device info Dec 02 22:49:59 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:59 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:59 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:49:59 volumio-raspi3b volumio[1011]: info: Successfully Updated MyVolumio device Dec 02 22:49:59 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 02 22:50:00 volumio-raspi3b volumio[1011]: info: [1733176200304] Starting BluetoothController Dec 02 22:50:00 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 02 22:50:00 volumio-raspi3b kernel: hwmon hwmon1: Undervoltage detected! Dec 02 22:50:04 volumio-raspi3b ntpd[796]: receive: Unexpected origin timestamp 0xeaf8aa0b.42f26322 does not match aorg 0000000000.00000000 from server@116.203.244.102 xmt 0xeaf8aa0c.31d36482 Dec 02 22:50:04 volumio-raspi3b ntpd[796]: receive: Unexpected origin timestamp 0xeaf8aa0b.42f4d516 does not match aorg 0000000000.00000000 from server@94.16.122.152 xmt 0xeaf8aa0c.31db8420 Dec 02 22:50:04 volumio-raspi3b ntpd[796]: receive: Unexpected origin timestamp 0xeaf8aa0b.42f70e0a does not match aorg 0000000000.00000000 from server@144.76.59.37 xmt 0xeaf8aa0c.32111e2a Dec 02 22:50:04 volumio-raspi3b ntpd[796]: receive: Unexpected origin timestamp 0xeaf8aa0b.42f10e20 does not match aorg 0000000000.00000000 from server@148.251.5.46 xmt 0xeaf8aa0c.32103b9c Dec 02 22:50:04 volumio-raspi3b ntpd[796]: receive: Unexpected origin timestamp 0xeaf8aa0b.42f5f14d does not match aorg 0000000000.00000000 from server@213.172.105.106 xmt 0xeaf8aa0c.317c5b8c Dec 02 22:50:04 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 02 22:50:04 volumio-raspi3b sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 02 22:50:04 volumio-raspi3b sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:04 volumio-raspi3b sudo[1744]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:04 volumio-raspi3b volumio[1011]: info: MRS: MultiRoom plugin initialized Dec 02 22:50:04 volumio-raspi3b volumio[1011]: info: MRS: STOPPING SNAPCLIENT Dec 02 22:50:04 volumio-raspi3b volumio[1011]: info: MRS: Snap server stop Dec 02 22:50:04 volumio-raspi3b volumio[1011]: info: MRS: STOPPING volumioStreaming Dec 02 22:50:04 volumio-raspi3b sudo[1760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 02 22:50:04 volumio-raspi3b sudo[1760]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:04 volumio-raspi3b sudo[1763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 02 22:50:04 volumio-raspi3b sudo[1763]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:04 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 02 22:50:05 volumio-raspi3b sudo[1766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 02 22:50:05 volumio-raspi3b sudo[1766]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:05 volumio-raspi3b sudo[1769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 02 22:50:05 volumio-raspi3b sudo[1769]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:05 volumio-raspi3b sudo[1760]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:05 volumio-raspi3b sudo[1769]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 02 22:50:05 volumio-raspi3b sudo[1763]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:05 volumio-raspi3b sudo[1766]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: Preparing to generate the ALSA configuration file Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: Reading ALSA contributions from plugins. Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: Successfully Updated MyVolumio device Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: MRS: Removed streaming files Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: MRS: volumioStreaming STOPPED Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: MRS: SNAPSERVER STOPPED Dec 02 22:50:05 volumio-raspi3b volumio[1011]: info: MRS: SNAPCLIENT STOPPED Dec 02 22:50:06 volumio-raspi3b sudo[1794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 02 22:50:06 volumio-raspi3b sudo[1794]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:06 volumio-raspi3b volumio[1011]: info: Asound.conf file written Dec 02 22:50:06 volumio-raspi3b systemd[1]: Started MyVolumio SSH Tunnel. Dec 02 22:50:06 volumio-raspi3b sudo[1794]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:06 volumio-raspi3b autossh[1798]: port set to 0, monitoring disabled Dec 02 22:50:06 volumio-raspi3b autossh[1798]: starting ssh (count 1) Dec 02 22:50:06 volumio-raspi3b autossh[1798]: ssh child pid is 1802 Dec 02 22:50:06 volumio-raspi3b sudo[1799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 02 22:50:06 volumio-raspi3b sudo[1799]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:06 volumio-raspi3b sudo[1799]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:06 volumio-raspi3b volumio[1011]: info: Output device has changed, restarting MPD Dec 02 22:50:06 volumio-raspi3b sudo[1807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 02 22:50:06 volumio-raspi3b volumio[1011]: info: Output device has changed, restarting Shairport Sync Dec 02 22:50:06 volumio-raspi3b sudo[1807]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:06 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:06 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:50:06 volumio-raspi3b sudo[1807]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:06 volumio-raspi3b sudo[1809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 02 22:50:06 volumio-raspi3b sudo[1809]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:06 volumio-raspi3b systemd[1]: Stopping Music Player Daemon... Dec 02 22:50:06 volumio-raspi3b volumio[1011]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 22:50:06 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Dec 02 22:50:06 volumio-raspi3b systemd[1]: mpd.service: Succeeded. Dec 02 22:50:06 volumio-raspi3b systemd[1]: Stopped Music Player Daemon. Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: volroon::Output device has changed Dec 02 22:50:07 volumio-raspi3b systemd[1]: Starting Music Player Daemon... Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: MRS: Pushing multiroomSync output for this device Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: MRS: Pushing multiroomSync output Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Adding audio output: Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Adding audio output: Dec 02 22:50:07 volumio-raspi3b sudo[1821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio-Raspi3b+ Dec 02 22:50:07 volumio-raspi3b sudo[1821]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:07 volumio-raspi3b sudo[1821]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Adding METAVOLUMIO REST API Endpoints Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Preparing CD Folders Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Adding CD REST API Endpoints Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Starting UDEV Watcher for CD Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Detecting CD presence with UDEV Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 02 22:50:07 volumio-raspi3b sudo[1817]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 02 22:50:07 volumio-raspi3b sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:07 volumio-raspi3b sudo[1817]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Adding inputs REST Endpoints Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Scanning Audio Inputs Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Checking against Known Cards name Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Adding Server instance for streaming Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 02 22:50:07 volumio-raspi3b volumio[1011]: error: Hi Res Audio Failed Login: Missing Login Data Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Refreshing TIDAL token Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Stopping AccessToken refresher cron for QOBUZ Dec 02 22:50:07 volumio-raspi3b sudo[1831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 02 22:50:07 volumio-raspi3b sudo[1831]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:07 volumio-raspi3b sudo[1831]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: AccessToken refresher cron started for QOBUZ Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Adding QOBUZ REST API Endpoints Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Bluetooth name changed to Volumio-Raspi3b+ Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: MPD Permissions set Dec 02 22:50:07 volumio-raspi3b volumio[1011]: info: Remote SSH Started Dec 02 22:50:08 volumio-raspi3b volumio[1011]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:08 volumio-raspi3b volumio[1011]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: Adding audio output: Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: Adding audio output: Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: Adding audio output: Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: Adding audio output: Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: MRS: Found cast device: cube1-airscreen[Cast] Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: Adding audio output: Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: Starting Shairport Sync Dec 02 22:50:08 volumio-raspi3b sudo[1843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 22:50:08 volumio-raspi3b sudo[1843]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:08 volumio-raspi3b systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 22:50:08 volumio-raspi3b systemd[1]: shairport-sync.service: Succeeded. Dec 02 22:50:08 volumio-raspi3b systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: Adding audio output: Dec 02 22:50:08 volumio-raspi3b systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 22:50:08 volumio-raspi3b sudo[1843]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: Adding audio output: Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioGetState Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: MRS: Found cast device: AirReceiver[Cast] Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: Adding audio output: Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: Preparing to generate the ALSA configuration file Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: MRS: Found cast device: WiiM-Pro-Receiver-d5107c7ab43619a680c168cc1e0753f4 Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: Adding audio output: Dec 02 22:50:08 volumio-raspi3b volumio[1011]: info: Access Token successfully retrieved Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioGetState Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: Shairport-Sync Started Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: Adding audio output: Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: MRS: Found cast device: SHIELD-Android-TV-85b4103629ac81a2d4d669058660012f Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: Adding audio output: Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioGetState Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:50:09 volumio-raspi3b mpd[1827]: Dec 02 22:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: Listing playlists Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: Listing playlists Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: Asound.conf file written Dec 02 22:50:09 volumio-raspi3b sudo[1849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 02 22:50:09 volumio-raspi3b sudo[1849]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:09 volumio-raspi3b sudo[1849]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: Output device has changed, restarting MPD Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: Output device has changed, restarting Shairport Sync Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:50:09 volumio-raspi3b sudo[1854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 02 22:50:09 volumio-raspi3b sudo[1854]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:09 volumio-raspi3b sudo[1854]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:09 volumio-raspi3b sudo[1856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 02 22:50:09 volumio-raspi3b sudo[1856]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:09 volumio-raspi3b systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 02 22:50:09 volumio-raspi3b systemd[1]: mpd.service: Succeeded. Dec 02 22:50:09 volumio-raspi3b systemd[1]: Stopped Music Player Daemon. Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: volroon::Output device has changed Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:09 volumio-raspi3b systemd[1]: Starting Music Player Daemon... Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: Preparing to generate the ALSA configuration file Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: Reading ALSA contributions from plugins. Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: MPD Permissions set Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: Starting Shairport Sync Dec 02 22:50:09 volumio-raspi3b sudo[1862]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 02 22:50:09 volumio-raspi3b sudo[1862]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: Asound.conf file written Dec 02 22:50:09 volumio-raspi3b sudo[1862]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:09 volumio-raspi3b sudo[1872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 22:50:09 volumio-raspi3b sudo[1872]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:09 volumio-raspi3b sudo[1876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 02 22:50:09 volumio-raspi3b sudo[1876]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:09 volumio-raspi3b sudo[1876]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:09 volumio-raspi3b systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 22:50:09 volumio-raspi3b systemd[1]: shairport-sync.service: Succeeded. Dec 02 22:50:09 volumio-raspi3b systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 02 22:50:09 volumio-raspi3b systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 22:50:09 volumio-raspi3b sudo[1872]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:09 volumio-raspi3b volumio[1011]: info: Output device has changed, restarting MPD Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: Output device has changed, restarting Shairport Sync Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:50:10 volumio-raspi3b sudo[1884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 02 22:50:10 volumio-raspi3b sudo[1884]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:10 volumio-raspi3b sudo[1884]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:10 volumio-raspi3b sudo[1887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 02 22:50:10 volumio-raspi3b sudo[1887]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:10 volumio-raspi3b systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 02 22:50:10 volumio-raspi3b systemd[1]: mpd.service: Succeeded. Dec 02 22:50:10 volumio-raspi3b systemd[1]: Stopped Music Player Daemon. Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Dec 02 22:50:10 volumio-raspi3b systemd[1]: Starting Music Player Daemon... Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: volroon::Output device has changed Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: Successfully retrieved User Session From TIDAL Dec 02 22:50:10 volumio-raspi3b sudo[1899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 02 22:50:10 volumio-raspi3b sudo[1899]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: MPD Permissions set Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: Shairport-Sync Started Dec 02 22:50:10 volumio-raspi3b sudo[1893]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 02 22:50:10 volumio-raspi3b sudo[1893]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:10 volumio-raspi3b systemd[1]: Started Volumio Bluetooth Module. Dec 02 22:50:10 volumio-raspi3b sudo[1893]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:10 volumio-raspi3b sudo[1899]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: Adding audio output: Dec 02 22:50:10 volumio-raspi3b sudo[1903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Dec 02 22:50:10 volumio-raspi3b sudo[1903]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:10 volumio-raspi3b volumiobt[1901]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Dec 02 22:50:10 volumio-raspi3b sudo[1903]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: TidalConnect service stoped! Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: Volumio BT Module successfully started Dec 02 22:50:10 volumio-raspi3b volumio[1011]: info: Starting Shairport Sync Dec 02 22:50:10 volumio-raspi3b sudo[1916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 22:50:10 volumio-raspi3b sudo[1916]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:11 volumio-raspi3b volumio[1011]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 02 22:50:11 volumio-raspi3b volumio[1011]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 02 22:50:11 volumio-raspi3b systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 22:50:11 volumio-raspi3b systemd[1]: shairport-sync.service: Succeeded. Dec 02 22:50:11 volumio-raspi3b systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 02 22:50:11 volumio-raspi3b volumio[1011]: info: Successfully retrieved User Subscription From TIDAL Dec 02 22:50:11 volumio-raspi3b volumio[1011]: info: Adding TIDAL to Browse Sources Dec 02 22:50:11 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 22:50:11 volumio-raspi3b volumio[1011]: info: [1733176211095] CoreMusicLibrary::Adding element TIDAL Dec 02 22:50:11 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 22:50:11 volumio-raspi3b volumio[1011]: Cannot find translation for source LastFM Dec 02 22:50:11 volumio-raspi3b volumio[1011]: Cannot find translation for source Plex Dec 02 22:50:11 volumio-raspi3b volumio[1011]: Cannot find translation for source TIDAL Dec 02 22:50:11 volumio-raspi3b volumio[1011]: info: Stopping AccessToken refresher cron Dec 02 22:50:11 volumio-raspi3b sudo[1924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 02 22:50:11 volumio-raspi3b sudo[1924]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:11 volumio-raspi3b systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 22:50:11 volumio-raspi3b volumio[1011]: info: AccessToken refresher cron started Dec 02 22:50:11 volumio-raspi3b volumio[1011]: info: Adding TIDAL REST API Endpoints Dec 02 22:50:11 volumio-raspi3b volumio[1011]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Dec 02 22:50:11 volumio-raspi3b sudo[1916]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:11 volumio-raspi3b volumio[1011]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 02 22:50:11 volumio-raspi3b volumio[1011]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 02 22:50:11 volumio-raspi3b systemd[1]: Started Volumio Tidal Connect Service. Dec 02 22:50:11 volumio-raspi3b sudo[1924]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:11 volumio-raspi3b volumio[1011]: info: Shairport-Sync Started Dec 02 22:50:11 volumio-raspi3b sudo[1932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Dec 02 22:50:11 volumio-raspi3b sudo[1932]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 22:50:11 volumio-raspi3b volumiobt[1901]: pulseaudio: no process found Dec 02 22:50:11 volumio-raspi3b sudo[1932]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:11 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:11 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:11 volumio-raspi3b dbus-daemon[676]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.26' (uid=1000 pid=1936 comm="/usr/bin/pulseaudio --high-priority=true ") Dec 02 22:50:11 volumio-raspi3b systemd[1]: Starting RealtimeKit Scheduling Policy Service... Dec 02 22:50:12 volumio-raspi3b volumio[1011]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 02 22:50:12 volumio-raspi3b dbus-daemon[676]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Dec 02 22:50:12 volumio-raspi3b systemd[1]: Started RealtimeKit Scheduling Policy Service. Dec 02 22:50:12 volumio-raspi3b rtkit-daemon[1937]: Successfully called chroot. Dec 02 22:50:12 volumio-raspi3b rtkit-daemon[1937]: Successfully dropped privileges. Dec 02 22:50:12 volumio-raspi3b rtkit-daemon[1937]: Successfully limited resources. Dec 02 22:50:12 volumio-raspi3b rtkit-daemon[1937]: Running. Dec 02 22:50:12 volumio-raspi3b rtkit-daemon[1937]: Canary thread running. Dec 02 22:50:12 volumio-raspi3b rtkit-daemon[1937]: Watchdog thread running. Dec 02 22:50:12 volumio-raspi3b dbus-daemon[676]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.27' (uid=0 pid=1937 comm="/usr/lib/rtkit/rtkit-daemon ") Dec 02 22:50:12 volumio-raspi3b systemd[1]: Starting Authorization Manager... Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: Executing endpoint tc_getconfig Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 02 22:50:12 volumio-raspi3b vtcs[1927]: STARTING TidalConnect services, version: 1.4.0.34 Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: MRS: Getting audio outputs on start Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: MRS: Requesting all other devices output Dec 02 22:50:12 volumio-raspi3b volumio[1011]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 02 22:50:12 volumio-raspi3b polkitd[1942]: started daemon version 0.105 using authority implementation `local' version `0.105' Dec 02 22:50:12 volumio-raspi3b dbus-daemon[676]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Dec 02 22:50:12 volumio-raspi3b systemd[1]: Started Authorization Manager. Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioGetBrowseSources Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 22:50:12 volumio-raspi3b vtcs[1927]: STARTED TidalConnect services. Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: Executing endpoint tc_connect Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: Connecting to TidalConnect Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::servicePushState Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreStateMachine::pushState Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioPushState Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: MRS: Pushing multiroomSync output update for this device Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: MRS: Pushing multiroomSync output Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:50:12 volumio-raspi3b volumio[1011]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 02 22:50:12 volumio-raspi3b volumio[1011]: verbose: CURRENT POSITION 0 Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreStateMachine::syncState stateService stop Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreStateMachine::syncState currentStatus stop Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreStateMachine::pushState Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioPushState Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: MRS: Pushing multiroomSync output update for this device Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: MRS: Pushing multiroomSync output Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: No code Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreStateMachine::pushState Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioPushState Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: MRS: Pushing multiroomSync output update for this device Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: MRS: Pushing multiroomSync output Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::servicePushState Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreStateMachine::pushState Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioPushState Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: MRS: Pushing multiroomSync output update for this device Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: MRS: Pushing multiroomSync output Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:50:12 volumio-raspi3b volumio[1011]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 02 22:50:12 volumio-raspi3b volumio[1011]: verbose: CURRENT POSITION 0 Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreStateMachine::syncState stateService stop Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreStateMachine::syncState currentStatus stop Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreStateMachine::pushState Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioPushState Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: MRS: Pushing multiroomSync output update for this device Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: MRS: Pushing multiroomSync output Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: No code Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreStateMachine::pushState Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioPushState Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: MRS: Pushing multiroomSync output update for this device Dec 02 22:50:12 volumio-raspi3b volumio[1011]: info: MRS: Pushing multiroomSync output Dec 02 22:50:13 volumio-raspi3b pulseaudio[1936]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 02 22:50:13 volumio-raspi3b pulseaudio[1936]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 02 22:50:13 volumio-raspi3b bluetoothd[791]: Endpoint registered: sender=:1.48 path=/MediaEndpoint/A2DPSource Dec 02 22:50:13 volumio-raspi3b bluetoothd[791]: Endpoint registered: sender=:1.48 path=/MediaEndpoint/A2DPSink Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: Applying permissions Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: Setting BT discoverable and pairable Dec 02 22:50:13 volumio-raspi3b pulseaudio[1936]: 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 Dec 02 22:50:13 volumio-raspi3b kernel: Bluetooth: RFCOMM TTY layer initialized Dec 02 22:50:13 volumio-raspi3b kernel: Bluetooth: RFCOMM socket layer initialized Dec 02 22:50:13 volumio-raspi3b kernel: Bluetooth: RFCOMM ver 1.11 Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [CHG] Controller B8:27:EB:09:80:D3 UUIDs: 00001112-0000-1000-8000-00805f9b34fb Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [117B blob data] Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [117B blob data] Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [117B blob data] Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [117B blob data] Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [117B blob data] Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [117B blob data] Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [117B blob data] Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [117B blob data] Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [55B blob data] Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [bluetooth]# -e power on Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: Invalid command in menu main: -e Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: Use "help" for a list of available commands in a menu. Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: Use "menu " if you want to enter any submenu. Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: Use "back" if you want to return to menu main. Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [bluetooth]# agent on Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: Agent is already registered Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [bluetooth]# discoverable on Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [bluetooth]# pairable on Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [bluetooth]# agent NoInputNoOutput Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: Agent is already registered Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [bluetooth]# default-agent Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [bluetooth]# quit Dec 02 22:50:13 volumio-raspi3b volumiobt[1901]: [59B blob data] Dec 02 22:50:13 volumio-raspi3b mpd[1904]: Dec 02 22:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 02 22:50:14 volumio-raspi3b volumio[1011]: info: TidalConnect service started! Dec 02 22:50:14 volumio-raspi3b volumio[1011]: [Metrics] CommandRouter: 63s 569.71ms Dec 02 22:50:14 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumiosetStartupVolume Dec 02 22:50:14 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 22:50:14 volumio-raspi3b volumio[1011]: info: VolumeController:: Setting startup Volume 100 Dec 02 22:50:14 volumio-raspi3b volumio[1011]: info: VolumeController::SetAlsaVolume100 Dec 02 22:50:14 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 22:50:14 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::Close All Modals sent Dec 02 22:50:14 volumio-raspi3b systemd[1]: Started Music Player Daemon. Dec 02 22:50:14 volumio-raspi3b sudo[1887]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:14 volumio-raspi3b sudo[1856]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:14 volumio-raspi3b sudo[1809]: pam_unix(sudo:session): session closed for user root Dec 02 22:50:14 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::Close All Modals sent Dec 02 22:50:14 volumio-raspi3b volumio[1011]: info: CoreStateMachine::pushState Dec 02 22:50:14 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:50:14 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioPushState Dec 02 22:50:14 volumio-raspi3b volumio[1011]: info: MRS: Pushing multiroomSync output update for this device Dec 02 22:50:14 volumio-raspi3b volumio[1011]: info: MRS: Pushing multiroomSync output Dec 02 22:50:14 volumio-raspi3b volumio[1011]: error: MPD error: The expression evaluated to a falsy value: Dec 02 22:50:14 volumio-raspi3b volumio[1011]: assert.ok(self.idling) Dec 02 22:50:14 volumio-raspi3b volumio[1011]: error: The expression evaluated to a falsy value: Dec 02 22:50:14 volumio-raspi3b volumio[1011]: assert.ok(self.idling) Dec 02 22:50:14 volumio-raspi3b volumio[1011]: error: MPD error: The expression evaluated to a falsy value: Dec 02 22:50:14 volumio-raspi3b volumio[1011]: assert.ok(self.idling) Dec 02 22:50:14 volumio-raspi3b volumio[1011]: error: The expression evaluated to a falsy value: Dec 02 22:50:14 volumio-raspi3b volumio[1011]: assert.ok(self.idling) Dec 02 22:50:14 volumio-raspi3b volumio[1011]: error: updateQueue error: null Dec 02 22:50:15 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 02 22:50:15 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 02 22:50:15 volumio-raspi3b volumio[1011]: xcb_connection_has_error() returned true Dec 02 22:50:15 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 02 22:50:18 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioGetState Dec 02 22:50:18 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:50:20 volumio-raspi3b kernel: hwmon hwmon1: Voltage normalised Dec 02 22:50:21 volumio-raspi3b volumio[1011]: info: BOOT COMPLETED Dec 02 22:50:24 volumio-raspi3b kernel: hwmon hwmon1: Undervoltage detected! Dec 02 22:50:28 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioGetState Dec 02 22:50:28 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:50:28 volumio-raspi3b volumio[1011]: info: Listing playlists Dec 02 22:50:28 volumio-raspi3b volumio[1011]: info: Listing playlists Dec 02 22:50:38 volumio-raspi3b volumio[1011]: info: CoreCommandRouter::volumioGetState Dec 02 22:50:38 volumio-raspi3b volumio[1011]: info: CorePlayQueue::getTrack 0 Dec 02 22:50:39 volumio-raspi3b kernel: hwmon hwmon1: Voltage normalised Dec 02 22:50:41 volumio-raspi3b kernel: hwmon hwmon1: Undervoltage detected! Dec 02 22:50:41 volumio-raspi3b volumiossh-tunnel[1796]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Dec 02 22:50:43 volumio-raspi3b volumio[1011]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 22:50:43 volumio-raspi3b volumio[1011]: TypeError: Cannot read property 'ping' of undefined Dec 02 22:50:43 volumio-raspi3b volumio[1011]: at Timeout._onTimeout (/data/plugins/music_service/volroon/node_modules/node-roon-api/transport-websocket.js:26:21) Dec 02 22:50:43 volumio-raspi3b volumio[1011]: at listOnTimeout (internal/timers.js:554:17) Dec 02 22:50:43 volumio-raspi3b volumio[1011]: at processTimers (internal/timers.js:497:7) Dec 02 22:50:43 volumio-raspi3b volumio[1011]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 22:50:44 volumio-raspi3b sudo[2042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-02 22:49 Dec 02 22:50:44 volumio-raspi3b sudo[2042]: 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="c56b231f88ce17023518c3b6aa9e933e8e8256d8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 25 Nov 2024 02:41:54 PM CET" VOLUMIO_VERSION="3.782" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="769a33e6dd61b9a5d2fa14f934eef739"