-- Logs begin at Wed 2025-07-02 14:55:44 UTC, end at Fri 2025-07-18 09:31:14 UTC. -- Jul 18 09:30:10 rivoplus volumio[3183]: Searching plugin music_service/mpd Jul 18 09:30:10 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 18 09:30:10 rivoplus volumio[3183]: info: All search sources collected, pushing search results Jul 18 09:30:11 rivoplus volumio[3183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 18 09:30:11 rivoplus volumio[3183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 18 09:30:17 rivoplus volumio[3183]: info: Preload queue cleared Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::ClearQueue Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::stop Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::stPlaybackTimer Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::updateTrackBlock Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrackBlock Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::pushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::serviceStop Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::serviceStop Jul 18 09:30:17 rivoplus volumio[3183]: info: ControllerMpd::stop Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::clearPlayQueue Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::saveQueue Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushQueue Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::addQueueItems Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::addQueueItems Jul 18 09:30:17 rivoplus volumio[3183]: info: Preload queue cleared Jul 18 09:30:17 rivoplus volumio[3183]: info: Adding Item to queue: albums://ABC/Western%20Electric%20Test%20Record%208%20Live Jul 18 09:30:17 rivoplus volumio[3183]: info: Exploding uri albums://ABC/Western%20Electric%20Test%20Record%208%20Live in service mpd Jul 18 09:30:17 rivoplus volumio[3183]: info: Updating RAAT Signal Path Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushQueue Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::saveQueue Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::updateTrackBlock Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrackBlock Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPlay Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::play index 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::stop Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::play index undefined Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::startPlaybackTimer Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Vocal/1.Demo Various/ABC/Western Electric Test Record 8 Live/1-02 Simply Beautiful.flac Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand stop took 59 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: info: Jul 18 09:30:17 rivoplus volumio[3183]: ---------------------------- MPD announces state update: player Jul 18 09:30:17 rivoplus volumio[3183]: info: ControllerMpd::getState Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand stop took 11 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 09:30:17 rivoplus volumio[3183]: info: Jul 18 09:30:17 rivoplus volumio[3183]: ---------------------------- MPD announces system playlist update Jul 18 09:30:17 rivoplus volumio[3183]: info: Ignoring MPD Status Update Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand status took 5 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand clear took 4 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::parseState Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Vocal/1.Demo Various/ABC/Western Electric Test Record 8 Live/1-02 Simply Beautiful.flac" Jul 18 09:30:17 rivoplus volumio[3183]: error: updateQueue error: null Jul 18 09:30:17 rivoplus volumio[3183]: info: Jul 18 09:30:17 rivoplus volumio[3183]: ---------------------------- MPD announces system playlist update Jul 18 09:30:17 rivoplus volumio[3183]: info: Ignoring MPD Status Update Jul 18 09:30:17 rivoplus volumio[3183]: info: ------------------------------ 8ms Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand add "INTERNAL/Vocal/1.Demo Various/ABC/Western Electric Test Record 8 Live/1-02 Simply Beautiful.flac" took 5 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand play Jul 18 09:30:17 rivoplus volumio[3183]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 09:30:17 rivoplus volumio[3183]: info: ------------------------------ 17ms Jul 18 09:30:17 rivoplus volumio[3183]: info: ------------------------------ 7ms Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand play took 4 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: info: Jul 18 09:30:17 rivoplus volumio[3183]: ---------------------------- MPD announces state update: player Jul 18 09:30:17 rivoplus volumio[3183]: info: ControllerMpd::getState Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:30:17 rivoplus volumio[3183]: info: Jul 18 09:30:17 rivoplus volumio[3183]: ---------------------------- MPD announces state update: player Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand status took 13 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: info: ControllerMpd::getState Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::parseState Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand status took 4 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::parseState Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:30:17 rivoplus volumio[3183]: info: ControllerMpd::pushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::servicePushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simply Beautiful","artist":"ABC","album":"Western Electric Test Record 8 Live","uri":"INTERNAL/Vocal/1.Demo Various/ABC/Western Electric Test Record 8 Live/1-02 Simply Beautiful.flac","trackType":"flac"} Jul 18 09:30:17 rivoplus volumio[3183]: verbose: CURRENT POSITION 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::syncState stateService play Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::syncState currentStatus stop Jul 18 09:30:17 rivoplus volumio[3183]: info: ------------------------------ 37ms Jul 18 09:30:17 rivoplus volumio[3183]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 18 09:30:17 rivoplus volumio[3183]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:30:17 rivoplus volumio[3183]: info: ControllerMpd::pushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::servicePushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simply Beautiful","artist":"ABC","album":"Western Electric Test Record 8 Live","uri":"INTERNAL/Vocal/1.Demo Various/ABC/Western Electric Test Record 8 Live/1-02 Simply Beautiful.flac","trackType":"flac"} Jul 18 09:30:17 rivoplus volumio[3183]: verbose: CURRENT POSITION 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::syncState stateService play Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:30:17 rivoplus volumio[3183]: info: Received an update from plugin. extracting info from payload Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::pushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreStateMachine::pushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioPushState Jul 18 09:30:17 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:30:17 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:17 rivoplus volumio[3183]: info: ------------------------------ 73ms Jul 18 09:30:17 rivoplus volumio[3183]: info: MCU Signalled Playback Inactive Jul 18 09:30:17 rivoplus volumio[3183]: info: Signalling Playback active due to playback status change Jul 18 09:30:17 rivoplus volumio[3183]: info: Signalling Playback active due to playback status change Jul 18 09:30:17 rivoplus volumio[3183]: info: Updating RAAT Signal Path Jul 18 09:30:17 rivoplus volumio[3183]: info: Updating RAAT Signal Path Jul 18 09:30:17 rivoplus volumio[3183]: info: MCU Signalled Playback Active Jul 18 09:30:19 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:30:19 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:30:19 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:30:19 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:30:19 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:30:19 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:30:21 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:30:21 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:24 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:30:24 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:30:24 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:30:24 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:30:24 rivoplus volumio[3183]: info: Executing endpoint metavolumio Jul 18 09:30:24 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 18 09:30:24 rivoplus volumio[3183]: info: Saving local item Female Jul 18 09:30:24 rivoplus volumio[3183]: info: Pushing Favourites {"uri":"/mnt/INTERNAL/Vocal/1.Demo Various/ABC/Western Electric Test Record 8 Live/1-02 Simply Beautiful.flac","favourite":false} Jul 18 09:30:24 rivoplus volumio[3183]: info: Listing playlists Jul 18 09:30:24 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 09:30:24 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 09:30:24 rivoplus volumio[3183]: info: Discovery: Getting this device information Jul 18 09:30:24 rivoplus volumio[3183]: info: CoreCommandRouter::volumioGetState Jul 18 09:30:24 rivoplus volumio[3183]: info: CorePlayQueue::getTrack 0 Jul 18 09:30:24 rivoplus volumio[3183]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 09:30:30 rivoplus volumio[3183]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 18 09:30:30 rivoplus ntpd[18307]: ntpd exiting on signal 15 (Terminated) Jul 18 09:30:30 rivoplus systemd[1]: Stopping Network Time Service... Jul 18 09:30:30 rivoplus volumio[3183]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 18 09:30:30 rivoplus ntpd[18307]: 103.83.142.30 local addr 192.168.88.106 -> Jul 18 09:30:30 rivoplus ntpd[18307]: 103.134.77.146 local addr 192.168.88.106 -> Jul 18 09:30:30 rivoplus ntpd[18307]: 103.169.192.230 local addr 192.168.88.106 -> Jul 18 09:30:30 rivoplus ntpd[18307]: 119.110.74.101 local addr 192.168.88.106 -> Jul 18 09:30:30 rivoplus ntpd[18307]: 202.162.32.12 local addr 192.168.88.106 -> Jul 18 09:30:30 rivoplus ntpd[18307]: 203.114.225.252 local addr 192.168.88.106 -> Jul 18 09:30:30 rivoplus ntpd[18307]: 202.65.114.202 local addr 192.168.88.106 -> Jul 18 09:30:30 rivoplus ntpd[18307]: 160.30.225.225 local addr 192.168.88.106 -> Jul 18 09:30:30 rivoplus volumio[3183]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 18 09:30:30 rivoplus systemd[1]: ntp.service: Succeeded. Jul 18 09:30:30 rivoplus systemd[1]: Stopped Network Time Service. Jul 18 09:30:30 rivoplus volumio[3183]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 18 09:30:30 rivoplus systemd[1]: Starting Network Time Service... Jul 18 09:30:30 rivoplus volumio[3183]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 18 09:30:31 rivoplus ntpd[19717]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 18 09:30:31 rivoplus ntpd[19717]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 18 09:30:31 rivoplus systemd[1]: Started Network Time Service. Jul 18 09:30:31 rivoplus ntpd[19733]: proto: precision = 1.208 usec (-20) Jul 18 09:30:31 rivoplus ntpd[19733]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 18 09:30:31 rivoplus ntpd[19733]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 18 09:30:31 rivoplus ntpd[19733]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 934 days ago Jul 18 09:30:31 rivoplus ntpd[19733]: Listen and drop on 0 v6wildcard [::]:123 Jul 18 09:30:31 rivoplus ntpd[19733]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 18 09:30:31 rivoplus ntpd[19733]: Listen normally on 2 lo 127.0.0.1:123 Jul 18 09:30:31 rivoplus ntpd[19733]: Listen normally on 3 eth0 192.168.88.106:123 Jul 18 09:30:31 rivoplus ntpd[19733]: Listen normally on 4 wlan0 169.254.122.147:123 Jul 18 09:30:31 rivoplus ntpd[19733]: Listening on routing socket on fd #21 for interface updates Jul 18 09:30:31 rivoplus ntpd[19733]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jul 18 09:30:31 rivoplus ntpd[19733]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jul 18 09:30:32 rivoplus ntpd[19733]: Soliciting pool server 202.162.32.12 Jul 18 09:30:33 rivoplus ntpd[19733]: Soliciting pool server 103.83.142.30 Jul 18 09:30:33 rivoplus ntpd[19733]: Soliciting pool server 202.65.114.202 Jul 18 09:30:34 rivoplus ntpd[19733]: Soliciting pool server 160.30.224.224 Jul 18 09:30:34 rivoplus ntpd[19733]: Soliciting pool server 103.105.57.11 Jul 18 09:30:34 rivoplus ntpd[19733]: Soliciting pool server 203.114.224.252 Jul 18 09:30:34 rivoplus volumio[3183]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 09:30:34 rivoplus volumio[3183]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net Jul 18 09:30:34 rivoplus volumio[3183]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Jul 18 09:30:34 rivoplus volumio[3183]: errno: -3007, Jul 18 09:30:34 rivoplus volumio[3183]: code: 'ENOTFOUND', Jul 18 09:30:34 rivoplus volumio[3183]: syscall: 'getaddrinfo', Jul 18 09:30:34 rivoplus volumio[3183]: hostname: 'lastfm.freetls.fastly.net' Jul 18 09:30:34 rivoplus volumio[3183]: } Jul 18 09:30:34 rivoplus volumio[3183]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 09:30:35 rivoplus ntpd[19733]: Soliciting pool server 203.114.74.17 Jul 18 09:30:35 rivoplus ntpd[19733]: Soliciting pool server 27.54.117.72 Jul 18 09:30:35 rivoplus ntpd[19733]: Soliciting pool server 103.105.56.11 Jul 18 09:30:35 rivoplus ntpd[19733]: Soliciting pool server 203.89.31.13 Jul 18 09:30:35 rivoplus sudo[19810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-18 09:29 Jul 18 09:30:35 rivoplus sudo[19810]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:30:35 rivoplus sudo[19810]: pam_unix(sudo:session): session closed for user root Jul 18 09:30:36 rivoplus ntpd[19733]: Soliciting pool server 14.102.153.110 Jul 18 09:30:36 rivoplus ntpd[19733]: Soliciting pool server 119.110.74.102 Jul 18 09:30:36 rivoplus ntpd[19733]: Soliciting pool server 116.12.46.30 Jul 18 09:30:37 rivoplus ntpd[19733]: Soliciting pool server 103.134.77.146 Jul 18 09:30:37 rivoplus ntpd[19733]: Soliciting pool server 116.12.47.30 Jul 18 09:30:37 rivoplus volumio-remote-updater[2834]: [2025-07-18 09:30:37] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 18 09:30:37 rivoplus volumio-remote-updater[2834]: [2025-07-18 09:30:37] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 18 09:30:37 rivoplus go-librespot[4137]: time="2025-07-18T09:30:37Z" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jul 18 09:30:37 rivoplus systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 18 09:30:37 rivoplus qobuz-connect[4542]: 20250718 09:30:37.406 [4542.4542] INFO SampleApp: Socket closed Jul 18 09:30:37 rivoplus sudo[4086]: pam_unix(sudo:session): session closed for user root Jul 18 09:30:37 rivoplus vtcs[4603]: [2025-07-18 09:30:37.421] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jul 18 09:30:37 rivoplus systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 18 09:30:37 rivoplus systemd[1]: Started dynamicswap service. Jul 18 09:30:37 rivoplus systemd[1]: dynamicswap.service: Succeeded. Jul 18 09:30:37 rivoplus systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 18 09:30:37 rivoplus systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jul 18 09:30:37 rivoplus systemd[1]: Started dynamicswap service. Jul 18 09:30:37 rivoplus systemd[1]: Stopped Volumio Backend Module. Jul 18 09:30:37 rivoplus systemd[1]: Started Volumio Backend Module. Jul 18 09:30:38 rivoplus ntpd[19733]: Soliciting pool server 103.169.192.229 Jul 18 09:30:38 rivoplus ntpd[19733]: Soliciting pool server 2001:df5:e280::900:123 Jul 18 09:30:38 rivoplus systemd[1]: dynamicswap.service: Succeeded. Jul 18 09:30:41 rivoplus volumio[19832]: info: ------------------------------------------- Jul 18 09:30:41 rivoplus volumio[19832]: info: ----- Volumio3 ---- Jul 18 09:30:41 rivoplus volumio[19832]: info: ------------------------------------------- Jul 18 09:30:41 rivoplus volumio[19832]: info: ----- System startup ---- Jul 18 09:30:41 rivoplus volumio[19832]: info: ------------------------------------------- Jul 18 09:30:42 rivoplus volumio-remote-updater[2834]: [2025-07-18 09:30:42] [connect] Successful connection Jul 18 09:30:43 rivoplus volumio[19832]: info: MYVOLUMIO Environment detected Jul 18 09:30:43 rivoplus volumio[19832]: info: Plugin folders cleanup Jul 18 09:30:43 rivoplus volumio[19832]: info: Scanning into folder /volumio/app/plugins/ Jul 18 09:30:43 rivoplus volumio[19832]: info: Scanning category audio_interface Jul 18 09:30:43 rivoplus volumio[19832]: info: Scanning category miscellanea Jul 18 09:30:43 rivoplus volumio[19832]: info: Scanning category music_service Jul 18 09:30:43 rivoplus volumio[19832]: info: Scanning category plugins.json Jul 18 09:30:43 rivoplus volumio[19832]: info: Scanning category system_controller Jul 18 09:30:43 rivoplus volumio[19832]: info: Scanning category user_interface Jul 18 09:30:43 rivoplus volumio[19832]: info: Scanning into folder /data/plugins/ Jul 18 09:30:43 rivoplus volumio[19832]: info: Scanning category music_service Jul 18 09:30:43 rivoplus volumio[19832]: info: Plugin folders cleanup completed Jul 18 09:30:43 rivoplus volumio[19832]: info: ------------------------------------------- Jul 18 09:30:43 rivoplus volumio[19832]: info: ----- Core plugins startup ---- Jul 18 09:30:43 rivoplus volumio[19832]: info: ------------------------------------------- Jul 18 09:30:43 rivoplus volumio[19832]: info: Loading plugins from folder /volumio/app/plugins/ Jul 18 09:30:43 rivoplus volumio[19832]: info: Adding plugin bluetooth to MyMusic Plugins Jul 18 09:30:43 rivoplus volumio[19832]: info: Adding plugin multiroom to MyMusic Plugins Jul 18 09:30:43 rivoplus volumio[19832]: info: Adding plugin upnp to MyMusic Plugins Jul 18 09:30:43 rivoplus volumio[19832]: info: Adding plugin metavolumio to MyMusic Plugins Jul 18 09:30:43 rivoplus volumio[19832]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 18 09:30:43 rivoplus volumio[19832]: info: Adding plugin cd_controller to MyMusic Plugins Jul 18 09:30:43 rivoplus volumio[19832]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 18 09:30:43 rivoplus volumio[19832]: info: Adding plugin raat to MyMusic Plugins Jul 18 09:30:43 rivoplus volumio[19832]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 18 09:30:43 rivoplus volumio[19832]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 18 09:30:43 rivoplus volumio[19832]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 18 09:30:43 rivoplus volumio[19832]: info: Loading plugins from folder /data/plugins/ Jul 18 09:30:43 rivoplus volumio[19832]: info: Loading plugin "system"... Jul 18 09:30:43 rivoplus volumio[19832]: info: Loading plugin "appearance"... Jul 18 09:30:45 rivoplus volumio[19832]: info: Loading plugin "network"... Jul 18 09:30:45 rivoplus volumio[19832]: info: Refreshing Cached IP Addresses Jul 18 09:30:45 rivoplus sudo[19863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 09:30:45 rivoplus sudo[19863]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:30:45 rivoplus sudo[19866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 09:30:45 rivoplus sudo[19866]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:30:45 rivoplus volumio[19832]: info: Loading plugin "services"... Jul 18 09:30:45 rivoplus volumio[19832]: info: Loading plugin "alsa_controller"... Jul 18 09:30:45 rivoplus sudo[19863]: pam_unix(sudo:session): session closed for user root Jul 18 09:30:45 rivoplus sudo[19866]: pam_unix(sudo:session): session closed for user root Jul 18 09:30:45 rivoplus sudo[19874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 18 09:30:45 rivoplus sudo[19874]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:30:46 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 09:30:46 rivoplus volumio[19832]: info: Loading plugin "wizard"... Jul 18 09:30:46 rivoplus volumio[19832]: info: Loading plugin "networkfs"... Jul 18 09:30:46 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f614000 drv_status 0x0 e_type 69 e_states 8 Jul 18 09:30:46 rivoplus volumio[19832]: info: Starting Udev Watcher for removable devices Jul 18 09:30:46 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f614000 drv_status 0x0 e_type 69 e_states 8 Jul 18 09:30:46 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f614000 drv_status 0x0 e_type 69 e_states 8 Jul 18 09:30:46 rivoplus volumio[19832]: info: Mounting Device ISSD Jul 18 09:30:46 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f614000 drv_status 0x0 e_type 69 e_states 8 Jul 18 09:30:46 rivoplus sudo[19897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/mmcblk1p1 /mnt/INTERNAL/ -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 18 09:30:46 rivoplus sudo[19897]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:30:46 rivoplus sudo[19897]: pam_unix(sudo:session): session closed for user root Jul 18 09:30:46 rivoplus volumio[19832]: mount: /data/INTERNAL: /dev/mmcblk1p1 already mounted on /data/INTERNAL. Jul 18 09:30:46 rivoplus volumio[19832]: error: Failed to mount ISSD: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/mmcblk1p1" "/mnt/INTERNAL/" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 18 09:30:46 rivoplus volumio[19832]: mount: /data/INTERNAL: /dev/mmcblk1p1 already mounted on /data/INTERNAL. Jul 18 09:30:46 rivoplus volumio[19832]: info: Ignoring mount for partition: BOOT Jul 18 09:30:46 rivoplus volumio[19832]: info: Ignoring mount for partition: volumio Jul 18 09:30:46 rivoplus volumio[19832]: info: Ignoring mount for partition: volumio_data Jul 18 09:30:46 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 09:30:46 rivoplus volumio[19832]: info: Loading plugin "volumio_command_line_client"... Jul 18 09:30:46 rivoplus volumio[19832]: info: Loading plugin "upnp"... Jul 18 09:30:46 rivoplus volumio[19832]: info: [1752831046454] Starting Upmpd Daemon Jul 18 09:30:46 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 09:30:46 rivoplus volumio[19832]: info: Loading plugin "my_music"... Jul 18 09:30:46 rivoplus volumio[19832]: info: Loading plugin "mpd"... Jul 18 09:30:46 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f614000 drv_status 0x0 e_type 69 e_states 8 Jul 18 09:30:46 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f614000 drv_status 0x0 e_type 69 e_states 8 Jul 18 09:30:46 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f614000 drv_status 0x0 e_type 69 e_states 8 Jul 18 09:30:46 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f614000 drv_status 0x0 e_type 69 e_states 8 Jul 18 09:30:46 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f614000 drv_status 0x0 e_type 69 e_states 8 Jul 18 09:30:47 rivoplus volumio[19832]: info: Loading plugin "upnp_browser"... Jul 18 09:30:47 rivoplus volumio[19832]: info: Loading plugin "bluetooth"... Jul 18 09:30:47 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f614000 drv_status 0x0 e_type 69 e_states 0 Jul 18 09:30:47 rivoplus volumio[19832]: info: [1752831047946] Starting BluetoothController Jul 18 09:30:48 rivoplus sudo[19874]: pam_unix(sudo:session): session closed for user root Jul 18 09:30:48 rivoplus volumio[19832]: info: Loading plugin "alarm-clock"... Jul 18 09:30:48 rivoplus volumio[19832]: info: Loading plugin "manifestui"... Jul 18 09:30:48 rivoplus volumio[19832]: info: Loading plugin "metavolumio"... Jul 18 09:30:50 rivoplus volumio[19832]: info: Loading plugin "airplay_emulation"... Jul 18 09:30:50 rivoplus volumio[19832]: info: Starting Shairport Sync Jul 18 09:30:50 rivoplus volumio[19832]: info: Loading plugin "cd_controller"... Jul 18 09:30:51 rivoplus volumio[19832]: info: Loading plugin "last_100"... Jul 18 09:30:51 rivoplus volumio[19832]: info: Loading plugin "qobuzconnect"... Jul 18 09:30:51 rivoplus volumio[19832]: info: Loading plugin "raat"... Jul 18 09:30:51 rivoplus volumio[19832]: info: RAAT Plugin loaded Jul 18 09:30:51 rivoplus volumio[19832]: info: Adding restartRAATSocket REST API Endpoint Jul 18 09:30:51 rivoplus volumio[19832]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Jul 18 09:30:51 rivoplus volumio[19832]: info: Loading plugin "streaming_services"... Jul 18 09:30:53 rivoplus volumio[19832]: info: Starting Streaming Service Transparent Proxy Jul 18 09:30:53 rivoplus volumio[19832]: info: Loading plugin "tidalconnect"... Jul 18 09:30:53 rivoplus volumio[19832]: info: Loading plugin "webradio"... Jul 18 09:30:53 rivoplus volumio[19832]: info: Loading plugin "i2s_dacs"... Jul 18 09:30:53 rivoplus volumio[19832]: info: I2S DAC not set, start Auto-detection Jul 18 09:30:53 rivoplus volumio[19832]: info: Loading plugin "volumiodiscovery"... Jul 18 09:30:53 rivoplus volumio[19832]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 09:30:53 rivoplus volumio[19832]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 09:30:53 rivoplus volumio[19832]: *** WARNING *** For more information see Jul 18 09:30:53 rivoplus volumio[19832]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 09:30:53 rivoplus node[19832]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 09:30:53 rivoplus volumio[19832]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 09:30:53 rivoplus volumio[19832]: *** WARNING *** For more information see Jul 18 09:30:53 rivoplus node[19832]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 09:30:53 rivoplus node[19832]: *** WARNING *** For more information see Jul 18 09:30:53 rivoplus node[19832]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 09:30:53 rivoplus volumio[19832]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 18 09:30:53 rivoplus node[19832]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 09:30:53 rivoplus node[19832]: *** WARNING *** For more information see Jul 18 09:30:53 rivoplus volumio[19832]: info: Discovery: Started advertising with name: Rivoplus Jul 18 09:30:53 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 09:30:53 rivoplus volumio[19832]: info: Loading plugin "spop"... Jul 18 09:30:54 rivoplus volumio[19832]: STREAMING PROXY: Starting server on port 3245 Jul 18 09:30:54 rivoplus volumio[19832]: Node JS runtime: 14 Jul 18 09:30:54 rivoplus volumio-remote-updater[2834]: [2025-07-18 09:30:54] [connect] Successful connection Jul 18 09:30:55 rivoplus volumio[19832]: info: Loading plugin "ytmusic"... Jul 18 09:31:00 rivoplus volumio[19832]: info: Plugin multiroom is not enabled Jul 18 09:31:00 rivoplus volumio[19832]: info: Loading plugin "outputs"... Jul 18 09:31:00 rivoplus volumio[19832]: info: Loading plugin "albumart"... Jul 18 09:31:00 rivoplus volumio[19832]: info: Plugin example_plugin is not enabled Jul 18 09:31:00 rivoplus volumio[19832]: info: Loading plugin "hi_res_audio"... Jul 18 09:31:01 rivoplus volumio[19832]: Forking 3 albumart workers Jul 18 09:31:03 rivoplus volumio[19832]: Starting albumart workers Jul 18 09:31:03 rivoplus volumio[19832]: Starting albumart workers Jul 18 09:31:03 rivoplus volumio[19832]: Starting albumart workers Jul 18 09:31:04 rivoplus volumio[19832]: info: Applying required configuration parameters for plugin hi_res_audio Jul 18 09:31:04 rivoplus volumio[19832]: info: Loading plugin "inputs"... Jul 18 09:31:05 rivoplus volumio[19832]: info: Loading plugin "qobuz"... Jul 18 09:31:07 rivoplus volumio[19832]: info: Loading plugin "smart_inputs"... Jul 18 09:31:08 rivoplus volumio[19832]: info: Loading plugin "tidal"... Jul 18 09:31:09 rivoplus volumio-remote-updater[2834]: [2025-07-18 09:31:09] [connect] Successful connection Jul 18 09:31:10 rivoplus volumio[19832]: info: Loading plugin "rivopluscontrol"... Jul 18 09:31:11 rivoplus volumio[19832]: info: Adding this device properties Jul 18 09:31:11 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Jul 18 09:31:11 rivoplus volumio[19832]: info: Setting Additional Device Volumio Properties: [object Object] Jul 18 09:31:11 rivoplus volumio[19832]: info: Loading plugin "updater_comm"... Jul 18 09:31:11 rivoplus volumio[19832]: info: Loading plugin "cec_controller"... Jul 18 09:31:12 rivoplus volumio[19832]: info: Applying required configuration parameters for plugin cec_controller Jul 18 09:31:12 rivoplus volumio[19832]: info: Plugin mpdemulation is not enabled Jul 18 09:31:12 rivoplus volumio[19832]: info: Loading plugin "rest_api"... Jul 18 09:31:12 rivoplus volumio[19832]: info: Loading plugin "websocket"... Jul 18 09:31:12 rivoplus volumio[19832]: info: Starting Socket.io Server version 2.3.0 Jul 18 09:31:12 rivoplus volumio[19832]: info: Loading i18n strings for locale en Jul 18 09:31:12 rivoplus volumio[19832]: Updating browse sources language Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreCommandRouter::initPlayerControls Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 09:31:12 rivoplus volumio[19832]: Express server listening on port 3000 Jul 18 09:31:12 rivoplus volumio[19832]: [Metrics] WebUI: 32s 131.23ms Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreStateMachine::resetVolumioState Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreStateMachine::getcurrentVolume Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreStateMachine::pushState Jul 18 09:31:12 rivoplus volumio[19832]: info: CorePlayQueue::getTrack 0 Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreCommandRouter::volumioPushState Jul 18 09:31:12 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:31:12 rivoplus volumio[19832]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 18 09:31:12 rivoplus volumio[19832]: info: Completed loading Core Plugins Jul 18 09:31:12 rivoplus volumio[19832]: info: Preparing to generate the ALSA configuration file Jul 18 09:31:12 rivoplus sudo[20010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 09:31:12 rivoplus sudo[20010]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:31:12 rivoplus sudo[20012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 09:31:12 rivoplus sudo[20012]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:31:12 rivoplus sudo[20010]: pam_unix(sudo:session): session closed for user root Jul 18 09:31:12 rivoplus sudo[20012]: pam_unix(sudo:session): session closed for user root Jul 18 09:31:12 rivoplus volumio[19832]: info: Volumio Network Manager: Network status updated: 3 Jul 18 09:31:12 rivoplus volumio[19832]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jul 18 09:31:13 rivoplus volumio[19832]: verbose: New Socket.io Connection to 192.168.88.106 from 192.168.88.38 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 1 Jul 18 09:31:13 rivoplus volumio[19832]: verbose: New Socket.io Connection to 192.168.88.106 from 192.168.88.38 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 2 Jul 18 09:31:13 rivoplus volumio[19832]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 3 Jul 18 09:31:13 rivoplus volumio[19832]: verbose: New Socket.io Connection to 192.168.88.106 from 192.168.88.38 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 4 Jul 18 09:31:13 rivoplus volumio[19832]: verbose: New Socket.io Connection to 192.168.88.106 from 192.168.88.38 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 5 Jul 18 09:31:13 rivoplus volumio[19832]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 6 Jul 18 09:31:13 rivoplus volumio[19832]: verbose: New Socket.io Connection to 192.168.88.106:3000 from 192.168.88.181 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 18 09:31:13 rivoplus volumio[19832]: verbose: New Socket.io Connection to 192.168.88.106:3000 from 192.168.88.181 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 18 09:31:13 rivoplus volumio[19832]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 9 Jul 18 09:31:13 rivoplus volumio[19832]: verbose: New Socket.io Connection to 192.168.88.106 from 192.168.88.38 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 10 Jul 18 09:31:13 rivoplus volumio[19832]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 11 Jul 18 09:31:13 rivoplus volumio[19832]: verbose: New Socket.io Connection to 192.168.88.106:3000 from 192.168.88.181 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jul 18 09:31:13 rivoplus volumio[19832]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 13 Jul 18 09:31:13 rivoplus volumio[19832]: verbose: New Socket.io Connection to 192.168.88.106:3000 from 192.168.88.181 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jul 18 09:31:13 rivoplus volumio-remote-updater[2834]: [2025-07-18 09:31:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752831069 101 Jul 18 09:31:13 rivoplus volumio[19832]: 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: 15 Jul 18 09:31:13 rivoplus volumio[19832]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 16 Jul 18 09:31:13 rivoplus volumio[19832]: verbose: New Socket.io Connection to 192.168.88.106 from 192.168.88.38 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 17 Jul 18 09:31:13 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 09:31:13 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 09:31:13 rivoplus volumio[19832]: info: Discovery: Getting this device information Jul 18 09:31:13 rivoplus volumio[19832]: info: CoreCommandRouter::volumioGetState Jul 18 09:31:13 rivoplus volumio[19832]: info: CorePlayQueue::getTrack 0 Jul 18 09:31:13 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 09:31:13 rivoplus volumio[19832]: info: Reloading queue from file Jul 18 09:31:13 rivoplus volumio[19832]: Searching plugin music_service/mpd Jul 18 09:31:13 rivoplus volumio[19832]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 18 09:31:13 rivoplus volumio[19832]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 09:31:13 rivoplus volumio[19832]: TypeError: Cannot read property 'then' of undefined Jul 18 09:31:13 rivoplus volumio[19832]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1583:17) Jul 18 09:31:13 rivoplus volumio[19832]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jul 18 09:31:13 rivoplus volumio[19832]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:477:37) Jul 18 09:31:13 rivoplus volumio[19832]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Jul 18 09:31:13 rivoplus volumio[19832]: at Socket.emit (events.js:400:28) Jul 18 09:31:13 rivoplus volumio[19832]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 18 09:31:13 rivoplus volumio[19832]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jul 18 09:31:13 rivoplus volumio[19832]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 09:31:14 rivoplus sudo[20032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-18 09:30 Jul 18 09:31:14 rivoplus sudo[20032]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:55:44 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="66719bc94798fca30ca68a8e6d955a83"