-- Logs begin at Tue 2024-12-17 17:28:04 EST, end at Wed 2025-05-14 10:01:17 EDT. -- May 14 10:00:19 volumiodine systemd-timedated[994]: Changed local time to Wed May 14 10:00:19 2025 May 14 10:00:19 volumiodine sudo[992]: pam_unix(sudo:session): session closed for user root May 14 10:00:19 volumiodine volumio-time-update[584]: volumio-time-update-util: System time updated successfully. May 14 10:00:19 volumiodine systemd[1]: Starting Daily apt download activities... May 14 10:00:19 volumiodine systemd[1]: Started Volumio Time Update Utility. May 14 10:00:19 volumiodine systemd[1]: Reached target Multi-User System. May 14 10:00:19 volumiodine systemd[1]: Reached target Graphical Interface. May 14 10:00:19 volumiodine systemd[1]: Starting Update UTMP about System Runlevel Changes... May 14 10:00:19 volumiodine systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 14 10:00:19 volumiodine systemd[1]: Started Update UTMP about System Runlevel Changes. May 14 10:00:22 volumiodine volumio[959]: info: ------------------------------------------- May 14 10:00:22 volumiodine volumio[959]: info: ----- Volumio3 ---- May 14 10:00:22 volumiodine volumio[959]: info: ------------------------------------------- May 14 10:00:22 volumiodine volumio[959]: info: ----- System startup ---- May 14 10:00:22 volumiodine volumio[959]: info: ------------------------------------------- May 14 10:00:24 volumiodine systemd[1]: apt-daily.service: Succeeded. May 14 10:00:24 volumiodine systemd[1]: Started Daily apt download activities. May 14 10:00:24 volumiodine systemd[1]: Starting Daily apt upgrade and clean activities... May 14 10:00:24 volumiodine volumio[959]: info: MYVOLUMIO Environment detected May 14 10:00:25 volumiodine volumio[959]: info: Plugin folders cleanup May 14 10:00:25 volumiodine volumio[959]: info: Scanning into folder /volumio/app/plugins/ May 14 10:00:25 volumiodine volumio[959]: info: Scanning category audio_interface May 14 10:00:25 volumiodine volumio[959]: info: Scanning category miscellanea May 14 10:00:25 volumiodine volumio[959]: info: Scanning category music_service May 14 10:00:25 volumiodine volumio[959]: info: Scanning category plugins.json May 14 10:00:25 volumiodine volumio[959]: info: Scanning category system_controller May 14 10:00:25 volumiodine volumio[959]: info: Scanning category user_interface May 14 10:00:25 volumiodine volumio[959]: info: Scanning into folder /data/plugins/ May 14 10:00:25 volumiodine volumio[959]: info: Scanning category music_service May 14 10:00:25 volumiodine volumio[959]: info: Scanning category system_controller May 14 10:00:25 volumiodine volumio[959]: info: Scanning category user_interface May 14 10:00:25 volumiodine volumio[959]: info: Plugin folders cleanup completed May 14 10:00:25 volumiodine volumio[959]: info: ------------------------------------------- May 14 10:00:25 volumiodine volumio[959]: info: ----- Core plugins startup ---- May 14 10:00:25 volumiodine volumio[959]: info: ------------------------------------------- May 14 10:00:25 volumiodine volumio[959]: info: Loading plugins from folder /volumio/app/plugins/ May 14 10:00:25 volumiodine volumio[959]: info: Adding plugin upnp to MyMusic Plugins May 14 10:00:25 volumiodine volumio[959]: info: Adding plugin airplay_emulation to MyMusic Plugins May 14 10:00:25 volumiodine volumio[959]: info: Adding plugin upnp_browser to MyMusic Plugins May 14 10:00:25 volumiodine volumio[959]: info: Loading plugins from folder /data/plugins/ May 14 10:00:25 volumiodine volumio[959]: info: Loading plugin "system"... May 14 10:00:25 volumiodine volumio[959]: info: Loading plugin "appearance"... May 14 10:00:25 volumiodine volumio-remote-updater[614]: [2025-05-14 10:00:25] [connect] Successful connection May 14 10:00:27 volumiodine systemd[1]: apt-daily-upgrade.service: Succeeded. May 14 10:00:27 volumiodine systemd[1]: Started Daily apt upgrade and clean activities. May 14 10:00:27 volumiodine systemd[1]: Startup finished in 16.276s (kernel) + 34.110s (userspace) = 50.386s. May 14 10:00:28 volumiodine volumio[959]: info: Loading plugin "network"... May 14 10:00:28 volumiodine volumio[959]: info: Refreshing Cached IP Addresses May 14 10:00:28 volumiodine sudo[1115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 10:00:28 volumiodine sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:28 volumiodine sudo[1115]: pam_unix(sudo:session): session closed for user root May 14 10:00:28 volumiodine sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 10:00:28 volumiodine sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:28 volumiodine sudo[1117]: pam_unix(sudo:session): session closed for user root May 14 10:00:28 volumiodine volumio[959]: info: Loading plugin "services"... May 14 10:00:28 volumiodine volumio[959]: info: Loading plugin "alsa_controller"... May 14 10:00:28 volumiodine sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 14 10:00:28 volumiodine sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:28 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 10:00:28 volumiodine volumio[959]: info: Loading plugin "wizard"... May 14 10:00:28 volumiodine volumio[959]: info: Loading plugin "networkfs"... May 14 10:00:28 volumiodine volumio[959]: info: Starting Udev Watcher for removable devices May 14 10:00:28 volumiodine sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=roon,password=6RmcX8TeuBWmGC7BjmDuQ2rC,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.26.41/Synology\\Music /mnt/NAS/Synology May 14 10:00:28 volumiodine sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:28 volumiodine systemd[1]: systemd-fsckd.service: Succeeded. May 14 10:00:28 volumiodine volumio[959]: info: Ignoring mount for partition: boot May 14 10:00:28 volumiodine volumio[959]: info: Ignoring mount for partition: volumio May 14 10:00:28 volumiodine volumio[959]: info: Ignoring mount for partition: volumio_data May 14 10:00:28 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 14 10:00:28 volumiodine volumio[959]: info: Loading plugin "volumio_command_line_client"... May 14 10:00:28 volumiodine volumio[959]: info: Loading plugin "upnp"... May 14 10:00:28 volumiodine volumio[959]: info: [1747231228686] Starting Upmpd Daemon May 14 10:00:28 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 14 10:00:28 volumiodine volumio[959]: info: Loading plugin "my_music"... May 14 10:00:28 volumiodine volumio[959]: info: Loading plugin "mpd"... May 14 10:00:28 volumiodine sudo[1126]: pam_unix(sudo:session): session closed for user root May 14 10:00:28 volumiodine kernel: Key type cifs.spnego registered May 14 10:00:28 volumiodine kernel: Key type cifs.idmap registered May 14 10:00:28 volumiodine 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. May 14 10:00:28 volumiodine kernel: CIFS: Attempting to mount //192.168.26.41/Synology/Music May 14 10:00:29 volumiodine volumio[959]: info: Loading plugin "upnp_browser"... May 14 10:00:30 volumiodine kernel: cryptd: max_cpu_qlen set to 1000 May 14 10:00:30 volumiodine volumio[959]: info: Loading plugin "alarm-clock"... May 14 10:00:31 volumiodine sh[496]: timed out May 14 10:00:31 volumiodine dhcpcd[533]: timed out May 14 10:00:31 volumiodine sh[496]: dhcpcd exited May 14 10:00:31 volumiodine dhcpcd[533]: dhcpcd exited May 14 10:00:31 volumiodine sh[496]: ifup: failed to bring up eth0 May 14 10:00:31 volumiodine systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 14 10:00:31 volumiodine systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 14 10:00:31 volumiodine volumio[959]: info: Loading plugin "airplay_emulation"... May 14 10:00:31 volumiodine volumio[959]: info: Starting Shairport Sync May 14 10:00:31 volumiodine volumio[959]: info: Loading plugin "last_100"... May 14 10:00:31 volumiodine volumio[959]: info: Loading plugin "webradio"... May 14 10:00:31 volumiodine volumio[959]: info: Loading plugin "i2s_dacs"... May 14 10:00:31 volumiodine volumio[959]: info: Loading plugin "volumiodiscovery"... May 14 10:00:31 volumiodine volumio[959]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 14 10:00:31 volumiodine volumio[959]: *** WARNING *** Please fix your application to use the native API of Avahi! May 14 10:00:31 volumiodine volumio[959]: *** WARNING *** For more information see May 14 10:00:31 volumiodine volumio[959]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 14 10:00:31 volumiodine volumio[959]: *** WARNING *** Please fix your application to use the native API of Avahi! May 14 10:00:31 volumiodine volumio[959]: *** WARNING *** For more information see May 14 10:00:31 volumiodine node[959]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 14 10:00:31 volumiodine node[959]: *** WARNING *** Please fix your application to use the native API of Avahi! May 14 10:00:31 volumiodine node[959]: *** WARNING *** For more information see May 14 10:00:31 volumiodine node[959]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 14 10:00:31 volumiodine node[959]: *** WARNING *** Please fix your application to use the native API of Avahi! May 14 10:00:31 volumiodine node[959]: *** WARNING *** For more information see May 14 10:00:31 volumiodine volumio[959]: info: Applying required configuration parameters for plugin volumiodiscovery May 14 10:00:31 volumiodine volumio[959]: info: Discovery: Started advertising with name: Volumiodine May 14 10:00:31 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 14 10:00:31 volumiodine volumio[959]: info: Loading plugin "mixcloud"... May 14 10:00:31 volumiodine sudo[1143]: pam_unix(sudo:session): session closed for user root May 14 10:00:33 volumiodine volumio[959]: info: Loading plugin "soundcloud"... May 14 10:00:33 volumiodine volumio[959]: info: Loading plugin "ytcr"... May 14 10:00:35 volumiodine systemd[1]: systemd-hostnamed.service: Succeeded. May 14 10:00:37 volumiodine volumio[959]: info: Loading plugin "ytmusic"... May 14 10:00:39 volumiodine volumio[959]: info: Loading plugin "now_playing"... May 14 10:00:40 volumiodine volumio-remote-updater[614]: [2025-05-14 10:00:40] [connect] Successful connection May 14 10:00:42 volumiodine volumio[959]: info: Loading plugin "outputs"... May 14 10:00:42 volumiodine volumio[959]: info: Loading plugin "albumart"... May 14 10:00:42 volumiodine volumio[959]: info: Plugin example_plugin is not enabled May 14 10:00:42 volumiodine volumio[959]: info: Loading plugin "inputs"... May 14 10:00:42 volumiodine volumio[959]: info: Loading plugin "updater_comm"... May 14 10:00:42 volumiodine volumio[959]: info: Plugin mpdemulation is not enabled May 14 10:00:42 volumiodine volumio[959]: info: Loading plugin "rest_api"... May 14 10:00:42 volumiodine volumio[959]: info: Loading plugin "websocket"... May 14 10:00:42 volumiodine volumio[959]: info: Starting Socket.io Server version 2.3.0 May 14 10:00:42 volumiodine volumio[959]: info: Loading plugin "RoonBridge"... May 14 10:00:43 volumiodine volumio[959]: Forking 3 albumart workers May 14 10:00:43 volumiodine volumio[959]: info: Applying required configuration parameters for plugin RoonBridge May 14 10:00:43 volumiodine volumio[959]: info: Loading plugin "minidlna"... May 14 10:00:43 volumiodine volumio[959]: info: Applying required configuration parameters for plugin minidlna May 14 10:00:44 volumiodine volumio[959]: info: Loading plugin "pandora"... May 14 10:00:44 volumiodine volumio[959]: Starting albumart workers May 14 10:00:44 volumiodine volumio[959]: Starting albumart workers May 14 10:00:44 volumiodine volumio[959]: Starting albumart workers May 14 10:00:47 volumiodine volumio[959]: info: Applying required configuration parameters for plugin pandora May 14 10:00:47 volumiodine volumio[959]: info: Loading plugin "radio_paradise"... May 14 10:00:48 volumiodine volumio[959]: info: Applying required configuration parameters for plugin radio_paradise May 14 10:00:48 volumiodine volumio[959]: info: [1747231248078] [RadioParadise] API delay: 5 May 14 10:00:48 volumiodine volumio[959]: info: Loading plugin "volroon"... May 14 10:00:48 volumiodine volumio[959]: info: Applying required configuration parameters for plugin volroon May 14 10:00:48 volumiodine volumio[959]: info: Loading plugin "backup_restore"... May 14 10:00:49 volumiodine systemd[1]: systemd-timedated.service: Succeeded. May 14 10:00:49 volumiodine volumio[959]: info: Applying required configuration parameters for plugin backup_restore May 14 10:00:49 volumiodine volumio[959]: info: Loading plugin "Systeminfo"... May 14 10:00:50 volumiodine volumio[959]: info: Loading i18n strings for locale en May 14 10:00:50 volumiodine volumio[959]: Updating browse sources language May 14 10:00:50 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 10:00:50 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 10:00:50 volumiodine volumio[959]: info: CoreCommandRouter::initPlayerControls May 14 10:00:50 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:50 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:50 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:50 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:50 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:50 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:50 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:50 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:50 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 10:00:50 volumiodine volumio[959]: Express server listening on port 3000 May 14 10:00:50 volumiodine volumio[959]: [Metrics] WebUI: 30s 740.50ms May 14 10:00:51 volumiodine volumio[959]: info: CoreStateMachine::resetVolumioState May 14 10:00:51 volumiodine volumio[959]: info: CoreStateMachine::getcurrentVolume May 14 10:00:51 volumiodine volumio[959]: info: CoreCommandRouter::volumioRetrievevolume May 14 10:00:51 volumiodine sudo[1282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 10:00:51 volumiodine sudo[1282]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:51 volumiodine sudo[1282]: pam_unix(sudo:session): session closed for user root May 14 10:00:51 volumiodine sudo[1284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 10:00:51 volumiodine sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:51 volumiodine sudo[1284]: pam_unix(sudo:session): session closed for user root May 14 10:00:51 volumiodine volumio[959]: info: Volumio Network Manager: Network status updated: 2 May 14 10:00:51 volumiodine volumio[959]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Headphone',0 May 14 10:00:51 volumiodine volumio[959]: info: VolumeController:: Volume=undefined Mute =false May 14 10:00:51 volumiodine volumio[959]: info: CoreStateMachine::pushState May 14 10:00:51 volumiodine volumio[959]: info: CorePlayQueue::getTrack 0 May 14 10:00:51 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 10:00:51 volumiodine volumio[959]: info: CoreCommandRouter::volumioPushState May 14 10:00:51 volumiodine volumio[959]: info: CoreStateMachine::updateTrackBlock May 14 10:00:51 volumiodine volumio[959]: info: CorePlayQueue::getTrackBlock May 14 10:00:51 volumiodine volumio[959]: info: CoreCommandRouter::volumioRetrievevolume May 14 10:00:51 volumiodine volumio[959]: info: Reloading queue from file May 14 10:00:51 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 10:00:51 volumiodine volumio[959]: info: CoreStateMachine::setRepeat null single undefined May 14 10:00:51 volumiodine volumio[959]: info: CoreStateMachine::pushState May 14 10:00:51 volumiodine volumio[959]: info: CorePlayQueue::getTrack 0 May 14 10:00:51 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 10:00:51 volumiodine volumio[959]: info: CoreCommandRouter::volumioPushState May 14 10:00:51 volumiodine volumio[959]: info: CoreStateMachine::setRandom null May 14 10:00:51 volumiodine volumio[959]: info: CoreStateMachine::pushState May 14 10:00:51 volumiodine volumio[959]: info: CorePlayQueue::getTrack 0 May 14 10:00:51 volumiodine volumio[959]: info: CoreCommandRouter::volumioPushState May 14 10:00:52 volumiodine volumio[959]: info: Setting Device type: Raspberry PI May 14 10:00:52 volumiodine volumio[959]: info: Completed loading Core Plugins May 14 10:00:52 volumiodine volumio[959]: info: Preparing to generate the ALSA configuration file May 14 10:00:52 volumiodine volumio[959]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Headphone',0 May 14 10:00:52 volumiodine volumio[959]: info: Asound.conf file unchanged, so no further update is needed May 14 10:00:52 volumiodine volumio[959]: info: Output device has changed, restarting MPD May 14 10:00:52 volumiodine sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 14 10:00:52 volumiodine sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:52 volumiodine sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 10:00:52 volumiodine sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:52 volumiodine volumio[959]: info: Output device has changed, restarting Shairport Sync May 14 10:00:52 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:52 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 10:00:52 volumiodine sudo[1298]: pam_unix(sudo:session): session closed for user root May 14 10:00:52 volumiodine sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 10:00:52 volumiodine sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:52 volumiodine systemd[1]: Started UPnP Renderer front-end to MPD. May 14 10:00:52 volumiodine volumio[959]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 10:00:52 volumiodine sudo[1296]: pam_unix(sudo:session): session closed for user root May 14 10:00:52 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo May 14 10:00:52 volumiodine systemd[1]: Stopping Music Player Daemon... May 14 10:00:52 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:52 volumiodine volumio[959]: info: volroon::Output device has changed May 14 10:00:52 volumiodine volumio[959]: info: ___________ START PLUGINS ___________ May 14 10:00:52 volumiodine volumio[959]: info: ControllerMpd::onStart: Initializing MPD May 14 10:00:52 volumiodine volumio[959]: info: Creating MPD Configuration file May 14 10:00:52 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 10:00:52 volumiodine volumio[959]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 14 10:00:52 volumiodine volumio[959]: info: [1747231252560] CoreMusicLibrary::Adding element Media Servers May 14 10:00:52 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 10:00:52 volumiodine sudo[1311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 10:00:52 volumiodine sudo[1311]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:52 volumiodine sudo[1311]: pam_unix(sudo:session): session closed for user root May 14 10:00:52 volumiodine systemd[1]: mpd.service: Succeeded. May 14 10:00:52 volumiodine systemd[1]: Stopped Music Player Daemon. May 14 10:00:52 volumiodine sudo[1313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 10:00:52 volumiodine sudo[1313]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:52 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:52 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 10:00:52 volumiodine systemd[1]: Starting Music Player Daemon... May 14 10:00:52 volumiodine systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 14 10:00:52 volumiodine systemd[1]: mpd.service: Succeeded. May 14 10:00:52 volumiodine systemd[1]: Stopped Music Player Daemon. May 14 10:00:52 volumiodine systemd[1]: Starting Music Player Daemon... May 14 10:00:52 volumiodine volumio[959]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 10:00:52 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:52 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 10:00:52 volumiodine volumio[959]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 10:00:52 volumiodine volumio[959]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 14 10:00:52 volumiodine volumio[959]: info: [1747231252850] CoreMusicLibrary::Adding element Last_100 May 14 10:00:52 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 10:00:52 volumiodine volumio[959]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 14 10:00:52 volumiodine volumio[959]: info: [1747231252857] CoreMusicLibrary::Adding element Webradio May 14 10:00:52 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 10:00:52 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 10:00:52 volumiodine volumio[959]: info: Initializing BBC Radios May 14 10:00:52 volumiodine sudo[1320]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 14 10:00:52 volumiodine sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:53 volumiodine sudo[1320]: pam_unix(sudo:session): session closed for user root May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 14 10:00:53 volumiodine volumio[959]: info: [1747231253046] CoreMusicLibrary::Adding element Mixcloud May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 10:00:53 volumiodine volumio[959]: Cannot find translation for source Mixcloud May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 14 10:00:53 volumiodine volumio[959]: info: [1747231253065] CoreMusicLibrary::Adding element SoundCloud May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 10:00:53 volumiodine volumio[959]: Cannot find translation for source Mixcloud May 14 10:00:53 volumiodine volumio[959]: Cannot find translation for source SoundCloud May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 14 10:00:53 volumiodine volumio[959]: info: [1747231253107] CoreMusicLibrary::Adding element YouTube Music May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 10:00:53 volumiodine volumio[959]: Cannot find translation for source Mixcloud May 14 10:00:53 volumiodine volumio[959]: Cannot find translation for source SoundCloud May 14 10:00:53 volumiodine volumio[959]: Cannot find translation for source YouTube Music May 14 10:00:53 volumiodine volumio[959]: info: [now-playing-config] Config is up to date. May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 10:00:53 volumiodine volumio[959]: info: Discovery: Getting this device information May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::volumioGetState May 14 10:00:53 volumiodine volumio[959]: info: CorePlayQueue::getTrack 0 May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 10:00:53 volumiodine volumio[959]: info: Loading i18n strings for locale en May 14 10:00:53 volumiodine sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service May 14 10:00:53 volumiodine sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 14 10:00:53 volumiodine volumio[959]: info: [1747231253302] CoreMusicLibrary::Adding element Pandora Radio May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 10:00:53 volumiodine volumio[959]: Cannot find translation for source Mixcloud May 14 10:00:53 volumiodine volumio[959]: Cannot find translation for source SoundCloud May 14 10:00:53 volumiodine volumio[959]: Cannot find translation for source YouTube Music May 14 10:00:53 volumiodine volumio[959]: Cannot find translation for source Pandora Radio May 14 10:00:53 volumiodine volumio[959]: info: [1747231253310] ControllerPandora::initializeMQTT May 14 10:00:53 volumiodine volumio[959]: info: [1747231253312] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 14 10:00:53 volumiodine volumio[959]: info: [1747231253330] CoreMusicLibrary::Adding element Radio Paradise May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 10:00:53 volumiodine volumio[959]: Cannot find translation for source Mixcloud May 14 10:00:53 volumiodine volumio[959]: Cannot find translation for source SoundCloud May 14 10:00:53 volumiodine volumio[959]: Cannot find translation for source YouTube Music May 14 10:00:53 volumiodine volumio[959]: Cannot find translation for source Pandora Radio May 14 10:00:53 volumiodine volumio[959]: Cannot find translation for source Radio Paradise May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo May 14 10:00:53 volumiodine systemd[1]: Started RoonBridge. May 14 10:00:53 volumiodine sudo[1337]: pam_unix(sudo:session): session closed for user root May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:53 volumiodine sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service May 14 10:00:53 volumiodine sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:53 volumiodine sudo[1352]: pam_unix(sudo:session): session closed for user root May 14 10:00:53 volumiodine volumio[959]: info: volroon::Roon API Services Started May 14 10:00:53 volumiodine volumio[959]: info: Volumio Calling Home May 14 10:00:53 volumiodine volumio[959]: info: [now-playing-app] App is listening on port 4004. May 14 10:00:53 volumiodine volumio[959]: info: [1747231253855] ControllerPandora::initialSetup May 14 10:00:53 volumiodine volumio[959]: info: [1747231253875] ControllerPandora::PandoraHandler::init May 14 10:00:53 volumiodine volumio[959]: info: [1747231253877] ControllerPandora::PandoraHandler::loadErrorCodes May 14 10:00:53 volumiodine volumio[959]: info: [1747231253925] [Pandora] PandoraHandler::setMaxStationTracks: 16 May 14 10:00:53 volumiodine volumio[959]: info: [1747231253928] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty May 14 10:00:53 volumiodine volumio[959]: info: [1747231253930] ControllerPandora::flushPandora May 14 10:00:53 volumiodine volumio[959]: info: CorePlayQueue::getQueue May 14 10:00:53 volumiodine volumio[959]: info: CorePlayQueue::clearAddPlayQueue May 14 10:00:53 volumiodine volumio[959]: info: CorePlayQueue::saveQueue May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::serviceClearAddPlayTracks May 14 10:00:53 volumiodine volumio[959]: error: WARNING: No clearAddPlayTracks method for service webradio May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::volumioPushQueue May 14 10:00:53 volumiodine volumio[959]: info: [1747231253947] ControllerPandora::validateAndSetAccountOptions May 14 10:00:53 volumiodine volumio[959]: info: [1747231253950] ControllerPandora::PandoraHandler::setAccountOptions May 14 10:00:53 volumiodine volumio[959]: info: [1747231253954] ControllerPandora::flushPandora May 14 10:00:53 volumiodine volumio[959]: info: CorePlayQueue::getQueue May 14 10:00:53 volumiodine volumio[959]: info: CorePlayQueue::clearAddPlayQueue May 14 10:00:53 volumiodine volumio[959]: info: CorePlayQueue::saveQueue May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::serviceClearAddPlayTracks May 14 10:00:53 volumiodine volumio[959]: error: WARNING: No clearAddPlayTracks method for service webradio May 14 10:00:53 volumiodine volumio[959]: info: CoreCommandRouter::volumioPushQueue May 14 10:00:53 volumiodine volumio[959]: info: [1747231253967] [Pandora] PreventAuthTimeout::init: Timer loaded May 14 10:00:53 volumiodine volumio[959]: info: [1747231253968] [Pandora] PreventAuthTimeout::init: Interval set to 10800000 ms May 14 10:00:53 volumiodine volumio[959]: info: [1747231253969] [Pandora] PreventAuthTimeout::init: Starting delayed timer after next interval May 14 10:00:53 volumiodine volumio[959]: info: [1747231253971] ControllerPandora::PandoraHandler::pandoraLoginAndGetStations May 14 10:00:54 volumiodine volumio[959]: info: Discovery: adding c58b88b9-ea68-424d-8ed7-1a6a6b70d1f1 May 14 10:00:54 volumiodine volumio[959]: info: Discovery: Found device Volumiodine May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::volumioGetState May 14 10:00:54 volumiodine volumio[959]: info: CorePlayQueue::getTrack 0 May 14 10:00:54 volumiodine volumio[959]: info: ::Roon Bridge has successfully started May 14 10:00:54 volumiodine volumio[959]: info: minidlna: Found miniDLNA version 1.2.1 May 14 10:00:54 volumiodine volumio[959]: info: minidlna: Starting minidlna.service May 14 10:00:54 volumiodine volumio[959]: info: MPD Permissions set May 14 10:00:54 volumiodine volumio[959]: info: MPD Permissions set May 14 10:00:54 volumiodine volumio[959]: info: Upmpdcli Daemon Started May 14 10:00:54 volumiodine volumio[959]: info: VolumeController:: Volume=undefined Mute =false May 14 10:00:54 volumiodine volumio[959]: info: CoreStateMachine::pushState May 14 10:00:54 volumiodine volumio[959]: info: CorePlayQueue::getTrack 0 May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::volumioPushState May 14 10:00:54 volumiodine sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service May 14 10:00:54 volumiodine sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:54 volumiodine volumio[959]: info: Volumio called home May 14 10:00:54 volumiodine systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. May 14 10:00:54 volumiodine volumio[959]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:54 volumiodine sudo[1360]: pam_unix(sudo:session): session closed for user root May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 10:00:54 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 10:00:55 volumiodine volumio-remote-updater[614]: [2025-05-14 10:00:55] [connect] Successful connection May 14 10:00:55 volumiodine volumio[959]: info: minidlna: systemctl start minidlna.service succeeded. May 14 10:00:55 volumiodine volumio[959]: info: Starting Shairport Sync May 14 10:00:55 volumiodine volumio[959]: info: Starting Shairport Sync May 14 10:00:55 volumiodine sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 10:00:55 volumiodine volumio[959]: info: Starting Shairport Sync May 14 10:00:55 volumiodine sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:56 volumiodine sudo[1383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 10:00:56 volumiodine volumio[959]: info: CoreCommandRouter::volumioGetState May 14 10:00:56 volumiodine volumio[959]: info: CorePlayQueue::getTrack 0 May 14 10:00:56 volumiodine sudo[1383]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:56 volumiodine volumio-remote-updater[614]: [2025-05-14 10:00:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747231255 101 May 14 10:00:56 volumiodine sudo[1380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 10:00:56 volumiodine systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 14 10:00:56 volumiodine sudo[1380]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:00:56 volumiodine systemd[1]: shairport-sync.service: Succeeded. May 14 10:00:56 volumiodine volumio[959]: 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: 2 May 14 10:00:56 volumiodine systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 14 10:00:56 volumiodine systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 14 10:00:56 volumiodine sudo[1378]: pam_unix(sudo:session): session closed for user root May 14 10:00:56 volumiodine volumio[959]: info: Shairport-Sync Started May 14 10:00:56 volumiodine volumio[959]: Error adding Membership: Error: addMembership EINVAL May 14 10:00:56 volumiodine systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 14 10:00:56 volumiodine systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 14 10:00:56 volumiodine systemd[1]: shairport-sync.service: Succeeded. May 14 10:00:56 volumiodine systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 14 10:00:56 volumiodine systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 14 10:00:56 volumiodine sudo[1380]: pam_unix(sudo:session): session closed for user root May 14 10:00:56 volumiodine sudo[1383]: pam_unix(sudo:session): session closed for user root May 14 10:00:56 volumiodine volumio[959]: info: [1747231256386] [Pandora] PandoraHandler::pandoraLoginAndGetStations::pandoraLogin: [<=- Logged in to Pandora Servers -=>] May 14 10:00:56 volumiodine volumio[959]: info: [1747231256407] [Pandora] validateAndSetAccountOptions: ***DEBUG*** self.loggedIn: true May 14 10:00:56 volumiodine volumio[959]: info: [1747231256416] [Pandora] ExpireOldTracks::init: Timer loaded May 14 10:00:56 volumiodine volumio[959]: info: [1747231256417] [Pandora] ExpireOldTracks::init: Interval set to 300000 ms May 14 10:00:56 volumiodine volumio[959]: info: [1747231256418] [Pandora] ExpireOldTracks::init: Starting delayed timer after next interval May 14 10:00:56 volumiodine volumio[959]: info: [1747231256420] ControllerPandora::PandoraHandler::fillStationData May 14 10:00:56 volumiodine volumio[959]: info: Shairport-Sync Started May 14 10:00:56 volumiodine volumio[959]: info: Shairport-Sync Started May 14 10:00:56 volumiodine mpd[1335]: May 14 10:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 14 10:00:56 volumiodine systemd[1]: Started Music Player Daemon. May 14 10:00:56 volumiodine sudo[1301]: pam_unix(sudo:session): session closed for user root May 14 10:00:56 volumiodine volumio[959]: Upnp client error: Error: This socket has been ended by the other party May 14 10:00:56 volumiodine sudo[1313]: pam_unix(sudo:session): session closed for user root May 14 10:00:56 volumiodine volroon[1341]: 00:00:00.035 Warn: get lock file path: /tmp/.rnbgem0- May 14 10:00:56 volumiodine volumio[959]: error: MPD error: The expression evaluated to a falsy value: May 14 10:00:56 volumiodine volumio[959]: assert.ok(self.idling) May 14 10:00:56 volumiodine volumio[959]: error: The expression evaluated to a falsy value: May 14 10:00:56 volumiodine volumio[959]: assert.ok(self.idling) May 14 10:00:56 volumiodine volumio[959]: error: updateQueue error: null May 14 10:00:56 volumiodine volumio[959]: info: MPD running with PID1335 May 14 10:00:56 volumiodine volumio[959]: ,establishing connection May 14 10:00:56 volumiodine volumio[959]: error: updateQueue error: null May 14 10:00:57 volumiodine volumio[1303]: Generating RSA private key, 4096 bit long modulus (2 primes) May 14 10:00:57 volumiodine volumio[959]: [yt-cast-receiver] Listening on port 8098 May 14 10:00:57 volumiodine volumio[959]: info: Completed starting Core Plugins May 14 10:00:57 volumiodine volumio[959]: info: ------------------------------------------- May 14 10:00:57 volumiodine volumio[959]: info: ----- MyVolumio plugins startup ---- May 14 10:00:57 volumiodine volumio[959]: info: ------------------------------------------- May 14 10:00:57 volumiodine volumio[959]: info: [MyVolumio PluginManager] Fetching plans data.... May 14 10:00:57 volumiodine volroon[1341]: 00:00:01.377 Trace: [childprocess] using unix child process May 14 10:00:57 volumiodine volroon[1341]: 00:00:01.580 Debug: PathForResource, filename: ../.update May 14 10:00:58 volumiodine volroon[1341]: 00:00:01.726 Debug: PathForResource, candidate: /data/plugins/music_service/volroon/RoonBridge/Bridge/../.update May 14 10:00:58 volumiodine volroon[1341]: 00:00:01.751 Debug: PathForResource, filename: RAATServer May 14 10:00:58 volumiodine volroon[1341]: 00:00:01.752 Debug: PathForResource, candidate: /data/plugins/music_service/volroon/RoonBridge/Bridge/RAATServer May 14 10:00:58 volumiodine volroon[1341]: 00:00:01.755 Debug: PathForResource, filename: RoonBridgeHelper May 14 10:00:58 volumiodine volroon[1341]: 00:00:01.758 Debug: PathForResource, candidate: /data/plugins/music_service/volroon/RoonBridge/Bridge/RoonBridgeHelper May 14 10:00:58 volumiodine volroon[1341]: Initializing May 14 10:00:58 volumiodine volroon[1341]: 00:00:01.893 Info: Starting /data/plugins/music_service/volroon/RoonBridge/Bridge/RoonBridgeHelper May 14 10:00:58 volumiodine volroon[1341]: 00:00:02.001 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/volroon/RoonBridge/Bridge/RAATServer May 14 10:00:58 volumiodine volroon[1341]: Not Running (.o) May 14 10:00:59 volumiodine volroon[1341]: Running May 14 10:01:00 volumiodine volroon[1341]: 00:00:00.106 Warn: get lock file path: /tmp/.rnbhgem0- May 14 10:01:01 volumiodine volumio[959]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 14 10:01:02 volumiodine volumio[1303]: ...............++++ May 14 10:01:05 volumiodine volroon[1341]: 00:00:09.424 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused May 14 10:01:05 volumiodine volroon[1341]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in :0 May 14 10:01:05 volumiodine volroon[1341]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in :0 May 14 10:01:05 volumiodine volroon[1341]: Not Running (.o) May 14 10:01:05 volumiodine volroon[1341]: 00:00:09.484 Debug: PathForResource, filename: ../.update May 14 10:01:05 volumiodine volroon[1341]: 00:00:09.485 Debug: PathForResource, candidate: /data/plugins/music_service/volroon/RoonBridge/Bridge/../.update May 14 10:01:06 volumiodine sudo[1498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 10:01:06 volumiodine sudo[1498]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:01:06 volumiodine sudo[1500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 10:01:06 volumiodine sudo[1500]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:01:06 volumiodine sudo[1498]: pam_unix(sudo:session): session closed for user root May 14 10:01:06 volumiodine sudo[1500]: pam_unix(sudo:session): session closed for user root May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 14 10:01:06 volumiodine volumio[959]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 14 10:01:06 volumiodine volumio[959]: info: Adding plugin bluetooth to MyMusic Plugins May 14 10:01:06 volumiodine volumio[959]: info: Adding plugin multiroom to MyMusic Plugins May 14 10:01:06 volumiodine volumio[959]: info: Adding plugin metavolumio to MyMusic Plugins May 14 10:01:06 volumiodine volumio[959]: info: Adding plugin cd_controller to MyMusic Plugins May 14 10:01:06 volumiodine volumio[959]: info: Adding plugin smart_inputs to MyMusic Plugins May 14 10:01:06 volumiodine volumio[959]: info: Adding plugin tidalconnect to MyMusic Plugins May 14 10:01:07 volumiodine volumio[959]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 14 10:01:07 volumiodine volroon[1341]: 00:00:11.487 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/volroon/RoonBridge/Bridge/RAATServer May 14 10:01:07 volumiodine volroon[1341]: Running May 14 10:01:10 volumiodine ntpd[764]: Soliciting pool server 198.71.50.75 May 14 10:01:10 volumiodine volumio[959]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 14 10:01:10 volumiodine volumio[959]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 14 10:01:10 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 10:01:10 volumiodine volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 10:01:10 volumiodine volumio[959]: info: Starting MyVolumio Remote Streaming Endpoints May 14 10:01:10 volumiodine volumio[959]: info: MyVolumio login type: Token May 14 10:01:10 volumiodine volumio[959]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 14 10:01:10 volumiodine volumio[959]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 14 10:01:10 volumiodine ntpd[764]: Soliciting pool server 216.240.36.24 May 14 10:01:11 volumiodine ntpd[764]: Soliciting pool server 137.110.222.27 May 14 10:01:11 volumiodine ntpd[764]: Soliciting pool server 144.202.62.209 May 14 10:01:11 volumiodine ntpd[764]: Soliciting pool server 23.142.248.8 May 14 10:01:11 volumiodine ntpd[764]: Soliciting pool server 50.251.160.20 May 14 10:01:13 volumiodine ntpd[764]: Soliciting pool server 23.95.49.216 May 14 10:01:13 volumiodine volumio[959]: info: Starting Streaming Service Transparent Proxy May 14 10:01:13 volumiodine volumio[959]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 14 10:01:13 volumiodine volumio[959]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 14 10:01:13 volumiodine volumio[959]: info: Streaming services startup May 14 10:01:13 volumiodine volumio[959]: info: Starting Streaming Daemon May 14 10:01:14 volumiodine ntpd[764]: Soliciting pool server 199.68.201.235 May 14 10:01:14 volumiodine sudo[1551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 14 10:01:14 volumiodine sudo[1551]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:01:14 volumiodine sudo[1551]: pam_unix(sudo:session): session closed for user root May 14 10:01:14 volumiodine volumio[959]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 14 10:01:14 volumiodine volumio[959]: error: Cannot start Volumio Streaming Daemon May 14 10:01:14 volumiodine volumio[959]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 14 10:01:14 volumiodine volumio[959]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 14 10:01:14 volumiodine ntpd[764]: Soliciting pool server 216.66.48.42 May 14 10:01:15 volumiodine volumio[959]: verbose: New Socket.io Connection to 192.168.26.30 from 192.168.26.223 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Edg/137.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 May 14 10:01:15 volumiodine volumio[959]: verbose: New Socket.io Connection to 192.168.26.30 from 192.168.26.223 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Edg/137.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 May 14 10:01:15 volumiodine volumio[959]: verbose: New Socket.io Connection to 192.168.26.30 from 192.168.26.223 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Edg/137.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 May 14 10:01:15 volumiodine volumio[959]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 10:01:15 volumiodine volumio[959]: TypeError: Cannot read property 'ping' of undefined May 14 10:01:15 volumiodine volumio[959]: at Timeout._onTimeout (/data/plugins/music_service/volroon/node_modules/node-roon-api/transport-websocket.js:26:21) May 14 10:01:15 volumiodine volumio[959]: at listOnTimeout (internal/timers.js:554:17) May 14 10:01:15 volumiodine volumio[959]: at processTimers (internal/timers.js:497:7) May 14 10:01:15 volumiodine volumio[959]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 10:01:15 volumiodine ntpd[764]: Soliciting pool server 158.51.99.19 May 14 10:01:15 volumiodine ntpd[764]: Soliciting pool server 104.131.155.175 May 14 10:01:16 volumiodine kernel: TCP: request_sock_TCP: Possible SYN flooding on port 0.0.0.0:8200. Sending cookies. May 14 10:01:16 volumiodine ntpd[764]: Soliciting pool server 70.116.101.97 May 14 10:01:16 volumiodine ntpd[764]: Soliciting pool server 5.78.121.38 May 14 10:01:17 volumiodine sudo[1578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-14 10:00 May 14 10:01:17 volumiodine sudo[1578]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 10:01:17 volumiodine ntpd[764]: Soliciting pool server 15.204.87.223 PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"