Jan 10 13:15:01 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7. Jan 10 13:15:01 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 13:15:01 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 13:15:01 volumio upmpdcli[1830]: Could not open config: /tmp/upmpdcli.conf Jan 10 13:15:01 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 10 13:15:01 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 10 13:15:16 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 8. Jan 10 13:15:16 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 13:15:16 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 13:15:16 volumio upmpdcli[1862]: Could not open config: /tmp/upmpdcli.conf Jan 10 13:15:16 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 10 13:15:16 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 10 13:15:31 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 9. Jan 10 13:15:31 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 13:15:31 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 13:15:32 volumio upmpdcli[1880]: Could not open config: /tmp/upmpdcli.conf Jan 10 13:15:32 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 10 13:15:32 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 10 13:15:43 volumio volumio[1102]: verbose: New Socket.io Connection to pi3.quba from 192.168.3.2 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.2 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 5 Jan 10 13:15:43 volumio volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 10 13:15:43 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Jan 10 13:15:43 volumio volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 10 13:15:43 volumio volumio[1102]: info: CoreStateMachine::getQueue Jan 10 13:15:43 volumio volumio[1102]: info: CorePlayQueue::getQueue Jan 10 13:15:43 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jan 10 13:15:43 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Jan 10 13:15:43 volumio volumio[1102]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Jan 10 13:15:43 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Jan 10 13:15:43 volumio volumio[1102]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Jan 10 13:15:43 volumio volumio[1102]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Jan 10 13:15:43 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Jan 10 13:15:47 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 10. Jan 10 13:15:47 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 13:15:47 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 13:15:47 volumio upmpdcli[1912]: Could not open config: /tmp/upmpdcli.conf Jan 10 13:15:47 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 10 13:15:47 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 10 13:15:52 volumio volumio[1102]: info: Tunnel connection is inactive, restarting it Jan 10 13:15:52 volumio volumio[1102]: info: Starting Tunnel 1 Jan 10 13:15:52 volumio volumio[1102]: info: Starting Tunnel Connection Checker Jan 10 13:15:53 volumio sudo[1919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 10 13:15:53 volumio sudo[1919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 13:15:53 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 10 13:15:53 volumio sudo[1919]: pam_unix(sudo:session): session closed for user root Jan 10 13:15:53 volumio autossh[1922]: port set to 0, monitoring disabled Jan 10 13:15:53 volumio autossh[1922]: starting ssh (count 1) Jan 10 13:15:53 volumio autossh[1922]: ssh child pid is 1925 Jan 10 13:15:53 volumio volumio[1102]: info: Remote SSH Started Jan 10 13:15:53 volumio volumiossh-tunnel[1925]: Warning: Permanently added '[eu7.myvolumio.org]:2222' (RSA) to the list of known hosts. Jan 10 13:15:53 volumio volumio[1102]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 10 13:15:53 volumio volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 10 13:15:53 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Jan 10 13:16:02 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 11. Jan 10 13:16:02 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 13:16:02 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 13:16:02 volumio upmpdcli[1941]: Could not open config: /tmp/upmpdcli.conf Jan 10 13:16:02 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 10 13:16:02 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 10 13:16:05 volumio sudo[1943]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 10 13:16:05 volumio sudo[1943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 13:16:05 volumio sudo[1943]: pam_unix(sudo:session): session closed for user root Jan 10 13:16:05 volumio sudo[1945]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 13:16:05 volumio sudo[1945]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 13:16:05 volumio sudo[1945]: pam_unix(sudo:session): session closed for user root Jan 10 13:16:05 volumio volumio[1102]: verbose: New Socket.io Connection to 192.168.3.16 from 192.168.3.2 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.2 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Jan 10 13:16:05 volumio volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 10 13:16:05 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Jan 10 13:16:05 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 10 13:16:05 volumio volumio[1102]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 10 13:16:05 volumio volumio[1102]: info: CoreCommandRouter::volumioGetVisibleSources Jan 10 13:16:05 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 13:16:05 volumio volumio[1102]: info: Listing playlists Jan 10 13:16:05 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 10 13:16:05 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 10 13:16:05 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 10 13:16:05 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 10 13:16:08 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 13:16:08 volumio volumio[1102]: info: Received Get System Info Jan 10 13:16:08 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 13:16:08 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 13:16:08 volumio volumio[1102]: info: Discovery: Getting this device information Jan 10 13:16:08 volumio volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 10 13:16:08 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Jan 10 13:16:08 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 13:16:11 volumio sshd[1965]: Accepted publickey for volumio from 192.168.3.2 port 54907 ssh2: RSA SHA256:GJx8zgsSxUFPfvLFJh3azEz5eK+/2eK+SLk/H4pKsnU Jan 10 13:16:11 volumio sshd[1965]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Jan 10 13:16:11 volumio systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Jan 10 13:16:11 volumio systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Jan 10 13:16:11 volumio systemd-logind[747]: New session 1 of user volumio. Jan 10 13:16:11 volumio systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Jan 10 13:16:11 volumio systemd[1]: Starting user@1000.service - User Manager for UID 1000... Jan 10 13:16:11 volumio (systemd)[1968]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Jan 10 13:16:11 volumio systemd[1968]: Queued start job for default target default.target. Jan 10 13:16:11 volumio systemd[1968]: Created slice app.slice - User Application Slice. Jan 10 13:16:11 volumio systemd[1968]: Reached target paths.target - Paths. Jan 10 13:16:11 volumio systemd[1968]: Reached target timers.target - Timers. Jan 10 13:16:11 volumio systemd[1968]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Jan 10 13:16:11 volumio systemd[1968]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Jan 10 13:16:11 volumio systemd[1968]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Jan 10 13:16:11 volumio systemd[1968]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Jan 10 13:16:11 volumio systemd[1968]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Jan 10 13:16:11 volumio systemd[1968]: Reached target sockets.target - Sockets. Jan 10 13:16:11 volumio systemd[1968]: Reached target basic.target - Basic System. Jan 10 13:16:11 volumio systemd[1]: Started user@1000.service - User Manager for UID 1000. Jan 10 13:16:11 volumio systemd[1968]: Started mpris-proxy.service - Bluetooth mpris proxy. Jan 10 13:16:11 volumio systemd[1968]: Reached target default.target - Main User Target. Jan 10 13:16:11 volumio systemd[1968]: Startup finished in 467ms. Jan 10 13:16:11 volumio systemd[1]: Started session-1.scope - Session 1 of User volumio. Jan 10 13:16:11 volumio systemd[1968]: mpris-proxy.service: Main process exited, code=exited, status=1/FAILURE Jan 10 13:16:11 volumio mpris-proxy[1983]: Can't get on session bus Jan 10 13:16:11 volumio systemd[1968]: mpris-proxy.service: Failed with result 'exit-code'. Jan 10 13:16:12 volumio sshd[1965]: pam_env(sshd:session): deprecated reading of user environment enabled Jan 10 13:16:12 volumio volumio[1102]: info: Jan 10 13:16:12 volumio volumio[1102]: ---------------------------- Client requests Volumio toggle Jan 10 13:16:12 volumio volumio[1102]: info: CoreCommandRouter::volumioToggle Jan 10 13:16:12 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Jan 10 13:16:12 volumio volumio[1102]: info: CoreStateMachine::play index undefined Jan 10 13:16:12 volumio volumio[1102]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 13:16:12 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Jan 10 13:16:12 volumio volumio[1102]: info: CoreStateMachine::startPlaybackTimer Jan 10 13:16:12 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Jan 10 13:16:12 volumio volumio[1102]: info: CoreCommandRouter::volumioGetVisibleSources Jan 10 13:16:12 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 13:16:12 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 10 13:16:12 volumio volumio[1102]: info: [RadioParadise] Matched station: Radio Paradise Mellow Mix (FLAC), chan=1 Jan 10 13:16:12 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 13:16:12 volumio sshd[1993]: Received disconnect from 192.168.3.2 port 54907:11: disconnected by user Jan 10 13:16:12 volumio sshd[1993]: Disconnected from user volumio 192.168.3.2 port 54907 Jan 10 13:16:12 volumio sshd[1965]: pam_unix(sshd:session): session closed for user volumio Jan 10 13:16:12 volumio systemd[1]: session-1.scope: Deactivated successfully. Jan 10 13:16:12 volumio systemd-logind[747]: Session 1 logged out. Waiting for processes to exit. Jan 10 13:16:12 volumio volumio[1102]: info: sendMpdCommand stop took 40 milliseconds Jan 10 13:16:12 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 13:16:12 volumio systemd-logind[747]: Removed session 1. Jan 10 13:16:12 volumio volumio[1102]: info: Jan 10 13:16:12 volumio volumio[1102]: ---------------------------- MPD announces system playlist update Jan 10 13:16:12 volumio volumio[1102]: info: Ignoring MPD Status Update Jan 10 13:16:12 volumio volumio[1102]: info: sendMpdCommand clear took 14 milliseconds Jan 10 13:16:12 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 10 13:16:12 volumio volumio[1102]: error: updateQueue error: null Jan 10 13:16:12 volumio volumio[1102]: info: Jan 10 13:16:12 volumio volumio[1102]: ---------------------------- MPD announces state update: options Jan 10 13:16:12 volumio volumio[1102]: info: ------------------------------ 13ms Jan 10 13:16:12 volumio volumio[1102]: info: sendMpdCommand consume 1 took 2 milliseconds Jan 10 13:16:12 volumio volumio[1102]: info: ControllerMpd::getState Jan 10 13:16:12 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Jan 10 13:16:12 volumio volumio[1102]: info: [1768047372241] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flacm Jan 10 13:16:12 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flacm" Jan 10 13:16:12 volumio volumio[1102]: info: Jan 10 13:16:12 volumio volumio[1102]: ---------------------------- MPD announces system playlist update Jan 10 13:16:12 volumio volumio[1102]: info: Ignoring MPD Status Update Jan 10 13:16:12 volumio volumio[1102]: info: sendMpdCommand status took 3 milliseconds Jan 10 13:16:12 volumio volumio[1102]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flacm" took 2 milliseconds Jan 10 13:16:12 volumio volumio[1102]: verbose: ControllerMpd::parseState Jan 10 13:16:12 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand play Jan 10 13:16:12 volumio volumio[1102]: info: ControllerMpd::pushState Jan 10 13:16:12 volumio volumio[1102]: info: CoreCommandRouter::servicePushState Jan 10 13:16:12 volumio volumio[1102]: info: CoreStateMachine::pushState Jan 10 13:16:12 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Jan 10 13:16:12 volumio volumio[1102]: info: CoreCommandRouter::volumioPushState Jan 10 13:16:12 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Jan 10 13:16:12 volumio volumio[1102]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 10 13:16:12 volumio volumio[1102]: info: ------------------------------ 94ms Jan 10 13:16:12 volumio volumio[1102]: info: ------------------------------ 92ms Jan 10 13:16:12 volumio volumio[1102]: info: sendMpdCommand play took 43 milliseconds Jan 10 13:16:12 volumio volumio[1102]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=1 Jan 10 13:16:12 volumio volumio[1102]: info: [1768047372335] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Jan 10 13:16:13 volumio volumio[1102]: info: [1768047373020] [RadioParadise] received new metadata: {"time":6,"artist":"Pearl Jam","title":"Off He Goes","album":"No Code","year":"1996","cover":"https://img.radioparadise.com/covers/l/11325.jpg","cover_med":"https://img.radioparadise.com/covers/m/11325.jpg","cover_small":"https://img.radioparadise.com/covers/s/11325.jpg"} Jan 10 13:16:13 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Jan 10 13:16:13 volumio volumio[1102]: info: CoreCommandRouter::servicePushState Jan 10 13:16:13 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Jan 10 13:16:13 volumio volumio[1102]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/11325.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"Off He Goes","title":"Off He Goes","artist":"Pearl Jam","album":"No Code","streaming":true,"disableUiControls":true,"duration":11,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 10 13:16:13 volumio volumio[1102]: verbose: CURRENT POSITION 0 Jan 10 13:16:13 volumio volumio[1102]: info: CoreStateMachine::syncState stateService play Jan 10 13:16:13 volumio volumio[1102]: info: CoreStateMachine::syncState currentStatus stop Jan 10 13:16:13 volumio volumio[1102]: info: [1768047373043] [RadioParadise] setting new timer with duration of 11000 seconds. Jan 10 13:16:13 volumio volumio[1102]: info: Jan 10 13:16:13 volumio volumio[1102]: ---------------------------- MPD announces state update: player Jan 10 13:16:13 volumio volumio[1102]: info: ControllerMpd::getState Jan 10 13:16:13 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Jan 10 13:16:13 volumio volumio[1102]: info: Jan 10 13:16:13 volumio volumio[1102]: ---------------------------- MPD announces state update: player Jan 10 13:16:13 volumio volumio[1102]: info: ControllerMpd::getState Jan 10 13:16:13 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Jan 10 13:16:13 volumio volumio[1102]: info: sendMpdCommand status took 4 milliseconds Jan 10 13:16:13 volumio volumio[1102]: verbose: ControllerMpd::parseState Jan 10 13:16:13 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 13:16:13 volumio volumio[1102]: info: sendMpdCommand status took 14 milliseconds Jan 10 13:16:13 volumio volumio[1102]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 10 13:16:13 volumio volumio[1102]: verbose: ControllerMpd::parseState Jan 10 13:16:13 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 13:16:13 volumio volumio[1102]: verbose: ControllerMpd::parseTrackInfo Jan 10 13:16:13 volumio volumio[1102]: info: ControllerMpd::pushState Jan 10 13:16:13 volumio volumio[1102]: info: CoreCommandRouter::servicePushState Jan 10 13:16:13 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Jan 10 13:16:13 volumio volumio[1102]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 10 13:16:13 volumio volumio[1102]: info: ------------------------------ 30ms Jan 10 13:16:13 volumio volumio[1102]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 10 13:16:13 volumio volumio[1102]: verbose: ControllerMpd::parseTrackInfo Jan 10 13:16:13 volumio volumio[1102]: info: ControllerMpd::pushState Jan 10 13:16:13 volumio volumio[1102]: info: CoreCommandRouter::servicePushState Jan 10 13:16:13 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Jan 10 13:16:13 volumio volumio[1102]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 10 13:16:13 volumio volumio[1102]: info: ------------------------------ 34ms Jan 10 13:16:14 volumio volumio[1102]: info: Jan 10 13:16:14 volumio volumio[1102]: ---------------------------- MPD announces system playlist update Jan 10 13:16:14 volumio volumio[1102]: info: Ignoring MPD Status Update Jan 10 13:16:14 volumio volumio[1102]: info: Jan 10 13:16:14 volumio volumio[1102]: ---------------------------- MPD announces state update: player Jan 10 13:16:14 volumio volumio[1102]: info: ControllerMpd::getState Jan 10 13:16:14 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Jan 10 13:16:14 volumio volumio[1102]: info: ------------------------------ 8ms Jan 10 13:16:14 volumio volumio[1102]: info: sendMpdCommand status took 5 milliseconds Jan 10 13:16:14 volumio volumio[1102]: verbose: ControllerMpd::parseState Jan 10 13:16:14 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 13:16:14 volumio volumio[1102]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 10 13:16:14 volumio volumio[1102]: verbose: ControllerMpd::parseTrackInfo Jan 10 13:16:14 volumio volumio[1102]: info: ControllerMpd::pushState Jan 10 13:16:14 volumio volumio[1102]: info: CoreCommandRouter::servicePushState Jan 10 13:16:14 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Jan 10 13:16:14 volumio volumio[1102]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 10 13:16:14 volumio volumio[1102]: info: ------------------------------ 20ms Jan 10 13:16:17 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 12. Jan 10 13:16:17 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 13:16:17 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 10 13:16:17 volumio upmpdcli[2015]: Could not open config: /tmp/upmpdcli.conf Jan 10 13:16:17 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 10 13:16:17 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 10 13:16:18 volumio volumio[1102]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 13:16:18 volumio volumio[1102]: info: CoreCommandRouter::Close All Modals sent Jan 10 13:16:18 volumio volumio[1102]: info: Jan 10 13:16:18 volumio volumio[1102]: ---------------------------- MPD announces system playlist update Jan 10 13:16:18 volumio volumio[1102]: info: Ignoring MPD Status Update Jan 10 13:16:18 volumio volumio[1102]: info: Jan 10 13:16:18 volumio volumio[1102]: ---------------------------- MPD announces state update: player Jan 10 13:16:18 volumio volumio[1102]: info: ControllerMpd::getState Jan 10 13:16:18 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Jan 10 13:16:18 volumio volumio[1102]: info: ------------------------------ 4ms Jan 10 13:16:18 volumio volumio[1102]: info: sendMpdCommand status took 3 milliseconds Jan 10 13:16:18 volumio volumio[1102]: verbose: ControllerMpd::parseState Jan 10 13:16:18 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 13:16:18 volumio volumio[1102]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 10 13:16:18 volumio volumio[1102]: verbose: ControllerMpd::parseTrackInfo Jan 10 13:16:18 volumio volumio[1102]: info: ControllerMpd::pushState Jan 10 13:16:18 volumio volumio[1102]: info: CoreCommandRouter::servicePushState Jan 10 13:16:18 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Jan 10 13:16:18 volumio volumio[1102]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 10 13:16:18 volumio volumio[1102]: info: ------------------------------ 20ms Jan 10 13:16:22 volumio systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Jan 10 13:16:22 volumio systemd[1968]: Activating special unit exit.target... Jan 10 13:16:22 volumio systemd[1968]: Stopped target default.target - Main User Target. Jan 10 13:16:22 volumio systemd[1968]: Stopped target basic.target - Basic System. Jan 10 13:16:22 volumio systemd[1968]: Stopped target paths.target - Paths. Jan 10 13:16:22 volumio systemd[1968]: Stopped target sockets.target - Sockets. Jan 10 13:16:22 volumio systemd[1968]: Stopped target timers.target - Timers. Jan 10 13:16:22 volumio systemd[1968]: Closed dirmngr.socket - GnuPG network certificate management daemon. Jan 10 13:16:22 volumio systemd[1968]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Jan 10 13:16:22 volumio systemd[1968]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Jan 10 13:16:22 volumio systemd[1968]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Jan 10 13:16:22 volumio systemd[1968]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Jan 10 13:16:22 volumio systemd[1968]: Removed slice app.slice - User Application Slice. Jan 10 13:16:22 volumio systemd[1968]: Reached target shutdown.target - Shutdown. Jan 10 13:16:22 volumio systemd[1968]: Finished systemd-exit.service - Exit the Session. Jan 10 13:16:22 volumio systemd[1968]: Reached target exit.target - Exit the Session. Jan 10 13:16:22 volumio systemd[1]: user@1000.service: Deactivated successfully. Jan 10 13:16:22 volumio systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Jan 10 13:16:22 volumio systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Jan 10 13:16:22 volumio systemd[1]: run-user-1000.mount: Deactivated successfully. Jan 10 13:16:22 volumio systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Jan 10 13:16:22 volumio systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Jan 10 13:16:22 volumio systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Jan 10 13:16:23 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 10 13:16:23 volumio volumio[1102]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 10 13:16:23 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 10 13:16:23 volumio volumio[1102]: info: Received Get System Version Jan 10 13:16:23 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 13:16:23 volumio volumio[1102]: info: Received Get System Info Jan 10 13:16:23 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 13:16:23 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 13:16:23 volumio volumio[1102]: info: Discovery: Getting this device information Jan 10 13:16:23 volumio volumio[1102]: info: CoreCommandRouter::volumioGetState Jan 10 13:16:23 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Jan 10 13:16:23 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 13:16:24 volumio volumio[1102]: info: [1768047384099] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Jan 10 13:16:24 volumio volumio[1102]: info: CoreCommandRouter::volumioGetQueue Jan 10 13:16:24 volumio volumio[1102]: info: CoreStateMachine::getQueue Jan 10 13:16:24 volumio volumio[1102]: info: CorePlayQueue::getQueue Jan 10 13:16:24 volumio volumio[1102]: info: Preload queue cleared Jan 10 13:16:24 volumio volumio[1102]: info: Preloading song: webrp/1 Jan 10 13:16:24 volumio volumio[1102]: info: Preloading song: webrp/0 Jan 10 13:16:24 volumio volumio[1102]: info: Preloading song: webrp/3 Jan 10 13:16:24 volumio volumio[1102]: info: Preloading song: webrp/11 Jan 10 13:16:24 volumio volumio[1102]: info: Preloading song: webrp/9 Jan 10 13:16:24 volumio volumio[1102]: info: Preloading song: webrp/0 Jan 10 13:16:24 volumio volumio[1102]: info: Exploding uri webrp/1 in service radio_paradise Jan 10 13:16:24 volumio volumio[1102]: info: Exploding uri webrp/0 in service radio_paradise Jan 10 13:16:24 volumio volumio[1102]: info: Exploding uri webrp/3 in service radio_paradise Jan 10 13:16:24 volumio volumio[1102]: info: Exploding uri webrp/11 in service radio_paradise Jan 10 13:16:24 volumio volumio[1102]: error: [RadioParadise] explodeUri: No station found for uri "webrp/11" Jan 10 13:16:24 volumio volumio[1102]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 13:16:24 volumio volumio[1102]: TypeError: Cannot read properties of undefined (reading 'then') Jan 10 13:16:24 volumio volumio[1102]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Jan 10 13:16:24 volumio volumio[1102]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Jan 10 13:16:24 volumio volumio[1102]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Jan 10 13:16:24 volumio volumio[1102]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Jan 10 13:16:24 volumio volumio[1102]: at listOnTimeout (node:internal/timers:573:17) Jan 10 13:16:24 volumio volumio[1102]: at process.processTimers (node:internal/timers:514:7) Jan 10 13:16:24 volumio volumio[1102]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 13:16:28 volumio sudo[2048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-10 13:15' Jan 10 13:16:28 volumio sudo[2048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"