-- Logs begin at Sat 2024-11-02 19:39:41 EDT, end at Sat 2024-11-02 19:41:54 EDT. -- Nov 02 19:40:00 volumio systemd[1]: Started Wireless Services. Nov 02 19:40:00 volumio systemd[1]: Started Volumio Backend Module. Nov 02 19:40:00 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 02 19:40:00 volumio systemd[1]: Started Volumio Cpu Tweaker. Nov 02 19:40:00 volumio volumio-cpu-tweak[988]: Setting RT Priority for mpd Nov 02 19:40:00 volumio volumio-cpu-tweak[988]: pid 35's current scheduling policy: SCHED_OTHER Nov 02 19:40:00 volumio volumio-cpu-tweak[988]: pid 35's current scheduling priority: 0 Nov 02 19:40:00 volumio volumio-cpu-tweak[988]: Setting MPD Affinity Nov 02 19:40:00 volumio volumio-cpu-tweak[988]: pid 3's current affinity mask: f Nov 02 19:40:00 volumio volumio-cpu-tweak[988]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Nov 02 19:40:00 volumio volumio-cpu-tweak[988]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Nov 02 19:40:00 volumio nmbd[755]: [2024/11/02 19:40:00.206169, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 02 19:40:00 volumio systemd[1]: Started Samba NMB Daemon. Nov 02 19:40:00 volumio nmbd[755]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 02 19:40:00 volumio systemd[1]: Starting Samba Winbind Daemon... Nov 02 19:40:00 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Nov 02 19:40:00 volumio winbindd[1004]: [2024/11/02 19:40:00.596399, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 02 19:40:00 volumio winbindd[1004]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 02 19:40:00 volumio ntpd[797]: Listen normally on 3 wlan0 192.168.0.181:123 Nov 02 19:40:00 volumio ntpd[797]: new interface(s) found: waking up resolver Nov 02 19:40:00 volumio winbindd[1004]: [2024/11/02 19:40:00.641674, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 02 19:40:00 volumio systemd[1]: Started Samba Winbind Daemon. Nov 02 19:40:00 volumio winbindd[1004]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 02 19:40:00 volumio systemd[1]: Starting Samba SMB Daemon... Nov 02 19:40:01 volumio volumio-remote-updater[655]: [2024-11-02 19:40:01] [info] asio async_connect error: system:111 (Connection refused) Nov 02 19:40:01 volumio volumio-remote-updater[655]: [2024-11-02 19:40:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 02 19:40:01 volumio volumio-remote-updater[655]: [2024-11-02 19:40:01] [error] handle_connect error: Underlying Transport Error Nov 02 19:40:01 volumio smbd[1009]: [2024/11/02 19:40:01.362057, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 02 19:40:01 volumio systemd[1]: Started Samba SMB Daemon. Nov 02 19:40:01 volumio smbd[1009]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 02 19:40:01 volumio systemd[1]: Reached target Multi-User System. Nov 02 19:40:01 volumio systemd[1]: Reached target Graphical Interface. Nov 02 19:40:01 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 02 19:40:01 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 02 19:40:01 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 02 19:40:01 volumio systemd[1]: Startup finished in 17.174s (kernel) + 22.637s (userspace) = 39.811s. Nov 02 19:40:03 volumio volumio[987]: info: ------------------------------------------- Nov 02 19:40:03 volumio volumio[987]: info: ----- Volumio3 ---- Nov 02 19:40:03 volumio volumio[987]: info: ------------------------------------------- Nov 02 19:40:03 volumio volumio[987]: info: ----- System startup ---- Nov 02 19:40:03 volumio volumio[987]: info: ------------------------------------------- Nov 02 19:40:04 volumio volumio[987]: info: MYVOLUMIO Environment detected Nov 02 19:40:04 volumio volumio[987]: info: Plugin folders cleanup Nov 02 19:40:04 volumio volumio[987]: info: Scanning into folder /volumio/app/plugins/ Nov 02 19:40:04 volumio volumio[987]: info: Scanning category audio_interface Nov 02 19:40:04 volumio volumio[987]: info: Scanning category miscellanea Nov 02 19:40:04 volumio volumio[987]: info: Scanning category music_service Nov 02 19:40:04 volumio volumio[987]: info: Scanning category plugins.json Nov 02 19:40:04 volumio volumio[987]: info: Scanning category system_controller Nov 02 19:40:04 volumio volumio[987]: info: Scanning category user_interface Nov 02 19:40:04 volumio volumio[987]: info: Scanning into folder /data/plugins/ Nov 02 19:40:04 volumio volumio[987]: info: Plugin folders cleanup completed Nov 02 19:40:04 volumio volumio[987]: info: ------------------------------------------- Nov 02 19:40:04 volumio volumio[987]: info: ----- Core plugins startup ---- Nov 02 19:40:04 volumio volumio[987]: info: ------------------------------------------- Nov 02 19:40:04 volumio volumio[987]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 19:40:04 volumio volumio[987]: info: Adding plugin upnp to MyMusic Plugins Nov 02 19:40:04 volumio volumio[987]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 19:40:04 volumio volumio[987]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 19:40:04 volumio volumio[987]: info: Loading plugins from folder /data/plugins/ Nov 02 19:40:04 volumio volumio[987]: info: Loading plugin "system"... Nov 02 19:40:04 volumio volumio[987]: info: Loading plugin "appearance"... Nov 02 19:40:06 volumio volumio[987]: info: Loading plugin "network"... Nov 02 19:40:06 volumio volumio[987]: info: Refreshing Cached IP Addresses Nov 02 19:40:06 volumio sudo[1032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 19:40:06 volumio sudo[1032]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:06 volumio sudo[1032]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:06 volumio volumio[987]: info: Loading plugin "services"... Nov 02 19:40:06 volumio sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 19:40:06 volumio sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:06 volumio volumio[987]: info: Loading plugin "alsa_controller"... Nov 02 19:40:06 volumio sudo[1034]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:06 volumio sudo[1037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 02 19:40:06 volumio sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:06 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 19:40:06 volumio volumio[987]: info: Loading plugin "wizard"... Nov 02 19:40:06 volumio volumio[987]: info: Loading plugin "networkfs"... Nov 02 19:40:06 volumio volumio[987]: info: Starting Udev Watcher for removable devices Nov 02 19:40:06 volumio sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.196/Raaverok_Media /mnt/NAS/Raaverok_Media Nov 02 19:40:06 volumio sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:06 volumio sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.196/Raaverok_Media\\Music /mnt/NAS/Raaverok_Media Nov 02 19:40:06 volumio sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:06 volumio volumio[987]: info: Ignoring mount for partition: boot Nov 02 19:40:06 volumio volumio[987]: info: Ignoring mount for partition: volumio Nov 02 19:40:06 volumio volumio[987]: info: Ignoring mount for partition: volumio_data Nov 02 19:40:06 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 19:40:06 volumio volumio[987]: info: Loading plugin "volumio_command_line_client"... Nov 02 19:40:06 volumio volumio[987]: info: Loading plugin "upnp"... Nov 02 19:40:06 volumio volumio[987]: info: [1730590806632] Starting Upmpd Daemon Nov 02 19:40:06 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 19:40:06 volumio volumio[987]: info: Loading plugin "my_music"... Nov 02 19:40:06 volumio volumio[987]: info: Loading plugin "mpd"... Nov 02 19:40:06 volumio kernel: Key type cifs.spnego registered Nov 02 19:40:06 volumio kernel: Key type cifs.idmap registered Nov 02 19:40:06 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Nov 02 19:40:06 volumio kernel: CIFS: Attempting to mount \\192.168.0.196\Raaverok_Media Nov 02 19:40:07 volumio sudo[1037]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:07 volumio kernel: CIFS: Attempting to mount \\192.168.0.196\Raaverok_Media Nov 02 19:40:07 volumio sudo[1043]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:07 volumio volumio[987]: info: Loading plugin "upnp_browser"... Nov 02 19:40:07 volumio sudo[1045]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:07 volumio volumio[987]: info: Loading plugin "alarm-clock"... Nov 02 19:40:07 volumio volumio[987]: info: Loading plugin "airplay_emulation"... Nov 02 19:40:07 volumio volumio[987]: info: Starting Shairport Sync Nov 02 19:40:07 volumio volumio[987]: info: Loading plugin "last_100"... Nov 02 19:40:07 volumio volumio[987]: info: Loading plugin "webradio"... Nov 02 19:40:08 volumio volumio[987]: info: Loading plugin "i2s_dacs"... Nov 02 19:40:08 volumio volumio[987]: info: Loading plugin "volumiodiscovery"... Nov 02 19:40:08 volumio volumio[987]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 19:40:08 volumio volumio[987]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 19:40:08 volumio volumio[987]: *** WARNING *** For more information see Nov 02 19:40:08 volumio node[987]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 19:40:08 volumio volumio[987]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 19:40:08 volumio volumio[987]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 19:40:08 volumio volumio[987]: *** WARNING *** For more information see Nov 02 19:40:08 volumio node[987]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 19:40:08 volumio node[987]: *** WARNING *** For more information see Nov 02 19:40:08 volumio node[987]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 19:40:08 volumio node[987]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 19:40:08 volumio node[987]: *** WARNING *** For more information see Nov 02 19:40:08 volumio volumio[987]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 19:40:08 volumio volumio[987]: info: Discovery: Started advertising with name: Volumio Nov 02 19:40:08 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 19:40:08 volumio volumio[987]: info: Loading plugin "outputs"... Nov 02 19:40:08 volumio volumio[987]: info: Loading plugin "albumart"... Nov 02 19:40:08 volumio volumio[987]: info: Plugin example_plugin is not enabled Nov 02 19:40:08 volumio volumio[987]: info: Loading plugin "inputs"... Nov 02 19:40:08 volumio volumio[987]: info: Loading plugin "updater_comm"... Nov 02 19:40:08 volumio volumio[987]: info: Plugin mpdemulation is not enabled Nov 02 19:40:08 volumio volumio[987]: info: Loading plugin "rest_api"... Nov 02 19:40:08 volumio volumio[987]: info: Loading plugin "websocket"... Nov 02 19:40:08 volumio volumio[987]: info: Starting Socket.io Server version 2.3.0 Nov 02 19:40:08 volumio volumio[987]: info: Loading i18n strings for locale en Nov 02 19:40:08 volumio volumio[987]: Updating browse sources language Nov 02 19:40:08 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 19:40:08 volumio volumio[987]: Forking 3 albumart workers Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::initPlayerControls Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 19:40:09 volumio volumio[987]: Express server listening on port 3000 Nov 02 19:40:09 volumio volumio[987]: [Metrics] WebUI: 6s 853.51ms Nov 02 19:40:09 volumio volumio[987]: info: CoreStateMachine::resetVolumioState Nov 02 19:40:09 volumio volumio[987]: info: CoreStateMachine::getcurrentVolume Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 19:40:09 volumio volumio[987]: info: CoreStateMachine::pushState Nov 02 19:40:09 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Nov 02 19:40:09 volumio volumio[987]: verbose: New Socket.io Connection to 192.168.0.181 from 192.168.0.218 UA: Mozilla/5.0 (Linux; Android 14; moto g play - 2024 Build/U1TF34.100-35; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 02 19:40:09 volumio volumio[987]: verbose: New Socket.io Connection to 192.168.0.181 from 192.168.0.153 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:40:09 volumio volumio[987]: info: Reloading queue from file Nov 02 19:40:09 volumio volumio[987]: info: CoreStateMachine::setRepeat true single undefined Nov 02 19:40:09 volumio volumio[987]: info: CoreStateMachine::pushState Nov 02 19:40:09 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Nov 02 19:40:09 volumio volumio[987]: info: CoreStateMachine::setRandom null Nov 02 19:40:09 volumio volumio[987]: info: CoreStateMachine::pushState Nov 02 19:40:09 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Nov 02 19:40:09 volumio volumio[987]: info: Setting Device type: Raspberry PI Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:40:09 volumio volumio[987]: info: Discovery: Getting this device information Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:09 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:40:09 volumio volumio[987]: info: Completed loading Core Plugins Nov 02 19:40:09 volumio volumio[987]: info: Preparing to generate the ALSA configuration file Nov 02 19:40:09 volumio volumio[987]: verbose: New Socket.io Connection to 192.168.0.181 from 192.168.0.218 UA: Mozilla/5.0 (Linux; Android 14; moto g play - 2024 Build/U1TF34.100-35; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 02 19:40:09 volumio volumio[987]: verbose: New Socket.io Connection to 192.168.0.181:3000 from 192.168.0.218 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Nov 02 19:40:09 volumio volumio[987]: info: Asound.conf file unchanged, so no further update is needed Nov 02 19:40:09 volumio volumio[987]: info: Output device has changed, restarting MPD Nov 02 19:40:09 volumio volumio[987]: info: Output device has changed, restarting Shairport Sync Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:40:09 volumio sudo[1115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 19:40:09 volumio sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:09 volumio sudo[1115]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:09 volumio sudo[1116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 19:40:09 volumio sudo[1116]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:09 volumio volumio[987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 19:40:09 volumio volumio[987]: info: ___________ START PLUGINS ___________ Nov 02 19:40:09 volumio volumio[987]: info: ControllerMpd::onStart: Initializing MPD Nov 02 19:40:09 volumio volumio[987]: info: Creating MPD Configuration file Nov 02 19:40:09 volumio volumio[987]: Starting albumart workers Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 19:40:09 volumio systemd[1]: Listening on mpd.socket. Nov 02 19:40:09 volumio systemd[1]: Starting Music Player Daemon... Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 19:40:09 volumio volumio[987]: info: [1730590809886] CoreMusicLibrary::Adding element Media Servers Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 19:40:09 volumio sudo[1124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 19:40:09 volumio sudo[1124]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:40:09 volumio sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 19:40:09 volumio sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:09 volumio sudo[1124]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:09 volumio volumio[987]: Starting albumart workers Nov 02 19:40:09 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 02 19:40:09 volumio systemd[1]: mpd.service: Succeeded. Nov 02 19:40:09 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 19:40:10 volumio volumio[987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:40:10 volumio volumio[987]: Starting albumart workers Nov 02 19:40:10 volumio systemd[1]: Starting Music Player Daemon... Nov 02 19:40:10 volumio volumio[987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 19:40:10 volumio volumio[987]: info: [1730590810163] CoreMusicLibrary::Adding element Last_100 Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 19:40:10 volumio volumio[987]: info: [1730590810168] CoreMusicLibrary::Adding element Webradio Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 19:40:10 volumio volumio[987]: info: Initializing BBC Radios Nov 02 19:40:10 volumio sudo[1133]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 19:40:10 volumio sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:10 volumio sudo[1133]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Nov 02 19:40:10 volumio sudo[1133]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:40:10 volumio volumio[987]: info: Volumio Calling Home Nov 02 19:40:10 volumio volumio[987]: info: Discovery: adding 181f00fe-a4ea-49c4-9865-f1747a456c25 Nov 02 19:40:10 volumio volumio[987]: info: Discovery: Found device Volumio Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:10 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:10 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 19:40:10 volumio volumio[987]: info: MPD Permissions set Nov 02 19:40:10 volumio volumio[987]: info: MPD Permissions set Nov 02 19:40:10 volumio volumio[987]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:10 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 19:40:10 volumio volumio[987]: info: Received Get System Info Nov 02 19:40:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:40:11 volumio volumio[987]: info: Discovery: Getting this device information Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: Listing playlists Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Nov 02 19:40:11 volumio volumio[987]: info: CoreStateMachine::getQueue Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getQueue Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:11 volumio volumio-remote-updater[655]: [2024-11-02 19:40:11] [connect] Successful connection Nov 02 19:40:11 volumio volumio[987]: verbose: New Socket.io Connection to 192.168.0.181 from 192.168.0.153 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Nov 02 19:40:11 volumio volumio[987]: info: Volumio called home Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: Received Get System Info Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:40:11 volumio volumio[987]: info: Discovery: Getting this device information Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 19:40:11 volumio volumio[987]: info: Received Get System Info Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:40:11 volumio volumio[987]: info: Discovery: Getting this device information Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: Listing playlists Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Nov 02 19:40:11 volumio volumio[987]: info: CoreStateMachine::getQueue Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getQueue Nov 02 19:40:11 volumio volumio[987]: info: Received Get System Info Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:40:11 volumio volumio[987]: info: Discovery: Getting this device information Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 19:40:11 volumio volumio[987]: info: Received Get System Info Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:40:11 volumio volumio[987]: info: Discovery: Getting this device information Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: Listing playlists Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Nov 02 19:40:11 volumio volumio[987]: info: CoreStateMachine::getQueue Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getQueue Nov 02 19:40:11 volumio volumio[987]: info: Received Get System Info Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:40:11 volumio volumio[987]: info: Discovery: Getting this device information Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 19:40:11 volumio volumio[987]: info: Received Get System Info Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:40:11 volumio volumio[987]: info: Discovery: Getting this device information Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: Listing playlists Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Nov 02 19:40:11 volumio volumio[987]: info: CoreStateMachine::getQueue Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getQueue Nov 02 19:40:11 volumio volumio[987]: info: Received Get System Info Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:40:11 volumio volumio[987]: info: Discovery: Getting this device information Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:40:11 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:11 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:11 volumio volumio-remote-updater[655]: [2024-11-02 19:40:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1730590811 101 Nov 02 19:40:11 volumio volumio[987]: 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: 7 Nov 02 19:40:11 volumio volumio[987]: info: Starting Shairport Sync Nov 02 19:40:11 volumio volumio[987]: info: Starting Shairport Sync Nov 02 19:40:11 volumio volumio[987]: info: Starting Shairport Sync Nov 02 19:40:11 volumio sudo[1185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 19:40:11 volumio sudo[1185]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 19:40:11 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 19:40:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 19:40:11 volumio sudo[1190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 19:40:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 19:40:11 volumio sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:11 volumio sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 19:40:11 volumio sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:11 volumio sudo[1185]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 19:40:11 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 19:40:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 19:40:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 19:40:11 volumio sudo[1190]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:11 volumio sudo[1187]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:12 volumio volumio[987]: info: Shairport-Sync Started Nov 02 19:40:12 volumio volumio[987]: Error adding Membership: Error: addMembership EINVAL Nov 02 19:40:12 volumio volumio[987]: info: Shairport-Sync Started Nov 02 19:40:12 volumio volumio[987]: info: Shairport-Sync Started Nov 02 19:40:13 volumio systemd[1]: systemd-fsckd.service: Succeeded. Nov 02 19:40:13 volumio mpd[1139]: Nov 02 19:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 19:40:13 volumio systemd[1]: Started Music Player Daemon. Nov 02 19:40:13 volumio sudo[1126]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:13 volumio sudo[1116]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:13 volumio volumio[987]: info: Completed starting Core Plugins Nov 02 19:40:13 volumio volumio[987]: info: ------------------------------------------- Nov 02 19:40:13 volumio volumio[987]: info: ----- MyVolumio plugins startup ---- Nov 02 19:40:13 volumio volumio[987]: info: ------------------------------------------- Nov 02 19:40:13 volumio volumio[987]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 02 19:40:14 volumio volumio[987]: error: MPD error: The expression evaluated to a falsy value: Nov 02 19:40:14 volumio volumio[987]: assert.ok(self.idling) Nov 02 19:40:14 volumio volumio[987]: error: The expression evaluated to a falsy value: Nov 02 19:40:14 volumio volumio[987]: assert.ok(self.idling) Nov 02 19:40:14 volumio volumio[987]: error: updateQueue error: null Nov 02 19:40:14 volumio volumio[987]: info: MPD running with PID1139 Nov 02 19:40:14 volumio volumio[987]: ,establishing connection Nov 02 19:40:14 volumio volumio[987]: error: updateQueue error: null Nov 02 19:40:15 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:15 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:16 volumio sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 19:40:16 volumio sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:16 volumio sudo[1203]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:16 volumio sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 19:40:16 volumio sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:16 volumio sudo[1205]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:16 volumio sudo[1208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 02 19:40:16 volumio sudo[1208]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:16 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Nov 02 19:40:16 volumio sudo[1208]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:16 volumio volumio[987]: info: Upmpdcli Daemon Started Nov 02 19:40:16 volumio volumio[1211]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 02 19:40:17 volumio sh[549]: timed out Nov 02 19:40:17 volumio dhcpcd[594]: timed out Nov 02 19:40:17 volumio sh[549]: dhcpcd exited Nov 02 19:40:17 volumio dhcpcd[594]: dhcpcd exited Nov 02 19:40:17 volumio sh[549]: ifup: failed to bring up eth0 Nov 02 19:40:17 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Nov 02 19:40:17 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Nov 02 19:40:18 volumio volumio[987]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 02 19:40:19 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 02 19:40:27 volumio volumio[987]: info: Adding plugin bluetooth to MyMusic Plugins Nov 02 19:40:27 volumio volumio[987]: info: Adding plugin multiroom to MyMusic Plugins Nov 02 19:40:27 volumio volumio[987]: info: Adding plugin metavolumio to MyMusic Plugins Nov 02 19:40:27 volumio volumio[987]: info: Adding plugin cd_controller to MyMusic Plugins Nov 02 19:40:27 volumio volumio[987]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 02 19:40:27 volumio volumio[987]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 02 19:40:27 volumio volumio[987]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 02 19:40:27 volumio volumio[1211]: ............................................................................++++ Nov 02 19:40:28 volumio volumio[987]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 02 19:40:28 volumio volumio[987]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 02 19:40:28 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:40:28 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:40:28 volumio volumio[987]: info: Starting MyVolumio Remote Streaming Endpoints Nov 02 19:40:28 volumio volumio[987]: info: MyVolumio login type: Token Nov 02 19:40:28 volumio volumio[987]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 02 19:40:28 volumio volumio[987]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 02 19:40:30 volumio volumio[987]: info: Starting Streaming Service Transparent Proxy Nov 02 19:40:30 volumio volumio[987]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 02 19:40:30 volumio volumio[987]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 02 19:40:30 volumio volumio[987]: info: Streaming services startup Nov 02 19:40:30 volumio volumio[987]: info: Starting Streaming Daemon Nov 02 19:40:30 volumio sudo[1238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 19:40:30 volumio volumio[987]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 02 19:40:30 volumio sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:30 volumio sudo[1238]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:30 volumio volumio[987]: error: Cannot start Volumio Streaming Daemon Nov 02 19:40:30 volumio volumio[987]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 19:40:30 volumio volumio[987]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 19:40:31 volumio volumio[987]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 02 19:40:31 volumio volumio[987]: STREAMING PROXY: Starting server on port 3245 Nov 02 19:40:31 volumio volumio[987]: Node JS runtime: 14 Nov 02 19:40:32 volumio volumio[987]: info: MyVolumio token set successfully Nov 02 19:40:32 volumio volumio[987]: info: MYVOLUMIO: Adding device Nov 02 19:40:32 volumio volumio[987]: info: MYVOLUMIO: Evaluating Server Nov 02 19:40:32 volumio volumio[987]: info: MyVolumio status changed Nov 02 19:40:32 volumio volumio[987]: info: Streaming services startup Nov 02 19:40:32 volumio volumio[987]: info: Starting Streaming Daemon Nov 02 19:40:32 volumio volumio[987]: info: Removing browser output: myVolumio user plan is not superstar Nov 02 19:40:32 volumio volumio[987]: info: Removing audio output: Nov 02 19:40:32 volumio volumio[987]: info: Stoppping Tunnel 1 Nov 02 19:40:32 volumio sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 19:40:32 volumio sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:32 volumio sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 02 19:40:32 volumio sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:40:32 volumio sudo[1262]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:32 volumio volumio[987]: error: Cannot start Volumio Streaming Daemon Nov 02 19:40:32 volumio volumio[987]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 19:40:32 volumio volumio[987]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 19:40:32 volumio sudo[1265]: pam_unix(sudo:session): session closed for user root Nov 02 19:40:32 volumio volumio[987]: info: Remote SSH Stopped Nov 02 19:40:35 volumio volumio[1211]: ........................................................++++ Nov 02 19:40:35 volumio volumio[1211]: e is 65537 (0x010001) Nov 02 19:40:35 volumio volumio[1211]: writing RSA key Nov 02 19:40:36 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:40:36 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:40:36 volumio volumio[987]: info: Discovery: Getting this device information Nov 02 19:40:36 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:36 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:36 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:40:40 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 19:40:40 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:40:40 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 19:40:40 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 02 19:40:40 volumio volumio-remote-updater[655]: No test mode Nov 02 19:40:40 volumio volumio-remote-updater[655]: No alpha test mode Nov 02 19:40:40 volumio volumio[987]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 02 19:40:40 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 02 19:40:43 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 19:40:43 volumio volumio[987]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 02 19:40:43 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Nov 02 19:40:43 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Nov 02 19:40:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 02 19:40:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 02 19:40:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 02 19:40:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 02 19:40:48 volumio volumio[987]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 19:40:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 19:40:50 volumio volumio[987]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 02 19:40:50 volumio volumio[987]: info: Completed starting MyVolumio Plugin Nov 02 19:40:50 volumio volumio[987]: info: BOOT COMPLETED Nov 02 19:40:50 volumio volumio[987]: [Metrics] CommandRouter: 47s 284.40ms Nov 02 19:40:50 volumio volumio[987]: info: CoreCommandRouter::volumiosetStartupVolume Nov 02 19:40:50 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:40:50 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:40:50 volumio volumio[987]: info: CoreCommandRouter::Close All Modals sent Nov 02 19:40:50 volumio volumio[987]: info: CoreCommandRouter::Close All Modals sent Nov 02 19:40:51 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 02 19:40:51 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 19:40:51 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 02 19:40:54 volumio ntpd[797]: Soliciting pool server 208.81.1.244 Nov 02 19:40:55 volumio ntpd[797]: Soliciting pool server 206.108.0.132 Nov 02 19:40:55 volumio ntpd[797]: Soliciting pool server 142.4.192.253 Nov 02 19:40:55 volumio ntpd[797]: Soliciting pool server 217.180.209.214 Nov 02 19:40:56 volumio ntpd[797]: Soliciting pool server 162.159.200.123 Nov 02 19:40:56 volumio ntpd[797]: Soliciting pool server 23.133.168.244 Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 19:40:57 volumio volumio[987]: info: Retrieving Cloud Streaming UI Nov 02 19:40:57 volumio volumio[987]: info: Getting Tidal Cloud Configuration Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 19:40:57 volumio volumio[987]: info: Getting Qobuz Cloud Configuration Nov 02 19:40:57 volumio volumio[987]: info: Asking plugin for UI Config Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 19:40:57 volumio volumio[987]: info: Getting Spotify Cloud Configuration Nov 02 19:40:57 volumio volumio[987]: info: Asking plugin for UI Config Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 19:40:57 volumio volumio[987]: info: Saving Spotify Acccount Nov 02 19:40:57 volumio volumio[987]: info: Got Tidal Cloud Configuration Nov 02 19:40:57 volumio volumio[987]: info: Got it Nov 02 19:40:57 volumio volumio[987]: info: Got it Nov 02 19:40:57 volumio volumio[987]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 19:40:57 volumio volumio[987]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 19:40:57 volumio ntpd[797]: Soliciting pool server 23.133.168.245 Nov 02 19:40:57 volumio ntpd[797]: Soliciting pool server 66.49.223.159 Nov 02 19:40:57 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 02 19:40:57 volumio ntpd[797]: Soliciting pool server 216.232.132.95 Nov 02 19:40:57 volumio ntpd[797]: Soliciting pool server 172.97.221.33 Nov 02 19:40:58 volumio ntpd[797]: Soliciting pool server 216.232.132.102 Nov 02 19:40:58 volumio ntpd[797]: Soliciting pool server 173.183.146.26 Nov 02 19:40:58 volumio ntpd[797]: Soliciting pool server 192.95.27.155 Nov 02 19:40:59 volumio ntpd[797]: Soliciting pool server 149.56.19.163 Nov 02 19:40:59 volumio ntpd[797]: Soliciting pool server 23.133.168.246 Nov 02 19:40:59 volumio ntpd[797]: Soliciting pool server 54.39.23.64 Nov 02 19:41:00 volumio volumio[987]: error: Failed to ping endpoint eu10.myvolumio.org : unknown error Nov 02 19:41:00 volumio volumio[987]: error: Failed to ping endpoint us1.myvolumio.org : unknown error Nov 02 19:41:00 volumio volumio[987]: error: Failed to ping endpoint us2.myvolumio.org : unknown error Nov 02 19:41:00 volumio volumio[987]: error: Failed to ping endpoint us3.myvolumio.org : unknown error Nov 02 19:41:00 volumio volumio[987]: error: Failed to ping endpoint as1.myvolumio.org : unknown error Nov 02 19:41:00 volumio volumio[987]: error: Failed to ping endpoint as2.myvolumio.org : unknown error Nov 02 19:41:00 volumio volumio[987]: error: Failed to ping endpoint au1.myvolumio.org : unknown error Nov 02 19:41:00 volumio volumio[987]: info: Setting Geolocation for MyVolumio to eu5 Nov 02 19:41:00 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:41:00 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:41:00 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:41:00 volumio ntpd[797]: Soliciting pool server 167.114.93.151 Nov 02 19:41:00 volumio ntpd[797]: Soliciting pool server 54.39.196.172 Nov 02 19:41:00 volumio ntpd[797]: Soliciting pool server 23.162.240.10 Nov 02 19:41:01 volumio volumio[987]: info: Successfully Added MyVolumio device Nov 02 19:41:01 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Nov 02 19:41:01 volumio sudo[1333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Raaverok_Media Nov 02 19:41:01 volumio sudo[1333]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:01 volumio sudo[1333]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:01 volumio volumio[987]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 19:41:01 volumio volumio[987]: Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/Raaverok_Media' Nov 02 19:41:01 volumio volumio[987]: at Object.rmdirSync (fs.js:890:10) Nov 02 19:41:01 volumio volumio[987]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Nov 02 19:41:01 volumio volumio[987]: at ChildProcess.exithandler (child_process.js:299:7) Nov 02 19:41:01 volumio volumio[987]: at ChildProcess.emit (events.js:315:20) Nov 02 19:41:01 volumio volumio[987]: at maybeClose (internal/child_process.js:1048:16) Nov 02 19:41:01 volumio volumio[987]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) { Nov 02 19:41:01 volumio volumio[987]: errno: -16, Nov 02 19:41:01 volumio volumio[987]: syscall: 'rmdir', Nov 02 19:41:01 volumio volumio[987]: code: 'EBUSY', Nov 02 19:41:01 volumio volumio[987]: path: '/mnt/NAS/Raaverok_Media' Nov 02 19:41:01 volumio volumio[987]: } Nov 02 19:41:01 volumio volumio[987]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 19:41:02 volumio sudo[1345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 19:40 Nov 02 19:41:02 volumio sudo[1345]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:02 volumio sudo[1345]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:03 volumio volumio-remote-updater[655]: [2024-11-02 19:41:03] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Nov 02 19:41:03 volumio volumio-remote-updater[655]: [2024-11-02 19:41:03] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) Nov 02 19:41:03 volumio volumio-remote-updater[655]: [2024-11-02 19:41:03] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] Nov 02 19:41:03 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 19:41:03 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 02 19:41:03 volumio systemd[1]: Started dynamicswap service. Nov 02 19:41:03 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 02 19:41:03 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 19:41:03 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Nov 02 19:41:03 volumio systemd[1]: Started dynamicswap service. Nov 02 19:41:03 volumio systemd[1]: Stopped Volumio Backend Module. Nov 02 19:41:03 volumio systemd[1]: Started Volumio Backend Module. Nov 02 19:41:03 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 02 19:41:29 volumio ntpd[797]: receive: Unexpected origin timestamp 0xead1370f.a2bc497c does not match aorg 0000000000.00000000 from server@66.49.223.159 xmt 0xead13729.5c68eeb4 Nov 02 19:41:29 volumio ntpd[797]: receive: Unexpected origin timestamp 0xead1370f.a2b57b6e does not match aorg 0000000000.00000000 from server@167.114.93.151 xmt 0xead13729.5c561740 Nov 02 19:41:29 volumio ntpd[797]: receive: Unexpected origin timestamp 0xead1370f.a2b95e2f does not match aorg 0000000000.00000000 from server@54.39.23.64 xmt 0xead13729.5cb0fe50 Nov 02 19:41:29 volumio ntpd[797]: receive: Unexpected origin timestamp 0xead1370f.a2ad1491 does not match aorg 0000000000.00000000 from server@23.162.240.10 xmt 0xead13729.5d5efa72 Nov 02 19:41:29 volumio ntpd[797]: receive: Unexpected origin timestamp 0xead1370f.a2beab3b does not match aorg 0000000000.00000000 from server@217.180.209.214 xmt 0xead13729.60c0172d Nov 02 19:41:29 volumio ntpd[797]: receive: Unexpected origin timestamp 0xead1370f.a2baf1e5 does not match aorg 0000000000.00000000 from server@216.232.132.102 xmt 0xead13729.639e4e17 Nov 02 19:41:30 volumio volumio[1360]: info: ------------------------------------------- Nov 02 19:41:30 volumio volumio[1360]: info: ----- Volumio3 ---- Nov 02 19:41:30 volumio volumio[1360]: info: ------------------------------------------- Nov 02 19:41:30 volumio volumio[1360]: info: ----- System startup ---- Nov 02 19:41:30 volumio volumio[1360]: info: ------------------------------------------- Nov 02 19:41:31 volumio volumio[1360]: info: MYVOLUMIO Environment detected Nov 02 19:41:31 volumio volumio[1360]: info: Plugin folders cleanup Nov 02 19:41:31 volumio volumio[1360]: info: Scanning into folder /volumio/app/plugins/ Nov 02 19:41:31 volumio volumio[1360]: info: Scanning category audio_interface Nov 02 19:41:31 volumio volumio[1360]: info: Scanning category miscellanea Nov 02 19:41:31 volumio volumio[1360]: info: Scanning category music_service Nov 02 19:41:31 volumio volumio[1360]: info: Scanning category plugins.json Nov 02 19:41:31 volumio volumio[1360]: info: Scanning category system_controller Nov 02 19:41:31 volumio volumio[1360]: info: Scanning category user_interface Nov 02 19:41:31 volumio volumio[1360]: info: Scanning into folder /data/plugins/ Nov 02 19:41:31 volumio volumio[1360]: info: Plugin folders cleanup completed Nov 02 19:41:31 volumio volumio[1360]: info: ------------------------------------------- Nov 02 19:41:31 volumio volumio[1360]: info: ----- Core plugins startup ---- Nov 02 19:41:31 volumio volumio[1360]: info: ------------------------------------------- Nov 02 19:41:31 volumio volumio[1360]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 19:41:31 volumio volumio[1360]: info: Adding plugin upnp to MyMusic Plugins Nov 02 19:41:31 volumio volumio[1360]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 19:41:31 volumio volumio[1360]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 19:41:31 volumio volumio[1360]: info: Loading plugins from folder /data/plugins/ Nov 02 19:41:31 volumio volumio[1360]: info: Loading plugin "system"... Nov 02 19:41:31 volumio volumio[1360]: info: Loading plugin "appearance"... Nov 02 19:41:32 volumio volumio[1360]: info: Loading plugin "network"... Nov 02 19:41:32 volumio volumio[1360]: info: Refreshing Cached IP Addresses Nov 02 19:41:32 volumio sudo[1386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 19:41:32 volumio sudo[1386]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:32 volumio sudo[1386]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:32 volumio volumio[1360]: info: Loading plugin "services"... Nov 02 19:41:32 volumio sudo[1388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 19:41:32 volumio sudo[1388]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:32 volumio volumio[1360]: info: Loading plugin "alsa_controller"... Nov 02 19:41:32 volumio sudo[1388]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:32 volumio sudo[1391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 02 19:41:32 volumio sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:32 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 19:41:32 volumio volumio[1360]: info: Loading plugin "wizard"... Nov 02 19:41:32 volumio volumio[1360]: info: Loading plugin "networkfs"... Nov 02 19:41:33 volumio volumio[1360]: info: Cannot mount NAS Raaverok_Media at system boot, trial number 1 ,retrying in 5 seconds Nov 02 19:41:33 volumio volumio[1360]: info: Cannot mount NAS Raaverok_Media at system boot, trial number 1 ,retrying in 5 seconds Nov 02 19:41:33 volumio volumio[1360]: info: Starting Udev Watcher for removable devices Nov 02 19:41:33 volumio volumio[1360]: info: Ignoring mount for partition: boot Nov 02 19:41:33 volumio volumio[1360]: info: Ignoring mount for partition: volumio Nov 02 19:41:33 volumio volumio[1360]: info: Ignoring mount for partition: volumio_data Nov 02 19:41:33 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 19:41:33 volumio volumio[1360]: info: Loading plugin "volumio_command_line_client"... Nov 02 19:41:33 volumio volumio[1360]: info: Loading plugin "upnp"... Nov 02 19:41:33 volumio volumio[1360]: info: [1730590893074] Starting Upmpd Daemon Nov 02 19:41:33 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 19:41:33 volumio volumio[1360]: info: Loading plugin "my_music"... Nov 02 19:41:33 volumio volumio[1360]: info: Loading plugin "mpd"... Nov 02 19:41:33 volumio volumio[1360]: info: Loading plugin "upnp_browser"... Nov 02 19:41:33 volumio sudo[1391]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:33 volumio volumio-remote-updater[655]: [2024-11-02 19:41:33] [connect] Successful connection Nov 02 19:41:33 volumio volumio[1360]: info: Loading plugin "alarm-clock"... Nov 02 19:41:33 volumio volumio[1360]: info: Loading plugin "airplay_emulation"... Nov 02 19:41:33 volumio volumio[1360]: info: Starting Shairport Sync Nov 02 19:41:33 volumio volumio[1360]: info: Loading plugin "last_100"... Nov 02 19:41:33 volumio volumio[1360]: info: Loading plugin "webradio"... Nov 02 19:41:34 volumio volumio[1360]: info: Loading plugin "i2s_dacs"... Nov 02 19:41:34 volumio volumio[1360]: info: Loading plugin "volumiodiscovery"... Nov 02 19:41:34 volumio volumio[1360]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 19:41:34 volumio volumio[1360]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 19:41:34 volumio volumio[1360]: *** WARNING *** For more information see Nov 02 19:41:34 volumio node[1360]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 19:41:34 volumio volumio[1360]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 19:41:34 volumio volumio[1360]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 19:41:34 volumio volumio[1360]: *** WARNING *** For more information see Nov 02 19:41:34 volumio node[1360]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 19:41:34 volumio node[1360]: *** WARNING *** For more information see Nov 02 19:41:34 volumio node[1360]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 19:41:34 volumio node[1360]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 19:41:34 volumio node[1360]: *** WARNING *** For more information see Nov 02 19:41:34 volumio volumio[1360]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 19:41:34 volumio volumio[1360]: info: Discovery: Started advertising with name: Volumio Nov 02 19:41:34 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 19:41:34 volumio volumio[1360]: info: Loading plugin "outputs"... Nov 02 19:41:34 volumio volumio[1360]: info: Loading plugin "albumart"... Nov 02 19:41:34 volumio volumio[1360]: info: Plugin example_plugin is not enabled Nov 02 19:41:34 volumio volumio[1360]: info: Loading plugin "inputs"... Nov 02 19:41:34 volumio volumio[1360]: info: Loading plugin "updater_comm"... Nov 02 19:41:34 volumio volumio[1360]: info: Plugin mpdemulation is not enabled Nov 02 19:41:34 volumio volumio[1360]: info: Loading plugin "rest_api"... Nov 02 19:41:34 volumio volumio[1360]: info: Loading plugin "websocket"... Nov 02 19:41:34 volumio volumio[1360]: info: Starting Socket.io Server version 2.3.0 Nov 02 19:41:34 volumio volumio[1360]: info: Loading i18n strings for locale en Nov 02 19:41:34 volumio volumio[1360]: Updating browse sources language Nov 02 19:41:34 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 19:41:34 volumio volumio[1360]: Forking 3 albumart workers Nov 02 19:41:34 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 19:41:34 volumio volumio[1360]: info: CoreCommandRouter::initPlayerControls Nov 02 19:41:34 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:34 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:34 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:34 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:34 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:34 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:34 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:34 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:34 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 19:41:34 volumio volumio[1360]: Express server listening on port 3000 Nov 02 19:41:34 volumio volumio[1360]: [Metrics] WebUI: 4s 342.13ms Nov 02 19:41:34 volumio volumio[1360]: info: CoreStateMachine::resetVolumioState Nov 02 19:41:34 volumio volumio[1360]: info: CoreStateMachine::getcurrentVolume Nov 02 19:41:34 volumio volumio[1360]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 19:41:34 volumio volumio[1360]: info: CoreStateMachine::pushState Nov 02 19:41:34 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:34 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 19:41:34 volumio volumio[1360]: info: CoreCommandRouter::volumioPushState Nov 02 19:41:34 volumio volumio[1360]: verbose: New Socket.io Connection to 192.168.0.181 from 192.168.0.218 UA: Mozilla/5.0 (Linux; Android 14; moto g play - 2024 Build/U1TF34.100-35; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 02 19:41:34 volumio volumio[1360]: verbose: New Socket.io Connection to 192.168.0.181 from 192.168.0.153 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Nov 02 19:41:34 volumio volumio-remote-updater[655]: [2024-11-02 19:41:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1730590893 101 Nov 02 19:41:34 volumio volumio[1360]: 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: 3 Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:41:35 volumio volumio[1360]: info: Reloading queue from file Nov 02 19:41:35 volumio volumio[1360]: info: CoreStateMachine::setRepeat true single undefined Nov 02 19:41:35 volumio volumio[1360]: info: CoreStateMachine::pushState Nov 02 19:41:35 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::volumioPushState Nov 02 19:41:35 volumio volumio[1360]: info: CoreStateMachine::setRandom null Nov 02 19:41:35 volumio volumio[1360]: info: CoreStateMachine::pushState Nov 02 19:41:35 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::volumioPushState Nov 02 19:41:35 volumio volumio[1360]: info: Setting Device type: Raspberry PI Nov 02 19:41:35 volumio volumio[1360]: info: Completed loading Core Plugins Nov 02 19:41:35 volumio volumio[1360]: info: Preparing to generate the ALSA configuration file Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:41:35 volumio volumio[1360]: info: Discovery: Getting this device information Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:35 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:41:35 volumio volumio[1360]: info: Discovery: adding 181f00fe-a4ea-49c4-9865-f1747a456c25 Nov 02 19:41:35 volumio volumio[1360]: info: Discovery: Found device Volumio Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:35 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:35 volumio volumio[1360]: info: Asound.conf file unchanged, so no further update is needed Nov 02 19:41:35 volumio volumio[1360]: info: Output device has changed, restarting MPD Nov 02 19:41:35 volumio volumio[1360]: info: Output device has changed, restarting Shairport Sync Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:41:35 volumio sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 19:41:35 volumio sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:35 volumio sudo[1436]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:35 volumio sudo[1437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 19:41:35 volumio sudo[1437]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:35 volumio systemd[1]: Stopping Music Player Daemon... Nov 02 19:41:35 volumio volumio[1360]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 19:41:35 volumio volumio[1360]: info: ___________ START PLUGINS ___________ Nov 02 19:41:35 volumio volumio[1360]: info: ControllerMpd::onStart: Initializing MPD Nov 02 19:41:35 volumio volumio[1360]: info: Creating MPD Configuration file Nov 02 19:41:35 volumio sudo[1444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 19:41:35 volumio sudo[1444]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 19:41:35 volumio sudo[1444]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 19:41:35 volumio volumio[1360]: info: [1730590895431] CoreMusicLibrary::Adding element Media Servers Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:41:35 volumio sudo[1447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 19:41:35 volumio sudo[1447]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:35 volumio systemd[1]: mpd.service: Succeeded. Nov 02 19:41:35 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 19:41:35 volumio volumio[1360]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:41:35 volumio systemd[1]: Starting Music Player Daemon... Nov 02 19:41:35 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 02 19:41:35 volumio systemd[1]: mpd.service: Succeeded. Nov 02 19:41:35 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 19:41:35 volumio systemd[1]: Starting Music Player Daemon... Nov 02 19:41:35 volumio volumio[1360]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 19:41:35 volumio volumio[1360]: info: [1730590895659] CoreMusicLibrary::Adding element Last_100 Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 19:41:35 volumio volumio[1360]: info: [1730590895664] CoreMusicLibrary::Adding element Webradio Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 19:41:35 volumio volumio[1360]: info: Initializing BBC Radios Nov 02 19:41:35 volumio sudo[1456]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 19:41:35 volumio sudo[1456]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:35 volumio sudo[1456]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:35 volumio volumio[1360]: Starting albumart workers Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 19:41:35 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:41:35 volumio volumio[1360]: info: Volumio Calling Home Nov 02 19:41:35 volumio volumio[1360]: Starting albumart workers Nov 02 19:41:36 volumio volumio[1360]: verbose: New Socket.io Connection to 192.168.0.181 from 192.168.0.153 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 02 19:41:36 volumio volumio[1360]: Starting albumart workers Nov 02 19:41:36 volumio volumio[1360]: info: MPD Permissions set Nov 02 19:41:36 volumio volumio[1360]: info: MPD Permissions set Nov 02 19:41:36 volumio volumio[1360]: verbose: New Socket.io Connection to 192.168.0.181:3000 from 192.168.0.218 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Nov 02 19:41:36 volumio volumio[1360]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:36 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 19:41:36 volumio volumio[1360]: info: Received Get System Info Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:41:36 volumio volumio[1360]: info: Discovery: Getting this device information Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:36 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:36 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:36 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:36 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 19:41:36 volumio volumio[1360]: info: Received Get System Info Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:41:36 volumio volumio[1360]: info: Discovery: Getting this device information Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:36 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:36 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:36 volumio volumio[1360]: info: Listing playlists Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::volumioGetQueue Nov 02 19:41:36 volumio volumio[1360]: info: CoreStateMachine::getQueue Nov 02 19:41:36 volumio volumio[1360]: info: CorePlayQueue::getQueue Nov 02 19:41:36 volumio volumio[1360]: info: Received Get System Info Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:41:36 volumio volumio[1360]: info: Discovery: Getting this device information Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:36 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:41:36 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:36 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:37 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:37 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 19:41:37 volumio volumio[1360]: info: Received Get System Info Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:41:37 volumio volumio[1360]: info: Discovery: Getting this device information Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:37 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:37 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:37 volumio volumio[1360]: info: Listing playlists Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetQueue Nov 02 19:41:37 volumio volumio[1360]: info: CoreStateMachine::getQueue Nov 02 19:41:37 volumio volumio[1360]: info: CorePlayQueue::getQueue Nov 02 19:41:37 volumio volumio[1360]: info: Received Get System Info Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:41:37 volumio volumio[1360]: info: Discovery: Getting this device information Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:37 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:37 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:37 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:37 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 19:41:37 volumio volumio[1360]: info: Received Get System Info Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:41:37 volumio volumio[1360]: info: Discovery: Getting this device information Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:37 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:37 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:37 volumio volumio[1360]: info: Listing playlists Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetQueue Nov 02 19:41:37 volumio volumio[1360]: info: CoreStateMachine::getQueue Nov 02 19:41:37 volumio volumio[1360]: info: CorePlayQueue::getQueue Nov 02 19:41:37 volumio volumio[1360]: info: Received Get System Info Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:41:37 volumio volumio[1360]: info: Discovery: Getting this device information Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:37 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:37 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:37 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 02 19:41:37 volumio volumio[1360]: info: Volumio called home Nov 02 19:41:37 volumio volumio[1360]: info: Starting Shairport Sync Nov 02 19:41:37 volumio volumio[1360]: info: Starting Shairport Sync Nov 02 19:41:37 volumio volumio[1360]: info: Starting Shairport Sync Nov 02 19:41:37 volumio sudo[1505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 19:41:37 volumio sudo[1505]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:37 volumio sudo[1507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 19:41:37 volumio sudo[1507]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 19:41:37 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 19:41:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 19:41:37 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:37 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:37 volumio sudo[1511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 19:41:37 volumio sudo[1511]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 19:41:37 volumio sudo[1505]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 19:41:37 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 02 19:41:37 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 19:41:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 19:41:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 19:41:37 volumio sudo[1507]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:37 volumio sudo[1511]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:37 volumio mpd[1465]: Nov 02 19:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 19:41:37 volumio systemd[1]: Started Music Player Daemon. Nov 02 19:41:37 volumio sudo[1437]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:37 volumio sudo[1447]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:38 volumio volumio[1360]: verbose: New Socket.io Connection to 192.168.0.181 from 192.168.0.218 UA: Mozilla/5.0 (Linux; Android 14; moto g play - 2024 Build/U1TF34.100-35; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Nov 02 19:41:38 volumio volumio[1360]: info: Cannot mount NAS Raaverok_Media at system boot, trial number 2 ,retrying in 5 seconds Nov 02 19:41:38 volumio volumio[1360]: info: Cannot mount NAS Raaverok_Media at system boot, trial number 2 ,retrying in 5 seconds Nov 02 19:41:38 volumio volumio[1360]: info: Completed starting Core Plugins Nov 02 19:41:38 volumio volumio[1360]: info: ------------------------------------------- Nov 02 19:41:38 volumio volumio[1360]: info: ----- MyVolumio plugins startup ---- Nov 02 19:41:38 volumio volumio[1360]: info: ------------------------------------------- Nov 02 19:41:38 volumio volumio[1360]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 02 19:41:38 volumio volumio[1360]: info: Shairport-Sync Started Nov 02 19:41:38 volumio volumio[1360]: Error adding Membership: Error: addMembership EINVAL Nov 02 19:41:38 volumio volumio[1360]: info: Shairport-Sync Started Nov 02 19:41:38 volumio volumio[1360]: info: Shairport-Sync Started Nov 02 19:41:38 volumio volumio[1360]: info: MPD running with PID1465 Nov 02 19:41:38 volumio volumio[1360]: ,establishing connection Nov 02 19:41:38 volumio volumio[1360]: error: MPD error: The expression evaluated to a falsy value: Nov 02 19:41:38 volumio volumio[1360]: assert.ok(self.idling) Nov 02 19:41:38 volumio volumio[1360]: error: The expression evaluated to a falsy value: Nov 02 19:41:38 volumio volumio[1360]: assert.ok(self.idling) Nov 02 19:41:38 volumio volumio[1360]: error: MPD error: The expression evaluated to a falsy value: Nov 02 19:41:38 volumio volumio[1360]: assert.ok(self.idling) Nov 02 19:41:38 volumio volumio[1360]: error: The expression evaluated to a falsy value: Nov 02 19:41:38 volumio volumio[1360]: assert.ok(self.idling) Nov 02 19:41:38 volumio volumio[1360]: error: updateQueue error: null Nov 02 19:41:43 volumio volumio[1360]: verbose: New Socket.io Connection to 192.168.0.181 from 192.168.0.218 UA: Mozilla/5.0 (Linux; Android 14; moto g play - 2024 Build/U1TF34.100-35; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Nov 02 19:41:43 volumio sudo[1523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 19:41:43 volumio sudo[1523]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:43 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 19:41:43 volumio sudo[1523]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:43 volumio sudo[1525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 19:41:43 volumio sudo[1525]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:43 volumio sudo[1525]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:43 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 19:41:43 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 19:41:43 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 19:41:43 volumio volumio[1360]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 19:41:43 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 19:41:43 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:43 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:43 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 19:41:43 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 19:41:43 volumio volumio[1360]: info: Received Get System Info Nov 02 19:41:43 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 19:41:43 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 19:41:43 volumio volumio[1360]: info: Discovery: Getting this device information Nov 02 19:41:43 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:43 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:43 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 19:41:43 volumio volumio[1360]: info: CoreCommandRouter::volumioGetState Nov 02 19:41:43 volumio volumio[1360]: info: CorePlayQueue::getTrack 0 Nov 02 19:41:43 volumio volumio[1360]: info: Listing playlists Nov 02 19:41:43 volumio volumio[1360]: info: CoreCommandRouter::volumioGetQueue Nov 02 19:41:43 volumio volumio[1360]: info: CoreStateMachine::getQueue Nov 02 19:41:43 volumio volumio[1360]: info: CorePlayQueue::getQueue Nov 02 19:41:43 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 19:41:43 volumio volumio[1360]: info: Cannot mount NAS Raaverok_Media at system boot, trial number 3 ,retrying in 5 seconds Nov 02 19:41:43 volumio volumio[1360]: info: Cannot mount NAS Raaverok_Media at system boot, trial number 3 ,retrying in 5 seconds Nov 02 19:41:43 volumio sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 02 19:41:43 volumio sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:43 volumio volumio[1360]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 02 19:41:43 volumio sudo[1529]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:43 volumio volumio[1360]: info: Upmpdcli Daemon Started Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 02 19:41:46 volumio volumio[1360]: info: Adding plugin bluetooth to MyMusic Plugins Nov 02 19:41:46 volumio volumio[1360]: info: Adding plugin multiroom to MyMusic Plugins Nov 02 19:41:46 volumio volumio[1360]: info: Adding plugin metavolumio to MyMusic Plugins Nov 02 19:41:46 volumio volumio[1360]: info: Adding plugin cd_controller to MyMusic Plugins Nov 02 19:41:46 volumio volumio[1360]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 02 19:41:46 volumio volumio[1360]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 02 19:41:46 volumio volumio[1360]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 02 19:41:47 volumio volumio[1360]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 02 19:41:47 volumio volumio[1360]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 02 19:41:47 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:41:47 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 19:41:47 volumio volumio[1360]: info: Starting MyVolumio Remote Streaming Endpoints Nov 02 19:41:47 volumio volumio[1360]: info: MyVolumio login type: Token Nov 02 19:41:47 volumio volumio[1360]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 02 19:41:47 volumio volumio[1360]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 02 19:41:48 volumio volumio[1360]: info: Starting Streaming Service Transparent Proxy Nov 02 19:41:48 volumio volumio[1360]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 02 19:41:48 volumio volumio[1360]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 02 19:41:48 volumio volumio[1360]: info: Streaming services startup Nov 02 19:41:48 volumio volumio[1360]: info: Starting Streaming Daemon Nov 02 19:41:48 volumio sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 19:41:48 volumio sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:49 volumio volumio[1360]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 02 19:41:49 volumio sudo[1538]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:49 volumio volumio[1360]: info: Cannot mount NAS Raaverok_Media at system boot, trial number 4 ,retrying in 5 seconds Nov 02 19:41:49 volumio volumio[1360]: info: Cannot mount NAS Raaverok_Media at system boot, trial number 4 ,retrying in 5 seconds Nov 02 19:41:49 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 19:41:49 volumio volumio[1360]: error: Cannot start Volumio Streaming Daemon Nov 02 19:41:49 volumio volumio[1360]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 19:41:49 volumio volumio[1360]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 19:41:49 volumio volumio[1360]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 02 19:41:50 volumio volumio[1360]: info: MyVolumio token set successfully Nov 02 19:41:50 volumio volumio[1360]: info: MYVOLUMIO: Adding device Nov 02 19:41:50 volumio volumio[1360]: info: MYVOLUMIO: Evaluating Server Nov 02 19:41:50 volumio volumio[1360]: STREAMING PROXY: Starting server on port 3245 Nov 02 19:41:50 volumio volumio[1360]: Node JS runtime: 14 Nov 02 19:41:50 volumio volumio[1360]: info: MyVolumio status changed Nov 02 19:41:50 volumio volumio[1360]: info: Streaming services startup Nov 02 19:41:50 volumio volumio[1360]: info: Starting Streaming Daemon Nov 02 19:41:50 volumio volumio[1360]: info: Removing browser output: myVolumio user plan is not superstar Nov 02 19:41:50 volumio volumio[1360]: info: Removing audio output: Nov 02 19:41:50 volumio volumio[1360]: info: Stoppping Tunnel 1 Nov 02 19:41:50 volumio sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 19:41:50 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:50 volumio sudo[1565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 02 19:41:50 volumio sudo[1565]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:50 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:50 volumio volumio[1360]: error: Cannot start Volumio Streaming Daemon Nov 02 19:41:50 volumio volumio[1360]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 19:41:50 volumio volumio[1360]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 19:41:50 volumio sudo[1565]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:50 volumio volumio[1360]: info: Remote SSH Stopped Nov 02 19:41:51 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 19:41:52 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Nov 02 19:41:52 volumio sudo[1569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Raaverok_Media Nov 02 19:41:52 volumio sudo[1569]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:52 volumio systemd[1]: mnt-NAS-Raaverok_Media.mount: Succeeded. Nov 02 19:41:52 volumio sudo[1569]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:52 volumio volumio[1360]: info: Share Raaverok_Media successfully unmounted Nov 02 19:41:53 volumio volumio[1360]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 02 19:41:54 volumio volumio[1360]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 19:41:54 volumio volumio[1360]: TypeError: Cannot read property 'replace' of undefined Nov 02 19:41:54 volumio volumio[1360]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Nov 02 19:41:54 volumio volumio[1360]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Nov 02 19:41:54 volumio volumio[1360]: at listOnTimeout (internal/timers.js:554:17) Nov 02 19:41:54 volumio volumio[1360]: at processTimers (internal/timers.js:497:7) Nov 02 19:41:54 volumio volumio[1360]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 19:41:54 volumio sudo[1576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.196/Raaverok_Media /mnt/NAS/Raaverok_Media Nov 02 19:41:54 volumio sudo[1576]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 19:41:54 volumio kernel: CIFS: Attempting to mount \\192.168.0.196\Raaverok_Media Nov 02 19:41:54 volumio sudo[1576]: pam_unix(sudo:session): session closed for user root Nov 02 19:41:54 volumio sudo[1590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 19:40 Nov 02 19:41:54 volumio sudo[1590]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"