-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Fri 2024-10-04 04:11:30 UTC. -- Oct 04 04:10:09 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Oct 04 04:10:11 volumio volumio[839]: info: Oct 04 04:10:11 volumio volumio[839]: ---------------------------- Client requests Volumio next Oct 04 04:10:11 volumio volumio[839]: info: CoreCommandRouter::volumioNext Oct 04 04:10:11 volumio volumio[839]: info: CoreStateMachine::next Oct 04 04:10:11 volumio volumio[839]: info: [1728015011924] ControllerTidal::next Oct 04 04:10:11 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 04 04:10:11 volumio volumio[839]: info: CoreStateMachine::next Oct 04 04:10:11 volumio volumio[839]: info: CoreStateMachine::stop Oct 04 04:10:11 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 04 04:10:11 volumio volumio[839]: info: CoreStateMachine::stPlaybackTimer Oct 04 04:10:11 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock Oct 04 04:10:11 volumio volumio[839]: info: CorePlayQueue::getTrackBlock Oct 04 04:10:11 volumio volumio[839]: info: CoreStateMachine::pushState Oct 04 04:10:11 volumio volumio[839]: info: CorePlayQueue::getTrack 0 Oct 04 04:10:11 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 04:10:11 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Oct 04 04:10:11 volumio volumio[839]: info: CoreStateMachine::serviceStop Oct 04 04:10:11 volumio volumio[839]: info: CorePlayQueue::getTrack 0 Oct 04 04:10:11 volumio volumio[839]: info: CoreCommandRouter::serviceStop Oct 04 04:10:11 volumio volumio[839]: info: [1728015011929] ControllerTidal::stop Oct 04 04:10:11 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 04 04:10:11 volumio volumio[839]: info: ControllerMpd::stop Oct 04 04:10:11 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop Oct 04 04:10:11 volumio volumio[839]: info: sendMpdCommand stop took 10 milliseconds Oct 04 04:10:11 volumio volumio[839]: info: CoreStateMachine::play index undefined Oct 04 04:10:11 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 04 04:10:11 volumio volumio[839]: info: CorePlayQueue::getTrack 1 Oct 04 04:10:11 volumio volumio[839]: info: CoreStateMachine::startPlaybackTimer Oct 04 04:10:11 volumio volumio[839]: info: CorePlayQueue::getTrack 1 Oct 04 04:10:11 volumio volumio[839]: info: [1728015011941] ControllerTidal::clearAddPlayTrack Oct 04 04:10:11 volumio volumio[839]: info: Getting stream with soundQuality LOSSLESS Oct 04 04:10:11 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop Oct 04 04:10:11 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock Oct 04 04:10:11 volumio volumio[839]: info: CorePlayQueue::getTrackBlock Oct 04 04:10:11 volumio volumio[839]: info: Oct 04 04:10:11 volumio volumio[839]: ---------------------------- MPD announces state update: player Oct 04 04:10:11 volumio volumio[839]: info: ControllerMpd::getState Oct 04 04:10:11 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Oct 04 04:10:11 volumio volumio[839]: info: sendMpdCommand stop took 1 milliseconds Oct 04 04:10:11 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand clear Oct 04 04:10:11 volumio volumio[839]: info: Oct 04 04:10:11 volumio volumio[839]: ---------------------------- MPD announces system playlist update Oct 04 04:10:11 volumio volumio[839]: info: Ignoring MPD Status Update Oct 04 04:10:11 volumio volumio[839]: info: sendMpdCommand status took 2 milliseconds Oct 04 04:10:11 volumio volumio[839]: info: sendMpdCommand clear took 1 milliseconds Oct 04 04:10:11 volumio volumio[839]: verbose: ControllerMpd::parseState Oct 04 04:10:11 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 04:10:11 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/135428&soundQuality=LOSSLESS" Oct 04 04:10:11 volumio volumio[839]: error: updateQueue error: null Oct 04 04:10:11 volumio volumio[839]: info: Oct 04 04:10:11 volumio volumio[839]: ---------------------------- MPD announces system playlist update Oct 04 04:10:11 volumio volumio[839]: info: Ignoring MPD Status Update Oct 04 04:10:11 volumio volumio[839]: info: ------------------------------ 3ms Oct 04 04:10:11 volumio volumio[839]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 04 04:10:11 volumio volumio[839]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/135428&soundQuality=LOSSLESS" took 2 milliseconds Oct 04 04:10:11 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo Oct 04 04:10:11 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 04 04:10:11 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand play Oct 04 04:10:11 volumio volumio[839]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 04 04:10:11 volumio volumio[839]: info: ------------------------------ 5ms Oct 04 04:10:11 volumio volumio[839]: info: ------------------------------ 3ms Oct 04 04:10:11 volumio volumio[839]: info: sendMpdCommand play took 1 milliseconds Oct 04 04:10:11 volumio volumio[839]: STREAMING PROXY: Handling url /?data=tidal://song/135428&soundQuality=LOSSLESS Oct 04 04:10:11 volumio volumio[839]: info: Executing endpoint getStreamUrltidal Oct 04 04:10:11 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Oct 04 04:10:11 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Oct 04 04:10:12 volumio volumio[839]: info: getStreamUrl took 523 milliseconds Oct 04 04:10:12 volumio volumio[839]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic1NzI3NWYyNTQ3NTM3N2FmZmQxNjJhZjBiYTQ3YzIzY182MS5tcDQ/0.flac?Expires=1728018612&Signature=LTo2ef8aJt7xfrKO9xiwm~ad2uoHByrniqRLBt0nFxLyXJHrcK8uv7V~bHdB-uBC71nzVCivu5G7myLy1~jdwGHMXRoQftXompL1QP6FuXCXsgGF-45f7Gqi5lmf5NkqaDvDK0jCzlZwLF7aVbyX6~3DrUy2VFZivqcMpxn9NqPsmsZkSs5v6W~Qw2w26Aygh-cr3Q~KwxeL0ogai8kPYGnA4ERkzzckBuWOfNDrpNuu2x-8E3KCItzGAqmOg3IYhsMGpiQ8XqKVqsTWBFiwICSU~ToLVrqYx~hiokxqygRW9ODfa5Ms0goHfe6cLONLthhtPj9VD5dmXDramd7uyg__&Key-Pair-Id=K14LZCZ9QUI4JL Oct 04 04:10:12 volumio volumio[839]: STREAMING PROXY: Response: 200, length: 21174560 Oct 04 04:10:12 volumio volumio[839]: info: Oct 04 04:10:12 volumio volumio[839]: ---------------------------- MPD announces state update: player Oct 04 04:10:12 volumio volumio[839]: info: ControllerMpd::getState Oct 04 04:10:12 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Oct 04 04:10:12 volumio volumio[839]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 04 04:10:12 volumio volumio[839]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 04 04:10:12 volumio volumio[839]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 04 04:10:12 volumio volumio[839]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 04 04:10:12 volumio volumio[839]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 04 04:10:12 volumio volumio[839]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 04 04:10:12 volumio volumio[839]: info: Oct 04 04:10:12 volumio volumio[839]: ---------------------------- MPD announces state update: player Oct 04 04:10:12 volumio volumio[839]: info: sendMpdCommand status took 23 milliseconds Oct 04 04:10:12 volumio volumio[839]: info: ControllerMpd::getState Oct 04 04:10:12 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Oct 04 04:10:12 volumio volumio[839]: verbose: ControllerMpd::parseState Oct 04 04:10:12 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 04:10:12 volumio volumio[839]: info: sendMpdCommand status took 2 milliseconds Oct 04 04:10:12 volumio volumio[839]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 04 04:10:12 volumio volumio[839]: verbose: ControllerMpd::parseState Oct 04 04:10:12 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 04:10:12 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo Oct 04 04:10:12 volumio volumio[839]: info: ControllerMpd::pushState Oct 04 04:10:12 volumio volumio[839]: info: CoreCommandRouter::servicePushState Oct 04 04:10:12 volumio volumio[839]: info: CorePlayQueue::getTrack 1 Oct 04 04:10:12 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"135428&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/135428&soundQuality=LOSSLESS","trackType":"tidal"} Oct 04 04:10:12 volumio volumio[839]: verbose: CURRENT POSITION 1 Oct 04 04:10:12 volumio volumio[839]: info: CoreStateMachine::syncState stateService play Oct 04 04:10:12 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus stop Oct 04 04:10:12 volumio volumio[839]: info: ------------------------------ 27ms Oct 04 04:10:12 volumio volumio[839]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 04 04:10:12 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo Oct 04 04:10:12 volumio volumio[839]: info: ControllerMpd::pushState Oct 04 04:10:12 volumio volumio[839]: info: CoreCommandRouter::servicePushState Oct 04 04:10:12 volumio volumio[839]: info: CorePlayQueue::getTrack 1 Oct 04 04:10:12 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"540 Kbps","isStreaming":false,"title":"135428&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/135428&soundQuality=LOSSLESS","trackType":"tidal"} Oct 04 04:10:12 volumio volumio[839]: verbose: CURRENT POSITION 1 Oct 04 04:10:12 volumio volumio[839]: info: CoreStateMachine::syncState stateService play Oct 04 04:10:12 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play Oct 04 04:10:12 volumio volumio[839]: info: Received an update from plugin. extracting info from payload Oct 04 04:10:12 volumio volumio[839]: info: CoreStateMachine::pushState Oct 04 04:10:12 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 04:10:12 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Oct 04 04:10:12 volumio volumio[839]: info: CoreStateMachine::pushState Oct 04 04:10:12 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Oct 04 04:10:12 volumio volumio[839]: info: ------------------------------ 28ms Oct 04 04:10:12 volumio volumio[839]: info: FusionDsp - {"Reload":{"result":"Ok"}} Oct 04 04:10:12 volumio volumio[839]: info: FusionDsp - {"Reload":{"result":"Ok"}} Oct 04 04:10:12 volumio volumio[839]: info: camilladsp spawned new process with pid 9291, instance 1, run: true Oct 04 04:10:12 volumio volumio[839]: STREAMING PROXY: Client dropped request, destroying Oct 04 04:10:15 volumio volumio[839]: info: Executing endpoint metavolumio Oct 04 04:10:15 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 04:10:15 volumio volumio[839]: info: Executing endpoint metavolumio Oct 04 04:10:15 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 04:10:15 volumio volumio[839]: info: Executing endpoint metavolumio Oct 04 04:10:15 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 04:10:16 volumio volumio[839]: info: CoreCommandRouter::volumioGetVisibleSources Oct 04 04:10:16 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 04:10:19 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Oct 04 04:10:19 volumio volumio[839]: info: Listing playlists Oct 04 04:10:19 volumio volumio[839]: info: Listing playlists Oct 04 04:10:26 volumio volumio[839]: info: Oct 04 04:10:26 volumio volumio[839]: ---------------------------- Client requests Volumio pause Oct 04 04:10:26 volumio volumio[839]: info: CoreCommandRouter::volumioPause Oct 04 04:10:26 volumio volumio[839]: info: CoreStateMachine::pause Oct 04 04:10:26 volumio volumio[839]: info: CoreStateMachine::stPlaybackTimer Oct 04 04:10:26 volumio volumio[839]: info: CoreStateMachine::servicePause Oct 04 04:10:26 volumio volumio[839]: info: CorePlayQueue::getTrack 1 Oct 04 04:10:26 volumio volumio[839]: info: CoreCommandRouter::servicePause Oct 04 04:10:26 volumio volumio[839]: info: [1728015026775] ControllerTidal::pause Oct 04 04:10:26 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 04 04:10:26 volumio volumio[839]: info: ControllerMpd::pause Oct 04 04:10:26 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand pause Oct 04 04:10:26 volumio volumio[839]: info: sendMpdCommand pause took 1 milliseconds Oct 04 04:10:26 volumio volumio[839]: info: Oct 04 04:10:26 volumio volumio[839]: ---------------------------- MPD announces state update: player Oct 04 04:10:26 volumio volumio[839]: info: ControllerMpd::getState Oct 04 04:10:26 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Oct 04 04:10:26 volumio volumio[839]: info: sendMpdCommand status took 1 milliseconds Oct 04 04:10:26 volumio volumio[839]: verbose: ControllerMpd::parseState Oct 04 04:10:26 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 04:10:26 volumio volumio[839]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 04 04:10:26 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo Oct 04 04:10:26 volumio volumio[839]: info: ControllerMpd::pushState Oct 04 04:10:26 volumio volumio[839]: info: CoreCommandRouter::servicePushState Oct 04 04:10:26 volumio volumio[839]: info: CorePlayQueue::getTrack 1 Oct 04 04:10:26 volumio volumio[839]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15718,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"753 Kbps","isStreaming":false,"title":"135428&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/135428&soundQuality=LOSSLESS","trackType":"tidal"} Oct 04 04:10:26 volumio volumio[839]: verbose: CURRENT POSITION 1 Oct 04 04:10:26 volumio volumio[839]: info: CoreStateMachine::syncState stateService pause Oct 04 04:10:26 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus pause Oct 04 04:10:26 volumio volumio[839]: info: CoreStateMachine::pushState Oct 04 04:10:26 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 04:10:26 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Oct 04 04:10:26 volumio volumio[839]: info: CoreStateMachine::stPlaybackTimer Oct 04 04:10:26 volumio volumio[839]: info: ------------------------------ 15ms Oct 04 04:10:26 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Oct 04 04:10:27 volumio volumio[839]: info: camilladsp spawned new process with pid 9333, instance 1, run: true Oct 04 04:10:29 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Oct 04 04:10:39 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Oct 04 04:10:39 volumio volumio[839]: info: Listing playlists Oct 04 04:10:39 volumio volumio[839]: info: Listing playlists Oct 04 04:10:49 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Oct 04 04:10:59 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Oct 04 04:10:59 volumio volumio[839]: info: Listing playlists Oct 04 04:10:59 volumio volumio[839]: info: Listing playlists Oct 04 04:11:09 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Oct 04 04:11:19 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Oct 04 04:11:19 volumio volumio[839]: info: Listing playlists Oct 04 04:11:19 volumio volumio[839]: info: Listing playlists Oct 04 04:11:25 volumio volumio[839]: info: Clearing queue after UPNP request Oct 04 04:11:25 volumio volumio[839]: info: CoreStateMachine::ClearQueue Oct 04 04:11:25 volumio volumio[839]: info: CoreStateMachine::stop Oct 04 04:11:25 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 04 04:11:25 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock Oct 04 04:11:25 volumio volumio[839]: info: CorePlayQueue::getTrackBlock Oct 04 04:11:25 volumio volumio[839]: info: CoreStateMachine::stPlaybackTimer Oct 04 04:11:25 volumio volumio[839]: info: CoreStateMachine::pushState Oct 04 04:11:25 volumio volumio[839]: info: CorePlayQueue::getTrack 1 Oct 04 04:11:25 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 04:11:25 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Oct 04 04:11:25 volumio volumio[839]: info: CoreStateMachine::serviceStop Oct 04 04:11:25 volumio volumio[839]: info: CorePlayQueue::getTrack 1 Oct 04 04:11:25 volumio volumio[839]: info: CoreCommandRouter::serviceStop Oct 04 04:11:25 volumio volumio[839]: info: [1728015085760] ControllerTidal::stop Oct 04 04:11:25 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 04 04:11:25 volumio volumio[839]: info: ControllerMpd::stop Oct 04 04:11:25 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop Oct 04 04:11:25 volumio volumio[839]: info: CorePlayQueue::clearPlayQueue Oct 04 04:11:25 volumio volumio[839]: info: CorePlayQueue::saveQueue Oct 04 04:11:25 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Oct 04 04:11:25 volumio volumio[839]: info: CoreCommandRouter::volumioPushQueue Oct 04 04:11:25 volumio volumio[839]: info: Oct 04 04:11:25 volumio volumio[839]: ---------------------------- MPD announces state update: player Oct 04 04:11:25 volumio volumio[839]: info: sendMpdCommand stop took 21 milliseconds Oct 04 04:11:25 volumio volumio[839]: info: ControllerMpd::getState Oct 04 04:11:25 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Oct 04 04:11:25 volumio volumio[839]: info: Oct 04 04:11:25 volumio volumio[839]: ---------------------------- MPD announces system playlist update Oct 04 04:11:25 volumio volumio[839]: info: Ignoring MPD Status Update Oct 04 04:11:25 volumio volumio[839]: info: sendMpdCommand status took 1 milliseconds Oct 04 04:11:25 volumio volumio[839]: verbose: ControllerMpd::parseState Oct 04 04:11:25 volumio volumio[839]: info: ControllerMpd::pushState Oct 04 04:11:25 volumio volumio[839]: info: CoreCommandRouter::servicePushState Oct 04 04:11:25 volumio volumio[839]: info: CoreStateMachine::pushState Oct 04 04:11:25 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 04:11:25 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Oct 04 04:11:25 volumio volumio[839]: info: CorePlayQueue::getTrack 1 Oct 04 04:11:25 volumio volumio[839]: 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} Oct 04 04:11:25 volumio volumio[839]: verbose: CURRENT POSITION 1 Oct 04 04:11:25 volumio volumio[839]: info: CoreStateMachine::syncState stateService stop Oct 04 04:11:25 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus stop Oct 04 04:11:25 volumio volumio[839]: info: CoreStateMachine::pushState Oct 04 04:11:25 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Oct 04 04:11:25 volumio volumio[839]: info: No code Oct 04 04:11:25 volumio volumio[839]: info: CoreStateMachine::pushState Oct 04 04:11:25 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Oct 04 04:11:25 volumio volumio[839]: info: ------------------------------ 21ms Oct 04 04:11:25 volumio volumio[839]: error: updateQueue error: null Oct 04 04:11:25 volumio volumio[839]: info: ------------------------------ 21ms Oct 04 04:11:25 volumio volumio[839]: info: Starting UPNP Playback Oct 04 04:11:25 volumio volumio[839]: info: Preparing playback through UPNP Oct 04 04:11:25 volumio volumio[839]: info: Consume mode Oct 04 04:11:25 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Oct 04 04:11:25 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 04 04:11:26 volumio volumio[839]: info: Oct 04 04:11:26 volumio volumio[839]: ---------------------------- MPD announces system playlist update Oct 04 04:11:26 volumio volumio[839]: info: Ignoring MPD Status Update Oct 04 04:11:26 volumio volumio[839]: info: Oct 04 04:11:26 volumio volumio[839]: ---------------------------- MPD announces system playlist update Oct 04 04:11:26 volumio volumio[839]: info: Ignoring MPD Status Update Oct 04 04:11:26 volumio volumio[839]: info: ------------------------------ 1ms Oct 04 04:11:26 volumio volumio[839]: info: ------------------------------ 1ms Oct 04 04:11:26 volumio volumio[839]: info: Oct 04 04:11:26 volumio volumio[839]: ---------------------------- MPD announces system playlist update Oct 04 04:11:26 volumio volumio[839]: info: Ignoring MPD Status Update Oct 04 04:11:26 volumio volumio[839]: info: ------------------------------ 1ms Oct 04 04:11:26 volumio volumio[839]: info: Oct 04 04:11:26 volumio volumio[839]: ---------------------------- MPD announces system playlist update Oct 04 04:11:26 volumio volumio[839]: info: Ignoring MPD Status Update Oct 04 04:11:26 volumio volumio[839]: info: ------------------------------ 1ms Oct 04 04:11:26 volumio volumio[839]: info: Oct 04 04:11:26 volumio volumio[839]: ---------------------------- MPD announces system playlist update Oct 04 04:11:26 volumio volumio[839]: info: Ignoring MPD Status Update Oct 04 04:11:26 volumio volumio[839]: info: Oct 04 04:11:26 volumio volumio[839]: ---------------------------- MPD announces system playlist update Oct 04 04:11:26 volumio volumio[839]: info: Ignoring MPD Status Update Oct 04 04:11:26 volumio volumio[839]: info: ------------------------------ 1ms Oct 04 04:11:26 volumio volumio[839]: info: ------------------------------ 1ms Oct 04 04:11:28 volumio volumio[839]: info: Oct 04 04:11:28 volumio volumio[839]: ---------------------------- MPD announces state update: player Oct 04 04:11:28 volumio volumio[839]: info: ControllerMpd::getState Oct 04 04:11:28 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Oct 04 04:11:28 volumio volumio[839]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Oct 04 04:11:28 volumio volumio[839]: info: FusionDsp - ---- read samplerate from file: 96000 Oct 04 04:11:28 volumio volumio[839]: info: camilladsp stopping service pid 9333... Oct 04 04:11:28 volumio volumio[839]: info: camilladsp service terminated, instance 1 Oct 04 04:11:28 volumio volumio[839]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 04 04:11:28 volumio volumio[839]: info: camilladsp service started and running in background, instance 1 Oct 04 04:11:28 volumio volumio[839]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Oct 04 04:11:28 volumio volumio[839]: info: FusionDsp - ---- read samplerate from file: 96000 Oct 04 04:11:28 volumio volumio[839]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 04 04:11:28 volumio volumio[839]: info: Oct 04 04:11:28 volumio volumio[839]: ---------------------------- MPD announces state update: player Oct 04 04:11:28 volumio volumio[839]: info: sendMpdCommand status took 44 milliseconds Oct 04 04:11:28 volumio volumio[839]: info: ControllerMpd::getState Oct 04 04:11:28 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Oct 04 04:11:28 volumio volumio[839]: verbose: ControllerMpd::parseState Oct 04 04:11:28 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 04:11:28 volumio volumio[839]: info: sendMpdCommand status took 2 milliseconds Oct 04 04:11:28 volumio volumio[839]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 04 04:11:28 volumio volumio[839]: verbose: ControllerMpd::parseState Oct 04 04:11:28 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 04:11:28 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo Oct 04 04:11:28 volumio volumio[839]: info: ControllerMpd::pushState Oct 04 04:11:28 volumio volumio[839]: info: CoreCommandRouter::servicePushState Oct 04 04:11:28 volumio volumio[839]: verbose: In UPNP mode Oct 04 04:11:28 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.100.223:8097/flow/uuid:548a2cb5-2925-37ea-1818-dca632e4a438/995c19c07f4644959157530bf95d5935.flac?ts=1728015085","trackType":""} Oct 04 04:11:28 volumio volumio[839]: verbose: CURRENT POSITION 1 Oct 04 04:11:28 volumio volumio[839]: info: CoreStateMachine::syncState stateService play Oct 04 04:11:28 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus stop Oct 04 04:11:28 volumio volumio[839]: info: ------------------------------ 49ms Oct 04 04:11:28 volumio volumio[839]: error: FusionDsp - WebSocket error: [object Object] Oct 04 04:11:28 volumio volumio[839]: info: camilladsp spawned new process with pid 9398, instance 1, run: true Oct 04 04:11:28 volumio volumio[839]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 04 04:11:28 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo Oct 04 04:11:28 volumio volumio[839]: info: ControllerMpd::pushState Oct 04 04:11:28 volumio volumio[839]: info: CoreCommandRouter::servicePushState Oct 04 04:11:28 volumio volumio[839]: verbose: In UPNP mode Oct 04 04:11:28 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.100.223:8097/flow/uuid:548a2cb5-2925-37ea-1818-dca632e4a438/995c19c07f4644959157530bf95d5935.flac?ts=1728015085","trackType":""} Oct 04 04:11:28 volumio volumio[839]: verbose: CURRENT POSITION 1 Oct 04 04:11:28 volumio volumio[839]: info: CoreStateMachine::syncState stateService play Oct 04 04:11:28 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play Oct 04 04:11:28 volumio volumio[839]: info: Received an update from plugin. extracting info from payload Oct 04 04:11:28 volumio volumio[839]: info: CoreStateMachine::pushState Oct 04 04:11:28 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 04:11:28 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Oct 04 04:11:28 volumio volumio[839]: info: CoreStateMachine::pushState Oct 04 04:11:28 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Oct 04 04:11:28 volumio volumio[839]: info: ------------------------------ 45ms Oct 04 04:11:28 volumio volumio[839]: info: Oct 04 04:11:28 volumio volumio[839]: ---------------------------- MPD announces system playlist update Oct 04 04:11:28 volumio volumio[839]: info: Ignoring MPD Status Update Oct 04 04:11:28 volumio volumio[839]: info: Oct 04 04:11:28 volumio volumio[839]: ---------------------------- MPD announces state update: player Oct 04 04:11:28 volumio volumio[839]: info: ControllerMpd::getState Oct 04 04:11:28 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Oct 04 04:11:28 volumio volumio[839]: info: ------------------------------ 4ms Oct 04 04:11:28 volumio volumio[839]: info: sendMpdCommand status took 3 milliseconds Oct 04 04:11:28 volumio volumio[839]: verbose: ControllerMpd::parseState Oct 04 04:11:28 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 04:11:28 volumio volumio[839]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 04 04:11:28 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo Oct 04 04:11:28 volumio volumio[839]: info: ControllerMpd::pushState Oct 04 04:11:28 volumio volumio[839]: info: CoreCommandRouter::servicePushState Oct 04 04:11:28 volumio volumio[839]: verbose: In UPNP mode Oct 04 04:11:28 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":766,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2434 Kbps","isStreaming":false,"title":"995c19c07f4644959157530bf95d5935.flac?ts=1728015085","artist":"Music Assistant","album":null,"uri":"http://192.168.100.223:8097/flow/uuid:548a2cb5-2925-37ea-1818-dca632e4a438/995c19c07f4644959157530bf95d5935.flac?ts=1728015085","trackType":""} Oct 04 04:11:28 volumio volumio[839]: verbose: CURRENT POSITION 1 Oct 04 04:11:28 volumio volumio[839]: info: CoreStateMachine::syncState stateService play Oct 04 04:11:28 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play Oct 04 04:11:28 volumio volumio[839]: info: Received an update from plugin. extracting info from payload Oct 04 04:11:28 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 04 04:11:28 volumio volumio[839]: info: CoreStateMachine::pushState Oct 04 04:11:28 volumio volumio[839]: info: CorePlayQueue::getTrack 1 Oct 04 04:11:28 volumio volumio[839]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 04 04:11:28 volumio volumio[839]: info: ------------------------------ 8ms Oct 04 04:11:29 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Oct 04 04:11:29 volumio volumio[839]: info: CorePlayQueue::getTrack 1 Oct 04 04:11:29 volumio volumio[839]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Oct 04 04:11:29 volumio volumio[839]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Oct 04 04:11:29 volumio volumio[839]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Oct 04 04:11:29 volumio volumio[839]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Oct 04 04:11:29 volumio volumio[839]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 04 04:11:29 volumio volumio[839]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Oct 04 04:11:29 volumio volumio[839]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Oct 04 04:11:29 volumio volumio[839]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 04 04:11:29 volumio volumio[839]: at /volumio/node_modules/express/lib/router/index.js:281:22 Oct 04 04:11:29 volumio volumio[839]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Oct 04 04:11:29 volumio volumio[839]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Oct 04 04:11:29 volumio volumio[839]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Oct 04 04:11:29 volumio volumio[839]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 04 04:11:29 volumio volumio[839]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Oct 04 04:11:29 volumio volumio[839]: at /volumio/node_modules/express/lib/router/index.js:284:7 Oct 04 04:11:29 volumio volumio[839]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Oct 04 04:11:29 volumio volumio[839]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Oct 04 04:11:30 volumio volumio[839]: info: Starting UPNP Playback Oct 04 04:11:30 volumio volumio[839]: info: Preparing playback through UPNP Oct 04 04:11:30 volumio volumio[839]: info: Consume mode Oct 04 04:11:30 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Oct 04 04:11:30 volumio volumio[839]: info: CorePlayQueue::getTrack 1 Oct 04 04:11:30 volumio volumio[839]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 04:11:30 volumio volumio[839]: TypeError: Cannot read property 'name' of undefined Oct 04 04:11:30 volumio volumio[839]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Oct 04 04:11:30 volumio volumio[839]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Oct 04 04:11:30 volumio volumio[839]: at UpnpInterface.prepareUpnpPlayback (/volumio/app/plugins/audio_interface/upnp/index.js:257:34) Oct 04 04:11:30 volumio volumio[839]: at Socket. (/volumio/app/plugins/audio_interface/upnp/index.js:44:14) Oct 04 04:11:30 volumio volumio[839]: at Socket.emit (events.js:315:20) Oct 04 04:11:30 volumio volumio[839]: at addChunk (internal/streams/readable.js:309:12) Oct 04 04:11:30 volumio volumio[839]: at readableAddChunk (internal/streams/readable.js:280:11) Oct 04 04:11:30 volumio volumio[839]: at Socket.Readable.push (internal/streams/readable.js:223:10) Oct 04 04:11:30 volumio volumio[839]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) Oct 04 04:11:30 volumio volumio[839]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 04:11:30 volumio sudo[9421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-04 04:10 Oct 04 04:11:30 volumio sudo[9421]: 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"