-- Logs begin at Thu 2019-02-14 18:11:58 +08, end at Mon 2025-05-19 01:04:06 +08. -- May 19 01:03:01 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 19 01:03:01 volumio volumio[903]: verbose: ControllerMpd::sendMpdCommand rescan May 19 01:03:01 volumio volumio[903]: verbose: ControllerMpd::sendMpdCommand status May 19 01:03:01 volumio volumio[903]: info: May 19 01:03:01 volumio volumio[903]: ---------------------------- MPD announces state update: update May 19 01:03:01 volumio volumio[903]: info: sendMpdCommand rescan took 3 milliseconds May 19 01:03:01 volumio volumio[903]: info: ControllerMpd::getState May 19 01:03:01 volumio volumio[903]: verbose: ControllerMpd::sendMpdCommand status May 19 01:03:01 volumio volumio[903]: verbose: ControllerMpd::sendMpdCommand status May 19 01:03:01 volumio volumio[903]: info: May 19 01:03:01 volumio volumio[903]: ---------------------------- MPD announces state update: update May 19 01:03:01 volumio volumio[903]: info: ControllerMpd::getState May 19 01:03:01 volumio volumio[903]: verbose: ControllerMpd::sendMpdCommand status May 19 01:03:01 volumio volumio[903]: info: sendMpdCommand status took 3 milliseconds May 19 01:03:01 volumio volumio[903]: info: sendMpdCommand status took 2 milliseconds May 19 01:03:01 volumio volumio[903]: info: sendMpdCommand status took 1 milliseconds May 19 01:03:01 volumio volumio[903]: info: sendMpdCommand status took 2 milliseconds May 19 01:03:01 volumio volumio[903]: verbose: ControllerMpd::parseState May 19 01:03:01 volumio volumio[903]: info: Command Router : Notfying DB Updatefalse May 19 01:03:01 volumio volumio[903]: info: CoreCommandRouter::Close All Modals sent May 19 01:03:01 volumio volumio[903]: verbose: ControllerMpd::parseState May 19 01:03:01 volumio volumio[903]: verbose: ControllerMpd::parseState May 19 01:03:01 volumio volumio[903]: info: Command Router : Notfying DB Updatefalse May 19 01:03:01 volumio volumio[903]: info: CoreCommandRouter::Close All Modals sent May 19 01:03:01 volumio volumio[903]: verbose: ControllerMpd::parseState May 19 01:03:01 volumio volumio[903]: info: ControllerMpd::pushState May 19 01:03:01 volumio volumio[903]: info: CoreCommandRouter::servicePushState May 19 01:03:01 volumio volumio[903]: info: CoreStateMachine::pushState May 19 01:03:01 volumio volumio[903]: info: CorePlayQueue::getTrack 0 May 19 01:03:01 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 01:03:01 volumio volumio[903]: info: CoreCommandRouter::volumioPushState May 19 01:03:01 volumio volumio[903]: info: CorePlayQueue::getTrack 0 May 19 01:03:01 volumio volumio[903]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 19 01:03:01 volumio volumio[903]: verbose: CURRENT POSITION 0 May 19 01:03:01 volumio volumio[903]: info: CoreStateMachine::syncState stateService stop May 19 01:03:01 volumio volumio[903]: info: CoreStateMachine::syncState currentStatus stop May 19 01:03:01 volumio volumio[903]: info: CoreStateMachine::pushState May 19 01:03:01 volumio volumio[903]: info: CorePlayQueue::getTrack 0 May 19 01:03:01 volumio volumio[903]: info: CoreCommandRouter::volumioPushState May 19 01:03:01 volumio volumio[903]: info: No code May 19 01:03:01 volumio volumio[903]: info: CoreStateMachine::pushState May 19 01:03:01 volumio volumio[903]: info: CorePlayQueue::getTrack 0 May 19 01:03:01 volumio volumio[903]: info: CoreCommandRouter::volumioPushState May 19 01:03:01 volumio volumio[903]: info: ControllerMpd::pushState May 19 01:03:01 volumio volumio[903]: info: CoreCommandRouter::servicePushState May 19 01:03:01 volumio volumio[903]: info: CoreStateMachine::pushState May 19 01:03:01 volumio volumio[903]: info: CorePlayQueue::getTrack 0 May 19 01:03:01 volumio volumio[903]: info: CoreCommandRouter::volumioPushState May 19 01:03:01 volumio volumio[903]: info: CorePlayQueue::getTrack 0 May 19 01:03:01 volumio volumio[903]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 19 01:03:01 volumio volumio[903]: verbose: CURRENT POSITION 0 May 19 01:03:01 volumio volumio[903]: info: CoreStateMachine::syncState stateService stop May 19 01:03:01 volumio volumio[903]: info: CoreStateMachine::syncState currentStatus stop May 19 01:03:01 volumio volumio[903]: info: CoreStateMachine::pushState May 19 01:03:01 volumio volumio[903]: info: CorePlayQueue::getTrack 0 May 19 01:03:01 volumio volumio[903]: info: CoreCommandRouter::volumioPushState May 19 01:03:01 volumio volumio[903]: info: No code May 19 01:03:01 volumio volumio[903]: info: CoreStateMachine::pushState May 19 01:03:01 volumio volumio[903]: info: CorePlayQueue::getTrack 0 May 19 01:03:01 volumio volumio[903]: info: CoreCommandRouter::volumioPushState May 19 01:03:01 volumio volumio[903]: info: ------------------------------ 83ms May 19 01:03:01 volumio volumio[903]: info: ------------------------------ 82ms May 19 01:03:02 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 01:03:06 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 19 01:03:06 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 19 01:03:06 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 01:03:09 volumio ntpd[799]: Soliciting pool server 111.90.158.16 May 19 01:03:10 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 01:03:14 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 01:03:18 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 01:03:21 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 19 01:03:21 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 19 01:03:22 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 01:03:24 volumio ntpd[799]: Soliciting pool server 240b:400e:5d:6d00:46e6:5a9e:75d6:421e May 19 01:03:26 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 01:03:27 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 19 01:03:27 volumio volumio[903]: verbose: ControllerMpd::sendMpdCommand rescan May 19 01:03:27 volumio volumio[903]: verbose: ControllerMpd::sendMpdCommand status May 19 01:03:27 volumio volumio[903]: info: May 19 01:03:27 volumio volumio[903]: ---------------------------- MPD announces state update: update May 19 01:03:27 volumio volumio[903]: info: sendMpdCommand rescan took 2 milliseconds May 19 01:03:27 volumio volumio[903]: info: ControllerMpd::getState May 19 01:03:27 volumio volumio[903]: verbose: ControllerMpd::sendMpdCommand status May 19 01:03:27 volumio volumio[903]: verbose: ControllerMpd::sendMpdCommand status May 19 01:03:27 volumio volumio[903]: info: May 19 01:03:27 volumio volumio[903]: ---------------------------- MPD announces state update: update May 19 01:03:27 volumio volumio[903]: info: sendMpdCommand status took 2 milliseconds May 19 01:03:27 volumio volumio[903]: info: ControllerMpd::getState May 19 01:03:27 volumio volumio[903]: verbose: ControllerMpd::sendMpdCommand status May 19 01:03:27 volumio volumio[903]: info: sendMpdCommand status took 2 milliseconds May 19 01:03:27 volumio volumio[903]: verbose: ControllerMpd::parseState May 19 01:03:27 volumio volumio[903]: info: Command Router : Notfying DB Updatetrue May 19 01:03:27 volumio volumio[903]: verbose: ControllerMpd::parseState May 19 01:03:27 volumio volumio[903]: info: ControllerMpd::pushState May 19 01:03:27 volumio volumio[903]: info: CoreCommandRouter::servicePushState May 19 01:03:27 volumio volumio[903]: info: CoreStateMachine::pushState May 19 01:03:27 volumio volumio[903]: info: CorePlayQueue::getTrack 0 May 19 01:03:27 volumio volumio[903]: info: CoreCommandRouter::volumioPushState May 19 01:03:27 volumio volumio[903]: info: CorePlayQueue::getTrack 0 May 19 01:03:27 volumio volumio[903]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 19 01:03:27 volumio volumio[903]: verbose: CURRENT POSITION 0 May 19 01:03:27 volumio volumio[903]: info: CoreStateMachine::syncState stateService stop May 19 01:03:27 volumio volumio[903]: info: CoreStateMachine::syncState currentStatus stop May 19 01:03:27 volumio volumio[903]: info: CoreStateMachine::pushState May 19 01:03:27 volumio volumio[903]: info: CorePlayQueue::getTrack 0 May 19 01:03:27 volumio volumio[903]: info: CoreCommandRouter::volumioPushState May 19 01:03:27 volumio volumio[903]: info: No code May 19 01:03:27 volumio volumio[903]: info: CoreStateMachine::pushState May 19 01:03:27 volumio volumio[903]: info: CorePlayQueue::getTrack 0 May 19 01:03:27 volumio volumio[903]: info: CoreCommandRouter::volumioPushState May 19 01:03:27 volumio volumio[903]: info: ------------------------------ 37ms May 19 01:03:27 volumio volumio[903]: info: sendMpdCommand status took 36 milliseconds May 19 01:03:27 volumio volumio[903]: info: sendMpdCommand status took 36 milliseconds May 19 01:03:27 volumio volumio[903]: verbose: ControllerMpd::parseState May 19 01:03:27 volumio volumio[903]: info: Command Router : Notfying DB Updatefalse May 19 01:03:27 volumio volumio[903]: info: CoreCommandRouter::Close All Modals sent May 19 01:03:27 volumio volumio[903]: verbose: ControllerMpd::parseState May 19 01:03:27 volumio volumio[903]: info: ControllerMpd::pushState May 19 01:03:27 volumio volumio[903]: info: CoreCommandRouter::servicePushState May 19 01:03:27 volumio volumio[903]: info: CoreStateMachine::pushState May 19 01:03:27 volumio volumio[903]: info: CorePlayQueue::getTrack 0 May 19 01:03:27 volumio volumio[903]: info: CoreCommandRouter::volumioPushState May 19 01:03:27 volumio volumio[903]: info: CorePlayQueue::getTrack 0 May 19 01:03:27 volumio volumio[903]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 19 01:03:27 volumio volumio[903]: verbose: CURRENT POSITION 0 May 19 01:03:27 volumio volumio[903]: info: CoreStateMachine::syncState stateService stop May 19 01:03:27 volumio volumio[903]: info: CoreStateMachine::syncState currentStatus stop May 19 01:03:27 volumio volumio[903]: info: CoreStateMachine::pushState May 19 01:03:27 volumio volumio[903]: info: CorePlayQueue::getTrack 0 May 19 01:03:27 volumio volumio[903]: info: CoreCommandRouter::volumioPushState May 19 01:03:27 volumio volumio[903]: info: No code May 19 01:03:27 volumio volumio[903]: info: CoreStateMachine::pushState May 19 01:03:27 volumio volumio[903]: info: CorePlayQueue::getTrack 0 May 19 01:03:27 volumio volumio[903]: info: CoreCommandRouter::volumioPushState May 19 01:03:27 volumio volumio[903]: info: ------------------------------ 70ms May 19 01:03:30 volumio ntpd[799]: Soliciting pool server 111.90.158.16 May 19 01:03:30 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 01:03:34 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 01:03:38 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 01:03:39 volumio volumio[903]: info: Enabling MyMusic plugin bluetooth May 19 01:03:39 volumio volumio[903]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth May 19 01:03:39 volumio volumio[903]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling May 19 01:03:39 volumio volumio[903]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling May 19 01:03:39 volumio volumio[903]: error: Could not Enable MyMusic Plugin: Error May 19 01:03:42 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 01:03:46 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 01:03:48 volumio volumio[903]: info: Disabling MyMusic plugin upnp May 19 01:03:48 volumio sudo[8515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 19 01:03:48 volumio sudo[8515]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 01:03:48 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... May 19 01:03:48 volumio volumio[903]: error: Upnp client error: Error: This socket has been ended by the other party May 19 01:03:50 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 01:03:51 volumio volumio[903]: info: Enabling MyMusic plugin upnp May 19 01:03:51 volumio volumio[903]: info: Enabling plugin upnp May 19 01:03:51 volumio volumio[903]: info: Loading plugin "upnp"... May 19 01:03:51 volumio volumio[903]: info: [1747587831224] Starting Upmpd Daemon May 19 01:03:51 volumio volumio[903]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 19 01:03:51 volumio volumio[903]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 19 01:03:51 volumio volumio[903]: Error: listen EADDRINUSE: address already in use :::6599 May 19 01:03:51 volumio volumio[903]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) May 19 01:03:51 volumio volumio[903]: at listenInCluster (net.js:1366:12) May 19 01:03:51 volumio volumio[903]: at Server.listen (net.js:1452:7) May 19 01:03:51 volumio volumio[903]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) May 19 01:03:51 volumio volumio[903]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) May 19 01:03:51 volumio volumio[903]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 19 01:03:51 volumio volumio[903]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 19 01:03:51 volumio volumio[903]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { May 19 01:03:51 volumio volumio[903]: code: 'EADDRINUSE', May 19 01:03:51 volumio volumio[903]: errno: -98, May 19 01:03:51 volumio volumio[903]: syscall: 'listen', May 19 01:03:51 volumio volumio[903]: address: '::', May 19 01:03:51 volumio volumio[903]: port: 6599 May 19 01:03:51 volumio volumio[903]: } May 19 01:03:51 volumio volumio[903]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 19 01:03:51 volumio sudo[8527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-19 01:02 May 19 01:03:51 volumio sudo[8527]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 01:03:51 volumio sudo[8527]: pam_unix(sudo:session): session closed for user root May 19 01:03:52 volumio volumio-remote-updater[2297]: [2025-05-18 17:03:52] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 19 01:03:52 volumio volumio-remote-updater[2297]: [2025-05-18 17:03:52] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 19 01:03:52 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 19 01:03:52 volumio systemd[1]: upmpdcli.service: Succeeded. May 19 01:03:52 volumio sudo[8515]: pam_unix(sudo:session): session closed for user root May 19 01:03:52 volumio systemd[1]: Stopped UPnP Renderer front-end to MPD. May 19 01:03:52 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 19 01:03:52 volumio systemd[1]: Started dynamicswap service. May 19 01:03:52 volumio systemd[1]: dynamicswap.service: Succeeded. May 19 01:03:52 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 19 01:03:52 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. May 19 01:03:52 volumio systemd[1]: Stopped Volumio Backend Module. May 19 01:03:52 volumio systemd[1]: Started Volumio Backend Module. May 19 01:03:52 volumio systemd[1]: Started dynamicswap service. May 19 01:03:52 volumio systemd[1]: dynamicswap.service: Succeeded. May 19 01:03:53 volumio volumio[8543]: info: ------------------------------------------- May 19 01:03:53 volumio volumio[8543]: info: ----- Volumio3 ---- May 19 01:03:53 volumio volumio[8543]: info: ------------------------------------------- May 19 01:03:53 volumio volumio[8543]: info: ----- System startup ---- May 19 01:03:53 volumio volumio[8543]: info: ------------------------------------------- May 19 01:03:53 volumio volumio[8543]: info: MYVOLUMIO Environment detected May 19 01:03:53 volumio volumio[8543]: info: Plugin folders cleanup May 19 01:03:53 volumio volumio[8543]: info: Scanning into folder /volumio/app/plugins/ May 19 01:03:53 volumio volumio[8543]: info: Scanning category audio_interface May 19 01:03:53 volumio volumio[8543]: info: Scanning category miscellanea May 19 01:03:53 volumio volumio[8543]: info: Scanning category music_service May 19 01:03:53 volumio volumio[8543]: info: Scanning category plugins.json May 19 01:03:53 volumio volumio[8543]: info: Scanning category system_controller May 19 01:03:53 volumio volumio[8543]: info: Scanning category user_interface May 19 01:03:53 volumio volumio[8543]: info: Scanning into folder /data/plugins/ May 19 01:03:53 volumio volumio[8543]: info: Scanning category user_interface May 19 01:03:53 volumio volumio[8543]: info: Plugin folders cleanup completed May 19 01:03:53 volumio volumio[8543]: info: ------------------------------------------- May 19 01:03:53 volumio volumio[8543]: info: ----- Core plugins startup ---- May 19 01:03:53 volumio volumio[8543]: info: ------------------------------------------- May 19 01:03:53 volumio volumio[8543]: info: Loading plugins from folder /volumio/app/plugins/ May 19 01:03:53 volumio volumio[8543]: info: Adding plugin upnp to MyMusic Plugins May 19 01:03:53 volumio volumio[8543]: info: Adding plugin airplay_emulation to MyMusic Plugins May 19 01:03:53 volumio volumio[8543]: info: Adding plugin upnp_browser to MyMusic Plugins May 19 01:03:53 volumio volumio[8543]: info: Loading plugins from folder /data/plugins/ May 19 01:03:53 volumio volumio[8543]: info: Loading plugin "system"... May 19 01:03:53 volumio volumio[8543]: info: Loading plugin "appearance"... May 19 01:03:54 volumio volumio[8543]: info: Loading plugin "network"... May 19 01:03:54 volumio volumio[8543]: info: Refreshing Cached IP Addresses May 19 01:03:54 volumio sudo[8570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 19 01:03:54 volumio sudo[8570]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 01:03:54 volumio sudo[8572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 19 01:03:54 volumio sudo[8572]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 01:03:54 volumio sudo[8570]: pam_unix(sudo:session): session closed for user root May 19 01:03:54 volumio sudo[8572]: pam_unix(sudo:session): session closed for user root May 19 01:03:54 volumio volumio[8543]: info: Loading plugin "services"... May 19 01:03:54 volumio volumio[8543]: info: Loading plugin "alsa_controller"... May 19 01:03:54 volumio sudo[8581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 19 01:03:54 volumio sudo[8581]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 01:03:54 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 19 01:03:54 volumio volumio[8543]: info: Loading plugin "wizard"... May 19 01:03:54 volumio volumio[8543]: info: Loading plugin "networkfs"... May 19 01:03:54 volumio volumio[8543]: info: Starting Udev Watcher for removable devices May 19 01:03:54 volumio volumio[8543]: info: Ignoring mount for partition: boot May 19 01:03:54 volumio volumio[8543]: info: Ignoring mount for partition: volumio May 19 01:03:54 volumio volumio[8543]: info: Ignoring mount for partition: volumio_data May 19 01:03:54 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 19 01:03:54 volumio volumio[8543]: info: Loading plugin "volumio_command_line_client"... May 19 01:03:54 volumio volumio[8543]: info: Loading plugin "upnp"... May 19 01:03:54 volumio volumio[8543]: info: [1747587834648] Starting Upmpd Daemon May 19 01:03:54 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 19 01:03:54 volumio volumio[8543]: info: Loading plugin "my_music"... May 19 01:03:54 volumio volumio[8543]: info: Loading plugin "mpd"... May 19 01:03:54 volumio volumio[8543]: info: Loading plugin "upnp_browser"... May 19 01:03:55 volumio volumio[8543]: info: Loading plugin "alarm-clock"... May 19 01:03:55 volumio volumio[8543]: info: Loading plugin "airplay_emulation"... May 19 01:03:55 volumio volumio[8543]: info: Starting Shairport Sync May 19 01:03:55 volumio volumio[8543]: info: Loading plugin "last_100"... May 19 01:03:55 volumio volumio[8543]: info: Loading plugin "webradio"... May 19 01:03:55 volumio volumio[8543]: info: Loading plugin "i2s_dacs"... May 19 01:03:55 volumio volumio[8543]: info: Loading plugin "volumiodiscovery"... May 19 01:03:55 volumio volumio[8543]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 19 01:03:55 volumio volumio[8543]: *** WARNING *** Please fix your application to use the native API of Avahi! May 19 01:03:55 volumio volumio[8543]: *** WARNING *** For more information see May 19 01:03:55 volumio node[8543]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 19 01:03:55 volumio volumio[8543]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 19 01:03:55 volumio volumio[8543]: *** WARNING *** Please fix your application to use the native API of Avahi! May 19 01:03:55 volumio volumio[8543]: *** WARNING *** For more information see May 19 01:03:55 volumio node[8543]: *** WARNING *** Please fix your application to use the native API of Avahi! May 19 01:03:55 volumio node[8543]: *** WARNING *** For more information see May 19 01:03:55 volumio node[8543]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 19 01:03:55 volumio node[8543]: *** WARNING *** Please fix your application to use the native API of Avahi! May 19 01:03:55 volumio node[8543]: *** WARNING *** For more information see May 19 01:03:55 volumio volumio[8543]: info: Applying required configuration parameters for plugin volumiodiscovery May 19 01:03:55 volumio volumio[8543]: info: Discovery: Started advertising with name: Volumio May 19 01:03:55 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 19 01:03:55 volumio volumio[8543]: info: Loading plugin "now_playing"... May 19 01:03:55 volumio volumio[8543]: info: Loading plugin "outputs"... May 19 01:03:55 volumio volumio[8543]: info: Loading plugin "albumart"... May 19 01:03:56 volumio volumio[8543]: info: Plugin example_plugin is not enabled May 19 01:03:56 volumio volumio[8543]: info: Loading plugin "inputs"... May 19 01:03:56 volumio volumio[8543]: info: Loading plugin "updater_comm"... May 19 01:03:56 volumio volumio[8543]: info: Plugin mpdemulation is not enabled May 19 01:03:56 volumio volumio[8543]: info: Loading plugin "rest_api"... May 19 01:03:56 volumio volumio[8543]: info: Loading plugin "websocket"... May 19 01:03:56 volumio volumio[8543]: info: Starting Socket.io Server version 2.3.0 May 19 01:03:56 volumio volumio[8543]: info: Loading i18n strings for locale zh_TW May 19 01:03:56 volumio volumio[8543]: Updating browse sources language May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 01:03:56 volumio volumio[8543]: Forking 3 albumart workers May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::initPlayerControls May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 19 01:03:56 volumio volumio[8543]: Express server listening on port 3000 May 19 01:03:56 volumio volumio[8543]: [Metrics] WebUI: 3s 87.90ms May 19 01:03:56 volumio volumio[8543]: info: CoreStateMachine::resetVolumioState May 19 01:03:56 volumio volumio[8543]: info: CoreStateMachine::getcurrentVolume May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::volumioRetrievevolume May 19 01:03:56 volumio volumio[8543]: info: Volumio Network Manager: Network status updated: 2 May 19 01:03:56 volumio volumio[8543]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.198 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 19 01:03:56 volumio volumio[8543]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.198 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 19 01:03:56 volumio volumio[8543]: info: VolumeController:: Volume=86 Mute =false May 19 01:03:56 volumio volumio[8543]: info: CoreStateMachine::pushState May 19 01:03:56 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::volumioPushState May 19 01:03:56 volumio volumio[8543]: info: CoreStateMachine::updateTrackBlock May 19 01:03:56 volumio volumio[8543]: info: CorePlayQueue::getTrackBlock May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::volumioRetrievevolume May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 01:03:56 volumio volumio[8543]: info: Executing start script for DAC IQaudIO DAC Plus May 19 01:03:56 volumio volumio[8543]: info: Reloading queue from file May 19 01:03:56 volumio volumio[8543]: info: CoreStateMachine::setRepeat null single undefined May 19 01:03:56 volumio volumio[8543]: info: CoreStateMachine::pushState May 19 01:03:56 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::volumioPushState May 19 01:03:56 volumio volumio[8543]: info: CoreStateMachine::setRandom null May 19 01:03:56 volumio volumio[8543]: info: CoreStateMachine::pushState May 19 01:03:56 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::volumioPushState May 19 01:03:56 volumio volumio[8543]: info: Setting Device type: Raspberry PI May 19 01:03:56 volumio volumio[8543]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 19 01:03:56 volumio volumio[8543]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 May 19 01:03:56 volumio volumio[8543]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 19 01:03:56 volumio volumio[8543]: info: DAC script executed May 19 01:03:56 volumio volumio[8543]: info: Completed loading Core Plugins May 19 01:03:56 volumio volumio[8543]: info: Preparing to generate the ALSA configuration file May 19 01:03:56 volumio volumio[8543]: info: VolumeController:: Volume=86 Mute =false May 19 01:03:56 volumio volumio[8543]: info: CoreStateMachine::pushState May 19 01:03:56 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::volumioPushState May 19 01:03:56 volumio volumio[8543]: info: Asound.conf file unchanged, so no further update is needed May 19 01:03:56 volumio volumio[8543]: info: Output device has changed, restarting MPD May 19 01:03:56 volumio volumio[8543]: info: Output device has changed, restarting Shairport Sync May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 01:03:56 volumio sudo[8644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 19 01:03:56 volumio volumio[8543]: Starting albumart workers May 19 01:03:56 volumio sudo[8644]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 01:03:56 volumio sudo[8644]: pam_unix(sudo:session): session closed for user root May 19 01:03:56 volumio sudo[8646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 19 01:03:56 volumio sudo[8646]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 01:03:56 volumio volumio[8543]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 19 01:03:56 volumio volumio[8543]: info: ___________ START PLUGINS ___________ May 19 01:03:56 volumio volumio[8543]: info: ControllerMpd::onStart: Initializing MPD May 19 01:03:56 volumio volumio[8543]: info: Creating MPD Configuration file May 19 01:03:56 volumio systemd[1]: Stopping Music Player Daemon... May 19 01:03:56 volumio volumio[8543]: Starting albumart workers May 19 01:03:56 volumio systemd[1]: mpd.service: Succeeded. May 19 01:03:56 volumio systemd[1]: Stopped Music Player Daemon. May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 19 01:03:56 volumio volumio[8543]: info: [1747587836927] CoreMusicLibrary::Adding element Media Servers May 19 01:03:56 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 01:03:56 volumio sudo[8653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 19 01:03:56 volumio sudo[8653]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 01:03:56 volumio sudo[8653]: pam_unix(sudo:session): session closed for user root May 19 01:03:57 volumio volumio[8543]: Starting albumart workers May 19 01:03:56 volumio sudo[8656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 19 01:03:57 volumio volumio[8543]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 01:03:56 volumio sudo[8656]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 01:03:57 volumio systemd[1]: Starting Music Player Daemon... May 19 01:03:57 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 19 01:03:57 volumio systemd[1]: mpd.service: Succeeded. May 19 01:03:57 volumio systemd[1]: Stopped Music Player Daemon. May 19 01:03:57 volumio systemd[1]: Starting Music Player Daemon... May 19 01:03:57 volumio volumio[8543]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 19 01:03:57 volumio volumio[8543]: info: [1747587837062] CoreMusicLibrary::Adding element Last_100 May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 19 01:03:57 volumio volumio[8543]: info: [1747587837064] CoreMusicLibrary::Adding element Webradio May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 19 01:03:57 volumio volumio[8543]: info: Initializing BBC Radios May 19 01:03:57 volumio sudo[8665]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 19 01:03:57 volumio sudo[8665]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 01:03:57 volumio sudo[8665]: pam_unix(sudo:session): session closed for user root May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: [now-playing] ConfigUpdater: config is up to date. May 19 01:03:57 volumio volumio[8543]: info: Volumio Calling Home May 19 01:03:57 volumio volumio-remote-updater[2297]: [2025-05-18 17:03:57] [connect] Successful connection May 19 01:03:57 volumio volumio[8543]: info: [now-playing] App is listening on port 4004. May 19 01:03:57 volumio volumio[8543]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds May 19 01:03:57 volumio volumio[8543]: info: Discovery: adding 04c3d421-d1ab-448f-a5d9-ec54eb1aca45 May 19 01:03:57 volumio volumio[8543]: info: Discovery: Found device Volumio May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:03:57 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:03:57 volumio volumio[8543]: info: MPD Permissions set May 19 01:03:57 volumio volumio[8543]: info: MPD Permissions set May 19 01:03:57 volumio volumio-remote-updater[2297]: [2025-05-18 17:03:57] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747587837 101 May 19 01:03:57 volumio volumio[8543]: 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 May 19 01:03:57 volumio volumio[8543]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 01:03:57 volumio volumio[8543]: info: Starting Shairport Sync May 19 01:03:57 volumio volumio[8543]: info: Starting Shairport Sync May 19 01:03:57 volumio sudo[8714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 19 01:03:57 volumio sudo[8714]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 01:03:57 volumio volumio[8543]: info: Starting Shairport Sync May 19 01:03:57 volumio sudo[8717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 19 01:03:57 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 19 01:03:57 volumio sudo[8717]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 01:03:57 volumio systemd[1]: shairport-sync.service: Succeeded. May 19 01:03:57 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 19 01:03:57 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:03:57 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:03:57 volumio sudo[8720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 19 01:03:57 volumio sudo[8720]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 01:03:57 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 19 01:03:57 volumio sudo[8714]: pam_unix(sudo:session): session closed for user root May 19 01:03:57 volumio volumio[8543]: info: Shairport-Sync Started May 19 01:03:57 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 19 01:03:57 volumio volumio[8543]: Error adding Membership: Error: addMembership EINVAL May 19 01:03:57 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 19 01:03:57 volumio systemd[1]: shairport-sync.service: Succeeded. May 19 01:03:57 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 19 01:03:57 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 19 01:03:57 volumio sudo[8717]: pam_unix(sudo:session): session closed for user root May 19 01:03:57 volumio sudo[8720]: pam_unix(sudo:session): session closed for user root May 19 01:03:57 volumio volumio[8543]: info: Shairport-Sync Started May 19 01:03:57 volumio volumio[8543]: info: Shairport-Sync Started May 19 01:03:57 volumio volumio[8543]: info: Volumio called home May 19 01:03:58 volumio sudo[8581]: pam_unix(sudo:session): session closed for user root May 19 01:03:58 volumio mpd[8674]: May 19 01:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 19 01:03:58 volumio systemd[1]: Started Music Player Daemon. May 19 01:03:58 volumio sudo[8656]: pam_unix(sudo:session): session closed for user root May 19 01:03:58 volumio sudo[8646]: pam_unix(sudo:session): session closed for user root May 19 01:03:58 volumio volumio[8543]: info: Completed starting Core Plugins May 19 01:03:58 volumio volumio[8543]: info: ------------------------------------------- May 19 01:03:58 volumio volumio[8543]: info: ----- MyVolumio plugins startup ---- May 19 01:03:58 volumio volumio[8543]: info: ------------------------------------------- May 19 01:03:58 volumio volumio[8543]: info: [MyVolumio PluginManager] Fetching plans data.... May 19 01:03:58 volumio volumio[8543]: error: MPD error: The expression evaluated to a falsy value: May 19 01:03:58 volumio volumio[8543]: assert.ok(self.idling) May 19 01:03:58 volumio volumio[8543]: error: The expression evaluated to a falsy value: May 19 01:03:58 volumio volumio[8543]: assert.ok(self.idling) May 19 01:03:58 volumio volumio[8543]: error: updateQueue error: null May 19 01:03:58 volumio volumio[8543]: info: MPD running with PID8674 May 19 01:03:58 volumio volumio[8543]: ,establishing connection May 19 01:03:58 volumio volumio[8543]: error: updateQueue error: null May 19 01:04:01 volumio volumio[8543]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.198 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: Received Get System Info May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 19 01:04:01 volumio volumio[8543]: info: Discovery: Getting this device information May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetVisibleSources May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 19 01:04:01 volumio volumio[8543]: info: Received Get System Info May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 19 01:04:01 volumio volumio[8543]: info: Discovery: Getting this device information May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: Listing playlists May 19 01:04:01 volumio volumio[8543]: info: Received Get System Info May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 19 01:04:01 volumio volumio[8543]: info: Discovery: Getting this device information May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetQueue May 19 01:04:01 volumio volumio[8543]: info: CoreStateMachine::getQueue May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getQueue May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetVisibleSources May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 19 01:04:01 volumio volumio[8543]: info: Received Get System Info May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 19 01:04:01 volumio volumio[8543]: info: Discovery: Getting this device information May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: Listing playlists May 19 01:04:01 volumio volumio[8543]: info: Received Get System Info May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 19 01:04:01 volumio volumio[8543]: info: Discovery: Getting this device information May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetQueue May 19 01:04:01 volumio volumio[8543]: info: CoreStateMachine::getQueue May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getQueue May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetVisibleSources May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 19 01:04:01 volumio volumio[8543]: info: Received Get System Info May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 19 01:04:01 volumio volumio[8543]: info: Discovery: Getting this device information May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: Listing playlists May 19 01:04:01 volumio volumio[8543]: info: Received Get System Info May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 19 01:04:01 volumio volumio[8543]: info: Discovery: Getting this device information May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetState May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getTrack 0 May 19 01:04:01 volumio volumio[8543]: info: CoreCommandRouter::volumioGetQueue May 19 01:04:01 volumio volumio[8543]: info: CoreStateMachine::getQueue May 19 01:04:01 volumio volumio[8543]: info: CorePlayQueue::getQueue May 19 01:04:02 volumio volumio[8543]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 01:04:03 volumio volumio[8543]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 19 01:04:04 volumio sudo[8747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 19 01:04:04 volumio sudo[8747]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 01:04:04 volumio sudo[8747]: pam_unix(sudo:session): session closed for user root May 19 01:04:04 volumio sudo[8750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 19 01:04:04 volumio sudo[8750]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 01:04:04 volumio sudo[8750]: pam_unix(sudo:session): session closed for user root May 19 01:04:04 volumio sudo[8753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 19 01:04:04 volumio sudo[8753]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 01:04:04 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 19 01:04:04 volumio sudo[8753]: pam_unix(sudo:session): session closed for user root May 19 01:04:04 volumio volumio[8543]: info: Upmpdcli Daemon Started May 19 01:04:04 volumio volumio[8755]: writing RSA key May 19 01:04:06 volumio volumio[8543]: info: Enabling MyMusic plugin qobuzconnect May 19 01:04:06 volumio volumio[8543]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service qobuzconnect May 19 01:04:06 volumio volumio[8543]: info: [MyVolumio PluginManager] Plugin music_service qobuzconnect not in user plan, not enabling May 19 01:04:06 volumio volumio[8543]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 19 01:04:06 volumio volumio[8543]: TypeError: Cannot read property 'toUpperCase' of undefined May 19 01:04:06 volumio volumio[8543]: at MyVolumioPluginManager.pushMyVolumioRequiredAccountModal (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:19516) May 19 01:04:06 volumio volumio[8543]: at MyVolumioPluginManager.enablePlugin (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:18418) May 19 01:04:06 volumio volumio[8543]: at MyVolumioPluginManager.enableAndStartPlugin (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:17039) May 19 01:04:06 volumio volumio[8543]: at PluginManager.enableDisableMyMusicPlugin (/volumio/app/pluginmanager.js:2024:48) May 19 01:04:06 volumio volumio[8543]: at CoreCommandRouter.enableDisableMyMusicPlugin (/volumio/app/index.js:2107:29) May 19 01:04:06 volumio volumio[8543]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1935:59) May 19 01:04:06 volumio volumio[8543]: at Socket.emit (events.js:315:20) May 19 01:04:06 volumio volumio[8543]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 19 01:04:06 volumio volumio[8543]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 19 01:04:06 volumio volumio[8543]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 19 01:04:06 volumio sudo[8794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-19 01:03 May 19 01:04:06 volumio sudo[8794]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"