Dec 18 17:27:26 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 17:27:26 volumio4 volumio[1307]: info: CURURI: music-library Dec 18 17:27:26 volumio4 volumio[1307]: info: Preload queue cleared Dec 18 17:27:28 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 17:27:28 volumio4 volumio[1307]: info: CURURI: artists:// Dec 18 17:27:28 volumio4 volumio[1307]: info: Preload queue cleared Dec 18 17:27:32 volumio4 sudo[8657]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 18 17:27:32 volumio4 sudo[8657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:32 volumio4 sudo[8657]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:32 volumio4 sudo[8659]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 17:27:32 volumio4 sudo[8659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:32 volumio4 sudo[8659]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:32 volumio4 volumio[1307]: verbose: New Socket.io Connection to 192.168.178.99 from 192.168.178.112 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 9 Dec 18 17:27:32 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetState Dec 18 17:27:32 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 10 Dec 18 17:27:32 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 18 17:27:32 volumio4 volumio[1307]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 18 17:27:32 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetVisibleSources Dec 18 17:27:32 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 17:27:32 volumio4 volumio[1307]: info: Listing playlists Dec 18 17:27:32 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetQueue Dec 18 17:27:32 volumio4 volumio[1307]: info: CoreStateMachine::getQueue Dec 18 17:27:32 volumio4 volumio[1307]: info: CorePlayQueue::getQueue Dec 18 17:27:34 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 18 17:27:34 volumio4 volumio[1307]: info: Received Get System Info Dec 18 17:27:34 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 17:27:34 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 17:27:34 volumio4 volumio[1307]: info: Discovery: Getting this device information Dec 18 17:27:34 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetState Dec 18 17:27:34 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 10 Dec 18 17:27:34 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 17:27:41 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 17:27:41 volumio4 volumio[1307]: info: CURURI: playlists Dec 18 17:27:41 volumio4 volumio[1307]: info: Listing playlists Dec 18 17:27:41 volumio4 sudo[8680]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 18 17:27:41 volumio4 sudo[8680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:41 volumio4 sudo[8680]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:41 volumio4 sudo[8682]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 17:27:41 volumio4 sudo[8682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:41 volumio4 sudo[8682]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:41 volumio4 volumio[1307]: verbose: New Socket.io Connection to 192.168.178.99 from 192.168.178.112 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 9 Dec 18 17:27:41 volumio4 volumio[1307]: info: Preload queue cleared Dec 18 17:27:41 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetState Dec 18 17:27:41 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 10 Dec 18 17:27:41 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 18 17:27:41 volumio4 volumio[1307]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 18 17:27:41 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetVisibleSources Dec 18 17:27:41 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 17:27:41 volumio4 volumio[1307]: info: Listing playlists Dec 18 17:27:41 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetQueue Dec 18 17:27:41 volumio4 volumio[1307]: info: CoreStateMachine::getQueue Dec 18 17:27:41 volumio4 volumio[1307]: info: CorePlayQueue::getQueue Dec 18 17:27:43 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 17:27:43 volumio4 volumio[1307]: info: CURURI: music-library Dec 18 17:27:43 volumio4 volumio[1307]: info: Preload queue cleared Dec 18 17:27:44 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 10 Dec 18 17:27:44 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:44 volumio4 volumio[1307]: info: Prefetching next song Dec 18 17:27:44 volumio4 volumio[1307]: info: DOING PREFETCH IN MPD Dec 18 17:27:44 volumio4 volumio[1307]: verbose: ControllerMpd::sendMpdCommand add "NAS/CyberServer/_New Tracks and Albums/Jonathan Roy - Keeping Me Alive (Live Acoustic Performance).flac" Dec 18 17:27:44 volumio4 volumio[1307]: info: Dec 18 17:27:44 volumio4 volumio[1307]: ---------------------------- MPD announces system playlist update Dec 18 17:27:44 volumio4 volumio[1307]: info: Ignoring MPD Status Update Dec 18 17:27:44 volumio4 volumio[1307]: info: sendMpdCommand add "NAS/CyberServer/_New Tracks and Albums/Jonathan Roy - Keeping Me Alive (Live Acoustic Performance).flac" took 0 milliseconds Dec 18 17:27:44 volumio4 volumio[1307]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 18 17:27:44 volumio4 volumio[1307]: info: Dec 18 17:27:44 volumio4 volumio[1307]: ---------------------------- MPD announces system playlist update Dec 18 17:27:44 volumio4 volumio[1307]: info: Ignoring MPD Status Update Dec 18 17:27:44 volumio4 volumio[1307]: info: ------------------------------ 4ms Dec 18 17:27:44 volumio4 volumio[1307]: info: sendMpdCommand consume 1 took 4 milliseconds Dec 18 17:27:44 volumio4 volumio[1307]: info: ------------------------------ 3ms Dec 18 17:27:49 volumio4 volumio[1307]: info: CoreStateMachine::startPlaybackTimer Dec 18 17:27:49 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:49 volumio4 volumio[1307]: info: CoreStateMachine::pushState Dec 18 17:27:49 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:49 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 17:27:49 volumio4 volumio[1307]: info: CoreCommandRouter::volumioPushState Dec 18 17:27:49 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output update for this device Dec 18 17:27:49 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output Dec 18 17:27:49 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetState Dec 18 17:27:49 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:49 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:49 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:49 volumio4 sudo[8705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:49 volumio4 sudo[8705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:49 volumio4 sudo[8707]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:49 volumio4 sudo[8707]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:49 volumio4 sudo[8705]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:49 volumio4 sudo[8707]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:49 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:49 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:49 volumio4 volumio[1307]: info: Dec 18 17:27:49 volumio4 volumio[1307]: ---------------------------- MPD announces system playlist update Dec 18 17:27:49 volumio4 volumio[1307]: info: Ignoring MPD Status Update Dec 18 17:27:49 volumio4 volumio[1307]: info: Dec 18 17:27:49 volumio4 volumio[1307]: ---------------------------- MPD announces state update: player Dec 18 17:27:49 volumio4 volumio[1307]: info: ControllerMpd::getState Dec 18 17:27:49 volumio4 volumio[1307]: verbose: ControllerMpd::sendMpdCommand status Dec 18 17:27:49 volumio4 volumio[1307]: info: Dec 18 17:27:49 volumio4 volumio[1307]: ---------------------------- MPD announces system playlist update Dec 18 17:27:49 volumio4 volumio[1307]: info: Ignoring MPD Status Update Dec 18 17:27:49 volumio4 volumio[1307]: info: Dec 18 17:27:49 volumio4 volumio[1307]: ---------------------------- MPD announces state update: player Dec 18 17:27:49 volumio4 volumio[1307]: info: ControllerMpd::getState Dec 18 17:27:49 volumio4 volumio[1307]: verbose: ControllerMpd::sendMpdCommand status Dec 18 17:27:49 volumio4 volumio[1307]: info: ------------------------------ 1ms Dec 18 17:27:50 volumio4 volumio[1307]: info: sendMpdCommand status took 1126 milliseconds Dec 18 17:27:50 volumio4 volumio[1307]: verbose: ControllerMpd::parseState Dec 18 17:27:50 volumio4 volumio[1307]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 17:27:50 volumio4 volumio[1307]: info: Dec 18 17:27:50 volumio4 volumio[1307]: ---------------------------- MPD announces state update: player Dec 18 17:27:50 volumio4 volumio[1307]: info: ControllerMpd::getState Dec 18 17:27:50 volumio4 volumio[1307]: verbose: ControllerMpd::sendMpdCommand status Dec 18 17:27:50 volumio4 volumio[1307]: info: Dec 18 17:27:50 volumio4 volumio[1307]: ---------------------------- MPD announces state update: player Dec 18 17:27:50 volumio4 volumio[1307]: info: ControllerMpd::getState Dec 18 17:27:50 volumio4 volumio[1307]: verbose: ControllerMpd::sendMpdCommand status Dec 18 17:27:50 volumio4 volumio[1307]: info: ------------------------------ 1128ms Dec 18 17:27:50 volumio4 volumio[1307]: info: sendMpdCommand status took 1128 milliseconds Dec 18 17:27:50 volumio4 volumio[1307]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 17:27:50 volumio4 volumio[1307]: info: sendMpdCommand status took 1 milliseconds Dec 18 17:27:50 volumio4 volumio[1307]: verbose: ControllerMpd::parseState Dec 18 17:27:50 volumio4 volumio[1307]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 17:27:50 volumio4 volumio[1307]: verbose: ControllerMpd::parseTrackInfo Dec 18 17:27:50 volumio4 volumio[1307]: verbose: ControllerMpd::parseState Dec 18 17:27:50 volumio4 volumio[1307]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 17:27:50 volumio4 volumio[1307]: info: ControllerMpd::pushState Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::servicePushState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Keeping Me Alive (Live Acoustic Performance)","artist":"Jonathan Roy","album":null,"uri":"NAS/CyberServer/_New Tracks and Albums/Jonathan Roy - Keeping Me Alive (Live Acoustic Performance).flac","trackType":"flac"} Dec 18 17:27:50 volumio4 volumio[1307]: verbose: CURRENT POSITION 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreStateMachine::syncState stateService play Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreStateMachine::syncState currentStatus play Dec 18 17:27:50 volumio4 volumio[1307]: info: Received an update from plugin. extracting info from payload Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreStateMachine::pushState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::volumioPushState Dec 18 17:27:50 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output update for this device Dec 18 17:27:50 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreStateMachine::pushState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::volumioPushState Dec 18 17:27:50 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output update for this device Dec 18 17:27:50 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: ------------------------------ 1138ms Dec 18 17:27:50 volumio4 volumio[1307]: info: sendMpdCommand status took 9 milliseconds Dec 18 17:27:50 volumio4 volumio[1307]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 18 17:27:50 volumio4 volumio[1307]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 18 17:27:50 volumio4 volumio[1307]: verbose: ControllerMpd::parseState Dec 18 17:27:50 volumio4 volumio[1307]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 17:27:50 volumio4 volumio[1307]: verbose: ControllerMpd::parseTrackInfo Dec 18 17:27:50 volumio4 volumio[1307]: verbose: ControllerMpd::parseTrackInfo Dec 18 17:27:50 volumio4 volumio[1307]: info: ControllerMpd::pushState Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::servicePushState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Keeping Me Alive (Live Acoustic Performance)","artist":"Jonathan Roy","album":null,"uri":"NAS/CyberServer/_New Tracks and Albums/Jonathan Roy - Keeping Me Alive (Live Acoustic Performance).flac","trackType":"flac"} Dec 18 17:27:50 volumio4 volumio[1307]: verbose: CURRENT POSITION 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreStateMachine::syncState stateService play Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreStateMachine::syncState currentStatus play Dec 18 17:27:50 volumio4 volumio[1307]: info: Received an update from plugin. extracting info from payload Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreStateMachine::pushState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::volumioPushState Dec 18 17:27:50 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output update for this device Dec 18 17:27:50 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreStateMachine::pushState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::volumioPushState Dec 18 17:27:50 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output update for this device Dec 18 17:27:50 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: ControllerMpd::pushState Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::servicePushState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":285,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"205 Kbps","isStreaming":false,"title":"Keeping Me Alive (Live Acoustic Performance)","artist":"Jonathan Roy","album":null,"uri":"NAS/CyberServer/_New Tracks and Albums/Jonathan Roy - Keeping Me Alive (Live Acoustic Performance).flac","trackType":"flac"} Dec 18 17:27:50 volumio4 volumio[1307]: verbose: CURRENT POSITION 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreStateMachine::syncState stateService play Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreStateMachine::syncState currentStatus play Dec 18 17:27:50 volumio4 volumio[1307]: info: Received an update from plugin. extracting info from payload Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreStateMachine::pushState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::volumioPushState Dec 18 17:27:50 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output update for this device Dec 18 17:27:50 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreStateMachine::pushState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::volumioPushState Dec 18 17:27:50 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output update for this device Dec 18 17:27:50 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: ------------------------------ 1148ms Dec 18 17:27:50 volumio4 volumio[1307]: info: ------------------------------ 21ms Dec 18 17:27:50 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:50 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:50 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:50 volumio4 sudo[8713]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:50 volumio4 sudo[8713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:50 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:50 volumio4 sudo[8715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:50 volumio4 sudo[8715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:50 volumio4 sudo[8718]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:50 volumio4 sudo[8718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:50 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:50 volumio4 sudo[8724]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:50 volumio4 sudo[8724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:50 volumio4 sudo[8721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:50 volumio4 sudo[8721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:50 volumio4 sudo[8713]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:50 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:50 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:50 volumio4 sudo[8715]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:50 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:50 volumio4 sudo[8728]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:50 volumio4 sudo[8728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:50 volumio4 sudo[8730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:50 volumio4 sudo[8730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:50 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:50 volumio4 sudo[8718]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:50 volumio4 sudo[8724]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:50 volumio4 sudo[8733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:50 volumio4 sudo[8733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:50 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:50 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:50 volumio4 sudo[8736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:50 volumio4 sudo[8736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:50 volumio4 sudo[8739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:50 volumio4 sudo[8739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:50 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:50 volumio4 sudo[8743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:50 volumio4 sudo[8743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:50 volumio4 volumio[1307]: info: sendMpdCommand playlistinfo took 123 milliseconds Dec 18 17:27:50 volumio4 volumio[1307]: verbose: ControllerMpd::parseTrackInfo Dec 18 17:27:50 volumio4 volumio[1307]: info: ControllerMpd::pushState Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::servicePushState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1221,"duration":285,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"480 Kbps","isStreaming":false,"title":"Keeping Me Alive (Live Acoustic Performance)","artist":"Jonathan Roy","album":null,"uri":"NAS/CyberServer/_New Tracks and Albums/Jonathan Roy - Keeping Me Alive (Live Acoustic Performance).flac","trackType":"flac"} Dec 18 17:27:50 volumio4 volumio[1307]: verbose: CURRENT POSITION 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreStateMachine::syncState stateService play Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreStateMachine::syncState currentStatus play Dec 18 17:27:50 volumio4 volumio[1307]: info: Received an update from plugin. extracting info from payload Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreStateMachine::pushState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::volumioPushState Dec 18 17:27:50 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output update for this device Dec 18 17:27:50 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreStateMachine::pushState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::volumioPushState Dec 18 17:27:50 volumio4 sudo[8745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:50 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output update for this device Dec 18 17:27:50 volumio4 sudo[8745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:50 volumio4 volumio[1307]: info: MRS: Pushing multiroomSync output Dec 18 17:27:50 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetState Dec 18 17:27:50 volumio4 volumio[1307]: info: CorePlayQueue::getTrack 11 Dec 18 17:27:50 volumio4 volumio[1307]: info: ------------------------------ 145ms Dec 18 17:27:50 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:50 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:50 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:50 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:50 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:50 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:50 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:50 volumio4 sudo[8749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:50 volumio4 sudo[8728]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:50 volumio4 sudo[8721]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:50 volumio4 sudo[8739]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:50 volumio4 sudo[8743]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:50 volumio4 sudo[8749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:50 volumio4 sudo[8730]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:50 volumio4 sudo[8736]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:50 volumio4 sudo[8733]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:50 volumio4 sudo[8751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:50 volumio4 sudo[8751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:50 volumio4 volumio[1307]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 18 17:27:50 volumio4 sudo[8745]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:50 volumio4 sudo[8755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:50 volumio4 sudo[8755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:50 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:50 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:50 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:50 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:50 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:50 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:50 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:50 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:50 volumio4 sudo[8757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 18 17:27:50 volumio4 sudo[8757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:27:51 volumio4 sudo[8755]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:51 volumio4 sudo[8749]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:51 volumio4 sudo[8751]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:51 volumio4 sudo[8757]: pam_unix(sudo:session): session closed for user root Dec 18 17:27:51 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:51 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:51 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:51 volumio4 volumio[1307]: info: peppymeterbasic Daemon Started Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 17:27:55 volumio4 volumio[1307]: info: Retrieving Cloud Streaming UI Dec 18 17:27:55 volumio4 volumio[1307]: info: Getting Tidal Cloud Configuration Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 17:27:55 volumio4 volumio[1307]: info: Getting Qobuz Cloud Configuration Dec 18 17:27:55 volumio4 volumio[1307]: info: Asking plugin for UI Config Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 17:27:55 volumio4 volumio[1307]: info: Getting Spotify Cloud Configuration Dec 18 17:27:55 volumio4 volumio[1307]: info: Asking plugin for UI Config Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 17:27:55 volumio4 volumio[1307]: info: Saving Spotify Acccount Dec 18 17:27:55 volumio4 volumio[1307]: info: Got it Dec 18 17:27:55 volumio4 volumio[1307]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 18 17:27:55 volumio4 volumio[1307]: info: Got Tidal Cloud Configuration Dec 18 17:27:55 volumio4 volumio[1307]: info: Got it Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 17:27:55 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 18 17:27:59 volumio4 volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 17:28:00 volumio4 volumio[1307]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 17:28:00 volumio4 volumio[1307]: TypeError: Cannot read properties of null (reading 'slice') Dec 18 17:28:00 volumio4 volumio[1307]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Dec 18 17:28:00 volumio4 volumio[1307]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Dec 18 17:28:00 volumio4 volumio[1307]: at Object.onceWrapper (node:events:629:26) Dec 18 17:28:00 volumio4 volumio[1307]: at ClientRequest.emit (node:events:514:28) Dec 18 17:28:00 volumio4 volumio[1307]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Dec 18 17:28:00 volumio4 volumio[1307]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Dec 18 17:28:00 volumio4 volumio[1307]: at TLSSocket.socketOnData (node:_http_client:535:22) Dec 18 17:28:00 volumio4 volumio[1307]: at TLSSocket.emit (node:events:514:28) Dec 18 17:28:00 volumio4 volumio[1307]: at addChunk (node:internal/streams/readable:343:12) Dec 18 17:28:00 volumio4 volumio[1307]: at readableAddChunk (node:internal/streams/readable:316:9) Dec 18 17:28:00 volumio4 volumio[1307]: at Readable.push (node:internal/streams/readable:253:10) Dec 18 17:28:00 volumio4 volumio[1307]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Dec 18 17:28:00 volumio4 volumio[1307]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 17:28:00 volumio4 sudo[8792]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-18 17:27' Dec 18 17:28:00 volumio4 sudo[8792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:00 volumio4 sudo[8792]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:00 volumio4 vtcs[5433]: [2025-12-18 17:28:00.988] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 18 17:28:00 volumio4 volumio-remote-updater[953]: [2025-12-18 17:28:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 18 17:28:00 volumio4 volumio-remote-updater[953]: [2025-12-18 17:28:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 18 17:28:00 volumio4 qobuz-connect[5399]: 20251218 17:28:00.990 [5399.5399] INFO SampleApp: Socket closed Dec 18 17:28:00 volumio4 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 18 17:28:00 volumio4 systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 18 17:28:01 volumio4 systemd[1]: volumio.service: Consumed 1min 23.037s CPU time. Dec 18 17:28:01 volumio4 systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 18 17:28:01 volumio4 systemd[1]: dynamicswap.service: Deactivated successfully. Dec 18 17:28:01 volumio4 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Dec 18 17:28:01 volumio4 systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 18 17:28:01 volumio4 systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 18 17:28:01 volumio4 systemd[1]: volumio.service: Consumed 1min 23.037s CPU time. Dec 18 17:28:01 volumio4 systemd[1]: Started volumio.service - Volumio Backend Module. Dec 18 17:28:01 volumio4 systemd[1]: dynamicswap.service: Deactivated successfully. Dec 18 17:28:01 volumio4 volumio[8834]: info: ------------------------------------------- Dec 18 17:28:01 volumio4 volumio[8834]: info: ----- Volumio3 ---- Dec 18 17:28:01 volumio4 volumio[8834]: info: ------------------------------------------- Dec 18 17:28:01 volumio4 volumio[8834]: info: ----- System startup ---- Dec 18 17:28:01 volumio4 volumio[8834]: info: ------------------------------------------- Dec 18 17:28:02 volumio4 volumio[8834]: info: MYVOLUMIO Environment detected Dec 18 17:28:02 volumio4 volumio[8834]: info: Plugin folders cleanup Dec 18 17:28:02 volumio4 volumio[8834]: info: Scanning into folder /volumio/app/plugins/ Dec 18 17:28:02 volumio4 volumio[8834]: info: Scanning category audio_interface Dec 18 17:28:02 volumio4 volumio[8834]: info: Scanning category miscellanea Dec 18 17:28:02 volumio4 volumio[8834]: info: Scanning category music_service Dec 18 17:28:02 volumio4 volumio[8834]: info: Scanning category plugins.json Dec 18 17:28:02 volumio4 volumio[8834]: info: Scanning category system_controller Dec 18 17:28:02 volumio4 volumio[8834]: info: Scanning category user_interface Dec 18 17:28:02 volumio4 volumio[8834]: info: Scanning into folder /data/plugins/ Dec 18 17:28:02 volumio4 volumio[8834]: info: Scanning category user_interface Dec 18 17:28:02 volumio4 volumio[8834]: info: Plugin folders cleanup completed Dec 18 17:28:02 volumio4 volumio[8834]: info: ------------------------------------------- Dec 18 17:28:02 volumio4 volumio[8834]: info: ----- Core plugins startup ---- Dec 18 17:28:02 volumio4 volumio[8834]: info: ------------------------------------------- Dec 18 17:28:02 volumio4 volumio[8834]: info: Loading plugins from folder /volumio/app/plugins/ Dec 18 17:28:02 volumio4 volumio[8834]: info: Adding plugin upnp to MyMusic Plugins Dec 18 17:28:02 volumio4 volumio[8834]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 18 17:28:02 volumio4 volumio[8834]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 18 17:28:02 volumio4 volumio[8834]: info: Loading plugins from folder /data/plugins/ Dec 18 17:28:02 volumio4 volumio[8834]: info: Loading plugin "system"... Dec 18 17:28:02 volumio4 volumio[8834]: info: Loading plugin "appearance"... Dec 18 17:28:02 volumio4 volumio[8834]: info: Loading plugin "network"... Dec 18 17:28:02 volumio4 volumio[8834]: info: Refreshing Cached IP Addresses Dec 18 17:28:02 volumio4 sudo[8862]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 18 17:28:02 volumio4 sudo[8862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:02 volumio4 sudo[8864]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 17:28:02 volumio4 sudo[8862]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:02 volumio4 sudo[8864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:02 volumio4 sudo[8864]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:02 volumio4 volumio[8834]: info: Loading plugin "services"... Dec 18 17:28:02 volumio4 volumio[8834]: info: Loading plugin "alsa_controller"... Dec 18 17:28:02 volumio4 sudo[8873]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 18 17:28:02 volumio4 sudo[8873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:02 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 18 17:28:02 volumio4 volumio[8834]: info: Loading plugin "wizard"... Dec 18 17:28:02 volumio4 volumio[8834]: info: Loading plugin "networkfs"... Dec 18 17:28:02 volumio4 volumio[8834]: info: Cannot mount NAS CyberServer at system boot, trial number 1 ,retrying in 5 seconds Dec 18 17:28:02 volumio4 volumio[8834]: info: Starting Udev Watcher for removable devices Dec 18 17:28:02 volumio4 volumio[8834]: info: Ignoring mount for partition: boot Dec 18 17:28:02 volumio4 volumio[8834]: info: Ignoring mount for partition: volumio Dec 18 17:28:02 volumio4 volumio[8834]: info: Ignoring mount for partition: volumio_data Dec 18 17:28:02 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 18 17:28:02 volumio4 volumio[8834]: info: Loading plugin "volumio_command_line_client"... Dec 18 17:28:02 volumio4 volumio[8834]: info: Loading plugin "upnp"... Dec 18 17:28:02 volumio4 volumio[8834]: info: [1766075282475] Starting Upmpd Daemon Dec 18 17:28:02 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 18 17:28:02 volumio4 volumio[8834]: info: Loading plugin "my_music"... Dec 18 17:28:02 volumio4 volumio[8834]: info: Loading plugin "mpd"... Dec 18 17:28:02 volumio4 volumio[8834]: info: Loading plugin "upnp_browser"... Dec 18 17:28:03 volumio4 volumio[8834]: info: Starting UPNP Browser Dec 18 17:28:03 volumio4 volumio[8834]: info: Loading plugin "alarm-clock"... Dec 18 17:28:03 volumio4 volumio[8834]: info: Loading plugin "airplay_emulation"... Dec 18 17:28:03 volumio4 volumio[8834]: info: Starting Shairport Sync Dec 18 17:28:03 volumio4 volumio[8834]: info: Loading plugin "last_100"... Dec 18 17:28:03 volumio4 volumio[8834]: info: Loading plugin "webradio"... Dec 18 17:28:03 volumio4 volumio[8834]: info: Loading plugin "i2s_dacs"... Dec 18 17:28:03 volumio4 volumio[8834]: info: I2S DAC not set, start Auto-detection Dec 18 17:28:03 volumio4 volumio[8834]: info: Loading plugin "volumiodiscovery"... Dec 18 17:28:03 volumio4 volumio[8834]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 18 17:28:03 volumio4 node[8834]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 18 17:28:03 volumio4 volumio[8834]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 18 17:28:03 volumio4 node[8834]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 18 17:28:03 volumio4 volumio[8834]: *** WARNING *** For more information see Dec 18 17:28:03 volumio4 volumio[8834]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 18 17:28:03 volumio4 volumio[8834]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 18 17:28:03 volumio4 volumio[8834]: *** WARNING *** For more information see Dec 18 17:28:03 volumio4 volumio[8834]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 18 17:28:03 volumio4 node[8834]: *** WARNING *** For more information see Dec 18 17:28:03 volumio4 node[8834]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 18 17:28:03 volumio4 node[8834]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 18 17:28:03 volumio4 node[8834]: *** WARNING *** For more information see Dec 18 17:28:03 volumio4 volumio[8834]: info: Discovery: Started advertising with name: Volumio4 Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 18 17:28:03 volumio4 volumio[8834]: info: Loading plugin "outputs"... Dec 18 17:28:03 volumio4 volumio[8834]: info: Loading plugin "albumart"... Dec 18 17:28:03 volumio4 volumio[8834]: info: Plugin example_plugin is not enabled Dec 18 17:28:03 volumio4 volumio[8834]: info: Loading plugin "inputs"... Dec 18 17:28:03 volumio4 volumio[8834]: info: Loading plugin "updater_comm"... Dec 18 17:28:03 volumio4 volumio[8834]: info: Plugin mpdemulation is not enabled Dec 18 17:28:03 volumio4 volumio[8834]: info: Loading plugin "rest_api"... Dec 18 17:28:03 volumio4 volumio[8834]: info: Loading plugin "websocket"... Dec 18 17:28:03 volumio4 volumio[8834]: info: Starting Socket.io Server version 1.7.4 Dec 18 17:28:03 volumio4 volumio[8834]: info: Loading plugin "Systeminfo"... Dec 18 17:28:03 volumio4 volumio[8834]: info: Loading plugin "peppymeterbasic"... Dec 18 17:28:03 volumio4 volumio[8894]: Forking 3 albumart workers Dec 18 17:28:03 volumio4 volumio[8834]: info: Loading i18n strings for locale de Dec 18 17:28:03 volumio4 volumio[8834]: Updating browse sources language Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::initPlayerControls Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 17:28:03 volumio4 volumio[8834]: Express server listening on port 3000 Dec 18 17:28:03 volumio4 volumio[8834]: [Metrics] WebUI: 1s 724.34ms Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreStateMachine::resetVolumioState Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreStateMachine::getcurrentVolume Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::volumioRetrievevolume Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreStateMachine::pushState Dec 18 17:28:03 volumio4 volumio[8834]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::volumioPushState Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:03 volumio4 volumio[8834]: info: Volumio Network Manager: Network status updated: 1 Dec 18 17:28:03 volumio4 volumio[8834]: info: Reloading queue from file Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreStateMachine::setRepeat null single undefined Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreStateMachine::pushState Dec 18 17:28:03 volumio4 volumio[8834]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::volumioPushState Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreStateMachine::setRandom null Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreStateMachine::pushState Dec 18 17:28:03 volumio4 volumio[8834]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::volumioPushState Dec 18 17:28:03 volumio4 volumio[8834]: info: Setting Device type: Raspberry PI Dec 18 17:28:03 volumio4 volumio[8834]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 18 17:28:03 volumio4 volumio[8834]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Dec 18 17:28:03 volumio4 volumio[8834]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 18 17:28:03 volumio4 volumio[8834]: verbose: New Socket.io Connection to 192.168.178.99:3000 from 192.168.178.55 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 18 17:28:03 volumio4 volumio[8905]: Starting albumart workers Dec 18 17:28:03 volumio4 volumio[8834]: verbose: New Socket.io Connection to 192.168.178.99 from 192.168.178.112 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 2 Dec 18 17:28:03 volumio4 volumio[8834]: info: Completed loading Core Plugins Dec 18 17:28:03 volumio4 volumio[8834]: info: Preparing to generate the ALSA configuration file Dec 18 17:28:03 volumio4 volumio[8834]: info: Discovery: adding 399bad66-2091-4955-8d80-5351ed1cf067 Dec 18 17:28:03 volumio4 volumio[8834]: info: Discovery: Found device Volumio Dec 18 17:28:03 volumio4 volumio[8906]: Starting albumart workers Dec 18 17:28:03 volumio4 volumio[8834]: info: Discovery: Connecting to remote: 192.168.178.55 Dec 18 17:28:03 volumio4 volumio[8834]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 18 17:28:03 volumio4 volumio[8834]: info: Reading ALSA contributions from plugins. Dec 18 17:28:03 volumio4 volumio[8904]: Starting albumart workers Dec 18 17:28:03 volumio4 volumio[8834]: info: Discovery: Connected to remote: 192.168.178.55 Dec 18 17:28:03 volumio4 volumio[8834]: info: Asound.conf file written Dec 18 17:28:03 volumio4 sudo[8951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 18 17:28:03 volumio4 sudo[8951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:03 volumio4 sudo[8951]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:03 volumio4 volumio[8834]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 18 17:28:03 volumio4 volumio[8834]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 18 17:28:03 volumio4 volumio[8834]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Dec 18 17:28:03 volumio4 volumio[8834]: No state is present for card Loopback Dec 18 17:28:03 volumio4 volumio[8834]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Dec 18 17:28:03 volumio4 volumio[8834]: Found hardware: "Loopback" "Loopback Mixer" "" "" "" Dec 18 17:28:03 volumio4 volumio[8834]: Hardware is initialized using a generic method Dec 18 17:28:03 volumio4 volumio[8834]: No state is present for card Loopback Dec 18 17:28:03 volumio4 volumio[8834]: info: Output device has changed, restarting MPD Dec 18 17:28:03 volumio4 volumio[8834]: info: Output device has changed, restarting Shairport Sync Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:03 volumio4 sudo[8957]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 17:28:03 volumio4 sudo[8957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:03 volumio4 sudo[8957]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:03 volumio4 sudo[8959]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 18 17:28:03 volumio4 sudo[8959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:03 volumio4 volumio[8834]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 18 17:28:03 volumio4 volumio[8834]: info: ___________ START PLUGINS ___________ Dec 18 17:28:03 volumio4 volumio[8834]: info: ControllerMpd::onStart: Initializing MPD Dec 18 17:28:03 volumio4 volumio[8834]: info: Creating MPD Configuration file Dec 18 17:28:03 volumio4 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 18 17:28:03 volumio4 sudo[8973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 17:28:03 volumio4 sudo[8973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 17:28:03 volumio4 sudo[8973]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 18 17:28:03 volumio4 sudo[8976]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 18 17:28:03 volumio4 sudo[8976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:03 volumio4 volumio[8834]: info: [1766075283961] CoreMusicLibrary::Adding element Medienserver Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 17:28:03 volumio4 volumio[8834]: info: UPNP Browser: Client initialized successfully Dec 18 17:28:03 volumio4 systemd[1]: mpd.service: Deactivated successfully. Dec 18 17:28:03 volumio4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 18 17:28:03 volumio4 systemd[1]: mpd.service: Consumed 13.560s CPU time. Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:03 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:03 volumio4 systemd[1]: mpd.socket: Deactivated successfully. Dec 18 17:28:03 volumio4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 18 17:28:03 volumio4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 18 17:28:04 volumio4 volumio[8834]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:04 volumio4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 18 17:28:04 volumio4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 18 17:28:04 volumio4 volumio[8834]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 18 17:28:04 volumio4 volumio[8834]: info: [1766075284022] CoreMusicLibrary::Adding element Last_100 Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 18 17:28:04 volumio4 volumio[8834]: info: [1766075284024] CoreMusicLibrary::Adding element Webradio Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 17:28:04 volumio4 systemd[1]: mpd.service: Deactivated successfully. Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 18 17:28:04 volumio4 volumio[8834]: info: Initializing BBC Radios Dec 18 17:28:04 volumio4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 18 17:28:04 volumio4 systemd[1]: mpd.socket: Deactivated successfully. Dec 18 17:28:04 volumio4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 18 17:28:04 volumio4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 18 17:28:04 volumio4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 18 17:28:04 volumio4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 18 17:28:04 volumio4 volumio[8834]: info: Volumio Calling Home Dec 18 17:28:04 volumio4 sudo[9017]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Dec 18 17:28:04 volumio4 sudo[9017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:04 volumio4 sudo[9017]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:04 volumio4 volumio[8834]: info: Preparing to generate the ALSA configuration file Dec 18 17:28:04 volumio4 sudo[9005]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 18 17:28:04 volumio4 sudo[9005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 18 17:28:04 volumio4 sudo[9005]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:04 volumio4 volumio[8834]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 18 17:28:04 volumio4 volumio[8834]: info: Reading ALSA contributions from plugins. Dec 18 17:28:04 volumio4 volumio[8834]: info: snd_dummy loaded Dec 18 17:28:04 volumio4 volumio[8834]: info: MPD Permissions set Dec 18 17:28:04 volumio4 volumio[8834]: info: MPD Permissions set Dec 18 17:28:04 volumio4 volumio[8834]: info: Volumio called home Dec 18 17:28:04 volumio4 volumio[8834]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 18 17:28:04 volumio4 volumio[8834]: verbose: New Socket.io Connection to 192.168.178.99:3000 from 192.168.178.55 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 18 17:28:04 volumio4 volumio[8834]: info: Discovery: adding 647ba0b0-f58b-4e21-8155-63447f5ec43a Dec 18 17:28:04 volumio4 volumio[8834]: info: Discovery: Found device Volumio4 Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::volumioGetState Dec 18 17:28:04 volumio4 volumio[8834]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:04 volumio4 volumio[8834]: info: Starting Shairport Sync Dec 18 17:28:04 volumio4 volumio[8834]: info: Starting Shairport Sync Dec 18 17:28:04 volumio4 volumio[8834]: info: Starting Shairport Sync Dec 18 17:28:04 volumio4 sudo[9037]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 18 17:28:04 volumio4 volumio[8834]: info: Discovery: this is already registered, 647ba0b0-f58b-4e21-8155-63447f5ec43a Dec 18 17:28:04 volumio4 volumio[8834]: info: Discovery: Found device Volumio4 Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::volumioGetState Dec 18 17:28:04 volumio4 volumio[8834]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:04 volumio4 sudo[9037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:04 volumio4 sudo[9039]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 18 17:28:04 volumio4 sudo[9039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:04 volumio4 sudo[9041]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 18 17:28:04 volumio4 sudo[9041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:04 volumio4 volumio[8834]: info: Asound.conf file unchanged, so no further update is needed Dec 18 17:28:04 volumio4 volumio[8834]: info: Output device has changed, restarting MPD Dec 18 17:28:04 volumio4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 18 17:28:04 volumio4 volumio[8834]: info: Output device has changed, restarting Shairport Sync Dec 18 17:28:04 volumio4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:04 volumio4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 18 17:28:04 volumio4 systemd[1]: shairport-sync.service: Consumed 2.904s CPU time. Dec 18 17:28:04 volumio4 sudo[9046]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 17:28:04 volumio4 sudo[9048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 18 17:28:04 volumio4 sudo[9048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:04 volumio4 volumio[8834]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 18 17:28:04 volumio4 sudo[9046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:04 volumio4 sudo[9046]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:04 volumio4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 18 17:28:04 volumio4 sudo[9037]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:04 volumio4 volumio[8834]: info: MPD Permissions set Dec 18 17:28:04 volumio4 sudo[9041]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:04 volumio4 sudo[9039]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:04 volumio4 volumio[8834]: info: Shairport-Sync Started Dec 18 17:28:04 volumio4 volumio[8834]: Error adding Membership: Error: addMembership EINVAL Dec 18 17:28:04 volumio4 volumio[8834]: info: Shairport-Sync Started Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:04 volumio4 volumio[8834]: info: Shairport-Sync Started Dec 18 17:28:04 volumio4 systemd[1]: mpd.service: Deactivated successfully. Dec 18 17:28:04 volumio4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 18 17:28:04 volumio4 systemd[1]: mpd.socket: Deactivated successfully. Dec 18 17:28:04 volumio4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 18 17:28:04 volumio4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 18 17:28:04 volumio4 volumio[8834]: info: Starting Shairport Sync Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::volumioGetState Dec 18 17:28:04 volumio4 volumio[8834]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:04 volumio4 sudo[9081]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 18 17:28:04 volumio4 sudo[9081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:04 volumio4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 18 17:28:04 volumio4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 18 17:28:04 volumio4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 18 17:28:04 volumio4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 18 17:28:04 volumio4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 18 17:28:04 volumio4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 18 17:28:04 volumio4 sudo[9081]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:04 volumio4 volumio[8834]: info: Shairport-Sync Started Dec 18 17:28:04 volumio4 sudo[9084]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 18 17:28:04 volumio4 sudo[9084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 18 17:28:04 volumio4 volumio[8834]: info: CoreCommandRouter::volumioGetState Dec 18 17:28:04 volumio4 volumio[8834]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:04 volumio4 sudo[9084]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:05 volumio4 sudo[8873]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:05 volumio4 mpd[9101]: 2025-12-18T17:28:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 18 17:28:05 volumio4 volumio[8834]: verbose: New Socket.io Connection to 192.168.178.99 from 192.168.178.112 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 6 Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreCommandRouter::volumioGetState Dec 18 17:28:05 volumio4 volumio[8834]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreCommandRouter::volumioGetVisibleSources Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 17:28:05 volumio4 volumio[8834]: info: Listing playlists Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreCommandRouter::volumioGetQueue Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreStateMachine::getQueue Dec 18 17:28:05 volumio4 volumio[8834]: info: CorePlayQueue::getQueue Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreCommandRouter::volumioGetState Dec 18 17:28:05 volumio4 volumio[8834]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreCommandRouter::volumioGetVisibleSources Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 17:28:05 volumio4 volumio[8834]: info: Listing playlists Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreCommandRouter::volumioGetQueue Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreStateMachine::getQueue Dec 18 17:28:05 volumio4 volumio[8834]: info: CorePlayQueue::getQueue Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreCommandRouter::volumioGetState Dec 18 17:28:05 volumio4 volumio[8834]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreCommandRouter::volumioGetVisibleSources Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 17:28:05 volumio4 volumio[8834]: info: Listing playlists Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreCommandRouter::volumioGetQueue Dec 18 17:28:05 volumio4 volumio[8834]: info: CoreStateMachine::getQueue Dec 18 17:28:05 volumio4 volumio[8834]: info: CorePlayQueue::getQueue Dec 18 17:28:05 volumio4 systemd[1]: Started mpd.service - Music Player Daemon. Dec 18 17:28:05 volumio4 sudo[8959]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:05 volumio4 sudo[9048]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:05 volumio4 sudo[8976]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:05 volumio4 volumio[8834]: info: Completed starting Core Plugins Dec 18 17:28:05 volumio4 volumio[8834]: info: ------------------------------------------- Dec 18 17:28:05 volumio4 volumio[8834]: info: ----- MyVolumio plugins startup ---- Dec 18 17:28:05 volumio4 volumio[8834]: info: ------------------------------------------- Dec 18 17:28:05 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 18 17:28:05 volumio4 volumio[8834]: error: MPD error: The expression evaluated to a falsy value: Dec 18 17:28:05 volumio4 volumio[8834]: assert.ok(self.idling) Dec 18 17:28:05 volumio4 volumio[8834]: error: The expression evaluated to a falsy value: Dec 18 17:28:05 volumio4 volumio[8834]: assert.ok(self.idling) Dec 18 17:28:05 volumio4 volumio[8834]: error: MPD error: The expression evaluated to a falsy value: Dec 18 17:28:05 volumio4 volumio[8834]: assert.ok(self.idling) Dec 18 17:28:05 volumio4 volumio[8834]: error: The expression evaluated to a falsy value: Dec 18 17:28:05 volumio4 volumio[8834]: assert.ok(self.idling) Dec 18 17:28:05 volumio4 volumio[8834]: error: updateQueue error: null Dec 18 17:28:05 volumio4 volumio[8834]: info: MPD running with PID9101 Dec 18 17:28:05 volumio4 volumio[8834]: ,establishing connection Dec 18 17:28:05 volumio4 volumio[8834]: error: updateQueue error: null Dec 18 17:28:05 volumio4 volumio-remote-updater[953]: [2025-12-18 17:28:05] [connect] Successful connection Dec 18 17:28:05 volumio4 volumio-remote-updater[953]: [2025-12-18 17:28:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766075285 101 Dec 18 17:28:05 volumio4 volumio[8834]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 7 Dec 18 17:28:07 volumio4 volumio[8834]: info: Cannot mount NAS CyberServer at system boot, trial number 2 ,retrying in 5 seconds Dec 18 17:28:07 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 17:28:08 volumio4 sudo[9131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 18 17:28:08 volumio4 sudo[9131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:08 volumio4 sudo[9133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 18 17:28:08 volumio4 sudo[9133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:08 volumio4 sudo[9136]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 18 17:28:08 volumio4 sudo[9136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:08 volumio4 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 18 17:28:08 volumio4 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 18 17:28:08 volumio4 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 18 17:28:08 volumio4 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 18 17:28:08 volumio4 sudo[9136]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:08 volumio4 mpd_monitor.sh[9139]: MPD Monitor Service: Starting MPD Monitor Service Dec 18 17:28:08 volumio4 sudo[9131]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:08 volumio4 sudo[9133]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:08 volumio4 volumio[8834]: info: Successfully started MPD Monitor Dec 18 17:28:08 volumio4 volumio[8834]: info: Successfully started MPD Monitor Dec 18 17:28:08 volumio4 volumio[8834]: info: Successfully started MPD Monitor Dec 18 17:28:10 volumio4 volumio[8834]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 18 17:28:11 volumio4 volumio[8834]: Cannot compose Albumart path Dec 18 17:28:12 volumio4 volumio[8834]: Cannot compose Albumart path Dec 18 17:28:12 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 17:28:12 volumio4 volumio[8834]: info: Cannot mount NAS CyberServer at system boot, trial number 3 ,retrying in 5 seconds Dec 18 17:28:12 volumio4 sudo[9167]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 18 17:28:12 volumio4 sudo[9167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:12 volumio4 sudo[9167]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:12 volumio4 sudo[9169]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 17:28:12 volumio4 sudo[9169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:12 volumio4 sudo[9169]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:12 volumio4 sudo[9173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 18 17:28:12 volumio4 sudo[9173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:12 volumio4 sudo[9173]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:12 volumio4 volumio[8834]: info: Upmpdcli Daemon Started Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 18 17:28:14 volumio4 volumio[8834]: info: Adding plugin bluetooth to MyMusic Plugins Dec 18 17:28:14 volumio4 volumio[8834]: info: Adding plugin multiroom to MyMusic Plugins Dec 18 17:28:14 volumio4 volumio[8834]: info: Adding plugin metavolumio to MyMusic Plugins Dec 18 17:28:14 volumio4 volumio[8834]: info: Adding plugin cd_controller to MyMusic Plugins Dec 18 17:28:14 volumio4 volumio[8834]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 18 17:28:14 volumio4 volumio[8834]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 18 17:28:14 volumio4 volumio[8834]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 18 17:28:14 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 18 17:28:15 volumio4 volumio[8834]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 18 17:28:15 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 18 17:28:15 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:15 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:15 volumio4 volumio[8834]: info: Starting MyVolumio Remote Streaming Endpoints Dec 18 17:28:15 volumio4 volumio[8834]: info: MyVolumio login type: Token Dec 18 17:28:15 volumio4 volumio[8834]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 18 17:28:15 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 18 17:28:15 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 18 17:28:15 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 18 17:28:15 volumio4 volumio[8834]: info: Streaming services startup Dec 18 17:28:15 volumio4 volumio[8834]: info: Starting Streaming Daemon Dec 18 17:28:15 volumio4 sudo[9180]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 18 17:28:15 volumio4 sudo[9180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:15 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 18 17:28:15 volumio4 sudo[9180]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:15 volumio4 volumio[8834]: error: Cannot start Volumio Streaming Daemon Dec 18 17:28:15 volumio4 volumio[8834]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 18 17:28:15 volumio4 volumio[8834]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 18 17:28:16 volumio4 volumio[8834]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 18 17:28:17 volumio4 volumio[8834]: info: Cannot mount NAS CyberServer at system boot, trial number 4 ,retrying in 5 seconds Dec 18 17:28:22 volumio4 volumio[8834]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Dec 18 17:28:30 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 18 17:28:30 volumio4 volumio[8834]: Upnp client error: Error: This socket has been ended by the other party Dec 18 17:28:30 volumio4 volumio[8834]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 18 17:28:30 volumio4 volumio[8834]: info: CoreCommandRouter::volumioGetState Dec 18 17:28:30 volumio4 volumio[8834]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:31 volumio4 volumio[8834]: info: MyVolumio token set successfully Dec 18 17:28:31 volumio4 volumio[8834]: info: MYVOLUMIO: Adding device Dec 18 17:28:31 volumio4 volumio[8834]: info: MYVOLUMIO: Evaluating Server Dec 18 17:28:31 volumio4 volumio[8834]: info: MyVolumio Plan changed: premium Dec 18 17:28:31 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 18 17:28:31 volumio4 volumio[8834]: info: Removing browser output: myVolumio user plan is not superstar Dec 18 17:28:31 volumio4 volumio[8834]: info: Removing audio output: Dec 18 17:28:31 volumio4 volumio[8834]: info: MYVOLUMIO: Adding device Dec 18 17:28:31 volumio4 volumio[8834]: info: MYVOLUMIO: Evaluating Server Dec 18 17:28:31 volumio4 volumio[8834]: info: Remote config written successfully Dec 18 17:28:31 volumio4 volumio[8834]: info: Starting Tunnel 1 Dec 18 17:28:31 volumio4 volumio[8834]: info: Starting Tunnel Connection Checker Dec 18 17:28:31 volumio4 volumio[8834]: info: Completed starting MyVolumio Plugin Dec 18 17:28:32 volumio4 volumio[8834]: info: MYVolumio Device enabled Dec 18 17:28:32 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 18 17:28:32 volumio4 volumio[8834]: info: MyVolumio status changed Dec 18 17:28:32 volumio4 volumio[8834]: info: Streaming services startup Dec 18 17:28:32 volumio4 volumio[8834]: info: Starting Streaming Daemon Dec 18 17:28:32 volumio4 sudo[9263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 18 17:28:32 volumio4 sudo[9263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:32 volumio4 volumio[8834]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 18 17:28:32 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 18 17:28:32 volumio4 sudo[9263]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:32 volumio4 volumio[8834]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 18 17:28:32 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 18 17:28:32 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 18 17:28:32 volumio4 sudo[9281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 18 17:28:32 volumio4 sudo[9281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:32 volumio4 sudo[9281]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:32 volumio4 volumio[8834]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Dec 18 17:28:32 volumio4 volumio[8834]: info: MRS: MultiRoom plugin initialized Dec 18 17:28:32 volumio4 volumio[8834]: info: MRS: STOPPING SNAPCLIENT Dec 18 17:28:32 volumio4 volumio[8834]: info: MRS: Snap server stop Dec 18 17:28:32 volumio4 volumio[8834]: info: MRS: STOPPING volumioStreaming Dec 18 17:28:32 volumio4 sudo[9298]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 18 17:28:32 volumio4 sudo[9298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:32 volumio4 sudo[9300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 18 17:28:32 volumio4 sudo[9300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:32 volumio4 sudo[9306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 18 17:28:32 volumio4 sudo[9306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:32 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 18 17:28:32 volumio4 sudo[9306]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:32 volumio4 sudo[9303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 18 17:28:32 volumio4 sudo[9303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:32 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 18 17:28:32 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 18 17:28:32 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 18 17:28:32 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 18 17:28:32 volumio4 sudo[9300]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:32 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 18 17:28:32 volumio4 sudo[9298]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:32 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 18 17:28:32 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 18 17:28:32 volumio4 sudo[9303]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:32 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 18 17:28:32 volumio4 volumio[8834]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 18 17:28:32 volumio4 volumio[8834]: info: Preparing to generate the ALSA configuration file Dec 18 17:28:32 volumio4 volumio[8834]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 17:28:32 volumio4 volumio[8834]: [Error: 001560F7:error:03000082:digital envelope routines:EVP_CIPHER_CTX_set_key_length:invalid key length:../deps/openssl/openssl/crypto/evp/evp_enc.c:1005: Dec 18 17:28:32 volumio4 volumio[8834]: ] { Dec 18 17:28:32 volumio4 volumio[8834]: library: 'digital envelope routines', Dec 18 17:28:32 volumio4 volumio[8834]: reason: 'invalid key length', Dec 18 17:28:32 volumio4 volumio[8834]: code: 'ERR_SSL_INVALID_KEY_LENGTH' Dec 18 17:28:32 volumio4 volumio[8834]: } Dec 18 17:28:32 volumio4 volumio[8834]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 17:28:32 volumio4 sudo[9323]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-18 17:27' Dec 18 17:28:32 volumio4 sudo[9323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:32 volumio4 sudo[9323]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:33 volumio4 volumio-remote-updater[953]: [2025-12-18 17:28:33] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 18 17:28:33 volumio4 volumio-remote-updater[953]: [2025-12-18 17:28:33] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 18 17:28:33 volumio4 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 18 17:28:33 volumio4 systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 18 17:28:33 volumio4 systemd[1]: volumio.service: Consumed 15.183s CPU time. Dec 18 17:28:33 volumio4 systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 18 17:28:33 volumio4 systemd[1]: dynamicswap.service: Deactivated successfully. Dec 18 17:28:33 volumio4 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Dec 18 17:28:33 volumio4 systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 18 17:28:33 volumio4 systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 18 17:28:33 volumio4 systemd[1]: volumio.service: Consumed 15.183s CPU time. Dec 18 17:28:33 volumio4 systemd[1]: Started volumio.service - Volumio Backend Module. Dec 18 17:28:33 volumio4 systemd[1]: dynamicswap.service: Deactivated successfully. Dec 18 17:28:34 volumio4 volumio[9344]: info: ------------------------------------------- Dec 18 17:28:34 volumio4 volumio[9344]: info: ----- Volumio3 ---- Dec 18 17:28:34 volumio4 volumio[9344]: info: ------------------------------------------- Dec 18 17:28:34 volumio4 volumio[9344]: info: ----- System startup ---- Dec 18 17:28:34 volumio4 volumio[9344]: info: ------------------------------------------- Dec 18 17:28:34 volumio4 volumio[9344]: info: MYVOLUMIO Environment detected Dec 18 17:28:34 volumio4 volumio[9344]: info: Plugin folders cleanup Dec 18 17:28:34 volumio4 volumio[9344]: info: Scanning into folder /volumio/app/plugins/ Dec 18 17:28:34 volumio4 volumio[9344]: info: Scanning category audio_interface Dec 18 17:28:34 volumio4 volumio[9344]: info: Scanning category miscellanea Dec 18 17:28:34 volumio4 volumio[9344]: info: Scanning category music_service Dec 18 17:28:34 volumio4 volumio[9344]: info: Scanning category plugins.json Dec 18 17:28:34 volumio4 volumio[9344]: info: Scanning category system_controller Dec 18 17:28:34 volumio4 volumio[9344]: info: Scanning category user_interface Dec 18 17:28:34 volumio4 volumio[9344]: info: Scanning into folder /data/plugins/ Dec 18 17:28:34 volumio4 volumio[9344]: info: Scanning category user_interface Dec 18 17:28:34 volumio4 volumio[9344]: info: Plugin folders cleanup completed Dec 18 17:28:34 volumio4 volumio[9344]: info: ------------------------------------------- Dec 18 17:28:34 volumio4 volumio[9344]: info: ----- Core plugins startup ---- Dec 18 17:28:34 volumio4 volumio[9344]: info: ------------------------------------------- Dec 18 17:28:34 volumio4 volumio[9344]: info: Loading plugins from folder /volumio/app/plugins/ Dec 18 17:28:34 volumio4 volumio[9344]: info: Adding plugin upnp to MyMusic Plugins Dec 18 17:28:34 volumio4 volumio[9344]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 18 17:28:34 volumio4 volumio[9344]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 18 17:28:34 volumio4 volumio[9344]: info: Loading plugins from folder /data/plugins/ Dec 18 17:28:34 volumio4 volumio[9344]: info: Loading plugin "system"... Dec 18 17:28:34 volumio4 volumio[9344]: info: Loading plugin "appearance"... Dec 18 17:28:34 volumio4 volumio[9344]: info: Loading plugin "network"... Dec 18 17:28:34 volumio4 volumio[9344]: info: Refreshing Cached IP Addresses Dec 18 17:28:34 volumio4 sudo[9372]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 18 17:28:34 volumio4 sudo[9372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:34 volumio4 sudo[9374]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 17:28:34 volumio4 sudo[9374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:34 volumio4 sudo[9372]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:34 volumio4 sudo[9374]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:34 volumio4 volumio[9344]: info: Loading plugin "services"... Dec 18 17:28:34 volumio4 volumio[9344]: info: Loading plugin "alsa_controller"... Dec 18 17:28:34 volumio4 sudo[9381]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 18 17:28:34 volumio4 sudo[9381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:34 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 18 17:28:34 volumio4 volumio[9344]: info: Loading plugin "wizard"... Dec 18 17:28:34 volumio4 volumio[9344]: info: Loading plugin "networkfs"... Dec 18 17:28:34 volumio4 volumio[9344]: info: Cannot mount NAS CyberServer at system boot, trial number 1 ,retrying in 5 seconds Dec 18 17:28:34 volumio4 volumio[9344]: info: Starting Udev Watcher for removable devices Dec 18 17:28:34 volumio4 volumio[9344]: info: Ignoring mount for partition: boot Dec 18 17:28:34 volumio4 volumio[9344]: info: Ignoring mount for partition: volumio Dec 18 17:28:34 volumio4 volumio[9344]: info: Ignoring mount for partition: volumio_data Dec 18 17:28:34 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 18 17:28:34 volumio4 volumio[9344]: info: Loading plugin "volumio_command_line_client"... Dec 18 17:28:34 volumio4 volumio[9344]: info: Loading plugin "upnp"... Dec 18 17:28:34 volumio4 volumio[9344]: info: [1766075314717] Starting Upmpd Daemon Dec 18 17:28:34 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 18 17:28:34 volumio4 volumio[9344]: info: Loading plugin "my_music"... Dec 18 17:28:34 volumio4 volumio[9344]: info: Loading plugin "mpd"... Dec 18 17:28:34 volumio4 volumio[9344]: info: Loading plugin "upnp_browser"... Dec 18 17:28:35 volumio4 volumio[9344]: info: Starting UPNP Browser Dec 18 17:28:35 volumio4 volumio[9344]: info: Loading plugin "alarm-clock"... Dec 18 17:28:35 volumio4 volumio[9344]: info: Loading plugin "airplay_emulation"... Dec 18 17:28:35 volumio4 volumio[9344]: info: Starting Shairport Sync Dec 18 17:28:35 volumio4 volumio[9344]: info: Loading plugin "last_100"... Dec 18 17:28:35 volumio4 volumio[9344]: info: Loading plugin "webradio"... Dec 18 17:28:35 volumio4 volumio[9344]: info: Loading plugin "i2s_dacs"... Dec 18 17:28:35 volumio4 volumio[9344]: info: I2S DAC not set, start Auto-detection Dec 18 17:28:35 volumio4 volumio[9344]: info: Loading plugin "volumiodiscovery"... Dec 18 17:28:35 volumio4 volumio[9344]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 18 17:28:35 volumio4 volumio[9344]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 18 17:28:35 volumio4 volumio[9344]: *** WARNING *** For more information see Dec 18 17:28:35 volumio4 volumio[9344]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 18 17:28:35 volumio4 volumio[9344]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 18 17:28:35 volumio4 volumio[9344]: *** WARNING *** For more information see Dec 18 17:28:35 volumio4 node[9344]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 18 17:28:35 volumio4 node[9344]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 18 17:28:35 volumio4 node[9344]: *** WARNING *** For more information see Dec 18 17:28:35 volumio4 node[9344]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 18 17:28:35 volumio4 node[9344]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 18 17:28:35 volumio4 node[9344]: *** WARNING *** For more information see Dec 18 17:28:35 volumio4 volumio[9344]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 18 17:28:35 volumio4 volumio[9344]: info: Discovery: Started advertising with name: Volumio4 Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 18 17:28:35 volumio4 volumio[9344]: info: Loading plugin "outputs"... Dec 18 17:28:35 volumio4 volumio[9344]: info: Loading plugin "albumart"... Dec 18 17:28:35 volumio4 volumio[9344]: info: Plugin example_plugin is not enabled Dec 18 17:28:35 volumio4 volumio[9344]: info: Loading plugin "inputs"... Dec 18 17:28:35 volumio4 volumio[9344]: info: Loading plugin "updater_comm"... Dec 18 17:28:35 volumio4 volumio[9344]: info: Plugin mpdemulation is not enabled Dec 18 17:28:35 volumio4 volumio[9344]: info: Loading plugin "rest_api"... Dec 18 17:28:35 volumio4 volumio[9344]: info: Loading plugin "websocket"... Dec 18 17:28:35 volumio4 volumio[9344]: info: Starting Socket.io Server version 1.7.4 Dec 18 17:28:35 volumio4 volumio[9344]: info: Loading plugin "Systeminfo"... Dec 18 17:28:35 volumio4 volumio[9344]: info: Loading plugin "peppymeterbasic"... Dec 18 17:28:35 volumio4 volumio[9404]: Forking 3 albumart workers Dec 18 17:28:35 volumio4 volumio[9344]: info: Loading i18n strings for locale de Dec 18 17:28:35 volumio4 volumio[9344]: Updating browse sources language Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::initPlayerControls Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 17:28:35 volumio4 volumio[9344]: Express server listening on port 3000 Dec 18 17:28:35 volumio4 volumio[9344]: [Metrics] WebUI: 1s 722.26ms Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreStateMachine::resetVolumioState Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreStateMachine::getcurrentVolume Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::volumioRetrievevolume Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreStateMachine::pushState Dec 18 17:28:35 volumio4 volumio[9344]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::volumioPushState Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:35 volumio4 volumio[9344]: info: Volumio Network Manager: Network status updated: 1 Dec 18 17:28:35 volumio4 volumio[9344]: info: Reloading queue from file Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreStateMachine::setRepeat null single undefined Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreStateMachine::pushState Dec 18 17:28:35 volumio4 volumio[9344]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::volumioPushState Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreStateMachine::setRandom null Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreStateMachine::pushState Dec 18 17:28:35 volumio4 volumio[9344]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::volumioPushState Dec 18 17:28:35 volumio4 volumio[9344]: info: Setting Device type: Raspberry PI Dec 18 17:28:35 volumio4 volumio[9344]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 18 17:28:35 volumio4 volumio[9344]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Dec 18 17:28:35 volumio4 volumio[9344]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 18 17:28:35 volumio4 volumio[9414]: Starting albumart workers Dec 18 17:28:35 volumio4 volumio[9344]: verbose: New Socket.io Connection to 192.168.178.99 from 192.168.178.112 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 1 Dec 18 17:28:35 volumio4 volumio[9344]: info: Discovery: adding 399bad66-2091-4955-8d80-5351ed1cf067 Dec 18 17:28:35 volumio4 volumio[9344]: info: Discovery: Found device Volumio Dec 18 17:28:35 volumio4 volumio[9344]: info: Discovery: Connecting to remote: 192.168.178.55 Dec 18 17:28:35 volumio4 volumio[9415]: Starting albumart workers Dec 18 17:28:35 volumio4 volumio[9344]: info: Completed loading Core Plugins Dec 18 17:28:35 volumio4 volumio[9344]: info: Preparing to generate the ALSA configuration file Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::volumioGetState Dec 18 17:28:35 volumio4 volumio[9344]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::volumioGetVisibleSources Dec 18 17:28:35 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 17:28:35 volumio4 volumio[9344]: info: Listing playlists Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::volumioGetQueue Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreStateMachine::getQueue Dec 18 17:28:36 volumio4 volumio[9344]: info: CorePlayQueue::getQueue Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::volumioGetState Dec 18 17:28:36 volumio4 volumio[9344]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::volumioGetVisibleSources Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 17:28:36 volumio4 volumio[9344]: info: Listing playlists Dec 18 17:28:36 volumio4 volumio[9416]: Starting albumart workers Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::volumioGetQueue Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreStateMachine::getQueue Dec 18 17:28:36 volumio4 volumio[9344]: info: CorePlayQueue::getQueue Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::volumioGetState Dec 18 17:28:36 volumio4 volumio[9344]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::volumioGetVisibleSources Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 17:28:36 volumio4 volumio[9344]: info: Listing playlists Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::volumioGetQueue Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreStateMachine::getQueue Dec 18 17:28:36 volumio4 volumio[9344]: info: CorePlayQueue::getQueue Dec 18 17:28:36 volumio4 volumio[9344]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 18 17:28:36 volumio4 volumio[9344]: info: Reading ALSA contributions from plugins. Dec 18 17:28:36 volumio4 volumio[9344]: info: Discovery: Connected to remote: 192.168.178.55 Dec 18 17:28:36 volumio4 volumio[9344]: verbose: New Socket.io Connection to 192.168.178.99:3000 from 192.168.178.55 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 18 17:28:36 volumio4 volumio[9344]: info: Asound.conf file unchanged, so no further update is needed Dec 18 17:28:36 volumio4 volumio[9344]: info: Output device has changed, restarting MPD Dec 18 17:28:36 volumio4 volumio[9344]: info: Output device has changed, restarting Shairport Sync Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:36 volumio4 sudo[9485]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 17:28:36 volumio4 sudo[9485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:36 volumio4 sudo[9485]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:36 volumio4 sudo[9487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 18 17:28:36 volumio4 sudo[9487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:36 volumio4 volumio[9344]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 18 17:28:36 volumio4 volumio[9344]: info: ___________ START PLUGINS ___________ Dec 18 17:28:36 volumio4 volumio[9344]: info: ControllerMpd::onStart: Initializing MPD Dec 18 17:28:36 volumio4 volumio[9344]: info: Creating MPD Configuration file Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 18 17:28:36 volumio4 sudo[9495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 17:28:36 volumio4 sudo[9495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:36 volumio4 volumio[9344]: info: [1766075316432] CoreMusicLibrary::Adding element Medienserver Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 17:28:36 volumio4 volumio[9344]: info: UPNP Browser: Client initialized successfully Dec 18 17:28:36 volumio4 sudo[9497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 18 17:28:36 volumio4 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 18 17:28:36 volumio4 sudo[9497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:36 volumio4 sudo[9495]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:36 volumio4 volumio[9344]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 18 17:28:36 volumio4 volumio[9344]: info: [1766075316466] CoreMusicLibrary::Adding element Last_100 Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 18 17:28:36 volumio4 volumio[9344]: info: [1766075316468] CoreMusicLibrary::Adding element Webradio Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 18 17:28:36 volumio4 volumio[9344]: info: Initializing BBC Radios Dec 18 17:28:36 volumio4 systemd[1]: mpd.service: Deactivated successfully. Dec 18 17:28:36 volumio4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 18 17:28:36 volumio4 systemd[1]: mpd.service: Consumed 1.142s CPU time. Dec 18 17:28:36 volumio4 systemd[1]: mpd.socket: Deactivated successfully. Dec 18 17:28:36 volumio4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 18 17:28:36 volumio4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 18 17:28:36 volumio4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:36 volumio4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 18 17:28:36 volumio4 volumio[9344]: info: Volumio Calling Home Dec 18 17:28:36 volumio4 sudo[9524]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Dec 18 17:28:36 volumio4 sudo[9524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:36 volumio4 sudo[9524]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:36 volumio4 volumio[9344]: info: Preparing to generate the ALSA configuration file Dec 18 17:28:36 volumio4 sudo[9522]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 18 17:28:36 volumio4 sudo[9522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 18 17:28:36 volumio4 sudo[9522]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:36 volumio4 volumio[9344]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 18 17:28:36 volumio4 volumio[9344]: info: Reading ALSA contributions from plugins. Dec 18 17:28:36 volumio4 volumio[9344]: info: snd_dummy loaded Dec 18 17:28:36 volumio4 volumio[9344]: info: MPD Permissions set Dec 18 17:28:36 volumio4 volumio[9344]: info: MPD Permissions set Dec 18 17:28:36 volumio4 volumio[9344]: verbose: New Socket.io Connection to 192.168.178.99:3000 from 192.168.178.55 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 18 17:28:36 volumio4 volumio[9344]: info: Volumio called home Dec 18 17:28:36 volumio4 volumio[9344]: info: Discovery: adding 647ba0b0-f58b-4e21-8155-63447f5ec43a Dec 18 17:28:36 volumio4 volumio[9344]: info: Discovery: Found device Volumio4 Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::volumioGetState Dec 18 17:28:36 volumio4 volumio[9344]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:36 volumio4 volumio[9344]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: Discovery: this is already registered, 647ba0b0-f58b-4e21-8155-63447f5ec43a Dec 18 17:28:36 volumio4 volumio[9344]: info: Discovery: Found device Volumio4 Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::volumioGetState Dec 18 17:28:36 volumio4 volumio[9344]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:36 volumio4 volumio[9344]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 18 17:28:36 volumio4 volumio[9344]: info: Starting Shairport Sync Dec 18 17:28:36 volumio4 volumio[9344]: info: Starting Shairport Sync Dec 18 17:28:36 volumio4 volumio[9344]: info: Starting Shairport Sync Dec 18 17:28:36 volumio4 sudo[9543]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 18 17:28:36 volumio4 sudo[9543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:36 volumio4 sudo[9545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 18 17:28:36 volumio4 sudo[9545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:36 volumio4 sudo[9548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 18 17:28:36 volumio4 sudo[9548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:36 volumio4 volumio[9344]: info: Asound.conf file unchanged, so no further update is needed Dec 18 17:28:36 volumio4 volumio[9344]: info: Output device has changed, restarting MPD Dec 18 17:28:36 volumio4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 18 17:28:36 volumio4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 18 17:28:36 volumio4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 18 17:28:36 volumio4 systemd[1]: shairport-sync.service: Consumed 1.564s CPU time. Dec 18 17:28:36 volumio4 volumio[9344]: info: Output device has changed, restarting Shairport Sync Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:36 volumio4 sudo[9552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 17:28:36 volumio4 sudo[9552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:36 volumio4 sudo[9552]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:36 volumio4 sudo[9554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 18 17:28:36 volumio4 sudo[9554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:36 volumio4 volumio[9344]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 18 17:28:36 volumio4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 18 17:28:36 volumio4 sudo[9545]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:36 volumio4 sudo[9548]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:36 volumio4 sudo[9543]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:36 volumio4 systemd[1]: mpd.service: Deactivated successfully. Dec 18 17:28:36 volumio4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 18 17:28:36 volumio4 systemd[1]: mpd.socket: Deactivated successfully. Dec 18 17:28:36 volumio4 volumio[9344]: info: MPD Permissions set Dec 18 17:28:36 volumio4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 18 17:28:36 volumio4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 18 17:28:36 volumio4 volumio[9344]: info: Shairport-Sync Started Dec 18 17:28:36 volumio4 volumio[9344]: Error adding Membership: Error: addMembership EINVAL Dec 18 17:28:36 volumio4 volumio[9344]: info: Shairport-Sync Started Dec 18 17:28:36 volumio4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 18 17:28:36 volumio4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 18 17:28:36 volumio4 volumio[9344]: info: Shairport-Sync Started Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 17:28:36 volumio4 volumio[9344]: info: CoreCommandRouter::volumioGetState Dec 18 17:28:36 volumio4 volumio[9344]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:36 volumio4 volumio[9344]: info: Starting Shairport Sync Dec 18 17:28:36 volumio4 sudo[9579]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 18 17:28:36 volumio4 sudo[9579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 18 17:28:36 volumio4 sudo[9587]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 18 17:28:36 volumio4 sudo[9587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:36 volumio4 sudo[9579]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:37 volumio4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 18 17:28:37 volumio4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 18 17:28:37 volumio4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 18 17:28:37 volumio4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 18 17:28:37 volumio4 sudo[9587]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:37 volumio4 volumio[9344]: info: Shairport-Sync Started Dec 18 17:28:37 volumio4 volumio[9344]: info: CoreCommandRouter::volumioGetState Dec 18 17:28:37 volumio4 volumio[9344]: info: CorePlayQueue::getTrack 0 Dec 18 17:28:37 volumio4 sudo[9381]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:37 volumio4 mpd[9590]: 2025-12-18T17:28:37 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 18 17:28:37 volumio4 systemd[1]: Started mpd.service - Music Player Daemon. Dec 18 17:28:37 volumio4 sudo[9497]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:37 volumio4 sudo[9554]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:37 volumio4 sudo[9487]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:37 volumio4 volumio[9344]: verbose: New Socket.io Connection to 192.168.178.99 from 192.168.178.112 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 6 Dec 18 17:28:37 volumio4 volumio[9344]: info: Completed starting Core Plugins Dec 18 17:28:37 volumio4 volumio[9344]: info: ------------------------------------------- Dec 18 17:28:37 volumio4 volumio[9344]: info: ----- MyVolumio plugins startup ---- Dec 18 17:28:37 volumio4 volumio[9344]: info: ------------------------------------------- Dec 18 17:28:37 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 18 17:28:37 volumio4 volumio[9344]: error: MPD error: The expression evaluated to a falsy value: Dec 18 17:28:37 volumio4 volumio[9344]: assert.ok(self.idling) Dec 18 17:28:37 volumio4 volumio[9344]: error: The expression evaluated to a falsy value: Dec 18 17:28:37 volumio4 volumio[9344]: assert.ok(self.idling) Dec 18 17:28:37 volumio4 volumio[9344]: error: MPD error: The expression evaluated to a falsy value: Dec 18 17:28:37 volumio4 volumio[9344]: assert.ok(self.idling) Dec 18 17:28:37 volumio4 volumio[9344]: error: The expression evaluated to a falsy value: Dec 18 17:28:37 volumio4 volumio[9344]: assert.ok(self.idling) Dec 18 17:28:37 volumio4 volumio[9344]: info: MPD running with PID9590 Dec 18 17:28:37 volumio4 volumio[9344]: ,establishing connection Dec 18 17:28:37 volumio4 volumio[9344]: error: updateQueue error: null Dec 18 17:28:37 volumio4 volumio[9344]: error: updateQueue error: null Dec 18 17:28:38 volumio4 volumio-remote-updater[953]: [2025-12-18 17:28:38] [connect] Successful connection Dec 18 17:28:38 volumio4 volumio-remote-updater[953]: [2025-12-18 17:28:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766075318 101 Dec 18 17:28:38 volumio4 volumio[9344]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 7 Dec 18 17:28:39 volumio4 volumio[9344]: info: Cannot mount NAS CyberServer at system boot, trial number 2 ,retrying in 5 seconds Dec 18 17:28:40 volumio4 sudo[9612]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 18 17:28:40 volumio4 sudo[9612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:40 volumio4 sudo[9615]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 18 17:28:40 volumio4 sudo[9615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:40 volumio4 sudo[9618]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 18 17:28:40 volumio4 sudo[9618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:40 volumio4 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 18 17:28:40 volumio4 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 18 17:28:40 volumio4 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 18 17:28:40 volumio4 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 18 17:28:40 volumio4 mpd_monitor.sh[9620]: MPD Monitor Service: Starting MPD Monitor Service Dec 18 17:28:40 volumio4 sudo[9612]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:40 volumio4 volumio[9344]: info: Successfully started MPD Monitor Dec 18 17:28:40 volumio4 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 18 17:28:40 volumio4 sudo[9615]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:40 volumio4 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 18 17:28:40 volumio4 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 18 17:28:40 volumio4 volumio[9344]: info: Successfully started MPD Monitor Dec 18 17:28:40 volumio4 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 18 17:28:40 volumio4 sudo[9618]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:40 volumio4 mpd_monitor.sh[9623]: MPD Monitor Service: Starting MPD Monitor Service Dec 18 17:28:40 volumio4 volumio[9344]: info: Successfully started MPD Monitor Dec 18 17:28:42 volumio4 volumio[9344]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 18 17:28:44 volumio4 volumio[9344]: Cannot compose Albumart path Dec 18 17:28:44 volumio4 volumio[9344]: Cannot compose Albumart path Dec 18 17:28:44 volumio4 volumio[9344]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 17:28:44 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 18 17:28:44 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 17:28:44 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 17:28:44 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 17:28:44 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 17:28:44 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 17:28:44 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 17:28:44 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 17:28:44 volumio4 volumio[9344]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 17:28:44 volumio4 volumio[9344]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 17:28:44 volumio4 volumio[9344]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 17:28:44 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 17:28:44 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 18 17:28:44 volumio4 volumio[9344]: info: Cannot mount NAS CyberServer at system boot, trial number 3 ,retrying in 5 seconds Dec 18 17:28:44 volumio4 sudo[9646]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 18 17:28:44 volumio4 sudo[9646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:44 volumio4 sudo[9646]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:44 volumio4 sudo[9648]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 17:28:44 volumio4 sudo[9648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:44 volumio4 sudo[9648]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:44 volumio4 sudo[9651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 18 17:28:44 volumio4 sudo[9651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:44 volumio4 sudo[9651]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:44 volumio4 volumio[9344]: info: Upmpdcli Daemon Started Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 18 17:28:46 volumio4 volumio[9344]: info: Adding plugin bluetooth to MyMusic Plugins Dec 18 17:28:46 volumio4 volumio[9344]: info: Adding plugin multiroom to MyMusic Plugins Dec 18 17:28:46 volumio4 volumio[9344]: info: Adding plugin metavolumio to MyMusic Plugins Dec 18 17:28:46 volumio4 volumio[9344]: info: Adding plugin cd_controller to MyMusic Plugins Dec 18 17:28:46 volumio4 volumio[9344]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 18 17:28:46 volumio4 volumio[9344]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 18 17:28:46 volumio4 volumio[9344]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 18 17:28:46 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:46 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:46 volumio4 volumio[9344]: info: Starting MyVolumio Remote Streaming Endpoints Dec 18 17:28:46 volumio4 volumio[9344]: info: MyVolumio login type: Token Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 18 17:28:46 volumio4 volumio[9344]: info: Streaming services startup Dec 18 17:28:46 volumio4 volumio[9344]: info: Starting Streaming Daemon Dec 18 17:28:46 volumio4 sudo[9655]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 18 17:28:46 volumio4 sudo[9655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:46 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 18 17:28:46 volumio4 sudo[9655]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:46 volumio4 volumio[9344]: error: Cannot start Volumio Streaming Daemon Dec 18 17:28:46 volumio4 volumio[9344]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 18 17:28:46 volumio4 volumio[9344]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 18 17:28:47 volumio4 volumio[9344]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 18 17:28:48 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 17:28:48 volumio4 volumio[9344]: info: MyVolumio token set successfully Dec 18 17:28:48 volumio4 volumio[9344]: info: MYVOLUMIO: Adding device Dec 18 17:28:48 volumio4 volumio[9344]: info: MYVOLUMIO: Evaluating Server Dec 18 17:28:49 volumio4 volumio[9344]: info: MyVolumio Plan changed: premium Dec 18 17:28:49 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 18 17:28:49 volumio4 volumio[9344]: info: Removing browser output: myVolumio user plan is not superstar Dec 18 17:28:49 volumio4 volumio[9344]: info: Removing audio output: Dec 18 17:28:49 volumio4 volumio[9344]: info: MYVOLUMIO: Adding device Dec 18 17:28:49 volumio4 volumio[9344]: info: MYVOLUMIO: Evaluating Server Dec 18 17:28:49 volumio4 volumio[9344]: info: Remote config written successfully Dec 18 17:28:49 volumio4 volumio[9344]: info: Starting Tunnel 1 Dec 18 17:28:49 volumio4 volumio[9344]: info: Starting Tunnel Connection Checker Dec 18 17:28:49 volumio4 volumio[9344]: info: Completed starting MyVolumio Plugin Dec 18 17:28:49 volumio4 volumio[9344]: info: MYVolumio Device enabled Dec 18 17:28:49 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 18 17:28:49 volumio4 volumio[9344]: info: MyVolumio status changed Dec 18 17:28:49 volumio4 volumio[9344]: info: Streaming services startup Dec 18 17:28:49 volumio4 volumio[9344]: info: Starting Streaming Daemon Dec 18 17:28:49 volumio4 sudo[9701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 18 17:28:49 volumio4 sudo[9701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:49 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 18 17:28:49 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 18 17:28:49 volumio4 sudo[9701]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:49 volumio4 volumio[9344]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 18 17:28:49 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 18 17:28:49 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 18 17:28:49 volumio4 sudo[9705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 18 17:28:49 volumio4 sudo[9705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:49 volumio4 sudo[9705]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:49 volumio4 volumio[9344]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Dec 18 17:28:49 volumio4 volumio[9344]: info: MRS: MultiRoom plugin initialized Dec 18 17:28:49 volumio4 volumio[9344]: info: MRS: STOPPING SNAPCLIENT Dec 18 17:28:49 volumio4 volumio[9344]: info: MRS: Snap server stop Dec 18 17:28:49 volumio4 volumio[9344]: info: MRS: STOPPING volumioStreaming Dec 18 17:28:49 volumio4 sudo[9722]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 18 17:28:49 volumio4 sudo[9722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:49 volumio4 sudo[9724]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 18 17:28:49 volumio4 sudo[9724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:49 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 18 17:28:49 volumio4 sudo[9727]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 18 17:28:49 volumio4 sudo[9730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 18 17:28:49 volumio4 sudo[9727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:49 volumio4 sudo[9730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 17:28:49 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 18 17:28:49 volumio4 sudo[9730]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:49 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 18 17:28:49 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 18 17:28:49 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 18 17:28:49 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 18 17:28:49 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 18 17:28:49 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 18 17:28:49 volumio4 sudo[9722]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:49 volumio4 sudo[9724]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:49 volumio4 sudo[9727]: pam_unix(sudo:session): session closed for user root Dec 18 17:28:49 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 18 17:28:49 volumio4 volumio[9344]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 18 17:28:49 volumio4 volumio[9344]: info: Preparing to generate the ALSA configuration file Dec 18 17:28:49 volumio4 volumio[9344]: info: Cannot mount NAS CyberServer at system boot, trial number 4 ,retrying in 5 seconds Dec 18 17:28:49 volumio4 volumio[9344]: info: Setting Geolocation for MyVolumio to eu11 Dec 18 17:28:49 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:49 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:49 volumio4 volumio[9344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 17:28:49 volumio4 volumio[9344]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 18 17:28:49 volumio4 volumio[9344]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 18 17:28:49 volumio4 volumio[9344]: info: Reading ALSA contributions from plugins. Dec 18 17:28:49 volumio4 volumio[9344]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 17:28:49 volumio4 volumio[9344]: [Error: 006557F7:error:03000082:digital envelope routines:EVP_CIPHER_CTX_set_key_length:invalid key length:../deps/openssl/openssl/crypto/evp/evp_enc.c:1005: Dec 18 17:28:49 volumio4 volumio[9344]: ] { Dec 18 17:28:49 volumio4 volumio[9344]: library: 'digital envelope routines', Dec 18 17:28:49 volumio4 volumio[9344]: reason: 'invalid key length', Dec 18 17:28:49 volumio4 volumio[9344]: code: 'ERR_SSL_INVALID_KEY_LENGTH' Dec 18 17:28:49 volumio4 volumio[9344]: } Dec 18 17:28:49 volumio4 volumio[9344]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 17:28:50 volumio4 sudo[9747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-18 17:27' Dec 18 17:28:50 volumio4 sudo[9747]: 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"