-- Logs begin at Sun 2025-12-07 19:18:13 EET, end at Tue 2025-12-23 17:07:24 EET. -- Dec 23 17:06:32 volumio kernel: usb 1-1: reset high-speed USB device number 2 using dwc2 Dec 23 17:06:33 volumio kernel: usb 1-1: USB disconnect, device number 2 Dec 23 17:06:33 volumio kernel: usb 1-1: new high-speed USB device number 3 using dwc2 Dec 23 17:06:33 volumio kernel: usb 1-1: New USB device found, idVendor=05e3, idProduct=0610 Dec 23 17:06:33 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Dec 23 17:06:33 volumio kernel: usb 1-1: Product: USB2.0 Hub Dec 23 17:06:33 volumio kernel: hub 1-1:1.0: USB hub found Dec 23 17:06:33 volumio kernel: hub 1-1:1.0: 4 ports detected Dec 23 17:06:34 volumio kernel: usb 1-1: reset high-speed USB device number 3 using dwc2 Dec 23 17:06:35 volumio kernel: usb 1-1: USB disconnect, device number 3 Dec 23 17:06:35 volumio kernel: usb 1-1: new high-speed USB device number 4 using dwc2 Dec 23 17:06:35 volumio kernel: usb 1-1: New USB device found, idVendor=05e3, idProduct=0610 Dec 23 17:06:35 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Dec 23 17:06:35 volumio kernel: usb 1-1: Product: USB2.0 Hub Dec 23 17:06:35 volumio kernel: hub 1-1:1.0: USB hub found Dec 23 17:06:35 volumio kernel: hub 1-1:1.0: 4 ports detected Dec 23 17:06:53 volumio volumio[1153]: verbose: New Socket.io Connection to 192.168.1.134:3000 from 192.168.1.220 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 23 17:06:53 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 17:06:53 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 17:06:53 volumio volumio[1153]: info: Discovery: Getting this device information Dec 23 17:06:53 volumio volumio[1153]: info: CoreCommandRouter::volumioGetState Dec 23 17:06:53 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 17:06:54 volumio volumio[1153]: verbose: New Socket.io Connection to 192.168.1.134 from 192.168.1.220 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Dec 23 17:06:54 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 23 17:06:54 volumio volumio[1153]: info: CoreCommandRouter::volumioGetVisibleSources Dec 23 17:06:54 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 17:06:54 volumio volumio[1153]: info: CoreCommandRouter::volumioGetState Dec 23 17:06:54 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 23 17:06:54 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 23 17:06:54 volumio volumio[1153]: info: Received Get System Info Dec 23 17:06:54 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 17:06:54 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 17:06:54 volumio volumio[1153]: info: Discovery: Getting this device information Dec 23 17:06:54 volumio volumio[1153]: info: CoreCommandRouter::volumioGetState Dec 23 17:06:54 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 17:06:54 volumio volumio[1153]: info: CoreCommandRouter::volumioGetState Dec 23 17:06:54 volumio volumio[1153]: info: Listing playlists Dec 23 17:06:54 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 23 17:06:57 volumio volumio[1153]: info: CoreCommandRouter::volumioNext Dec 23 17:06:57 volumio volumio[1153]: info: CoreStateMachine::next Dec 23 17:06:57 volumio volumio[1153]: info: [1766502417884] ControllerTidal::next Dec 23 17:06:57 volumio volumio[1153]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 17:06:57 volumio volumio[1153]: info: CoreStateMachine::next Dec 23 17:06:57 volumio volumio[1153]: info: CoreStateMachine::stop Dec 23 17:06:57 volumio volumio[1153]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 17:06:57 volumio volumio[1153]: info: CoreStateMachine::stPlaybackTimer Dec 23 17:06:57 volumio volumio[1153]: info: CoreStateMachine::updateTrackBlock Dec 23 17:06:57 volumio volumio[1153]: info: CorePlayQueue::getTrackBlock Dec 23 17:06:57 volumio volumio[1153]: info: CoreStateMachine::pushState Dec 23 17:06:57 volumio volumio[1153]: info: CorePlayQueue::getTrack 5 Dec 23 17:06:57 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 17:06:57 volumio volumio[1153]: info: CoreCommandRouter::volumioPushState Dec 23 17:06:57 volumio volumio[1153]: info: CoreStateMachine::serviceStop Dec 23 17:06:57 volumio volumio[1153]: info: CorePlayQueue::getTrack 5 Dec 23 17:06:57 volumio volumio[1153]: info: CoreCommandRouter::serviceStop Dec 23 17:06:57 volumio volumio[1153]: info: [1766502417895] ControllerTidal::stop Dec 23 17:06:57 volumio volumio[1153]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 17:06:57 volumio volumio[1153]: info: ControllerMpd::stop Dec 23 17:06:57 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 17:06:57 volumio volumio[1153]: info: Dec 23 17:06:57 volumio volumio[1153]: ---------------------------- MPD announces state update: player Dec 23 17:06:57 volumio volumio[1153]: info: sendMpdCommand stop took 37 milliseconds Dec 23 17:06:57 volumio volumio[1153]: info: ControllerMpd::getState Dec 23 17:06:57 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand status Dec 23 17:06:57 volumio volumio[1153]: info: CoreStateMachine::play index undefined Dec 23 17:06:57 volumio volumio[1153]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 17:06:57 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 Dec 23 17:06:57 volumio volumio[1153]: info: CoreStateMachine::startPlaybackTimer Dec 23 17:06:57 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 Dec 23 17:06:57 volumio volumio[1153]: info: [1766502417937] ControllerTidal::clearAddPlayTrack Dec 23 17:06:57 volumio volumio[1153]: info: Getting stream with soundQuality HI_RES Dec 23 17:06:57 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 17:06:57 volumio volumio[1153]: info: CoreStateMachine::updateTrackBlock Dec 23 17:06:57 volumio volumio[1153]: info: CorePlayQueue::getTrackBlock Dec 23 17:06:57 volumio volumio[1153]: info: Dec 23 17:06:57 volumio volumio[1153]: ---------------------------- MPD announces state update: player Dec 23 17:06:57 volumio volumio[1153]: info: ControllerMpd::getState Dec 23 17:06:57 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand status Dec 23 17:06:57 volumio volumio[1153]: info: sendMpdCommand status took 11 milliseconds Dec 23 17:06:57 volumio volumio[1153]: info: sendMpdCommand stop took 8 milliseconds Dec 23 17:06:57 volumio volumio[1153]: info: sendMpdCommand status took 2 milliseconds Dec 23 17:06:57 volumio volumio[1153]: verbose: ControllerMpd::parseState Dec 23 17:06:57 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 17:06:57 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand clear Dec 23 17:06:57 volumio volumio[1153]: verbose: ControllerMpd::parseState Dec 23 17:06:57 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 17:06:57 volumio volumio[1153]: info: Dec 23 17:06:57 volumio volumio[1153]: ---------------------------- MPD announces system playlist update Dec 23 17:06:57 volumio volumio[1153]: info: Ignoring MPD Status Update Dec 23 17:06:57 volumio volumio[1153]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 23 17:06:57 volumio volumio[1153]: info: sendMpdCommand clear took 6 milliseconds Dec 23 17:06:57 volumio volumio[1153]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 23 17:06:57 volumio volumio[1153]: verbose: ControllerMpd::parseTrackInfo Dec 23 17:06:57 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/16818607&soundQuality=HI_RES" Dec 23 17:06:57 volumio volumio[1153]: verbose: ControllerMpd::parseTrackInfo Dec 23 17:06:57 volumio volumio[1153]: info: ControllerMpd::pushState Dec 23 17:06:57 volumio volumio[1153]: info: CoreCommandRouter::servicePushState Dec 23 17:06:57 volumio volumio[1153]: info: CoreStateMachine::pushState Dec 23 17:06:57 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 Dec 23 17:06:57 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 17:06:57 volumio volumio[1153]: info: CoreCommandRouter::volumioPushState Dec 23 17:06:57 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 Dec 23 17:06:57 volumio volumio[1153]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Dec 23 17:06:57 volumio volumio[1153]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 23 17:06:57 volumio volumio[1153]: info: ------------------------------ 59ms Dec 23 17:06:57 volumio volumio[1153]: info: ------------------------------ 51ms Dec 23 17:06:57 volumio volumio[1153]: info: Dec 23 17:06:57 volumio volumio[1153]: ---------------------------- MPD announces system playlist update Dec 23 17:06:57 volumio volumio[1153]: info: Ignoring MPD Status Update Dec 23 17:06:57 volumio volumio[1153]: error: updateQueue error: null Dec 23 17:06:57 volumio volumio[1153]: info: Dec 23 17:06:57 volumio volumio[1153]: ---------------------------- MPD announces system playlist update Dec 23 17:06:58 volumio volumio[1153]: info: Ignoring MPD Status Update Dec 23 17:06:58 volumio volumio[1153]: info: ------------------------------ 50ms Dec 23 17:06:58 volumio volumio[1153]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/16818607&soundQuality=HI_RES" took 46 milliseconds Dec 23 17:06:58 volumio volumio[1153]: info: ------------------------------ 11ms Dec 23 17:06:58 volumio volumio[1153]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand play Dec 23 17:06:58 volumio volumio[1153]: info: Dec 23 17:06:58 volumio volumio[1153]: ---------------------------- MPD announces system playlist update Dec 23 17:06:58 volumio volumio[1153]: STREAMING PROXY: Handling url /?data=tidal://song/16818607&soundQuality=HI_RES Dec 23 17:06:58 volumio volumio[1153]: info: Ignoring MPD Status Update Dec 23 17:06:58 volumio volumio[1153]: info: ------------------------------ 41ms Dec 23 17:06:58 volumio volumio[1153]: info: sendMpdCommand play took 37 milliseconds Dec 23 17:06:58 volumio volumio[1153]: info: ------------------------------ 38ms Dec 23 17:06:58 volumio volumio[1153]: info: Executing endpoint getStreamUrltidal Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Dec 23 17:06:58 volumio volumio[1153]: info: getStreamUrl took 250 milliseconds Dec 23 17:06:58 volumio volumio[1153]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3ZGNmMTc1YmU0MWI5NGFhODNmZTNhYjAzMzQ2OGJjMF82MS5tcDQ/0.flac?token=1766506018~MzY2Yjk0ZDllZWU2ZDFjZTFiNmE0ZWEzZmNlMDIwZWFjNjk4ODAzNA== Dec 23 17:06:58 volumio volumio[1153]: STREAMING PROXY: Response: 200, length: 34096339 Dec 23 17:06:58 volumio volumio[1153]: info: Dec 23 17:06:58 volumio volumio[1153]: ---------------------------- MPD announces state update: player Dec 23 17:06:58 volumio volumio[1153]: info: ControllerMpd::getState Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand status Dec 23 17:06:58 volumio volumio[1153]: info: Dec 23 17:06:58 volumio volumio[1153]: ---------------------------- MPD announces state update: player Dec 23 17:06:58 volumio volumio[1153]: info: ControllerMpd::getState Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand status Dec 23 17:06:58 volumio volumio[1153]: info: sendMpdCommand status took 6 milliseconds Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::parseState Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 17:06:58 volumio volumio[1153]: info: Dec 23 17:06:58 volumio volumio[1153]: ---------------------------- MPD announces state update: player Dec 23 17:06:58 volumio volumio[1153]: info: ControllerMpd::getState Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand status Dec 23 17:06:58 volumio volumio[1153]: info: sendMpdCommand status took 8 milliseconds Dec 23 17:06:58 volumio volumio[1153]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::parseState Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::parseTrackInfo Dec 23 17:06:58 volumio volumio[1153]: info: ControllerMpd::pushState Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::servicePushState Dec 23 17:06:58 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 Dec 23 17:06:58 volumio volumio[1153]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"16818607&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/16818607&soundQuality=HI_RES","trackType":"tidal"} Dec 23 17:06:58 volumio volumio[1153]: verbose: CURRENT POSITION 6 Dec 23 17:06:58 volumio volumio[1153]: info: CoreStateMachine::syncState stateService play Dec 23 17:06:58 volumio volumio[1153]: info: CoreStateMachine::syncState currentStatus stop Dec 23 17:06:58 volumio volumio[1153]: info: ------------------------------ 23ms Dec 23 17:06:58 volumio volumio[1153]: info: Dec 23 17:06:58 volumio volumio[1153]: ---------------------------- MPD announces state update: player Dec 23 17:06:58 volumio volumio[1153]: info: ControllerMpd::getState Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand status Dec 23 17:06:58 volumio volumio[1153]: info: sendMpdCommand status took 15 milliseconds Dec 23 17:06:58 volumio volumio[1153]: info: sendMpdCommand playlistinfo took 13 milliseconds Dec 23 17:06:58 volumio volumio[1153]: info: sendMpdCommand status took 3 milliseconds Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::parseState Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::parseTrackInfo Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::parseState Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 17:06:58 volumio volumio[1153]: info: ControllerMpd::pushState Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::servicePushState Dec 23 17:06:58 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 Dec 23 17:06:58 volumio volumio[1153]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"16818607&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/16818607&soundQuality=HI_RES","trackType":"tidal"} Dec 23 17:06:58 volumio volumio[1153]: verbose: CURRENT POSITION 6 Dec 23 17:06:58 volumio volumio[1153]: info: CoreStateMachine::syncState stateService play Dec 23 17:06:58 volumio volumio[1153]: info: CoreStateMachine::syncState currentStatus play Dec 23 17:06:58 volumio volumio[1153]: info: Received an update from plugin. extracting info from payload Dec 23 17:06:58 volumio volumio[1153]: info: CoreStateMachine::pushState Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::volumioPushState Dec 23 17:06:58 volumio volumio[1153]: info: CoreStateMachine::pushState Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::volumioPushState Dec 23 17:06:58 volumio volumio[1153]: info: ------------------------------ 83ms Dec 23 17:06:58 volumio volumio[1153]: info: sendMpdCommand playlistinfo took 58 milliseconds Dec 23 17:06:58 volumio volumio[1153]: info: sendMpdCommand playlistinfo took 56 milliseconds Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::parseTrackInfo Dec 23 17:06:58 volumio volumio[1153]: verbose: ControllerMpd::parseTrackInfo Dec 23 17:06:58 volumio volumio[1153]: info: ControllerMpd::pushState Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::servicePushState Dec 23 17:06:58 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 Dec 23 17:06:58 volumio volumio[1153]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"16818607&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/16818607&soundQuality=HI_RES","trackType":"tidal"} Dec 23 17:06:58 volumio volumio[1153]: verbose: CURRENT POSITION 6 Dec 23 17:06:58 volumio volumio[1153]: info: CoreStateMachine::syncState stateService play Dec 23 17:06:58 volumio volumio[1153]: info: CoreStateMachine::syncState currentStatus play Dec 23 17:06:58 volumio volumio[1153]: info: Received an update from plugin. extracting info from payload Dec 23 17:06:58 volumio volumio[1153]: info: CoreStateMachine::pushState Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::volumioPushState Dec 23 17:06:58 volumio volumio[1153]: info: CoreStateMachine::pushState Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::volumioPushState Dec 23 17:06:58 volumio volumio[1153]: info: ControllerMpd::pushState Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::servicePushState Dec 23 17:06:58 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 Dec 23 17:06:58 volumio volumio[1153]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"16818607&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/16818607&soundQuality=HI_RES","trackType":"tidal"} Dec 23 17:06:58 volumio volumio[1153]: verbose: CURRENT POSITION 6 Dec 23 17:06:58 volumio volumio[1153]: info: CoreStateMachine::syncState stateService play Dec 23 17:06:58 volumio volumio[1153]: info: CoreStateMachine::syncState currentStatus play Dec 23 17:06:58 volumio volumio[1153]: info: Received an update from plugin. extracting info from payload Dec 23 17:06:58 volumio volumio[1153]: info: CoreStateMachine::pushState Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::volumioPushState Dec 23 17:06:58 volumio volumio[1153]: info: CoreStateMachine::pushState Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::volumioPushState Dec 23 17:06:58 volumio volumio[1153]: info: ------------------------------ 189ms Dec 23 17:06:58 volumio volumio[1153]: info: ------------------------------ 180ms Dec 23 17:06:58 volumio volumio[1153]: verbose: New Socket.io Connection to 192.168.1.134:3000 from 192.168.1.220 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 17:06:58 volumio volumio[1153]: info: Discovery: Getting this device information Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::volumioGetState Dec 23 17:06:58 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 17:07:01 volumio volumio[1153]: STREAMING PROXY: Client dropped request, destroying Dec 23 17:07:21 volumio volumio[1153]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 17:07:21 volumio volumio[1153]: No protocol specified Dec 23 17:07:21 volumio volumio[1153]: xcb_connection_has_error() returned true Dec 23 17:07:21 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 23 17:07:21 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 17:07:21 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 17:07:21 volumio volumio[1153]: Invalid card number. Dec 23 17:07:21 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:07:21 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:07:21 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:07:21 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:07:21 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:07:21 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:07:21 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:07:21 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 23 17:07:21 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 23 17:07:23 volumio volumio[1153]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 23 17:07:23 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 23 17:07:23 volumio volumio[1153]: info: Preparing to save Alsa Options, stopping services first Dec 23 17:07:23 volumio volumio[1153]: info: CoreCommandRouter::volumioGetState Dec 23 17:07:23 volumio volumio[1153]: info: CoreCommandRouter::volumioPause Dec 23 17:07:23 volumio volumio[1153]: info: CoreStateMachine::pause Dec 23 17:07:23 volumio volumio[1153]: info: CoreStateMachine::stPlaybackTimer Dec 23 17:07:23 volumio volumio[1153]: info: CoreStateMachine::servicePause Dec 23 17:07:23 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 Dec 23 17:07:23 volumio volumio[1153]: info: CoreCommandRouter::servicePause Dec 23 17:07:23 volumio volumio[1153]: info: [1766502443851] ControllerTidal::pause Dec 23 17:07:23 volumio volumio[1153]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 17:07:23 volumio volumio[1153]: info: ControllerMpd::pause Dec 23 17:07:23 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand pause Dec 23 17:07:23 volumio volumio[1153]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"D30"}} Dec 23 17:07:23 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 17:07:23 volumio volumio[1153]: No protocol specified Dec 23 17:07:23 volumio volumio[1153]: xcb_connection_has_error() returned true Dec 23 17:07:23 volumio volumio[1153]: warn: Unable to locate the device D30 a reboot may be required. Dec 23 17:07:23 volumio volumio[1153]: No protocol specified Dec 23 17:07:23 volumio volumio[1153]: xcb_connection_has_error() returned true Dec 23 17:07:23 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 17:07:23 volumio volumio[1153]: Invalid card number. Dec 23 17:07:23 volumio volumio[1153]: No protocol specified Dec 23 17:07:23 volumio volumio[1153]: xcb_connection_has_error() returned true Dec 23 17:07:23 volumio volumio[1153]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 23 17:07:23 volumio volumio[1153]: info: Updating Volume Controller Parameters: Device: 5 Name: D30 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 23 17:07:23 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 23 17:07:23 volumio volumio[1153]: info: Disabling external Volume Control Dec 23 17:07:23 volumio volumio[1153]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 17:07:23 volumio volumio[1153]: info: Preparing to generate the ALSA configuration file Dec 23 17:07:23 volumio volumio[1153]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 23 17:07:23 volumio volumio[1153]: info: Reading ALSA contributions from plugins. Dec 23 17:07:23 volumio volumio[1153]: info: Dec 23 17:07:23 volumio volumio[1153]: ---------------------------- MPD announces state update: player Dec 23 17:07:23 volumio volumio[1153]: info: sendMpdCommand pause took 138 milliseconds Dec 23 17:07:23 volumio volumio[1153]: info: ControllerMpd::getState Dec 23 17:07:23 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand status Dec 23 17:07:23 volumio volumio[1153]: info: Dec 23 17:07:23 volumio volumio[1153]: ---------------------------- MPD announces state update: player Dec 23 17:07:23 volumio volumio[1153]: info: ControllerMpd::getState Dec 23 17:07:23 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand status Dec 23 17:07:23 volumio volumio[1153]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 23 17:07:24 volumio volumio[1153]: info: sendMpdCommand status took 13 milliseconds Dec 23 17:07:24 volumio volumio[1153]: info: sendMpdCommand status took 13 milliseconds Dec 23 17:07:24 volumio volumio[1153]: verbose: ControllerMpd::parseState Dec 23 17:07:24 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 17:07:24 volumio volumio[1153]: verbose: ControllerMpd::parseState Dec 23 17:07:24 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 17:07:24 volumio volumio[1153]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 23 17:07:24 volumio volumio[1153]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 23 17:07:24 volumio volumio[1153]: verbose: ControllerMpd::parseTrackInfo Dec 23 17:07:24 volumio volumio[1153]: verbose: ControllerMpd::parseTrackInfo Dec 23 17:07:24 volumio volumio[1153]: info: ControllerMpd::pushState Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::servicePushState Dec 23 17:07:24 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 Dec 23 17:07:24 volumio volumio[1153]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26243,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"873 Kbps","isStreaming":false,"title":"16818607&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/16818607&soundQuality=HI_RES","trackType":"tidal"} Dec 23 17:07:24 volumio volumio[1153]: verbose: CURRENT POSITION 6 Dec 23 17:07:24 volumio volumio[1153]: info: CoreStateMachine::syncState stateService pause Dec 23 17:07:24 volumio volumio[1153]: info: CoreStateMachine::syncState currentStatus pause Dec 23 17:07:24 volumio volumio[1153]: info: CoreStateMachine::pushState Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::volumioPushState Dec 23 17:07:24 volumio volumio[1153]: info: CoreStateMachine::stPlaybackTimer Dec 23 17:07:24 volumio volumio[1153]: info: ControllerMpd::pushState Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::servicePushState Dec 23 17:07:24 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 Dec 23 17:07:24 volumio volumio[1153]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26243,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"873 Kbps","isStreaming":false,"title":"16818607&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/16818607&soundQuality=HI_RES","trackType":"tidal"} Dec 23 17:07:24 volumio volumio[1153]: verbose: CURRENT POSITION 6 Dec 23 17:07:24 volumio volumio[1153]: info: CoreStateMachine::syncState stateService pause Dec 23 17:07:24 volumio volumio[1153]: info: CoreStateMachine::syncState currentStatus pause Dec 23 17:07:24 volumio volumio[1153]: info: CoreStateMachine::pushState Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::volumioPushState Dec 23 17:07:24 volumio volumio[1153]: info: CoreStateMachine::stPlaybackTimer Dec 23 17:07:24 volumio volumio[1153]: info: ------------------------------ 54ms Dec 23 17:07:24 volumio volumio[1153]: info: ------------------------------ 52ms Dec 23 17:07:24 volumio volumio[1153]: info: VolumeController:: Volume=undefined Mute =false Dec 23 17:07:24 volumio volumio[1153]: info: CoreStateMachine::pushState Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::volumioPushState Dec 23 17:07:24 volumio volumio[1153]: No protocol specified Dec 23 17:07:24 volumio volumio[1153]: xcb_connection_has_error() returned true Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 17:07:24 volumio volumio[1153]: Invalid card number. Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 23 17:07:24 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 23 17:07:24 volumio volumio[1153]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 17:07:24 volumio volumio[1153]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Dec 23 17:07:24 volumio volumio[1153]: at Object.readdirSync (fs.js:1048:3) Dec 23 17:07:24 volumio volumio[1153]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Dec 23 17:07:24 volumio volumio[1153]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Dec 23 17:07:24 volumio volumio[1153]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Dec 23 17:07:24 volumio volumio[1153]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Dec 23 17:07:24 volumio volumio[1153]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Dec 23 17:07:24 volumio volumio[1153]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 23 17:07:24 volumio volumio[1153]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Dec 23 17:07:24 volumio volumio[1153]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Dec 23 17:07:24 volumio volumio[1153]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Dec 23 17:07:24 volumio volumio[1153]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 23 17:07:24 volumio volumio[1153]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Dec 23 17:07:24 volumio volumio[1153]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Dec 23 17:07:24 volumio volumio[1153]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 23 17:07:24 volumio volumio[1153]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Dec 23 17:07:24 volumio volumio[1153]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Dec 23 17:07:24 volumio volumio[1153]: errno: -20, Dec 23 17:07:24 volumio volumio[1153]: syscall: 'scandir', Dec 23 17:07:24 volumio volumio[1153]: code: 'ENOTDIR', Dec 23 17:07:24 volumio volumio[1153]: path: '/tmp/peppyrunning' Dec 23 17:07:24 volumio volumio[1153]: } Dec 23 17:07:24 volumio volumio[1153]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 17:07:24 volumio sudo[3648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-23 17:06 Dec 23 17:07:24 volumio sudo[3648]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:55:15 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="2f3686ade78abf3b92c180a0c5b20c6b"