-- Logs begin at Mon 2024-07-22 19:22:29 SAST, end at Sat 2024-08-17 12:27:59 SAST. -- Aug 17 12:26:01 volumio volumio[901]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 17 12:26:01 volumio volumio[901]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 17 12:26:05 volumio volumio[901]: info: MYVOLUMIO: Adding device Aug 17 12:26:05 volumio volumio[901]: info: MYVOLUMIO: Evaluating Server Aug 17 12:26:07 volumio volumio[901]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 17 12:26:07 volumio volumio[901]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/peppy_screensaver.zip Aug 17 12:26:07 volumio volumio[901]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/peppy_screensaver.zip Aug 17 12:26:07 volumio volumio[901]: info: Folder /tmp/plugins removed Aug 17 12:26:08 volumio volumio[901]: info: Setting Geolocation for MyVolumio to eu3 Aug 17 12:26:08 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 12:26:08 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 12:26:08 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 12:26:08 volumio volumio[901]: info: Check plugin dependencies Aug 17 12:26:08 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 17 12:26:08 volumio volumio[901]: info: Checking if plugin already exists Aug 17 12:26:08 volumio volumio[901]: info: Rename folder Aug 17 12:26:08 volumio volumio[901]: info: Move to category Aug 17 12:26:08 volumio volumio[901]: info: Folder /tmp/downloaded_plugin.zip removed Aug 17 12:26:11 volumio volumio[901]: info: Checking if install.sh is present Aug 17 12:26:11 volumio volumio[901]: info: Updating MyVolumio device info Aug 17 12:26:11 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 12:26:11 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 12:26:11 volumio volumio[901]: info: Executing install.sh Aug 17 12:26:11 volumio sudo[2585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/peppy_screensaver/install.sh Aug 17 12:26:11 volumio sudo[2585]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 12:26:11 volumio volumio[901]: info: Installing peppy-screensaver Dependencies Aug 17 12:26:11 volumio volumio[901]: info: ___Install peppyalsa ... Aug 17 12:26:11 volumio sudo[2608]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/peppyalsa.tar.gz -C /usr/local/lib Aug 17 12:26:11 volumio sudo[2608]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 12:26:11 volumio sudo[2608]: pam_unix(sudo:session): session closed for user root Aug 17 12:26:11 volumio volumio[901]: info: ___Install peppyalsa client ... Aug 17 12:26:11 volumio volumio[901]: info: ___commandline tool already compiled Aug 17 12:26:11 volumio volumio[901]: info: ___Install PeppyMeter... Aug 17 12:26:15 volumio volumio[901]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 17 12:26:15 volumio volumio[901]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 17 12:26:34 volumio volumio[901]: info: ___Install PeppySpectrum... Aug 17 12:26:39 volumio volumio[901]: info: ___Install python pygame... Aug 17 12:26:39 volumio sudo[2725]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/pygame2.tar.gz -C /usr Aug 17 12:26:39 volumio sudo[2725]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 12:26:40 volumio sudo[2725]: pam_unix(sudo:session): session closed for user root Aug 17 12:26:40 volumio volumio[901]: info: ___Install python socket-IO... Aug 17 12:26:40 volumio sudo[2732]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/socketio.tar.gz -C /usr/local/lib/python3.7/dist-packages Aug 17 12:26:40 volumio sudo[2732]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 12:26:40 volumio sudo[2732]: pam_unix(sudo:session): session closed for user root Aug 17 12:26:40 volumio volumio[901]: info: ___Install python pillow... Aug 17 12:26:40 volumio sudo[2739]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/PIL.tar.gz -C /usr/local/lib/python3.7/dist-packages Aug 17 12:26:40 volumio sudo[2739]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 12:26:40 volumio sudo[2739]: pam_unix(sudo:session): session closed for user root Aug 17 12:26:40 volumio volumio[901]: info: ___Install python screenshot... Aug 17 12:26:40 volumio sudo[2746]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/pyscreenshot.tar.gz -C /usr/local/lib/python3.7/dist-packages Aug 17 12:26:40 volumio sudo[2746]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 12:26:40 volumio sudo[2746]: pam_unix(sudo:session): session closed for user root Aug 17 12:26:40 volumio sudo[2749]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rd /data/plugins/user_interface/peppy_screensaver/dependencies Aug 17 12:26:40 volumio sudo[2749]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 12:26:40 volumio sudo[2749]: pam_unix(sudo:session): session closed for user root Aug 17 12:26:40 volumio volumio[901]: info: ___Modify PeppyMeter config for Volumio... Aug 17 12:26:40 volumio volumio[901]: info: ___Modify PeppySpectrum config for Volumio... Aug 17 12:26:40 volumio sudo[2585]: pam_unix(sudo:session): session closed for user root Aug 17 12:26:40 volumio volumio[901]: info: ___Finished Aug 17 12:26:40 volumio volumio[901]: Plugin install end detected on script Aug 17 12:26:40 volumio volumio[901]: info: Install script completed Aug 17 12:26:40 volumio volumio[901]: info: Adding reference to registry Aug 17 12:26:40 volumio volumio[901]: info: Done installing plugin. Aug 17 12:26:40 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 17 12:26:40 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 17 12:26:40 volumio volumio[901]: info: Folder /tmp/plugins removed Aug 17 12:26:40 volumio volumio[901]: info: Folder /tmp/downloaded_plugin.zip removed Aug 17 12:26:40 volumio volumio[901]: info: Folder /data/temp removed Aug 17 12:26:48 volumio sshd[2451]: pam_unix(sshd:session): session closed for user volumio Aug 17 12:26:48 volumio systemd[1]: session-4.scope: Succeeded. Aug 17 12:26:48 volumio systemd-logind[643]: Session 4 logged out. Waiting for processes to exit. Aug 17 12:26:48 volumio systemd-logind[643]: Removed session 4. Aug 17 12:26:49 volumio sshd[2773]: Accepted password for volumio from 192.168.10.171 port 59057 ssh2 Aug 17 12:26:49 volumio sshd[2773]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Aug 17 12:26:49 volumio systemd-logind[643]: New session 6 of user volumio. Aug 17 12:26:49 volumio systemd[1]: Started Session 6 of user volumio. Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 12:26:56 volumio volumio[901]: info: Retrieving Cloud Streaming UI Aug 17 12:26:56 volumio volumio[901]: info: Getting Tidal Cloud Configuration Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 12:26:56 volumio volumio[901]: info: Getting Qobuz Cloud Configuration Aug 17 12:26:56 volumio volumio[901]: info: Asking plugin for UI Config Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 12:26:56 volumio volumio[901]: info: Getting Spotify Cloud Configuration Aug 17 12:26:56 volumio volumio[901]: info: Asking plugin for UI Config Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 12:26:56 volumio volumio[901]: info: Saving Spotify Acccount Aug 17 12:26:56 volumio volumio[901]: info: Got Tidal Cloud Configuration Aug 17 12:26:56 volumio volumio[901]: info: Got it Aug 17 12:26:56 volumio volumio[901]: info: Got it Aug 17 12:26:56 volumio volumio[901]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 12:26:56 volumio volumio[901]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::volumioGetBrowseSources Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::volumioGetBrowseSources Aug 17 12:26:56 volumio volumio[901]: info: CoreCommandRouter::volumioGetBrowseSources Aug 17 12:26:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 17 12:26:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 17 12:27:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 17 12:27:05 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Aug 17 12:27:05 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Aug 17 12:27:06 volumio volumio[901]: info: CoreCommandRouter::volumioGetQueue Aug 17 12:27:06 volumio volumio[901]: info: CoreStateMachine::getQueue Aug 17 12:27:06 volumio volumio[901]: info: CorePlayQueue::getQueue Aug 17 12:27:07 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 17 12:27:07 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 17 12:27:08 volumio volumio[901]: info: Preload queue cleared Aug 17 12:27:20 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: webradio , addMyWebRadio Aug 17 12:27:20 volumio volumio[901]: info: {"name":"Radio Caroline","uri":"http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084"} Aug 17 12:27:20 volumio volumio[901]: info: Saving local item my-web-radio Aug 17 12:27:20 volumio volumio[901]: info: Saving local item my-web-radio Aug 17 12:27:24 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 17 12:27:24 volumio volumio[901]: info: Preload queue cleared Aug 17 12:27:26 volumio volumio[901]: info: Preload queue cleared Aug 17 12:27:26 volumio volumio[901]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 17 12:27:26 volumio volumio[901]: info: CoreStateMachine::ClearQueue Aug 17 12:27:26 volumio volumio[901]: info: CoreStateMachine::stop Aug 17 12:27:26 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 12:27:26 volumio volumio[901]: info: CorePlayQueue::clearPlayQueue Aug 17 12:27:26 volumio volumio[901]: info: CorePlayQueue::saveQueue Aug 17 12:27:26 volumio volumio[901]: info: CoreCommandRouter::volumioPushQueue Aug 17 12:27:26 volumio volumio[901]: info: CoreStateMachine::addQueueItems Aug 17 12:27:26 volumio volumio[901]: info: CorePlayQueue::addQueueItems Aug 17 12:27:26 volumio volumio[901]: info: Preload queue cleared Aug 17 12:27:26 volumio volumio[901]: info: Adding Item to queue: http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084 Aug 17 12:27:26 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 17 12:27:26 volumio volumio[901]: info: CoreCommandRouter::volumioPushQueue Aug 17 12:27:26 volumio volumio[901]: info: CorePlayQueue::saveQueue Aug 17 12:27:26 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock Aug 17 12:27:26 volumio volumio[901]: info: CorePlayQueue::getTrackBlock Aug 17 12:27:26 volumio volumio[901]: info: CoreCommandRouter::volumioPlay Aug 17 12:27:26 volumio volumio[901]: info: CoreStateMachine::play index 0 Aug 17 12:27:26 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 12:27:26 volumio volumio[901]: info: CoreStateMachine::stop Aug 17 12:27:26 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 12:27:26 volumio volumio[901]: info: CoreStateMachine::play index undefined Aug 17 12:27:26 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 12:27:26 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Aug 17 12:27:26 volumio volumio[901]: info: CoreStateMachine::startPlaybackTimer Aug 17 12:27:26 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Aug 17 12:27:26 volumio volumio[901]: info: [1723890446175] ControllerWebradio::clearAddPlayTrack Aug 17 12:27:26 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 12:27:26 volumio volumio[901]: info: sendMpdCommand stop took 3 milliseconds Aug 17 12:27:26 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand clear Aug 17 12:27:26 volumio volumio[901]: info: Aug 17 12:27:26 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 17 12:27:26 volumio volumio[901]: info: Ignoring MPD Status Update Aug 17 12:27:26 volumio volumio[901]: info: sendMpdCommand clear took 7 milliseconds Aug 17 12:27:26 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand load "http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084" Aug 17 12:27:26 volumio volumio[901]: info: Aug 17 12:27:26 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 17 12:27:26 volumio volumio[901]: info: Ignoring MPD Status Update Aug 17 12:27:26 volumio volumio[901]: error: updateQueue error: null Aug 17 12:27:26 volumio volumio[901]: info: ------------------------------ 14ms Aug 17 12:27:26 volumio volumio[901]: error: Upnp client error: Error: This socket has been ended by the other party Aug 17 12:27:27 volumio volumio[901]: error: updateQueue error: null Aug 17 12:27:27 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand add "http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084" Aug 17 12:27:27 volumio volumio[901]: info: ------------------------------ 1553ms Aug 17 12:27:27 volumio volumio[901]: info: Aug 17 12:27:27 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 17 12:27:27 volumio volumio[901]: info: Ignoring MPD Status Update Aug 17 12:27:27 volumio volumio[901]: info: sendMpdCommand add "http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084" took 2 milliseconds Aug 17 12:27:27 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 17 12:27:27 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand play Aug 17 12:27:27 volumio volumio[901]: info: Aug 17 12:27:27 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 17 12:27:27 volumio volumio[901]: info: Ignoring MPD Status Update Aug 17 12:27:27 volumio volumio[901]: info: ------------------------------ 8ms Aug 17 12:27:27 volumio volumio[901]: info: sendMpdCommand play took 6 milliseconds Aug 17 12:27:27 volumio volumio[901]: info: ------------------------------ 6ms Aug 17 12:27:28 volumio volumio[901]: info: Aug 17 12:27:28 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 17 12:27:28 volumio volumio[901]: info: ControllerMpd::getState Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:27:28 volumio volumio[901]: info: Aug 17 12:27:28 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 17 12:27:28 volumio volumio[901]: info: ControllerMpd::getState Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:27:28 volumio volumio[901]: info: sendMpdCommand status took 25 milliseconds Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:27:28 volumio volumio[901]: info: Aug 17 12:27:28 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 17 12:27:28 volumio volumio[901]: info: Ignoring MPD Status Update Aug 17 12:27:28 volumio volumio[901]: info: Aug 17 12:27:28 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 17 12:27:28 volumio volumio[901]: info: ControllerMpd::getState Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:27:28 volumio volumio[901]: info: sendMpdCommand status took 28 milliseconds Aug 17 12:27:28 volumio volumio[901]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:27:28 volumio volumio[901]: info: ControllerMpd::pushState Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 17 12:27:28 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Aug 17 12:27:28 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":";?rp_source=1&=&&___cb=948129876368084","artist":"Radio Caroline","album":null,"uri":"http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084","trackType":""} Aug 17 12:27:28 volumio volumio[901]: verbose: CURRENT POSITION 0 Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus stop Aug 17 12:27:28 volumio volumio[901]: info: ------------------------------ 41ms Aug 17 12:27:28 volumio volumio[901]: info: Aug 17 12:27:28 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 17 12:27:28 volumio volumio[901]: info: Ignoring MPD Status Update Aug 17 12:27:28 volumio volumio[901]: info: Aug 17 12:27:28 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 17 12:27:28 volumio volumio[901]: info: ControllerMpd::getState Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:27:28 volumio volumio[901]: info: ------------------------------ 15ms Aug 17 12:27:28 volumio volumio[901]: info: sendMpdCommand status took 13 milliseconds Aug 17 12:27:28 volumio volumio[901]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 17 12:27:28 volumio volumio[901]: info: ------------------------------ 4ms Aug 17 12:27:28 volumio volumio[901]: info: sendMpdCommand status took 2 milliseconds Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:27:28 volumio volumio[901]: info: ControllerMpd::pushState Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 17 12:27:28 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Aug 17 12:27:28 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":";?rp_source=1&=&&___cb=948129876368084","artist":"Radio Caroline","album":null,"uri":"http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084","trackType":""} Aug 17 12:27:28 volumio volumio[901]: verbose: CURRENT POSITION 0 Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Aug 17 12:27:28 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:28 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:28 volumio volumio[901]: info: ------------------------------ 65ms Aug 17 12:27:28 volumio volumio[901]: info: sendMpdCommand playlistinfo took 20 milliseconds Aug 17 12:27:28 volumio volumio[901]: info: sendMpdCommand playlistinfo took 20 milliseconds Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:27:28 volumio volumio[901]: info: ControllerMpd::pushState Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 17 12:27:28 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Aug 17 12:27:28 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":";?rp_source=1&=&&___cb=948129876368084","artist":"Radio Caroline","album":null,"uri":"http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084","trackType":""} Aug 17 12:27:28 volumio volumio[901]: verbose: CURRENT POSITION 0 Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Aug 17 12:27:28 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:28 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:28 volumio volumio[901]: info: ControllerMpd::pushState Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 17 12:27:28 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Aug 17 12:27:28 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":";?rp_source=1&=&&___cb=948129876368084","artist":"Radio Caroline","album":null,"uri":"http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084","trackType":""} Aug 17 12:27:28 volumio volumio[901]: verbose: CURRENT POSITION 0 Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Aug 17 12:27:28 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:28 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:28 volumio volumio[901]: info: ------------------------------ 74ms Aug 17 12:27:28 volumio volumio[901]: info: ------------------------------ 64ms Aug 17 12:27:28 volumio volumio[901]: info: Aug 17 12:27:28 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 17 12:27:28 volumio volumio[901]: info: Ignoring MPD Status Update Aug 17 12:27:28 volumio volumio[901]: info: Aug 17 12:27:28 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 17 12:27:28 volumio volumio[901]: info: ControllerMpd::getState Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:27:28 volumio volumio[901]: info: Aug 17 12:27:28 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 17 12:27:28 volumio volumio[901]: info: Ignoring MPD Status Update Aug 17 12:27:28 volumio volumio[901]: info: Aug 17 12:27:28 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 17 12:27:28 volumio volumio[901]: info: ControllerMpd::getState Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:27:28 volumio volumio[901]: info: ------------------------------ 9ms Aug 17 12:27:28 volumio volumio[901]: info: sendMpdCommand status took 7 milliseconds Aug 17 12:27:28 volumio volumio[901]: info: ------------------------------ 5ms Aug 17 12:27:28 volumio volumio[901]: info: sendMpdCommand status took 3 milliseconds Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:27:28 volumio volumio[901]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 17 12:27:28 volumio volumio[901]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:27:28 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:27:28 volumio volumio[901]: info: ControllerMpd::pushState Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 17 12:27:28 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Aug 17 12:27:28 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"The Doobie Brothers - Rockin' Down The Highway","artist":"Radio Caroline","album":null,"uri":"http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084","trackType":""} Aug 17 12:27:28 volumio volumio[901]: verbose: CURRENT POSITION 0 Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Aug 17 12:27:28 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:28 volumio volumio[901]: info: ControllerMpd::pushState Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 17 12:27:28 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Aug 17 12:27:28 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"The Doobie Brothers - Rockin' Down The Highway","artist":"Radio Caroline","album":null,"uri":"http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084","trackType":""} Aug 17 12:27:28 volumio volumio[901]: verbose: CURRENT POSITION 0 Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Aug 17 12:27:28 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:28 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:28 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:28 volumio volumio[901]: info: ------------------------------ 41ms Aug 17 12:27:28 volumio volumio[901]: info: ------------------------------ 37ms Aug 17 12:27:34 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Aug 17 12:27:36 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 17 12:27:36 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 17 12:27:39 volumio volumio[901]: info: Aug 17 12:27:39 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 17 12:27:39 volumio volumio[901]: info: Ignoring MPD Status Update Aug 17 12:27:39 volumio volumio[901]: info: Aug 17 12:27:39 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 17 12:27:39 volumio volumio[901]: info: Ignoring MPD Status Update Aug 17 12:27:39 volumio volumio[901]: info: Aug 17 12:27:39 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 17 12:27:39 volumio volumio[901]: info: ControllerMpd::getState Aug 17 12:27:39 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:27:39 volumio volumio[901]: info: ------------------------------ 3ms Aug 17 12:27:39 volumio volumio[901]: info: ------------------------------ 2ms Aug 17 12:27:39 volumio volumio[901]: info: Aug 17 12:27:39 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 17 12:27:39 volumio volumio[901]: info: ControllerMpd::getState Aug 17 12:27:39 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:27:39 volumio volumio[901]: info: sendMpdCommand status took 2 milliseconds Aug 17 12:27:39 volumio volumio[901]: info: sendMpdCommand status took 1 milliseconds Aug 17 12:27:39 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 17 12:27:39 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:27:39 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 17 12:27:39 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:27:39 volumio volumio[901]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 12:27:39 volumio volumio[901]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 12:27:39 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:27:39 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:27:39 volumio volumio[901]: info: ControllerMpd::pushState Aug 17 12:27:39 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 17 12:27:39 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Aug 17 12:27:39 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11970,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Radio Caroline - www.radiocaroline.co.uk (11:27)","artist":"Radio Caroline","album":null,"uri":"http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084","trackType":""} Aug 17 12:27:39 volumio volumio[901]: verbose: CURRENT POSITION 0 Aug 17 12:27:39 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Aug 17 12:27:39 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Aug 17 12:27:39 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Aug 17 12:27:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 17 12:27:39 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 12:27:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:39 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:39 volumio volumio[901]: info: ControllerMpd::pushState Aug 17 12:27:39 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 17 12:27:39 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Aug 17 12:27:39 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11970,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Radio Caroline - www.radiocaroline.co.uk (11:27)","artist":"Radio Caroline","album":null,"uri":"http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084","trackType":""} Aug 17 12:27:39 volumio volumio[901]: verbose: CURRENT POSITION 0 Aug 17 12:27:39 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Aug 17 12:27:39 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Aug 17 12:27:39 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Aug 17 12:27:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 17 12:27:39 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:39 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:39 volumio volumio[901]: info: ------------------------------ 34ms Aug 17 12:27:39 volumio volumio[901]: info: ------------------------------ 33ms Aug 17 12:27:44 volumio volumio[901]: info: Aug 17 12:27:44 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 17 12:27:44 volumio volumio[901]: info: Ignoring MPD Status Update Aug 17 12:27:44 volumio volumio[901]: info: Aug 17 12:27:44 volumio volumio[901]: ---------------------------- MPD announces system playlist update Aug 17 12:27:44 volumio volumio[901]: info: Ignoring MPD Status Update Aug 17 12:27:44 volumio volumio[901]: info: Aug 17 12:27:44 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 17 12:27:44 volumio volumio[901]: info: ControllerMpd::getState Aug 17 12:27:44 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:27:44 volumio volumio[901]: info: ------------------------------ 10ms Aug 17 12:27:44 volumio volumio[901]: info: ------------------------------ 7ms Aug 17 12:27:44 volumio volumio[901]: info: Aug 17 12:27:44 volumio volumio[901]: ---------------------------- MPD announces state update: player Aug 17 12:27:44 volumio volumio[901]: info: ControllerMpd::getState Aug 17 12:27:44 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:27:44 volumio volumio[901]: info: sendMpdCommand status took 5 milliseconds Aug 17 12:27:44 volumio volumio[901]: info: sendMpdCommand status took 1 milliseconds Aug 17 12:27:44 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 17 12:27:44 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:27:44 volumio volumio[901]: verbose: ControllerMpd::parseState Aug 17 12:27:44 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:27:44 volumio volumio[901]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 17 12:27:44 volumio volumio[901]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 17 12:27:44 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:27:44 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:27:44 volumio volumio[901]: info: ControllerMpd::pushState Aug 17 12:27:44 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 17 12:27:44 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Aug 17 12:27:44 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17085,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Michael Lawson - Lighter Load","artist":"Radio Caroline","album":null,"uri":"http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084","trackType":""} Aug 17 12:27:44 volumio volumio[901]: verbose: CURRENT POSITION 0 Aug 17 12:27:44 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Aug 17 12:27:44 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Aug 17 12:27:44 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Aug 17 12:27:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 17 12:27:44 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 12:27:44 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:44 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:44 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:44 volumio volumio[901]: info: ControllerMpd::pushState Aug 17 12:27:44 volumio volumio[901]: info: CoreCommandRouter::servicePushState Aug 17 12:27:44 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Aug 17 12:27:44 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17085,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Michael Lawson - Lighter Load","artist":"Radio Caroline","album":null,"uri":"http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084","trackType":""} Aug 17 12:27:44 volumio volumio[901]: verbose: CURRENT POSITION 0 Aug 17 12:27:44 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Aug 17 12:27:44 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Aug 17 12:27:44 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Aug 17 12:27:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 17 12:27:44 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:44 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:44 volumio volumio[901]: info: CoreStateMachine::pushState Aug 17 12:27:44 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Aug 17 12:27:44 volumio volumio[901]: info: ------------------------------ 50ms Aug 17 12:27:44 volumio volumio[901]: info: ------------------------------ 45ms Aug 17 12:27:58 volumio volumio[901]: info: Enabling plugin peppy_screensaver Aug 17 12:27:58 volumio volumio[901]: info: Loading plugin "peppy_screensaver"... Aug 17 12:27:58 volumio volumio[901]: error: !!!! WARNING !!!! Aug 17 12:27:58 volumio volumio[901]: error: The plugin user_interface/peppy_screensaver failed to load, setting it to stopped. Error: Error: Cannot find module '/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/index'. Please verify that the package.json has a valid "main" entry Aug 17 12:27:58 volumio volumio[901]: error: Stack trace: Error: Cannot find module '/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/index'. Please verify that the package.json has a valid "main" entry Aug 17 12:27:58 volumio volumio[901]: at tryPackage (internal/modules/cjs/loader.js:303:19) Aug 17 12:27:58 volumio volumio[901]: at Function.Module._findPath (internal/modules/cjs/loader.js:516:18) Aug 17 12:27:58 volumio volumio[901]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:867:27) Aug 17 12:27:58 volumio volumio[901]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) Aug 17 12:27:58 volumio volumio[901]: at Module.require (internal/modules/cjs/loader.js:952:19) Aug 17 12:27:58 volumio volumio[901]: at require (internal/modules/cjs/helpers.js:88:18) Aug 17 12:27:58 volumio volumio[901]: at Object. (/data/plugins/user_interface/peppy_screensaver/index.js:4:8) Aug 17 12:27:58 volumio volumio[901]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Aug 17 12:27:58 volumio volumio[901]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Aug 17 12:27:58 volumio volumio[901]: at Module.load (internal/modules/cjs/loader.js:928:32) Aug 17 12:27:58 volumio volumio[901]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Aug 17 12:27:58 volumio volumio[901]: at Module.require (internal/modules/cjs/loader.js:952:19) Aug 17 12:27:58 volumio volumio[901]: at require (internal/modules/cjs/helpers.js:88:18) Aug 17 12:27:58 volumio volumio[901]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Aug 17 12:27:58 volumio volumio[901]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 17 12:27:58 volumio volumio[901]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 17 12:27:58 volumio volumio[901]: error: !!!! WARNING !!!! Aug 17 12:27:58 volumio volumio[901]: info: Preparing to generate the ALSA configuration file Aug 17 12:27:58 volumio volumio[901]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Aug 17 12:27:58 volumio volumio[901]: info: Reading ALSA contributions from plugins. Aug 17 12:27:58 volumio volumio[901]: info: Asound.conf file written Aug 17 12:27:58 volumio sudo[2949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 17 12:27:58 volumio sudo[2949]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 12:27:58 volumio sudo[2949]: pam_unix(sudo:session): session closed for user root Aug 17 12:27:58 volumio volumio[901]: No state is present for card Headphones Aug 17 12:27:58 volumio volumio[901]: /usr/share/alsa/init/bcm2835:20: control element not found Aug 17 12:27:58 volumio volumio[901]: No state is present for card Headphones Aug 17 12:27:58 volumio volumio[901]: No state is present for card sndrpihifiberry Aug 17 12:27:58 volumio volumio[901]: Found hardware: "HifiberryDacplu" "" "" "" "" Aug 17 12:27:58 volumio volumio[901]: Hardware is initialized using a generic method Aug 17 12:27:58 volumio volumio[901]: No state is present for card sndrpihifiberry Aug 17 12:27:58 volumio volumio[901]: info: Output device has changed, restarting MPD Aug 17 12:27:58 volumio sudo[2954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 17 12:27:58 volumio sudo[2954]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 12:27:58 volumio volumio[901]: info: Output device has changed, restarting Shairport Sync Aug 17 12:27:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 12:27:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 12:27:58 volumio sudo[2954]: pam_unix(sudo:session): session closed for user root Aug 17 12:27:58 volumio sudo[2957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 17 12:27:58 volumio sudo[2957]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 12:27:58 volumio systemd[1]: Stopping Music Player Daemon... Aug 17 12:27:58 volumio volumio[901]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 17 12:27:58 volumio volumio[901]: info: Done. Aug 17 12:27:58 volumio volumio[901]: info: MPD Permissions set Aug 17 12:27:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 12:27:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 12:27:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 12:27:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 12:27:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 12:27:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 12:27:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 12:27:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 12:27:58 volumio systemd[1]: mpd.service: Succeeded. Aug 17 12:27:58 volumio systemd[1]: Stopped Music Player Daemon. Aug 17 12:27:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 12:27:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 12:27:58 volumio volumio[901]: info: Starting Shairport Sync Aug 17 12:27:58 volumio sudo[2968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 17 12:27:58 volumio sudo[2968]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 12:27:58 volumio systemd[1]: Starting Music Player Daemon... Aug 17 12:27:58 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 17 12:27:58 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 17 12:27:58 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 17 12:27:58 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 17 12:27:58 volumio sudo[2968]: pam_unix(sudo:session): session closed for user root Aug 17 12:27:58 volumio volumio[901]: info: Shairport-Sync Started Aug 17 12:27:58 volumio sudo[2962]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 17 12:27:58 volumio sudo[2962]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 12:27:58 volumio sudo[2962]: pam_unix(sudo:session): session closed for user root Aug 17 12:27:58 volumio volumio[901]: error: Upnp client error: Error: This socket has been ended by the other party Aug 17 12:27:59 volumio mpd[2974]: Aug 17 12:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 17 12:27:59 volumio systemd[1]: Started Music Player Daemon. Aug 17 12:27:59 volumio sudo[2957]: pam_unix(sudo:session): session closed for user root Aug 17 12:27:59 volumio volumio[901]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 12:27:59 volumio volumio[901]: TypeError: Cannot read property 'printToastMessage' of null Aug 17 12:27:59 volumio volumio[901]: at /volumio/app/index.js:1201:32 Aug 17 12:27:59 volumio volumio[901]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Aug 17 12:27:59 volumio volumio[901]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Aug 17 12:27:59 volumio volumio[901]: at CoreCommandRouter.pushToastMessage (/volumio/app/index.js:1199:13) Aug 17 12:27:59 volumio volumio[901]: at /volumio/app/plugins/music_service/mpd/index.js:797:30 Aug 17 12:27:59 volumio volumio[901]: at /volumio/app/plugins/music_service/mpd/index.js:941:9 Aug 17 12:27:59 volumio volumio[901]: at ChildProcess.exithandler (child_process.js:299:7) Aug 17 12:27:59 volumio volumio[901]: at ChildProcess.emit (events.js:315:20) Aug 17 12:27:59 volumio volumio[901]: at maybeClose (internal/child_process.js:1048:16) Aug 17 12:27:59 volumio volumio[901]: at Socket. (internal/child_process.js:439:11) Aug 17 12:27:59 volumio volumio[901]: at Socket.emit (events.js:315:20) Aug 17 12:27:59 volumio volumio[901]: at Pipe. (net.js:673:12) Aug 17 12:27:59 volumio volumio[901]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 12:27:59 volumio sudo[2986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-17 12:26 Aug 17 12:27:59 volumio sudo[2986]: 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"