-- Logs begin at Thu 2019-02-14 06:12:00 AST, end at Sun 2024-09-01 19:52:40 ADT. -- Sep 01 19:51:00 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 19:51:01 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:51:01 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:51:01 volumio volumio[880]: info: Listing playlists Sep 01 19:51:01 volumio volumio[880]: info: Listing playlists Sep 01 19:51:04 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 19:51:07 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:51:07 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:51:08 volumio volumio[880]: info: CoreCommandRouter::volumioGetQueue Sep 01 19:51:08 volumio volumio[880]: info: CoreStateMachine::getQueue Sep 01 19:51:08 volumio volumio[880]: info: CorePlayQueue::getQueue Sep 01 19:51:11 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:51:11 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:51:13 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 19:51:19 volumio kernel: CIFS: __readahead_batch() returned 491/1024 Sep 01 19:51:21 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:51:21 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:51:21 volumio volumio[880]: info: Listing playlists Sep 01 19:51:21 volumio volumio[880]: info: Listing playlists Sep 01 19:51:21 volumio kernel: CIFS: __readahead_batch() returned 630/1024 Sep 01 19:51:31 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:51:31 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:51:39 volumio volumio[880]: verbose: New Socket.io Connection to 192.168.1.75 from 192.168.1.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Edg/128.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Sep 01 19:51:39 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 19:51:39 volumio volumio[880]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 19:51:39 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 19:51:39 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:51:39 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:51:39 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 01 19:51:39 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 19:51:39 volumio volumio[880]: info: Received Get System Info Sep 01 19:51:39 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 19:51:39 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 19:51:39 volumio volumio[880]: info: Discovery: Getting this device information Sep 01 19:51:39 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:51:39 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:51:39 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 19:51:39 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:51:39 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:51:39 volumio volumio[880]: info: Listing playlists Sep 01 19:51:39 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 01 19:51:41 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:51:41 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:51:41 volumio volumio[880]: info: Listing playlists Sep 01 19:51:41 volumio volumio[880]: info: Listing playlists Sep 01 19:51:43 volumio volumio[880]: Searching all installed plugins Sep 01 19:51:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 19:51:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: , search Sep 01 19:51:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , search Sep 01 19:51:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Sep 01 19:51:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Sep 01 19:51:43 volumio volumio[880]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Sep 01 19:51:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: webradio , search Sep 01 19:51:45 volumio volumio[880]: info: All search sources collected, pushing search results Sep 01 19:51:51 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:51:51 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:51:51 volumio volumio[880]: info: Tunnel connection is inactive, restarting it Sep 01 19:51:51 volumio volumio[880]: info: Starting Tunnel 1 Sep 01 19:51:51 volumio volumio[880]: info: Starting Tunnel Connection Checker Sep 01 19:51:51 volumio sudo[3665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Sep 01 19:51:51 volumio sudo[3665]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 19:51:52 volumio autossh[3173]: received signal to exit (15) Sep 01 19:51:52 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... Sep 01 19:51:52 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Sep 01 19:51:52 volumio systemd[1]: sshtunnel.service: Succeeded. Sep 01 19:51:52 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Sep 01 19:51:52 volumio volumio[880]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 01 19:51:52 volumio volumio[880]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 01 19:51:52 volumio systemd[1]: Started MyVolumio SSH Tunnel. Sep 01 19:51:52 volumio sudo[3665]: pam_unix(sudo:session): session closed for user root Sep 01 19:51:52 volumio volumio[880]: info: Remote SSH Started Sep 01 19:51:52 volumio autossh[3668]: port set to 0, monitoring disabled Sep 01 19:51:52 volumio autossh[3668]: starting ssh (count 1) Sep 01 19:51:52 volumio autossh[3668]: ssh child pid is 3671 Sep 01 19:51:52 volumio volumio[880]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 01 19:51:52 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:51:52 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:51:52 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 01 19:51:52 volumio volumio[880]: info: CURURI: music-library Sep 01 19:51:52 volumio volumiossh-tunnel[3667]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Sep 01 19:51:52 volumio volumio[880]: info: Preload queue cleared Sep 01 19:51:53 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 01 19:51:53 volumio volumio[880]: info: CURURI: music-library/NAS Sep 01 19:51:53 volumio volumio[880]: info: Preload queue cleared Sep 01 19:51:55 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 01 19:51:55 volumio volumio[880]: info: CURURI: music-library/NAS/Lacasa_NAS Sep 01 19:51:55 volumio volumio[880]: info: Preload queue cleared Sep 01 19:52:01 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:52:01 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:52:01 volumio volumio[880]: info: Listing playlists Sep 01 19:52:01 volumio volumio[880]: info: Listing playlists Sep 01 19:52:11 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:52:11 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:52:13 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 19:52:21 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:52:21 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:52:21 volumio volumio[880]: info: Listing playlists Sep 01 19:52:21 volumio volumio[880]: info: Listing playlists Sep 01 19:52:29 volumio volumio[880]: info: Clearing queue after UPNP request Sep 01 19:52:29 volumio volumio[880]: info: CoreStateMachine::ClearQueue Sep 01 19:52:29 volumio volumio[880]: info: CoreStateMachine::stop Sep 01 19:52:29 volumio volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 19:52:29 volumio volumio[880]: info: CorePlayQueue::clearPlayQueue Sep 01 19:52:29 volumio volumio[880]: info: CorePlayQueue::saveQueue Sep 01 19:52:29 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Sep 01 19:52:29 volumio volumio[880]: info: CoreCommandRouter::volumioPushQueue Sep 01 19:52:29 volumio volumio[880]: error: Upnp client error: Error: This socket has been ended by the other party Sep 01 19:52:31 volumio volumio[880]: info: Starting UPNP Playback Sep 01 19:52:31 volumio volumio[880]: info: Preparing playback through UPNP Sep 01 19:52:31 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:52:31 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:52:31 volumio volumio[880]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 19:52:31 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:52:31 volumio volumio[880]: info: Sep 01 19:52:31 volumio volumio[880]: ---------------------------- MPD announces system playlist update Sep 01 19:52:31 volumio volumio[880]: info: Ignoring MPD Status Update Sep 01 19:52:31 volumio volumio[880]: info: Sep 01 19:52:31 volumio volumio[880]: ---------------------------- MPD announces system playlist update Sep 01 19:52:31 volumio volumio[880]: info: Ignoring MPD Status Update Sep 01 19:52:31 volumio volumio[880]: info: Sep 01 19:52:31 volumio volumio[880]: ---------------------------- MPD announces system playlist update Sep 01 19:52:31 volumio volumio[880]: info: Ignoring MPD Status Update Sep 01 19:52:31 volumio volumio[880]: info: ------------------------------ 2ms Sep 01 19:52:31 volumio volumio[880]: info: ------------------------------ 1ms Sep 01 19:52:31 volumio volumio[880]: info: Sep 01 19:52:31 volumio volumio[880]: ---------------------------- MPD announces system playlist update Sep 01 19:52:31 volumio volumio[880]: info: Ignoring MPD Status Update Sep 01 19:52:31 volumio volumio[880]: info: ------------------------------ 2ms Sep 01 19:52:31 volumio volumio[880]: info: ------------------------------ 1ms Sep 01 19:52:31 volumio volumio[880]: info: Sep 01 19:52:31 volumio volumio[880]: ---------------------------- MPD announces system playlist update Sep 01 19:52:31 volumio volumio[880]: info: Ignoring MPD Status Update Sep 01 19:52:31 volumio volumio[880]: info: ------------------------------ 2ms Sep 01 19:52:31 volumio volumio[880]: info: Sep 01 19:52:31 volumio volumio[880]: ---------------------------- MPD announces system playlist update Sep 01 19:52:31 volumio volumio[880]: info: Ignoring MPD Status Update Sep 01 19:52:31 volumio volumio[880]: info: Sep 01 19:52:31 volumio volumio[880]: ---------------------------- MPD announces system playlist update Sep 01 19:52:31 volumio volumio[880]: info: Ignoring MPD Status Update Sep 01 19:52:31 volumio volumio[880]: info: ------------------------------ 3ms Sep 01 19:52:31 volumio volumio[880]: info: Sep 01 19:52:31 volumio volumio[880]: ---------------------------- MPD announces system playlist update Sep 01 19:52:31 volumio volumio[880]: info: Ignoring MPD Status Update Sep 01 19:52:31 volumio volumio[880]: info: ------------------------------ 3ms Sep 01 19:52:31 volumio volumio[880]: info: ------------------------------ 2ms Sep 01 19:52:31 volumio volumio[880]: info: Sep 01 19:52:31 volumio volumio[880]: ---------------------------- MPD announces system playlist update Sep 01 19:52:31 volumio volumio[880]: info: Ignoring MPD Status Update Sep 01 19:52:31 volumio volumio[880]: info: Sep 01 19:52:31 volumio volumio[880]: ---------------------------- MPD announces system playlist update Sep 01 19:52:31 volumio volumio[880]: info: Ignoring MPD Status Update Sep 01 19:52:31 volumio volumio[880]: info: ------------------------------ 3ms Sep 01 19:52:31 volumio volumio[880]: info: ------------------------------ 2ms Sep 01 19:52:31 volumio volumio[880]: info: Sep 01 19:52:31 volumio volumio[880]: ---------------------------- MPD announces system playlist update Sep 01 19:52:31 volumio volumio[880]: info: Ignoring MPD Status Update Sep 01 19:52:31 volumio volumio[880]: info: Sep 01 19:52:31 volumio volumio[880]: ---------------------------- MPD announces system playlist update Sep 01 19:52:31 volumio volumio[880]: info: Ignoring MPD Status Update Sep 01 19:52:31 volumio volumio[880]: info: ------------------------------ 3ms Sep 01 19:52:31 volumio volumio[880]: info: ------------------------------ 1ms Sep 01 19:52:32 volumio volumio[880]: info: Sep 01 19:52:32 volumio volumio[880]: ---------------------------- MPD announces state update: player Sep 01 19:52:32 volumio volumio[880]: info: ControllerMpd::getState Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Sep 01 19:52:32 volumio volumio[880]: info: Sep 01 19:52:32 volumio volumio[880]: ---------------------------- MPD announces state update: player Sep 01 19:52:32 volumio volumio[880]: info: ControllerMpd::getState Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Sep 01 19:52:32 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Sep 01 19:52:32 volumio volumio[880]: info: sendMpdCommand status took 15 milliseconds Sep 01 19:52:32 volumio volumio[880]: info: sendMpdCommand status took 15 milliseconds Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::parseState Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::parseState Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 19:52:32 volumio volumio[880]: info: Sep 01 19:52:32 volumio volumio[880]: ---------------------------- MPD announces state update: player Sep 01 19:52:32 volumio volumio[880]: info: ControllerMpd::getState Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Sep 01 19:52:32 volumio volumio[880]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 19:52:32 volumio volumio[880]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Sep 01 19:52:32 volumio volumio[880]: info: ControllerMpd::pushState Sep 01 19:52:32 volumio volumio[880]: info: CoreCommandRouter::servicePushState Sep 01 19:52:32 volumio volumio[880]: verbose: In UPNP mode Sep 01 19:52:32 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"RadioGibraltar","album":null,"uri":"https://sh.uk.audio-stream.com/proxy/gibralta?mp=/stream","trackType":""} Sep 01 19:52:32 volumio volumio[880]: verbose: CURRENT POSITION 0 Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus stop Sep 01 19:52:32 volumio volumio[880]: info: ControllerMpd::pushState Sep 01 19:52:32 volumio volumio[880]: info: CoreCommandRouter::servicePushState Sep 01 19:52:32 volumio volumio[880]: verbose: In UPNP mode Sep 01 19:52:32 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"RadioGibraltar","album":null,"uri":"https://sh.uk.audio-stream.com/proxy/gibralta?mp=/stream","trackType":""} Sep 01 19:52:32 volumio volumio[880]: verbose: CURRENT POSITION 0 Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Sep 01 19:52:32 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Sep 01 19:52:32 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::pushState Sep 01 19:52:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:52:32 volumio volumio[880]: info: ------------------------------ 20ms Sep 01 19:52:32 volumio volumio[880]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 01 19:52:32 volumio volumio[880]: info: ------------------------------ 20ms Sep 01 19:52:32 volumio volumio[880]: info: Sep 01 19:52:32 volumio volumio[880]: ---------------------------- MPD announces state update: player Sep 01 19:52:32 volumio volumio[880]: info: ControllerMpd::getState Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Sep 01 19:52:32 volumio volumio[880]: info: sendMpdCommand status took 5 milliseconds Sep 01 19:52:32 volumio volumio[880]: info: sendMpdCommand status took 1 milliseconds Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::parseState Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::parseState Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 19:52:32 volumio volumio[880]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 19:52:32 volumio volumio[880]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Sep 01 19:52:32 volumio volumio[880]: info: ControllerMpd::pushState Sep 01 19:52:32 volumio volumio[880]: info: CoreCommandRouter::servicePushState Sep 01 19:52:32 volumio volumio[880]: verbose: In UPNP mode Sep 01 19:52:32 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"RadioGibraltar","album":null,"uri":"https://sh.uk.audio-stream.com/proxy/gibralta?mp=/stream","trackType":""} Sep 01 19:52:32 volumio volumio[880]: verbose: CURRENT POSITION 0 Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Sep 01 19:52:32 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Sep 01 19:52:32 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::pushState Sep 01 19:52:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:52:32 volumio volumio[880]: info: ControllerMpd::pushState Sep 01 19:52:32 volumio volumio[880]: info: CoreCommandRouter::servicePushState Sep 01 19:52:32 volumio volumio[880]: verbose: In UPNP mode Sep 01 19:52:32 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"RadioGibraltar","album":null,"uri":"https://sh.uk.audio-stream.com/proxy/gibralta?mp=/stream","trackType":""} Sep 01 19:52:32 volumio volumio[880]: verbose: CURRENT POSITION 0 Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Sep 01 19:52:32 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Sep 01 19:52:32 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::pushState Sep 01 19:52:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:52:32 volumio volumio[880]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 01 19:52:32 volumio volumio[880]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 01 19:52:32 volumio volumio[880]: info: ------------------------------ 10ms Sep 01 19:52:32 volumio volumio[880]: info: ------------------------------ 6ms Sep 01 19:52:32 volumio volumio[880]: info: Sep 01 19:52:32 volumio volumio[880]: ---------------------------- MPD announces system playlist update Sep 01 19:52:32 volumio volumio[880]: info: Ignoring MPD Status Update Sep 01 19:52:32 volumio volumio[880]: info: Sep 01 19:52:32 volumio volumio[880]: ---------------------------- MPD announces state update: player Sep 01 19:52:32 volumio volumio[880]: info: ControllerMpd::getState Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Sep 01 19:52:32 volumio volumio[880]: info: Sep 01 19:52:32 volumio volumio[880]: ---------------------------- MPD announces system playlist update Sep 01 19:52:32 volumio volumio[880]: info: Ignoring MPD Status Update Sep 01 19:52:32 volumio volumio[880]: info: Sep 01 19:52:32 volumio volumio[880]: ---------------------------- MPD announces state update: player Sep 01 19:52:32 volumio volumio[880]: info: ControllerMpd::getState Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Sep 01 19:52:32 volumio volumio[880]: info: ------------------------------ 3ms Sep 01 19:52:32 volumio volumio[880]: info: sendMpdCommand status took 3 milliseconds Sep 01 19:52:32 volumio volumio[880]: info: ------------------------------ 2ms Sep 01 19:52:32 volumio volumio[880]: info: sendMpdCommand status took 2 milliseconds Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::parseState Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::parseState Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 19:52:32 volumio volumio[880]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 19:52:32 volumio volumio[880]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Sep 01 19:52:32 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Sep 01 19:52:32 volumio volumio[880]: info: ControllerMpd::pushState Sep 01 19:52:32 volumio volumio[880]: info: CoreCommandRouter::servicePushState Sep 01 19:52:32 volumio volumio[880]: verbose: In UPNP mode Sep 01 19:52:32 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":1,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"RadioGibraltar","album":null,"uri":"https://sh.uk.audio-stream.com/proxy/gibralta?mp=/stream","trackType":""} Sep 01 19:52:32 volumio volumio[880]: verbose: CURRENT POSITION 0 Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Sep 01 19:52:32 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Sep 01 19:52:32 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::pushState Sep 01 19:52:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:52:32 volumio volumio[880]: info: ControllerMpd::pushState Sep 01 19:52:32 volumio volumio[880]: info: CoreCommandRouter::servicePushState Sep 01 19:52:32 volumio volumio[880]: verbose: In UPNP mode Sep 01 19:52:32 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":1,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"RadioGibraltar","album":null,"uri":"https://sh.uk.audio-stream.com/proxy/gibralta?mp=/stream","trackType":""} Sep 01 19:52:32 volumio volumio[880]: verbose: CURRENT POSITION 0 Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Sep 01 19:52:32 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Sep 01 19:52:32 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 19:52:32 volumio volumio[880]: info: CoreStateMachine::pushState Sep 01 19:52:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:52:32 volumio volumio[880]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 01 19:52:32 volumio volumio[880]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 01 19:52:32 volumio volumio[880]: info: ------------------------------ 8ms Sep 01 19:52:32 volumio volumio[880]: info: ------------------------------ 7ms Sep 01 19:52:35 volumio volumio[880]: info: VolumeController::SetAlsaVolume38 Sep 01 19:52:35 volumio volumio[880]: info: CoreStateMachine::pushState Sep 01 19:52:35 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:52:36 volumio volumio[880]: info: VolumeController::SetAlsaVolume45 Sep 01 19:52:36 volumio volumio[880]: info: CoreStateMachine::pushState Sep 01 19:52:36 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:52:37 volumio volumio[880]: info: VolumeController::SetAlsaVolume46 Sep 01 19:52:37 volumio volumio[880]: info: CoreStateMachine::pushState Sep 01 19:52:37 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:52:37 volumio volumio[880]: info: VolumeController::SetAlsaVolume50 Sep 01 19:52:37 volumio volumio[880]: info: CoreStateMachine::pushState Sep 01 19:52:37 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:52:38 volumio volumio[880]: info: VolumeController::SetAlsaVolume53 Sep 01 19:52:38 volumio volumio[880]: info: CoreStateMachine::pushState Sep 01 19:52:38 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:52:39 volumio volumio[880]: verbose: New Socket.io Connection to 192.168.1.75 from 192.168.1.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Edg/128.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Sep 01 19:52:39 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 19:52:39 volumio volumio[880]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 19:52:39 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 19:52:39 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Sep 01 19:52:39 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Sep 01 19:52:39 volumio volumio[880]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 19:52:39 volumio volumio[880]: TypeError: Cannot read property 'name' of undefined Sep 01 19:52:39 volumio volumio[880]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Sep 01 19:52:39 volumio volumio[880]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Sep 01 19:52:39 volumio volumio[880]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Sep 01 19:52:39 volumio volumio[880]: at Socket.emit (events.js:315:20) Sep 01 19:52:39 volumio volumio[880]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 01 19:52:39 volumio volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 01 19:52:39 volumio volumio[880]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 19:52:40 volumio sudo[3812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-01 19:51 Sep 01 19:52:40 volumio sudo[3812]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"