-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-07-02 19:31:51 CEST. -- Jul 02 19:30:01 volumiodev kernel: hwmon hwmon1: Undervoltage detected! Jul 02 19:30:02 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:03 volumiodev volumio[1024]: info: BOOT COMPLETED Jul 02 19:30:06 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:07 volumiodev kernel: hwmon hwmon1: Voltage normalised Jul 02 19:30:10 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPlay Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::play index undefined Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::startPlaybackTimer Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::clearAddPlayTracks NAS/KK-WYBRANE-UTWOTY/04 - Dbaj o milosc.flac Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 19:30:14 volumiodev volumio[1024]: info: sendMpdCommand stop took 3 milliseconds Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand clear Jul 02 19:30:14 volumiodev volumio[1024]: info: Jul 02 19:30:14 volumiodev volumio[1024]: ---------------------------- MPD announces system playlist update Jul 02 19:30:14 volumiodev volumio[1024]: info: Ignoring MPD Status Update Jul 02 19:30:14 volumiodev volumio[1024]: info: sendMpdCommand clear took 3 milliseconds Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand add "NAS/KK-WYBRANE-UTWOTY/04 - Dbaj o milosc.flac" Jul 02 19:30:14 volumiodev volumio[1024]: info: Jul 02 19:30:14 volumiodev volumio[1024]: ---------------------------- MPD announces system playlist update Jul 02 19:30:14 volumiodev volumio[1024]: info: Ignoring MPD Status Update Jul 02 19:30:14 volumiodev volumio[1024]: info: Jul 02 19:30:14 volumiodev volumio[1024]: ---------------------------- MPD announces system playlist update Jul 02 19:30:14 volumiodev volumio[1024]: info: Ignoring MPD Status Update Jul 02 19:30:14 volumiodev volumio[1024]: error: updateQueue error: null Jul 02 19:30:14 volumiodev volumio[1024]: info: Jul 02 19:30:14 volumiodev volumio[1024]: ---------------------------- MPD announces system playlist update Jul 02 19:30:14 volumiodev volumio[1024]: info: Ignoring MPD Status Update Jul 02 19:30:14 volumiodev volumio[1024]: info: ------------------------------ 13ms Jul 02 19:30:14 volumiodev volumio[1024]: info: sendMpdCommand add "NAS/KK-WYBRANE-UTWOTY/04 - Dbaj o milosc.flac" took 10 milliseconds Jul 02 19:30:14 volumiodev volumio[1024]: info: ------------------------------ 9ms Jul 02 19:30:14 volumiodev volumio[1024]: info: ------------------------------ 7ms Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand play Jul 02 19:30:14 volumiodev volumio[1024]: info: Jul 02 19:30:14 volumiodev volumio[1024]: ---------------------------- MPD announces system playlist update Jul 02 19:30:14 volumiodev volumio[1024]: info: Ignoring MPD Status Update Jul 02 19:30:14 volumiodev volumio[1024]: info: Jul 02 19:30:14 volumiodev volumio[1024]: ---------------------------- MPD announces system playlist update Jul 02 19:30:14 volumiodev volumio[1024]: info: Ignoring MPD Status Update Jul 02 19:30:14 volumiodev volumio[1024]: info: ------------------------------ 11ms Jul 02 19:30:14 volumiodev volumio[1024]: info: sendMpdCommand play took 7 milliseconds Jul 02 19:30:14 volumiodev volumio[1024]: info: ------------------------------ 7ms Jul 02 19:30:14 volumiodev volumio[1024]: info: ------------------------------ 5ms Jul 02 19:30:14 volumiodev volumio[1024]: info: Jul 02 19:30:14 volumiodev volumio[1024]: ---------------------------- MPD announces state update: player Jul 02 19:30:14 volumiodev volumio[1024]: info: ControllerMpd::getState Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Jul 02 19:30:14 volumiodev volumio[1024]: info: Jul 02 19:30:14 volumiodev volumio[1024]: ---------------------------- MPD announces state update: player Jul 02 19:30:14 volumiodev volumio[1024]: info: ControllerMpd::getState Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Jul 02 19:30:14 volumiodev volumio[1024]: info: Jul 02 19:30:14 volumiodev volumio[1024]: ---------------------------- MPD announces state update: player Jul 02 19:30:14 volumiodev volumio[1024]: info: ControllerMpd::getState Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Jul 02 19:30:14 volumiodev volumio[1024]: info: sendMpdCommand status took 27 milliseconds Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::parseState Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 19:30:14 volumiodev volumio[1024]: info: Jul 02 19:30:14 volumiodev volumio[1024]: ---------------------------- MPD announces state update: player Jul 02 19:30:14 volumiodev volumio[1024]: info: ControllerMpd::getState Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Jul 02 19:30:14 volumiodev volumio[1024]: info: sendMpdCommand status took 34 milliseconds Jul 02 19:30:14 volumiodev volumio[1024]: info: sendMpdCommand status took 32 milliseconds Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::parseState Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::parseState Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 19:30:14 volumiodev volumio[1024]: info: Jul 02 19:30:14 volumiodev volumio[1024]: ---------------------------- MPD announces state update: player Jul 02 19:30:14 volumiodev volumio[1024]: info: ControllerMpd::getState Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Jul 02 19:30:14 volumiodev volumio[1024]: info: Jul 02 19:30:14 volumiodev volumio[1024]: ---------------------------- MPD announces state update: player Jul 02 19:30:14 volumiodev volumio[1024]: info: ControllerMpd::getState Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Jul 02 19:30:14 volumiodev volumio[1024]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 02 19:30:14 volumiodev volumio[1024]: info: sendMpdCommand status took 19 milliseconds Jul 02 19:30:14 volumiodev volumio[1024]: info: sendMpdCommand playlistinfo took 16 milliseconds Jul 02 19:30:14 volumiodev volumio[1024]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 02 19:30:14 volumiodev volumio[1024]: info: sendMpdCommand status took 12 milliseconds Jul 02 19:30:14 volumiodev volumio[1024]: info: sendMpdCommand status took 10 milliseconds Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::parseTrackInfo Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::parseState Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::parseTrackInfo Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::parseTrackInfo Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::parseState Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::parseState Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 19:30:14 volumiodev volumio[1024]: info: ControllerMpd::pushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::servicePushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":472,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"795 Kbps","isStreaming":false,"title":"04 - Dbaj o milosc.flac","artist":null,"album":null,"uri":"NAS/KK-WYBRANE-UTWOTY/04 - Dbaj o milosc.flac","trackType":"flac"} Jul 02 19:30:14 volumiodev volumio[1024]: verbose: CURRENT POSITION 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::syncState stateService play Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::syncState currentStatus stop Jul 02 19:30:14 volumiodev volumio[1024]: info: ControllerMpd::pushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::servicePushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":594,"duration":472,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1074 Kbps","isStreaming":false,"title":"04 - Dbaj o milosc.flac","artist":null,"album":null,"uri":"NAS/KK-WYBRANE-UTWOTY/04 - Dbaj o milosc.flac","trackType":"flac"} Jul 02 19:30:14 volumiodev volumio[1024]: verbose: CURRENT POSITION 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::syncState stateService play Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::syncState currentStatus play Jul 02 19:30:14 volumiodev volumio[1024]: info: Received an update from plugin. extracting info from payload Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::pushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::pushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 02 19:30:14 volumiodev volumio[1024]: info: ControllerMpd::pushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::servicePushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":594,"duration":472,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1074 Kbps","isStreaming":false,"title":"04 - Dbaj o milosc.flac","artist":null,"album":null,"uri":"NAS/KK-WYBRANE-UTWOTY/04 - Dbaj o milosc.flac","trackType":"flac"} Jul 02 19:30:14 volumiodev volumio[1024]: verbose: CURRENT POSITION 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::syncState stateService play Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::syncState currentStatus play Jul 02 19:30:14 volumiodev volumio[1024]: info: Received an update from plugin. extracting info from payload Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::pushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::pushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 02 19:30:14 volumiodev volumio[1024]: info: ------------------------------ 160ms Jul 02 19:30:14 volumiodev volumio[1024]: info: ------------------------------ 209ms Jul 02 19:30:14 volumiodev volumio[1024]: info: ------------------------------ 209ms Jul 02 19:30:14 volumiodev volumio[1024]: info: sendMpdCommand playlistinfo took 150 milliseconds Jul 02 19:30:14 volumiodev volumio[1024]: info: sendMpdCommand playlistinfo took 146 milliseconds Jul 02 19:30:14 volumiodev volumio[1024]: info: sendMpdCommand playlistinfo took 145 milliseconds Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::parseTrackInfo Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::parseTrackInfo Jul 02 19:30:14 volumiodev volumio[1024]: verbose: ControllerMpd::parseTrackInfo Jul 02 19:30:14 volumiodev volumio[1024]: info: ControllerMpd::pushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::servicePushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":472,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1077 Kbps","isStreaming":false,"title":"04 - Dbaj o milosc.flac","artist":null,"album":null,"uri":"NAS/KK-WYBRANE-UTWOTY/04 - Dbaj o milosc.flac","trackType":"flac"} Jul 02 19:30:14 volumiodev volumio[1024]: verbose: CURRENT POSITION 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::syncState stateService play Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::syncState currentStatus play Jul 02 19:30:14 volumiodev volumio[1024]: info: Received an update from plugin. extracting info from payload Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::pushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::pushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 02 19:30:14 volumiodev volumio[1024]: info: ControllerMpd::pushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::servicePushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":472,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1077 Kbps","isStreaming":false,"title":"04 - Dbaj o milosc.flac","artist":null,"album":null,"uri":"NAS/KK-WYBRANE-UTWOTY/04 - Dbaj o milosc.flac","trackType":"flac"} Jul 02 19:30:14 volumiodev volumio[1024]: verbose: CURRENT POSITION 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::syncState stateService play Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::syncState currentStatus play Jul 02 19:30:14 volumiodev volumio[1024]: info: Received an update from plugin. extracting info from payload Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::pushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::pushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 02 19:30:14 volumiodev volumio[1024]: info: ControllerMpd::pushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::servicePushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":472,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1077 Kbps","isStreaming":false,"title":"04 - Dbaj o milosc.flac","artist":null,"album":null,"uri":"NAS/KK-WYBRANE-UTWOTY/04 - Dbaj o milosc.flac","trackType":"flac"} Jul 02 19:30:14 volumiodev volumio[1024]: verbose: CURRENT POSITION 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::syncState stateService play Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::syncState currentStatus play Jul 02 19:30:14 volumiodev volumio[1024]: info: Received an update from plugin. extracting info from payload Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::pushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreStateMachine::pushState Jul 02 19:30:14 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 02 19:30:14 volumiodev volumio[1024]: info: ------------------------------ 338ms Jul 02 19:30:14 volumiodev volumio[1024]: info: ------------------------------ 330ms Jul 02 19:30:14 volumiodev volumio[1024]: info: ------------------------------ 330ms Jul 02 19:30:14 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:16 volumiodev kernel: hwmon hwmon1: Undervoltage detected! Jul 02 19:30:18 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:20 volumiodev kernel: hwmon hwmon1: Voltage normalised Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPause Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreStateMachine::pause Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreStateMachine::stPlaybackTimer Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreStateMachine::servicePause Jul 02 19:30:22 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreCommandRouter::servicePause Jul 02 19:30:22 volumiodev volumio[1024]: info: ControllerMpd::pause Jul 02 19:30:22 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand pause Jul 02 19:30:22 volumiodev volumio[1024]: info: Jul 02 19:30:22 volumiodev volumio[1024]: ---------------------------- MPD announces state update: player Jul 02 19:30:22 volumiodev volumio[1024]: info: sendMpdCommand pause took 2 milliseconds Jul 02 19:30:22 volumiodev volumio[1024]: info: ControllerMpd::getState Jul 02 19:30:22 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Jul 02 19:30:22 volumiodev volumio[1024]: info: Jul 02 19:30:22 volumiodev volumio[1024]: ---------------------------- MPD announces state update: player Jul 02 19:30:22 volumiodev volumio[1024]: info: ControllerMpd::getState Jul 02 19:30:22 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Jul 02 19:30:22 volumiodev volumio[1024]: info: Jul 02 19:30:22 volumiodev volumio[1024]: ---------------------------- MPD announces state update: player Jul 02 19:30:22 volumiodev volumio[1024]: info: ControllerMpd::getState Jul 02 19:30:22 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Jul 02 19:30:22 volumiodev volumio[1024]: info: sendMpdCommand status took 5 milliseconds Jul 02 19:30:22 volumiodev volumio[1024]: info: sendMpdCommand status took 4 milliseconds Jul 02 19:30:22 volumiodev volumio[1024]: info: sendMpdCommand status took 2 milliseconds Jul 02 19:30:22 volumiodev volumio[1024]: verbose: ControllerMpd::parseState Jul 02 19:30:22 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 19:30:22 volumiodev volumio[1024]: verbose: ControllerMpd::parseState Jul 02 19:30:22 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 19:30:22 volumiodev volumio[1024]: verbose: ControllerMpd::parseState Jul 02 19:30:22 volumiodev volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 19:30:22 volumiodev volumio[1024]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 02 19:30:22 volumiodev volumio[1024]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 02 19:30:22 volumiodev volumio[1024]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 02 19:30:22 volumiodev volumio[1024]: verbose: ControllerMpd::parseTrackInfo Jul 02 19:30:22 volumiodev volumio[1024]: verbose: ControllerMpd::parseTrackInfo Jul 02 19:30:22 volumiodev volumio[1024]: verbose: ControllerMpd::parseTrackInfo Jul 02 19:30:22 volumiodev volumio[1024]: info: ControllerMpd::pushState Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreCommandRouter::servicePushState Jul 02 19:30:22 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:22 volumiodev volumio[1024]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":8831,"duration":472,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"742 Kbps","isStreaming":false,"title":"04 - Dbaj o milosc.flac","artist":null,"album":null,"uri":"NAS/KK-WYBRANE-UTWOTY/04 - Dbaj o milosc.flac","trackType":"flac"} Jul 02 19:30:22 volumiodev volumio[1024]: verbose: CURRENT POSITION 0 Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreStateMachine::syncState stateService pause Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreStateMachine::syncState currentStatus pause Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreStateMachine::pushState Jul 02 19:30:22 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreStateMachine::stPlaybackTimer Jul 02 19:30:22 volumiodev volumio[1024]: info: ControllerMpd::pushState Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreCommandRouter::servicePushState Jul 02 19:30:22 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:22 volumiodev volumio[1024]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":8831,"duration":472,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"742 Kbps","isStreaming":false,"title":"04 - Dbaj o milosc.flac","artist":null,"album":null,"uri":"NAS/KK-WYBRANE-UTWOTY/04 - Dbaj o milosc.flac","trackType":"flac"} Jul 02 19:30:22 volumiodev volumio[1024]: verbose: CURRENT POSITION 0 Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreStateMachine::syncState stateService pause Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreStateMachine::syncState currentStatus pause Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreStateMachine::pushState Jul 02 19:30:22 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreStateMachine::stPlaybackTimer Jul 02 19:30:22 volumiodev volumio[1024]: info: ControllerMpd::pushState Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreCommandRouter::servicePushState Jul 02 19:30:22 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:22 volumiodev volumio[1024]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":8831,"duration":472,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"742 Kbps","isStreaming":false,"title":"04 - Dbaj o milosc.flac","artist":null,"album":null,"uri":"NAS/KK-WYBRANE-UTWOTY/04 - Dbaj o milosc.flac","trackType":"flac"} Jul 02 19:30:22 volumiodev volumio[1024]: verbose: CURRENT POSITION 0 Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreStateMachine::syncState stateService pause Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreStateMachine::syncState currentStatus pause Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreStateMachine::pushState Jul 02 19:30:22 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreStateMachine::stPlaybackTimer Jul 02 19:30:22 volumiodev volumio[1024]: info: ------------------------------ 56ms Jul 02 19:30:22 volumiodev volumio[1024]: info: ------------------------------ 54ms Jul 02 19:30:22 volumiodev volumio[1024]: info: ------------------------------ 54ms Jul 02 19:30:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:24 volumiodev kernel: hwmon hwmon1: Undervoltage detected! Jul 02 19:30:25 volumiodev volumio[1024]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 19:30:25 volumiodev volumio[1024]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 19:30:25 volumiodev volumio[1024]: info: Retrieving Cloud Streaming UI Jul 02 19:30:25 volumiodev volumio[1024]: info: Getting Tidal Cloud Configuration Jul 02 19:30:25 volumiodev volumio[1024]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 19:30:25 volumiodev volumio[1024]: info: Getting Qobuz Cloud Configuration Jul 02 19:30:25 volumiodev volumio[1024]: info: Asking plugin for UI Config Jul 02 19:30:25 volumiodev volumio[1024]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 19:30:25 volumiodev volumio[1024]: info: Getting Spotify Cloud Configuration Jul 02 19:30:25 volumiodev volumio[1024]: info: Asking plugin for UI Config Jul 02 19:30:25 volumiodev volumio[1024]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 19:30:25 volumiodev volumio[1024]: info: Saving Spotify Acccount Jul 02 19:30:25 volumiodev volumio[1024]: info: Got Tidal Cloud Configuration Jul 02 19:30:25 volumiodev volumio[1024]: info: Got it Jul 02 19:30:25 volumiodev volumio[1024]: info: Got it Jul 02 19:30:25 volumiodev volumio[1024]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 02 19:30:26 volumiodev volumio[1024]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 19:30:26 volumiodev volumio[1024]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 02 19:30:26 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 02 19:30:26 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 02 19:30:26 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 02 19:30:26 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 02 19:30:26 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:30:26 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:30:26 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:30:26 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:30:26 volumiodev volumio[1024]: info: CoreCommandRouter::volumioGetBrowseSources Jul 02 19:30:26 volumiodev volumio[1024]: info: CoreCommandRouter::volumioGetBrowseSources Jul 02 19:30:26 volumiodev volumio[1024]: info: CoreCommandRouter::volumioGetBrowseSources Jul 02 19:30:26 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:26 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 02 19:30:26 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:30 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:30 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:30 volumiodev kernel: hwmon hwmon1: Voltage normalised Jul 02 19:30:34 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:34 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:34 volumiodev kernel: hwmon hwmon1: Undervoltage detected! Jul 02 19:30:38 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:38 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:38 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jul 02 19:30:39 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 02 19:30:41 volumiodev kernel: hwmon hwmon1: Voltage normalised Jul 02 19:30:42 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:42 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:45 volumiodev kernel: hwmon hwmon1: Undervoltage detected! Jul 02 19:30:46 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:46 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:50 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:50 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:54 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:54 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:58 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:58 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:30:59 volumiodev kernel: hwmon hwmon1: Voltage normalised Jul 02 19:31:02 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:31:02 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:31:06 volumiodev kernel: hwmon hwmon1: Undervoltage detected! Jul 02 19:31:06 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:31:06 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:31:07 volumiodev volumio[1024]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 19:31:07 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 02 19:31:07 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 19:31:07 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 02 19:31:07 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:31:07 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:31:07 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:31:07 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:31:07 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:31:07 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:31:07 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:31:07 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 02 19:31:07 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 02 19:31:10 volumiodev kernel: hwmon hwmon1: Voltage normalised Jul 02 19:31:10 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:31:14 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:31:16 volumiodev kernel: hwmon hwmon1: Undervoltage detected! Jul 02 19:31:18 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:31:20 volumiodev kernel: hwmon hwmon1: Voltage normalised Jul 02 19:31:20 volumiodev volumio[1024]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 02 19:31:20 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 02 19:31:20 volumiodev volumio[1024]: info: Preparing to save Alsa Options, stopping services first Jul 02 19:31:20 volumiodev volumio[1024]: info: CoreCommandRouter::volumioGetState Jul 02 19:31:20 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:31:20 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPause Jul 02 19:31:20 volumiodev volumio[1024]: info: CoreStateMachine::pause Jul 02 19:31:20 volumiodev volumio[1024]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphones"},"i2s":true,"i2sid":{"value":"hifiberry-dac","label":"HiFiBerry DAC"}} Jul 02 19:31:20 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 02 19:31:20 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 02 19:31:20 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 19:31:20 volumiodev volumio[1024]: info: Enabling I2S DAC: HiFiBerry DAC Jul 02 19:31:20 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Jul 02 19:31:20 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 19:31:20 volumiodev sudo[2178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jul 02 19:31:20 volumiodev sudo[2178]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 19:31:20 volumiodev sudo[2178]: pam_unix(sudo:session): session closed for user root Jul 02 19:31:20 volumiodev volumio[1024]: info: No Overlays Loaded Jul 02 19:31:20 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 19:31:20 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Jul 02 19:31:20 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 19:31:20 volumiodev sudo[2181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dac Jul 02 19:31:20 volumiodev sudo[2181]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 19:31:20 volumiodev kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Jul 02 19:31:20 volumiodev kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Jul 02 19:31:20 volumiodev kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Jul 02 19:31:20 volumiodev kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Jul 02 19:31:20 volumiodev volumio[1024]: warn: Unable to locate the device Headphones a reboot may be required. Jul 02 19:31:20 volumiodev sudo[2181]: pam_unix(sudo:session): session closed for user root Jul 02 19:31:20 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 02 19:31:20 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Jul 02 19:31:20 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 19:31:20 volumiodev volumio[1024]: Invalid card number. Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 02 19:31:21 volumiodev volumio[1024]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 02 19:31:21 volumiodev volumio[1024]: info: Disabling external Volume Control Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 19:31:21 volumiodev volumio[1024]: info: Enable softmixer device for audio device undefined Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Jul 02 19:31:21 volumiodev volumio[1024]: info: I2S Param [object Object] successfully enabled Jul 02 19:31:21 volumiodev volumio[1024]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 02 19:31:21 volumiodev volumio[1024]: info: Software Volume ALSA configuration written Jul 02 19:31:21 volumiodev volumio[1024]: info: Preparing to generate the ALSA configuration file Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 02 19:31:21 volumiodev volumio[1024]: info: Getting Alsa Cards List without I2S DAC Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 02 19:31:21 volumiodev volumio[1024]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 02 19:31:21 volumiodev volumio[1024]: info: Reading ALSA contributions from plugins. Jul 02 19:31:21 volumiodev volumio[1024]: info: VolumeController:: Volume=undefined Mute =false Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreStateMachine::pushState Jul 02 19:31:21 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:31:21 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 02 19:31:21 volumiodev volumio[1024]: info: Asound.conf file written Jul 02 19:31:21 volumiodev sudo[2217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 02 19:31:21 volumiodev sudo[2217]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 19:31:21 volumiodev sudo[2217]: pam_unix(sudo:session): session closed for user root Jul 02 19:31:22 volumiodev volumio[1024]: info: Output device has changed, restarting MPD Jul 02 19:31:22 volumiodev volumio[1024]: info: Output device has changed, restarting MPD Jul 02 19:31:22 volumiodev sudo[2222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 02 19:31:22 volumiodev sudo[2222]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 19:31:22 volumiodev sudo[2222]: pam_unix(sudo:session): session closed for user root Jul 02 19:31:22 volumiodev sudo[2224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 02 19:31:22 volumiodev sudo[2224]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 19:31:22 volumiodev volumio[1024]: info: MPD Permissions set Jul 02 19:31:22 volumiodev sudo[2227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 02 19:31:22 volumiodev sudo[2227]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 19:31:22 volumiodev sudo[2227]: pam_unix(sudo:session): session closed for user root Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 19:31:22 volumiodev sudo[2230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 19:31:22 volumiodev sudo[2230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 19:31:22 volumiodev systemd[1]: Stopping Music Player Daemon... Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 19:31:22 volumiodev systemd[1]: mpd.service: Succeeded. Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 19:31:22 volumiodev systemd[1]: Stopped Music Player Daemon. Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 19:31:22 volumiodev volumio[1024]: info: MPD Permissions set Jul 02 19:31:22 volumiodev systemd[1]: Starting Music Player Daemon... Jul 02 19:31:22 volumiodev sudo[2237]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 02 19:31:22 volumiodev sudo[2237]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 19:31:22 volumiodev sudo[2237]: pam_unix(sudo:session): session closed for user root Jul 02 19:31:22 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:31:22 volumiodev volumio[1024]: error: MPD error: Error: This socket has been ended by the other party Jul 02 19:31:22 volumiodev volumio[1024]: error: This socket has been ended by the other party {"code":"EPIPE"} Jul 02 19:31:22 volumiodev volumio[1024]: error: MPD error: Error: This socket has been ended by the other party Jul 02 19:31:22 volumiodev volumio[1024]: error: This socket has been ended by the other party {"code":"EPIPE"} Jul 02 19:31:22 volumiodev volumio[1024]: error: MPD error: Error: This socket has been ended by the other party Jul 02 19:31:22 volumiodev volumio[1024]: error: This socket has been ended by the other party {"code":"EPIPE"} Jul 02 19:31:22 volumiodev kernel: hwmon hwmon1: Undervoltage detected! Jul 02 19:31:24 volumiodev mpd[2246]: Jul 02 19:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 02 19:31:24 volumiodev systemd[1]: Started Music Player Daemon. Jul 02 19:31:24 volumiodev sudo[2224]: pam_unix(sudo:session): session closed for user root Jul 02 19:31:24 volumiodev sudo[2230]: pam_unix(sudo:session): session closed for user root Jul 02 19:31:24 volumiodev volumio[1024]: error: MPD error: The expression evaluated to a falsy value: Jul 02 19:31:24 volumiodev volumio[1024]: assert.ok(self.idling) Jul 02 19:31:24 volumiodev volumio[1024]: error: The expression evaluated to a falsy value: Jul 02 19:31:24 volumiodev volumio[1024]: assert.ok(self.idling) Jul 02 19:31:24 volumiodev volumio[1024]: error: updateQueue error: null Jul 02 19:31:25 volumiodev volumio[1024]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 02 19:31:25 volumiodev volumio[1024]: info: CoreStateMachine::getcurrentVolume Jul 02 19:31:25 volumiodev volumio[1024]: info: CoreCommandRouter::volumioRetrievevolume Jul 02 19:31:25 volumiodev volumio[1024]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 02 19:31:25 volumiodev volumio[1024]: info: VolumeController:: Volume=undefined Mute =false Jul 02 19:31:25 volumiodev volumio[1024]: info: CoreStateMachine::pushState Jul 02 19:31:25 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:31:25 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 02 19:31:25 volumiodev volumio[1024]: info: CoreStateMachine::updateTrackBlock Jul 02 19:31:25 volumiodev volumio[1024]: info: CorePlayQueue::getTrackBlock Jul 02 19:31:25 volumiodev volumio[1024]: info: CoreCommandRouter::volumioRetrievevolume Jul 02 19:31:25 volumiodev volumio[1024]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 02 19:31:25 volumiodev volumio[1024]: info: VolumeController:: Volume=undefined Mute =false Jul 02 19:31:25 volumiodev volumio[1024]: info: CoreStateMachine::pushState Jul 02 19:31:25 volumiodev volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 19:31:25 volumiodev volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 02 19:31:26 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:31:30 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:31:30 volumiodev kernel: hwmon hwmon1: Voltage normalised Jul 02 19:31:34 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:31:35 volumiodev kernel: hwmon hwmon1: Undervoltage detected! Jul 02 19:31:38 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:31:41 volumiodev kernel: hwmon hwmon1: Voltage normalised Jul 02 19:31:42 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:31:45 volumiodev sudo[1139]: pam_unix(sudo:session): session closed for user root Jul 02 19:31:45 volumiodev volumio[1024]: info: Cannot mount NAS QNAP1 at system boot, trial number 1 ,retrying in 5 seconds Jul 02 19:31:45 volumiodev kernel: hwmon hwmon1: Undervoltage detected! Jul 02 19:31:46 volumiodev volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 19:31:50 volumiodev volumio[1024]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 19:31:50 volumiodev volumio[1024]: TypeError: Cannot read property 'replace' of undefined Jul 02 19:31:50 volumiodev volumio[1024]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Jul 02 19:31:50 volumiodev volumio[1024]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Jul 02 19:31:50 volumiodev volumio[1024]: at listOnTimeout (internal/timers.js:554:17) Jul 02 19:31:50 volumiodev volumio[1024]: at processTimers (internal/timers.js:497:7) Jul 02 19:31:50 volumiodev volumio[1024]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 19:31:51 volumiodev sudo[2437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-02 19:30 Jul 02 19:31:51 volumiodev sudo[2437]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"