-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Wed 2024-11-27 12:23:43 CET. -- Nov 27 12:22:42 volumio kernel: hwmon hwmon1: Undervoltage detected! Nov 27 12:22:46 volumio kernel: hwmon hwmon1: Voltage normalised Nov 27 12:22:59 volumio volumio[1254]: 19 Nov 27 12:22:59 volumio volumio[1254]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 27 12:22:59 volumio volumio[1254]: Dload Upload Total Spent Left Speed Nov 27 12:22:59 volumio volumio[1051]: info: Nov 27 12:22:59 volumio volumio[1051]: ---------------------------- Client requests Volume 19 Nov 27 12:22:59 volumio volumio[1051]: info: VolumeController::SetAlsaVolume19 Nov 27 12:22:59 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:22:59 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:22:59 volumio volumio[1254]: [158B blob data] Nov 27 12:22:59 volumio volumio[1254]: {"time":1732706579433,"response":"volume Success"}19 Nov 27 12:22:59 volumio volumio[1254]: 16 Nov 27 12:23:00 volumio volumio[1254]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 27 12:23:00 volumio volumio[1254]: Dload Upload Total Spent Left Speed Nov 27 12:23:00 volumio volumio[1051]: info: Nov 27 12:23:00 volumio volumio[1051]: ---------------------------- Client requests Volume 16 Nov 27 12:23:00 volumio volumio[1051]: info: VolumeController::SetAlsaVolume16 Nov 27 12:23:00 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:00 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:00 volumio volumio[1254]: [158B blob data] Nov 27 12:23:00 volumio volumio[1254]: {"time":1732706580081,"response":"volume Success"}16 Nov 27 12:23:01 volumio kernel: hwmon hwmon1: Undervoltage detected! Nov 27 12:23:05 volumio kernel: hwmon hwmon1: Voltage normalised Nov 27 12:23:16 volumio volumio[1051]: info: Clearing queue after UPNP request Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::ClearQueue Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::stop Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::stPlaybackTimer Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::updateTrackBlock Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::getTrackBlock Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::serviceStop Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:16 volumio volumio[1051]: info: ControllerMpd::stop Nov 27 12:23:16 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand stop Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::clearPlayQueue Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::saveQueue Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::volumioPushQueue Nov 27 12:23:16 volumio volumio[1051]: info: sendMpdCommand stop took 9 milliseconds Nov 27 12:23:16 volumio volumio[1051]: info: Nov 27 12:23:16 volumio volumio[1051]: ---------------------------- MPD announces state update: player Nov 27 12:23:16 volumio volumio[1051]: info: ControllerMpd::getState Nov 27 12:23:16 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Nov 27 12:23:16 volumio volumio[1051]: info: Nov 27 12:23:16 volumio volumio[1051]: ---------------------------- MPD announces state update: player Nov 27 12:23:16 volumio volumio[1051]: info: ControllerMpd::getState Nov 27 12:23:16 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Nov 27 12:23:16 volumio volumio[1051]: info: Nov 27 12:23:16 volumio volumio[1051]: ---------------------------- MPD announces state update: player Nov 27 12:23:16 volumio volumio[1051]: info: ControllerMpd::getState Nov 27 12:23:16 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Nov 27 12:23:16 volumio volumio[1051]: info: Nov 27 12:23:16 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:16 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:16 volumio volumio[1051]: info: sendMpdCommand status took 5 milliseconds Nov 27 12:23:16 volumio volumio[1051]: info: sendMpdCommand status took 4 milliseconds Nov 27 12:23:16 volumio volumio[1051]: info: sendMpdCommand status took 3 milliseconds Nov 27 12:23:16 volumio volumio[1051]: verbose: ControllerMpd::parseState Nov 27 12:23:16 volumio volumio[1051]: verbose: ControllerMpd::parseState Nov 27 12:23:16 volumio volumio[1051]: verbose: ControllerMpd::parseState Nov 27 12:23:16 volumio volumio[1051]: info: ControllerMpd::pushState Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:16 volumio volumio[1051]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 27 12:23:16 volumio volumio[1051]: verbose: CURRENT POSITION 0 Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::syncState stateService stop Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus stop Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Nov 27 12:23:16 volumio volumio[1051]: info: No code Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Nov 27 12:23:16 volumio volumio[1051]: info: ControllerMpd::pushState Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:16 volumio volumio[1051]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 27 12:23:16 volumio volumio[1051]: verbose: CURRENT POSITION 0 Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::syncState stateService stop Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus stop Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Nov 27 12:23:16 volumio volumio[1051]: info: No code Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Nov 27 12:23:16 volumio volumio[1051]: info: ControllerMpd::pushState Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:16 volumio volumio[1051]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 27 12:23:16 volumio volumio[1051]: verbose: CURRENT POSITION 0 Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::syncState stateService stop Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus stop Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Nov 27 12:23:16 volumio volumio[1051]: info: No code Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Nov 27 12:23:16 volumio volumio[1051]: info: ------------------------------ 36ms Nov 27 12:23:16 volumio volumio[1051]: info: ------------------------------ 36ms Nov 27 12:23:16 volumio volumio[1051]: info: ------------------------------ 35ms Nov 27 12:23:16 volumio volumio[1051]: info: Nov 27 12:23:16 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:16 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:16 volumio volumio[1051]: info: Nov 27 12:23:16 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:16 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:16 volumio volumio[1051]: error: updateQueue error: null Nov 27 12:23:16 volumio volumio[1051]: error: updateQueue error: null Nov 27 12:23:16 volumio volumio[1051]: error: updateQueue error: null Nov 27 12:23:16 volumio volumio[1051]: info: ------------------------------ 39ms Nov 27 12:23:16 volumio volumio[1051]: info: ------------------------------ 5ms Nov 27 12:23:16 volumio volumio[1051]: info: ------------------------------ 7ms Nov 27 12:23:16 volumio volumio[1051]: info: Starting UPNP Playback Nov 27 12:23:16 volumio volumio[1051]: info: Preparing playback through UPNP Nov 27 12:23:16 volumio volumio[1051]: info: CoreCommandRouter::volumioGetState Nov 27 12:23:16 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:16 volumio volumio[1051]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 3ms Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 2ms Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 2ms Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 4ms Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 3ms Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 2ms Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 3ms Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 3ms Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 2ms Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 4ms Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 3ms Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 2ms Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 4ms Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 3ms Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 2ms Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Nov 27 12:23:17 volumio volumio[1051]: info: Ignoring MPD Status Update Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 4ms Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 3ms Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 2ms Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces state update: player Nov 27 12:23:17 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Nov 27 12:23:17 volumio volumio[1051]: info: ControllerMpd::getState Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces state update: player Nov 27 12:23:17 volumio volumio[1051]: info: ControllerMpd::getState Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces state update: player Nov 27 12:23:17 volumio volumio[1051]: info: ControllerMpd::getState Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Nov 27 12:23:17 volumio volumio[1051]: info: sendMpdCommand status took 12 milliseconds Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::parseState Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces state update: player Nov 27 12:23:17 volumio volumio[1051]: info: ControllerMpd::getState Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Nov 27 12:23:17 volumio volumio[1051]: info: sendMpdCommand status took 14 milliseconds Nov 27 12:23:17 volumio volumio[1051]: info: sendMpdCommand status took 12 milliseconds Nov 27 12:23:17 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::parseState Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::parseState Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Nov 27 12:23:17 volumio volumio[1051]: info: ControllerMpd::pushState Nov 27 12:23:17 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Nov 27 12:23:17 volumio volumio[1051]: verbose: In UPNP mode Nov 27 12:23:17 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HiFy","artist":"HiFy (Performer)","album":"HiFy","uri":"http://192.168.2.14:41013/HiFy.LPCM","trackType":"LPCM"} Nov 27 12:23:17 volumio volumio[1051]: verbose: CURRENT POSITION 0 Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus stop Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 22ms Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces state update: player Nov 27 12:23:17 volumio volumio[1051]: info: ControllerMpd::getState Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Nov 27 12:23:17 volumio volumio[1051]: info: Nov 27 12:23:17 volumio volumio[1051]: ---------------------------- MPD announces state update: player Nov 27 12:23:17 volumio volumio[1051]: info: ControllerMpd::getState Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Nov 27 12:23:17 volumio volumio[1051]: info: sendMpdCommand status took 9 milliseconds Nov 27 12:23:17 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 27 12:23:17 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 27 12:23:17 volumio volumio[1051]: info: sendMpdCommand status took 4 milliseconds Nov 27 12:23:17 volumio volumio[1051]: info: sendMpdCommand status took 3 milliseconds Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::parseState Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::parseState Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::parseState Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 12:23:17 volumio volumio[1051]: info: ControllerMpd::pushState Nov 27 12:23:17 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Nov 27 12:23:17 volumio volumio[1051]: verbose: In UPNP mode Nov 27 12:23:17 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HiFy","artist":"HiFy (Performer)","album":"HiFy","uri":"http://192.168.2.14:41013/HiFy.LPCM","trackType":"LPCM"} Nov 27 12:23:17 volumio volumio[1051]: verbose: CURRENT POSITION 0 Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Nov 27 12:23:17 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Nov 27 12:23:17 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:17 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:17 volumio volumio[1051]: info: ControllerMpd::pushState Nov 27 12:23:17 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Nov 27 12:23:17 volumio volumio[1051]: verbose: In UPNP mode Nov 27 12:23:17 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HiFy","artist":"HiFy (Performer)","album":"HiFy","uri":"http://192.168.2.14:41013/HiFy.LPCM","trackType":"LPCM"} Nov 27 12:23:17 volumio volumio[1051]: verbose: CURRENT POSITION 0 Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Nov 27 12:23:17 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Nov 27 12:23:17 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:17 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:17 volumio volumio[1051]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 27 12:23:17 volumio volumio[1051]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 36ms Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 36ms Nov 27 12:23:17 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 27 12:23:17 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 27 12:23:17 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Nov 27 12:23:17 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Nov 27 12:23:17 volumio volumio[1051]: info: ControllerMpd::pushState Nov 27 12:23:17 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Nov 27 12:23:17 volumio volumio[1051]: verbose: In UPNP mode Nov 27 12:23:17 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HiFy","artist":"HiFy (Performer)","album":"HiFy","uri":"http://192.168.2.14:41013/HiFy.LPCM","trackType":"LPCM"} Nov 27 12:23:17 volumio volumio[1051]: verbose: CURRENT POSITION 0 Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Nov 27 12:23:17 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Nov 27 12:23:17 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:17 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:17 volumio volumio[1051]: info: ControllerMpd::pushState Nov 27 12:23:17 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Nov 27 12:23:17 volumio volumio[1051]: verbose: In UPNP mode Nov 27 12:23:17 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HiFy","artist":"HiFy (Performer)","album":"HiFy","uri":"http://192.168.2.14:41013/HiFy.LPCM","trackType":"LPCM"} Nov 27 12:23:17 volumio volumio[1051]: verbose: CURRENT POSITION 0 Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Nov 27 12:23:17 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Nov 27 12:23:17 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:17 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:17 volumio volumio[1051]: info: ControllerMpd::pushState Nov 27 12:23:17 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Nov 27 12:23:17 volumio volumio[1051]: verbose: In UPNP mode Nov 27 12:23:17 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HiFy","artist":"HiFy (Performer)","album":"HiFy","uri":"http://192.168.2.14:41013/HiFy.LPCM","trackType":"LPCM"} Nov 27 12:23:17 volumio volumio[1051]: verbose: CURRENT POSITION 0 Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Nov 27 12:23:17 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Nov 27 12:23:17 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 27 12:23:17 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:17 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:17 volumio volumio[1051]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 27 12:23:17 volumio volumio[1051]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 27 12:23:17 volumio volumio[1051]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 42ms Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 36ms Nov 27 12:23:17 volumio volumio[1051]: info: ------------------------------ 35ms Nov 27 12:23:18 volumio kernel: hwmon hwmon1: Undervoltage detected! Nov 27 12:23:20 volumio volumio[1254]: 19 Nov 27 12:23:20 volumio volumio[1254]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 27 12:23:20 volumio volumio[1254]: Dload Upload Total Spent Left Speed Nov 27 12:23:20 volumio volumio[1051]: info: Nov 27 12:23:20 volumio volumio[1051]: ---------------------------- Client requests Volume 19 Nov 27 12:23:20 volumio volumio[1051]: info: VolumeController::SetAlsaVolume19 Nov 27 12:23:20 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:20 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:20 volumio volumio[1254]: [158B blob data] Nov 27 12:23:20 volumio volumio[1254]: {"time":1732706600359,"response":"volume Success"}19 Nov 27 12:23:24 volumio kernel: hwmon hwmon1: Voltage normalised Nov 27 12:23:26 volumio volumio[1051]: info: Nov 27 12:23:26 volumio volumio[1051]: ---------------------------- MPD announces state update: player Nov 27 12:23:26 volumio volumio[1051]: info: ControllerMpd::getState Nov 27 12:23:26 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Nov 27 12:23:26 volumio volumio[1051]: info: Nov 27 12:23:26 volumio volumio[1051]: ---------------------------- MPD announces state update: player Nov 27 12:23:26 volumio volumio[1051]: info: ControllerMpd::getState Nov 27 12:23:26 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Nov 27 12:23:26 volumio volumio[1051]: info: Nov 27 12:23:26 volumio volumio[1051]: ---------------------------- MPD announces state update: player Nov 27 12:23:26 volumio volumio[1051]: info: ControllerMpd::getState Nov 27 12:23:26 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Nov 27 12:23:26 volumio volumio[1051]: info: sendMpdCommand status took 6 milliseconds Nov 27 12:23:26 volumio volumio[1051]: info: sendMpdCommand status took 5 milliseconds Nov 27 12:23:26 volumio volumio[1051]: info: sendMpdCommand status took 2 milliseconds Nov 27 12:23:26 volumio volumio[1051]: verbose: ControllerMpd::parseState Nov 27 12:23:26 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 12:23:26 volumio volumio[1051]: verbose: ControllerMpd::parseState Nov 27 12:23:26 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 12:23:26 volumio volumio[1051]: verbose: ControllerMpd::parseState Nov 27 12:23:26 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 12:23:26 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 27 12:23:26 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 27 12:23:26 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 27 12:23:26 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Nov 27 12:23:26 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Nov 27 12:23:26 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Nov 27 12:23:26 volumio volumio[1051]: info: ControllerMpd::pushState Nov 27 12:23:26 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Nov 27 12:23:26 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:26 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:26 volumio volumio[1051]: info: ControllerMpd::pushState Nov 27 12:23:26 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Nov 27 12:23:26 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:26 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:26 volumio volumio[1051]: info: ControllerMpd::pushState Nov 27 12:23:26 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Nov 27 12:23:26 volumio volumio[1051]: info: CoreStateMachine::pushState Nov 27 12:23:26 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:26 volumio volumio[1051]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 27 12:23:26 volumio volumio[1051]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 27 12:23:26 volumio volumio[1051]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 27 12:23:26 volumio volumio[1051]: info: ------------------------------ 28ms Nov 27 12:23:26 volumio volumio[1051]: info: ------------------------------ 25ms Nov 27 12:23:26 volumio volumio[1051]: info: ------------------------------ 23ms Nov 27 12:23:42 volumio volumio[1051]: info: Airplay started streaming, receiving metadatas Nov 27 12:23:42 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Nov 27 12:23:42 volumio volumio[1051]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 12:23:42 volumio volumio[1051]: TypeError: Cannot read property 'name' of undefined Nov 27 12:23:42 volumio volumio[1051]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 27 12:23:42 volumio volumio[1051]: at AirPlayInterface.prepareAirplayPlayback (/volumio/app/plugins/music_service/airplay_emulation/index.js:538:47) Nov 27 12:23:42 volumio volumio[1051]: at ShairportSyncReaderUDP. (/volumio/app/plugins/music_service/airplay_emulation/index.js:257:12) Nov 27 12:23:42 volumio volumio[1051]: at ShairportSyncReaderUDP.emit (events.js:327:22) Nov 27 12:23:42 volumio volumio[1051]: at ShairportSyncReaderUDP.useData (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-base.js:86:16) Nov 27 12:23:42 volumio volumio[1051]: at Socket. (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-udp.js:21:12) Nov 27 12:23:42 volumio volumio[1051]: at Socket.emit (events.js:315:20) Nov 27 12:23:42 volumio volumio[1051]: at UDP.onMessage [as onmessage] (dgram.js:919:8) Nov 27 12:23:42 volumio volumio[1051]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 12:23:42 volumio shairport-sync[1242]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 27 12:23:42 volumio shairport-sync[1242]: Dload Upload Total Spent Left Speed Nov 27 12:23:43 volumio kernel: hwmon hwmon1: Undervoltage detected! Nov 27 12:23:43 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Nov 27 12:23:43 volumio sudo[12027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 12:22 Nov 27 12:23:43 volumio sudo[12027]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"