-- Logs begin at Thu 2019-02-14 19:11:59 KST, end at Sun 2025-06-01 23:36:14 KST. -- Jun 01 23:35:00 black volumio[981]: info: MYVOLUMIO Environment detected Jun 01 23:35:00 black volumio[981]: info: Plugin folders cleanup Jun 01 23:35:00 black volumio[981]: info: Scanning into folder /volumio/app/plugins/ Jun 01 23:35:00 black volumio[981]: info: Scanning category audio_interface Jun 01 23:35:00 black volumio[981]: info: Scanning category miscellanea Jun 01 23:35:00 black volumio[981]: info: Scanning category music_service Jun 01 23:35:00 black volumio[981]: info: Scanning category plugins.json Jun 01 23:35:00 black volumio[981]: info: Scanning category system_controller Jun 01 23:35:00 black volumio[981]: info: Scanning category user_interface Jun 01 23:35:00 black volumio[981]: info: Scanning into folder /data/plugins/ Jun 01 23:35:00 black volumio[981]: info: Scanning category music_service Jun 01 23:35:00 black volumio[981]: info: Plugin folders cleanup completed Jun 01 23:35:00 black volumio[981]: info: ------------------------------------------- Jun 01 23:35:00 black volumio[981]: info: ----- Core plugins startup ---- Jun 01 23:35:00 black volumio[981]: info: ------------------------------------------- Jun 01 23:35:00 black volumio[981]: info: Loading plugins from folder /volumio/app/plugins/ Jun 01 23:35:00 black volumio[981]: info: Adding plugin upnp to MyMusic Plugins Jun 01 23:35:00 black volumio[981]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 01 23:35:00 black volumio[981]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 01 23:35:00 black volumio[981]: info: Loading plugins from folder /data/plugins/ Jun 01 23:35:00 black volumio[981]: info: Loading plugin "system"... Jun 01 23:35:00 black volumio[981]: info: Loading plugin "appearance"... Jun 01 23:35:01 black systemd[1]: apt-daily.service: Succeeded. Jun 01 23:35:01 black systemd[1]: Started Daily apt download activities. Jun 01 23:35:01 black systemd[1]: Startup finished in 17.425s (kernel) + 40.730s (userspace) = 58.156s. Jun 01 23:35:01 black volumio-remote-updater[629]: [2025-06-01 23:35:01] [connect] Successful connection Jun 01 23:35:02 black volumio[981]: info: Loading plugin "network"... Jun 01 23:35:02 black volumio[981]: info: Refreshing Cached IP Addresses Jun 01 23:35:02 black sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 23:35:02 black sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:02 black sudo[1091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 23:35:02 black sudo[1091]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:02 black sudo[1089]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:02 black volumio[981]: info: Loading plugin "services"... Jun 01 23:35:02 black sudo[1091]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:02 black volumio[981]: info: Loading plugin "alsa_controller"... Jun 01 23:35:02 black sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 01 23:35:02 black sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:02 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 23:35:02 black volumio[981]: info: Loading plugin "wizard"... Jun 01 23:35:02 black volumio[981]: info: Loading plugin "networkfs"... Jun 01 23:35:02 black volumio[981]: info: Starting Udev Watcher for removable devices Jun 01 23:35:02 black volumio[981]: info: Ignoring mount for partition: boot Jun 01 23:35:02 black volumio[981]: info: Ignoring mount for partition: volumio Jun 01 23:35:02 black volumio[981]: info: Ignoring mount for partition: volumio_data Jun 01 23:35:02 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 01 23:35:02 black volumio[981]: info: Loading plugin "volumio_command_line_client"... Jun 01 23:35:02 black volumio[981]: info: Loading plugin "upnp"... Jun 01 23:35:02 black volumio[981]: info: [1748788502696] Starting Upmpd Daemon Jun 01 23:35:02 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 01 23:35:02 black volumio[981]: info: Loading plugin "my_music"... Jun 01 23:35:02 black volumio[981]: info: Loading plugin "mpd"... Jun 01 23:35:03 black systemd[1]: systemd-hostnamed.service: Succeeded. Jun 01 23:35:03 black volumio[981]: info: Loading plugin "upnp_browser"... Jun 01 23:35:04 black volumio[981]: info: Loading plugin "alarm-clock"... Jun 01 23:35:04 black volumio[981]: info: Loading plugin "airplay_emulation"... Jun 01 23:35:04 black volumio[981]: info: Starting Shairport Sync Jun 01 23:35:04 black volumio[981]: info: Loading plugin "last_100"... Jun 01 23:35:04 black volumio[981]: info: Loading plugin "webradio"... Jun 01 23:35:05 black volumio[981]: info: Loading plugin "i2s_dacs"... Jun 01 23:35:05 black volumio[981]: info: I2S DAC not set, start Auto-detection Jun 01 23:35:05 black volumio[981]: info: Loading plugin "volumiodiscovery"... Jun 01 23:35:05 black volumio[981]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 01 23:35:05 black volumio[981]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 01 23:35:05 black volumio[981]: *** WARNING *** For more information see Jun 01 23:35:05 black node[981]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 01 23:35:05 black volumio[981]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 01 23:35:05 black volumio[981]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 01 23:35:05 black volumio[981]: *** WARNING *** For more information see Jun 01 23:35:05 black node[981]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 01 23:35:05 black node[981]: *** WARNING *** For more information see Jun 01 23:35:05 black node[981]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 01 23:35:05 black node[981]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 01 23:35:05 black node[981]: *** WARNING *** For more information see Jun 01 23:35:05 black volumio[981]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 01 23:35:05 black volumio[981]: info: Discovery: Started advertising with name: Black Jun 01 23:35:05 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 01 23:35:05 black volumio[981]: info: Loading plugin "outputs"... Jun 01 23:35:05 black volumio[981]: info: Loading plugin "albumart"... Jun 01 23:35:05 black volumio[981]: info: Plugin example_plugin is not enabled Jun 01 23:35:05 black volumio[981]: info: Loading plugin "inputs"... Jun 01 23:35:05 black volumio[981]: info: Loading plugin "updater_comm"... Jun 01 23:35:05 black volumio[981]: info: Plugin mpdemulation is not enabled Jun 01 23:35:05 black volumio[981]: info: Loading plugin "rest_api"... Jun 01 23:35:06 black volumio[981]: info: Loading plugin "websocket"... Jun 01 23:35:06 black volumio[981]: info: Starting Socket.io Server version 2.3.0 Jun 01 23:35:06 black volumio[981]: info: Loading plugin "personal_radio"... Jun 01 23:35:06 black volumio[981]: Forking 3 albumart workers Jun 01 23:35:06 black sudo[1099]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:07 black volumio[981]: info: ControllerPersonalRadio::constructor Jun 01 23:35:07 black volumio[981]: info: Loading plugin "podcast"... Jun 01 23:35:08 black volumio[981]: Starting albumart workers Jun 01 23:35:08 black volumio[981]: Starting albumart workers Jun 01 23:35:08 black volumio[981]: info: ControllerPodcast::constructor Jun 01 23:35:08 black volumio[981]: Starting albumart workers Jun 01 23:35:08 black volumio[981]: info: Loading i18n strings for locale ko Jun 01 23:35:08 black volumio[981]: Updating browse sources language Jun 01 23:35:08 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 23:35:09 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 23:35:09 black volumio[981]: info: CoreCommandRouter::initPlayerControls Jun 01 23:35:09 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:09 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:09 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:09 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:09 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:09 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:09 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:09 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:09 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 23:35:09 black volumio[981]: Express server listening on port 3000 Jun 01 23:35:09 black volumio[981]: [Metrics] WebUI: 13s 613.07ms Jun 01 23:35:09 black volumio[981]: info: CoreStateMachine::resetVolumioState Jun 01 23:35:09 black volumio[981]: info: CoreStateMachine::getcurrentVolume Jun 01 23:35:09 black volumio[981]: info: CoreCommandRouter::volumioRetrievevolume Jun 01 23:35:09 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:09 black volumio[981]: info: Volumio Network Manager: Network status updated: 2 Jun 01 23:35:09 black volumio[981]: info: Reloading queue from file Jun 01 23:35:09 black volumio[981]: info: CoreStateMachine::setRepeat true single undefined Jun 01 23:35:09 black volumio[981]: info: CoreStateMachine::pushState Jun 01 23:35:09 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:09 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:35:09 black volumio[981]: info: CoreCommandRouter::volumioPushState Jun 01 23:35:09 black volumio[981]: info: CoreStateMachine::setRandom null Jun 01 23:35:09 black volumio[981]: info: CoreStateMachine::pushState Jun 01 23:35:09 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:09 black volumio[981]: info: CoreCommandRouter::volumioPushState Jun 01 23:35:10 black volumio[981]: info: VolumeController:: Volume=100 Mute =false Jun 01 23:35:10 black volumio[981]: info: CoreStateMachine::pushState Jun 01 23:35:10 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::volumioPushState Jun 01 23:35:10 black volumio[981]: info: CoreStateMachine::updateTrackBlock Jun 01 23:35:10 black volumio[981]: info: CorePlayQueue::getTrackBlock Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::volumioRetrievevolume Jun 01 23:35:10 black volumio[981]: info: Setting Device type: Raspberry PI Jun 01 23:35:10 black volumio[981]: info: Discovery: adding 4b2bcc54-bbc2-40a8-968c-40394b84a03f Jun 01 23:35:10 black volumio[981]: info: Discovery: Found device Black Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::volumioGetState Jun 01 23:35:10 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:10 black volumio[981]: info: Completed loading Core Plugins Jun 01 23:35:10 black volumio[981]: info: Preparing to generate the ALSA configuration file Jun 01 23:35:10 black volumio[981]: info: VolumeController:: Volume=100 Mute =false Jun 01 23:35:10 black volumio[981]: info: CoreStateMachine::pushState Jun 01 23:35:10 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::volumioPushState Jun 01 23:35:10 black volumio[981]: info: Asound.conf file unchanged, so no further update is needed Jun 01 23:35:10 black volumio[981]: info: Output device has changed, restarting MPD Jun 01 23:35:10 black volumio[981]: info: Output device has changed, restarting Shairport Sync Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:10 black sudo[1186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 23:35:10 black sudo[1186]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:10 black sudo[1186]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:10 black sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 23:35:10 black sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:10 black systemd[1]: Stopping Music Player Daemon... Jun 01 23:35:10 black volumio[981]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 23:35:10 black volumio[981]: info: ___________ START PLUGINS ___________ Jun 01 23:35:10 black volumio[981]: info: ControllerMpd::onStart: Initializing MPD Jun 01 23:35:10 black volumio[981]: info: Creating MPD Configuration file Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 23:35:10 black volumio[981]: info: [1748788510452] CoreMusicLibrary::Adding element 미디어 서버 Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 23:35:10 black sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 23:35:10 black sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:10 black sudo[1195]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:10 black sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 23:35:10 black sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:10 black systemd[1]: mpd.service: Succeeded. Jun 01 23:35:10 black systemd[1]: Stopped Music Player Daemon. Jun 01 23:35:10 black volumio[981]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:10 black systemd[1]: Starting Music Player Daemon... Jun 01 23:35:10 black volumio[981]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 23:35:10 black volumio[981]: info: [1748788510710] CoreMusicLibrary::Adding element Last_100 Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 23:35:10 black volumio[981]: info: [1748788510717] CoreMusicLibrary::Adding element Webradio Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 23:35:10 black volumio[981]: info: Initializing BBC Radios Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 23:35:10 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:10 black sudo[1203]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 23:35:10 black sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:10 black sudo[1203]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 23:35:11 black volumio[981]: info: [1748788511056] CoreMusicLibrary::Adding element 개인 라디오 Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 23:35:11 black volumio[981]: Cannot find translation for source 개인 라디오 Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 23:35:11 black volumio[981]: info: [1748788511079] CoreMusicLibrary::Adding element 팟캐스트 Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 23:35:11 black volumio[981]: Cannot find translation for source 개인 라디오 Jun 01 23:35:11 black volumio[981]: Cannot find translation for source 팟캐스트 Jun 01 23:35:11 black volumio[981]: info: Volumio Calling Home Jun 01 23:35:11 black volumio[981]: info: MPD Permissions set Jun 01 23:35:11 black volumio[981]: info: MPD Permissions set Jun 01 23:35:11 black volumio[981]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:11 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:12 black volumio[981]: info: Volumio called home Jun 01 23:35:12 black volumio[981]: info: Starting Shairport Sync Jun 01 23:35:12 black volumio[981]: info: Starting Shairport Sync Jun 01 23:35:12 black volumio[981]: info: Starting Shairport Sync Jun 01 23:35:12 black sudo[1231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 23:35:12 black sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:12 black volumio[981]: (node:981) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jun 01 23:35:12 black volumio[981]: (Use `node --trace-deprecation ...` to show where the warning was created) Jun 01 23:35:12 black volumio[981]: (node:981) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. Jun 01 23:35:12 black sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 23:35:12 black sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:12 black systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 01 23:35:12 black sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 23:35:12 black systemd[1]: shairport-sync.service: Succeeded. Jun 01 23:35:12 black systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 01 23:35:12 black sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:12 black volumio[981]: info: CoreCommandRouter::volumioGetState Jun 01 23:35:12 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:12 black systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 01 23:35:12 black sudo[1231]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:12 black systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 01 23:35:12 black volumio[981]: info: Shairport-Sync Started Jun 01 23:35:12 black systemd[1]: shairport-sync.service: Succeeded. Jun 01 23:35:12 black volumio[981]: Error adding Membership: Error: addMembership EINVAL Jun 01 23:35:12 black systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 01 23:35:12 black systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 01 23:35:12 black sudo[1236]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:12 black sudo[1233]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:12 black sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 23:35:12 black sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 23:35:12 black sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:12 black sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:12 black sudo[1257]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:12 black volumio[981]: info: Shairport-Sync Started Jun 01 23:35:12 black volumio[981]: info: Shairport-Sync Started Jun 01 23:35:12 black sudo[1259]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:12 black volumio[981]: verbose: New Socket.io Connection to 192.168.1.51 from 192.168.1.62 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 Jun 01 23:35:12 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 23:35:12 black volumio[981]: info: CoreCommandRouter::volumioGetVisibleSources Jun 01 23:35:12 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 23:35:12 black volumio[981]: info: CoreCommandRouter::volumioGetState Jun 01 23:35:12 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:12 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 01 23:35:12 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 01 23:35:12 black volumio[981]: info: Received Get System Info Jun 01 23:35:12 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 23:35:12 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 23:35:12 black volumio[981]: info: Discovery: Getting this device information Jun 01 23:35:12 black volumio[981]: info: CoreCommandRouter::volumioGetState Jun 01 23:35:12 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:12 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 23:35:12 black volumio[981]: info: CoreCommandRouter::volumioGetState Jun 01 23:35:12 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:12 black volumio[981]: info: Listing playlists Jun 01 23:35:12 black sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 23:35:12 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 01 23:35:12 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 23:35:12 black sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:12 black sudo[1265]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:12 black sudo[1267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 23:35:12 black sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:12 black sudo[1267]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:13 black sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 01 23:35:13 black sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:13 black sudo[1275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 23:35:13 black sudo[1275]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:13 black sudo[1275]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:13 black sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 23:35:13 black sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:13 black systemd[1]: Started UPnP Renderer front-end to MPD. Jun 01 23:35:13 black volumio[981]: verbose: New Socket.io Connection to 192.168.1.51 from 192.168.1.62 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 Jun 01 23:35:13 black sudo[1279]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:13 black sudo[1272]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:13 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 23:35:13 black volumio[981]: info: Upmpdcli Daemon Started Jun 01 23:35:13 black volumio[981]: info: CoreCommandRouter::volumioGetVisibleSources Jun 01 23:35:13 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 23:35:13 black volumio[981]: info: CoreCommandRouter::volumioGetState Jun 01 23:35:13 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:13 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 01 23:35:13 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 01 23:35:13 black volumio[981]: info: Received Get System Info Jun 01 23:35:13 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 23:35:13 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 23:35:13 black volumio[981]: info: Discovery: Getting this device information Jun 01 23:35:13 black volumio[981]: info: CoreCommandRouter::volumioGetState Jun 01 23:35:13 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:13 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 23:35:13 black volumio[981]: info: CoreCommandRouter::volumioGetState Jun 01 23:35:13 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:13 black volumio[981]: info: Listing playlists Jun 01 23:35:13 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 01 23:35:13 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 23:35:13 black mpd[1218]: Jun 01 23:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 23:35:14 black systemd[1]: Started Music Player Daemon. Jun 01 23:35:14 black sudo[1197]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:14 black sudo[1188]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:14 black volumio[981]: info: Completed starting Core Plugins Jun 01 23:35:14 black volumio[981]: info: ------------------------------------------- Jun 01 23:35:14 black volumio[981]: info: ----- MyVolumio plugins startup ---- Jun 01 23:35:14 black volumio[981]: info: ------------------------------------------- Jun 01 23:35:14 black volumio[981]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 01 23:35:14 black volumio[981]: error: MPD error: The expression evaluated to a falsy value: Jun 01 23:35:14 black volumio[981]: assert.ok(self.idling) Jun 01 23:35:14 black volumio[981]: error: The expression evaluated to a falsy value: Jun 01 23:35:14 black volumio[981]: assert.ok(self.idling) Jun 01 23:35:14 black volumio[981]: error: updateQueue error: null Jun 01 23:35:14 black volumio[981]: info: CoreCommandRouter::volumioGetState Jun 01 23:35:14 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:14 black volumio[981]: info: MPD running with PID1218 Jun 01 23:35:14 black volumio[981]: ,establishing connection Jun 01 23:35:14 black volumio[981]: error: updateQueue error: null Jun 01 23:35:14 black volumio[1278]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 01 23:35:14 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 01 23:35:15 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 01 23:35:15 black volumio[981]: info: Received Get System Info Jun 01 23:35:15 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 23:35:15 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 23:35:15 black volumio[981]: info: Discovery: Getting this device information Jun 01 23:35:15 black volumio[981]: info: CoreCommandRouter::volumioGetState Jun 01 23:35:15 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:15 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 23:35:16 black volumio[981]: info: CoreCommandRouter::volumioGetQueue Jun 01 23:35:16 black volumio[981]: info: CoreStateMachine::getQueue Jun 01 23:35:16 black volumio[981]: info: CorePlayQueue::getQueue Jun 01 23:35:16 black volumio-remote-updater[629]: [2025-06-01 23:35:16] [connect] Successful connection Jun 01 23:35:16 black volumio[981]: 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 Jun 01 23:35:16 black volumio-remote-updater[629]: [2025-06-01 23:35:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748788516 101 Jun 01 23:35:17 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 01 23:35:17 black volumio[981]: info: Received Get System Info Jun 01 23:35:17 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 23:35:17 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 23:35:17 black volumio[981]: info: Discovery: Getting this device information Jun 01 23:35:17 black volumio[981]: info: CoreCommandRouter::volumioGetState Jun 01 23:35:17 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:17 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 23:35:18 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 01 23:35:21 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 01 23:35:21 black volumio[981]: info: CURURI: music-library Jun 01 23:35:21 black volumio[981]: info: Preload queue cleared Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 01 23:35:22 black volumio[981]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 01 23:35:22 black volumio[981]: info: Adding plugin bluetooth to MyMusic Plugins Jun 01 23:35:22 black volumio[981]: info: Adding plugin multiroom to MyMusic Plugins Jun 01 23:35:22 black volumio[981]: info: Adding plugin metavolumio to MyMusic Plugins Jun 01 23:35:23 black volumio[981]: info: Adding plugin cd_controller to MyMusic Plugins Jun 01 23:35:23 black volumio[981]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 01 23:35:23 black volumio[981]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 01 23:35:23 black volumio[981]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 01 23:35:25 black volumio[981]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 01 23:35:25 black volumio[981]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 01 23:35:25 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:25 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:25 black volumio[981]: info: Starting MyVolumio Remote Streaming Endpoints Jun 01 23:35:25 black volumio[981]: info: MyVolumio login type: Token Jun 01 23:35:25 black volumio[981]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 01 23:35:25 black volumio[981]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 01 23:35:26 black systemd[1]: systemd-timedated.service: Succeeded. Jun 01 23:35:28 black volumio[981]: info: Starting Streaming Service Transparent Proxy Jun 01 23:35:28 black volumio[981]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 01 23:35:28 black volumio[981]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 01 23:35:28 black volumio[981]: info: Streaming services startup Jun 01 23:35:28 black volumio[981]: info: Starting Streaming Daemon Jun 01 23:35:28 black sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 01 23:35:28 black sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:28 black volumio[981]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 01 23:35:28 black sudo[1326]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:28 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 01 23:35:28 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 01 23:35:28 black volumio[981]: info: CURURI: music-library/USB Jun 01 23:35:28 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 01 23:35:28 black volumio[981]: info: CURURI: music-library/USB Jun 01 23:35:28 black volumio[981]: error: Cannot start Volumio Streaming Daemon Jun 01 23:35:28 black volumio[981]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 01 23:35:28 black volumio[981]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 01 23:35:28 black volumio[981]: info: Preload queue cleared Jun 01 23:35:28 black volumio[981]: info: Preload queue cleared Jun 01 23:35:28 black volumio[981]: error: MyVolumio Custom Token format not valid, refreshing it Jun 01 23:35:29 black volumio[1278]: ....................................................................++++ Jun 01 23:35:30 black volumio[981]: info: MyVolumio login type: Token Jun 01 23:35:30 black volumio[981]: STREAMING PROXY: Starting server on port 3245 Jun 01 23:35:30 black volumio[981]: Node JS runtime: 14 Jun 01 23:35:31 black volumio[981]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 01 23:35:31 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 23:35:31 black volumio[981]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 01 23:35:31 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 01 23:35:31 black volumio[981]: info: Received Get System Version Jun 01 23:35:31 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 23:35:31 black volumio[981]: info: Received Get System Info Jun 01 23:35:31 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 23:35:31 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 23:35:31 black volumio[981]: info: Discovery: Getting this device information Jun 01 23:35:31 black volumio[981]: info: CoreCommandRouter::volumioGetState Jun 01 23:35:31 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:31 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 23:35:32 black volumio[981]: info: MyVolumio token set successfully Jun 01 23:35:32 black volumio[981]: info: MYVOLUMIO: Adding device Jun 01 23:35:32 black volumio[981]: info: MYVOLUMIO: Evaluating Server Jun 01 23:35:32 black volumio[981]: info: MyVolumio status changed Jun 01 23:35:32 black volumio[981]: info: Streaming services startup Jun 01 23:35:32 black volumio[981]: info: Starting Streaming Daemon Jun 01 23:35:32 black volumio[981]: info: Removing browser output: myVolumio user plan is not superstar Jun 01 23:35:32 black volumio[981]: info: Removing audio output: Jun 01 23:35:32 black volumio[981]: info: Stoppping Tunnel 1 Jun 01 23:35:32 black sudo[1403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 01 23:35:32 black sudo[1403]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:32 black sudo[1405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 01 23:35:32 black sudo[1405]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 23:35:32 black sudo[1403]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:32 black volumio[981]: error: Cannot start Volumio Streaming Daemon Jun 01 23:35:32 black volumio[981]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 01 23:35:32 black volumio[981]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 01 23:35:32 black sudo[1405]: pam_unix(sudo:session): session closed for user root Jun 01 23:35:32 black volumio[981]: info: Remote SSH Stopped Jun 01 23:35:32 black volumio[981]: info: Setting Geolocation for MyVolumio to as1 Jun 01 23:35:32 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:32 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:32 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:33 black volumio[981]: info: Updating MyVolumio device info Jun 01 23:35:33 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:33 black volumio[981]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 01 23:35:36 black volumio[981]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 01 23:35:36 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 01 23:35:36 black volumio[981]: info: CURURI: music-library/USB/128G Jun 01 23:35:36 black volumio[981]: info: Preload queue cleared Jun 01 23:35:37 black volumio[981]: info: MYVOLUMIO: Adding device Jun 01 23:35:37 black volumio[981]: info: MYVOLUMIO: Evaluating Server Jun 01 23:35:38 black volumio[981]: info: Setting Geolocation for MyVolumio to as1 Jun 01 23:35:38 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:38 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:38 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:39 black ntpd[752]: Soliciting pool server 221.151.118.78 Jun 01 23:35:39 black ntpd[752]: Soliciting pool server 175.195.167.194 Jun 01 23:35:39 black volumio[981]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 01 23:35:39 black volumio[981]: info: Updating MyVolumio device info Jun 01 23:35:39 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:39 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:39 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:40 black ntpd[752]: Soliciting pool server 158.247.202.103 Jun 01 23:35:40 black ntpd[752]: Soliciting pool server 121.174.142.82 Jun 01 23:35:40 black ntpd[752]: Soliciting pool server 194.0.5.123 Jun 01 23:35:40 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 23:35:40 black volumio[981]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 01 23:35:40 black volumio[981]: info: CoreCommandRouter::volumioGetState Jun 01 23:35:40 black volumio[981]: info: CorePlayQueue::getTrack 0 Jun 01 23:35:40 black volumio[981]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 01 23:35:40 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 23:35:40 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:40 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 23:35:41 black volumio-remote-updater[629]: No test mode Jun 01 23:35:41 black volumio-remote-updater[629]: No alpha test mode Jun 01 23:35:41 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 01 23:35:41 black ntpd[752]: Soliciting pool server 203.32.26.46 Jun 01 23:35:41 black ntpd[752]: Soliciting pool server 193.123.243.2 Jun 01 23:35:41 black volumio[981]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Various Fixes for QOBUZ Connect
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Qobuz Connect Ready
    • \n
\n","title":"Update v3.812","updateavailable":true} Jun 01 23:35:41 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 01 23:35:42 black ntpd[752]: Soliciting pool server 121.174.142.81 Jun 01 23:35:43 black ntpd[752]: Soliciting pool server 2001:678:8::123 Jun 01 23:35:45 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:45 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:45 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 01 23:35:45 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 01 23:35:45 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 01 23:35:45 black volumio[981]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 23:35:45 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 23:35:45 black kernel: hwmon hwmon1: Voltage normalised Jun 01 23:35:47 black ntpd[752]: receive: Unexpected origin timestamp 0xebe6e3c2.1fb35c7b does not match aorg 0000000000.00000000 from server@121.174.142.81 xmt 0xebe6e3c3.55a57ab2 Jun 01 23:35:47 black ntpd[752]: receive: Unexpected origin timestamp 0xebe6e3c2.1fbd892a does not match aorg 0000000000.00000000 from server@203.32.26.46 xmt 0xebe6e3c3.786337a4 Jun 01 23:35:48 black kernel: hwmon hwmon1: Undervoltage detected! Jun 01 23:35:49 black volumio[981]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 01 23:35:49 black volumio[981]: info: Completed starting MyVolumio Plugin Jun 01 23:35:49 black volumio[981]: [Metrics] CommandRouter: 50s 493.80ms Jun 01 23:35:49 black volumio[981]: info: CoreCommandRouter::volumiosetStartupVolume Jun 01 23:35:49 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 23:35:49 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 23:35:49 black volumio[981]: info: CoreCommandRouter::Close All Modals sent Jun 01 23:35:49 black volumio[981]: info: CoreCommandRouter::Close All Modals sent Jun 01 23:35:50 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 01 23:35:50 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 01 23:35:50 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 01 23:35:56 black volumio[981]: info: BOOT COMPLETED Jun 01 23:35:56 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 01 23:35:56 black volumio[981]: info: Preload queue cleared Jun 01 23:35:58 black volumio[1278]: ...................................................................................................................................++++ Jun 01 23:35:58 black volumio[1278]: e is 65537 (0x010001) Jun 01 23:35:58 black volumio[1278]: writing RSA key Jun 01 23:35:58 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 01 23:35:58 black volumio[981]: info: Preload queue cleared Jun 01 23:36:02 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 01 23:36:02 black volumio[981]: info: Preload queue cleared Jun 01 23:36:04 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 01 23:36:04 black volumio[981]: info: Preload queue cleared Jun 01 23:36:07 black kernel: hwmon hwmon1: Voltage normalised Jun 01 23:36:09 black kernel: hwmon hwmon1: Undervoltage detected! Jun 01 23:36:09 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 01 23:36:09 black volumio[981]: info: Preload queue cleared Jun 01 23:36:13 black volumio[981]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 01 23:36:13 black volumio[981]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 23:36:13 black volumio[981]: TypeError: Cannot read property 'length' of undefined Jun 01 23:36:13 black volumio[981]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 01 23:36:13 black volumio[981]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 01 23:36:13 black volumio[981]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 01 23:36:13 black volumio[981]: at Parser.emit (events.js:315:20) Jun 01 23:36:13 black volumio[981]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 01 23:36:13 black volumio[981]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 01 23:36:13 black volumio[981]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 01 23:36:13 black volumio[981]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 01 23:36:13 black volumio[981]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 01 23:36:13 black volumio[981]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 01 23:36:13 black volumio[981]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 01 23:36:13 black volumio[981]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 01 23:36:13 black volumio[981]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 01 23:36:13 black volumio[981]: at IncomingMessage.emit (events.js:327:22) Jun 01 23:36:13 black volumio[981]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 01 23:36:13 black volumio[981]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 01 23:36:13 black volumio[981]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 23:36:13 black kernel: hwmon hwmon1: Voltage normalised Jun 01 23:36:14 black sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-01 23:35 Jun 01 23:36:14 black sudo[1529]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"