Mar 27 09:00:00 volumio volumio[1373]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 09:00:00 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 09:00:00 volumio volumio[1373]: info: CoreCommandRouter::volumioGetState Mar 27 09:00:00 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:00:00 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 27 09:00:00 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 27 09:00:00 volumio volumio[1373]: info: Received Get System Info Mar 27 09:00:00 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 09:00:00 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 09:00:00 volumio volumio[1373]: info: Discovery: Getting this device information Mar 27 09:00:00 volumio volumio[1373]: info: CoreCommandRouter::volumioGetState Mar 27 09:00:00 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:00:00 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 09:00:00 volumio volumio[1373]: info: CoreCommandRouter::volumioGetState Mar 27 09:00:00 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:00:00 volumio volumio[1373]: info: Listing playlists Mar 27 09:00:00 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 27 09:00:00 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 27 09:00:00 volumio volumio[1373]: info: CoreCommandRouter::volumioGetState Mar 27 09:00:00 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:00:01 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 09:00:01 volumio volumio[1373]: info: Received Get System Info Mar 27 09:00:01 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 09:00:01 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 09:00:01 volumio volumio[1373]: info: Discovery: Getting this device information Mar 27 09:00:01 volumio volumio[1373]: info: CoreCommandRouter::volumioGetState Mar 27 09:00:01 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:00:01 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 09:00:01 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 27 09:00:09 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 27 09:00:20 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 27 09:00:20 volumio volumio[1373]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 27 09:00:20 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 27 09:00:20 volumio volumio[1373]: info: Received Get System Version Mar 27 09:00:20 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 09:00:20 volumio volumio[1373]: info: Received Get System Info Mar 27 09:00:20 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 09:00:20 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 09:00:20 volumio volumio[1373]: info: Discovery: Getting this device information Mar 27 09:00:20 volumio volumio[1373]: info: CoreCommandRouter::volumioGetState Mar 27 09:00:20 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:00:20 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 09:00:40 volumio volumio[1373]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 27 09:00:40 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 27 09:00:40 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 09:00:40 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 27 09:00:40 volumio volumio[1373]: info: Getting Alsa Cards List without I2S DAC Mar 27 09:00:40 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 27 09:00:40 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 09:00:40 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 09:00:40 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 09:00:40 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 09:00:40 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 09:00:40 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 09:00:40 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 09:00:40 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 09:00:40 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 27 09:00:40 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 27 09:01:09 volumio volumio[1373]: info: CoreCommandRouter::volumioGetState Mar 27 09:01:09 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:16 volumio kernel: usb 1-1.3: new high-speed USB device number 4 using dwc_otg Mar 27 09:01:17 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Mar 27 09:01:18 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Mar 27 09:01:18 volumio kernel: usb 1-1.3: new high-speed USB device number 5 using dwc_otg Mar 27 09:01:18 volumio kernel: usb 1-1.3: New USB device found, idVendor=058f, idProduct=6387, bcdDevice= 1.00 Mar 27 09:01:18 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 27 09:01:18 volumio kernel: usb 1-1.3: Product: Mass Storage Device Mar 27 09:01:18 volumio kernel: usb 1-1.3: Manufacturer: JetFlash Mar 27 09:01:18 volumio kernel: usb 1-1.3: SerialNumber: DIPSH85M Mar 27 09:01:18 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected Mar 27 09:01:18 volumio kernel: scsi host0: usb-storage 1-1.3:1.0 Mar 27 09:01:19 volumio kernel: scsi 0:0:0:0: Direct-Access JetFlash Transcend 8GB 8.07 PQ: 0 ANSI: 2 Mar 27 09:01:19 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Mar 27 09:01:19 volumio kernel: sd 0:0:0:0: [sda] 15687680 512-byte logical blocks: (8.03 GB/7.48 GiB) Mar 27 09:01:19 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Mar 27 09:01:19 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Mar 27 09:01:19 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Mar 27 09:01:19 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Mar 27 09:01:19 volumio kernel: sda: sda1 Mar 27 09:01:19 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Mar 27 09:01:20 volumio volumio[1373]: info: Mounting Device 8___ Mar 27 09:01:20 volumio sudo[3264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/8___ -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 27 09:01:20 volumio sudo[3264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:01:20 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Mar 27 09:01:20 volumio sudo[3264]: pam_unix(sudo:session): session closed for user root Mar 27 09:01:20 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Mar 27 09:01:20 volumio volumio[1373]: MPD error: No database Mar 27 09:01:27 volumio volumio[1373]: info: CoreCommandRouter::volumioGetQueue Mar 27 09:01:27 volumio volumio[1373]: info: CoreStateMachine::getQueue Mar 27 09:01:27 volumio volumio[1373]: info: CorePlayQueue::getQueue Mar 27 09:01:31 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 27 09:01:31 volumio volumio[1373]: info: CURURI: music-library Mar 27 09:01:31 volumio mpd[1630]: exception: No database Mar 27 09:01:31 volumio volumio[1373]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Mar 27 09:01:31 volumio volumio[1373]: info: Preload queue cleared Mar 27 09:01:39 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 27 09:01:39 volumio volumio[1373]: info: CURURI: music-library Mar 27 09:01:39 volumio mpd[1630]: exception: No database Mar 27 09:01:39 volumio volumio[1373]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Mar 27 09:01:39 volumio volumio[1373]: info: Preload queue cleared Mar 27 09:01:45 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 27 09:01:45 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 09:01:46 volumio volumio[1373]: info: Preload queue cleared Mar 27 09:01:48 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 27 09:01:49 volumio volumio[1373]: info: Preload queue cleared Mar 27 09:01:52 volumio volumio[1373]: info: Preload queue cleared Mar 27 09:01:52 volumio volumio[1373]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 27 09:01:52 volumio volumio[1373]: info: CoreStateMachine::ClearQueue Mar 27 09:01:52 volumio volumio[1373]: info: CoreStateMachine::stop Mar 27 09:01:52 volumio volumio[1373]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 09:01:52 volumio volumio[1373]: info: CorePlayQueue::clearPlayQueue Mar 27 09:01:52 volumio volumio[1373]: info: CorePlayQueue::saveQueue Mar 27 09:01:52 volumio volumio[1373]: info: CoreCommandRouter::volumioPushQueue Mar 27 09:01:52 volumio volumio[1373]: info: CoreStateMachine::addQueueItems Mar 27 09:01:52 volumio volumio[1373]: info: CorePlayQueue::addQueueItems Mar 27 09:01:52 volumio volumio[1373]: info: Preload queue cleared Mar 27 09:01:52 volumio volumio[1373]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Mar 27 09:01:52 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 27 09:01:52 volumio volumio[1373]: info: CoreCommandRouter::volumioPushQueue Mar 27 09:01:52 volumio volumio[1373]: info: CorePlayQueue::saveQueue Mar 27 09:01:52 volumio volumio[1373]: info: CoreStateMachine::updateTrackBlock Mar 27 09:01:52 volumio volumio[1373]: info: CorePlayQueue::getTrackBlock Mar 27 09:01:52 volumio volumio[1373]: info: CoreCommandRouter::volumioPlay Mar 27 09:01:52 volumio volumio[1373]: info: CoreStateMachine::play index 0 Mar 27 09:01:52 volumio volumio[1373]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 09:01:52 volumio volumio[1373]: info: CoreStateMachine::stop Mar 27 09:01:52 volumio volumio[1373]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 09:01:52 volumio volumio[1373]: info: CoreStateMachine::play index undefined Mar 27 09:01:52 volumio volumio[1373]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 09:01:52 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:52 volumio volumio[1373]: info: CoreStateMachine::startPlaybackTimer Mar 27 09:01:52 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:52 volumio volumio[1373]: info: [1774591312827] ControllerWebradio::clearAddPlayTrack Mar 27 09:01:52 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand stop Mar 27 09:01:52 volumio volumio[1373]: info: sendMpdCommand stop took 7 milliseconds Mar 27 09:01:52 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand clear Mar 27 09:01:52 volumio volumio[1373]: info: Mar 27 09:01:52 volumio volumio[1373]: ---------------------------- MPD announces system playlist update Mar 27 09:01:52 volumio volumio[1373]: info: Ignoring MPD Status Update Mar 27 09:01:52 volumio volumio[1373]: info: sendMpdCommand clear took 5 milliseconds Mar 27 09:01:52 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Mar 27 09:01:52 volumio volumio[1373]: info: Mar 27 09:01:52 volumio volumio[1373]: ---------------------------- MPD announces system playlist update Mar 27 09:01:52 volumio volumio[1373]: info: Ignoring MPD Status Update Mar 27 09:01:52 volumio volumio[1373]: info: Mar 27 09:01:52 volumio volumio[1373]: ---------------------------- MPD announces system playlist update Mar 27 09:01:52 volumio volumio[1373]: info: Ignoring MPD Status Update Mar 27 09:01:52 volumio volumio[1373]: error: updateQueue error: null Mar 27 09:01:52 volumio volumio[1373]: info: ------------------------------ 12ms Mar 27 09:01:53 volumio volumio[1373]: info: Mar 27 09:01:53 volumio volumio[1373]: ---------------------------- MPD announces system playlist update Mar 27 09:01:53 volumio volumio[1373]: info: Ignoring MPD Status Update Mar 27 09:01:53 volumio volumio[1373]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 461 milliseconds Mar 27 09:01:53 volumio volumio[1373]: info: ------------------------------ 460ms Mar 27 09:01:53 volumio volumio[1373]: info: ------------------------------ 458ms Mar 27 09:01:53 volumio volumio[1373]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 27 09:01:53 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand play Mar 27 09:01:53 volumio volumio[1373]: info: Mar 27 09:01:53 volumio volumio[1373]: ---------------------------- MPD announces system playlist update Mar 27 09:01:53 volumio volumio[1373]: info: Ignoring MPD Status Update Mar 27 09:01:53 volumio volumio[1373]: info: Mar 27 09:01:53 volumio volumio[1373]: ---------------------------- MPD announces system playlist update Mar 27 09:01:53 volumio volumio[1373]: info: Ignoring MPD Status Update Mar 27 09:01:53 volumio volumio[1373]: info: ------------------------------ 11ms Mar 27 09:01:53 volumio volumio[1373]: info: sendMpdCommand play took 6 milliseconds Mar 27 09:01:53 volumio volumio[1373]: info: ------------------------------ 6ms Mar 27 09:01:53 volumio volumio[1373]: info: ------------------------------ 5ms Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces state update: player Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::getState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces state update: player Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::getState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces state update: player Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces state update: player Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::getState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand status took 9 milliseconds Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::getState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand status took 6 milliseconds Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces state update: player Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::getState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces state update: player Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::getState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand status took 13 milliseconds Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand status took 11 milliseconds Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand status took 5 milliseconds Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::servicePushState Mar 27 09:01:55 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:55 volumio volumio[1373]: 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":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 09:01:55 volumio volumio[1373]: verbose: CURRENT POSITION 0 Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus stop Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::servicePushState Mar 27 09:01:55 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:55 volumio volumio[1373]: 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":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 09:01:55 volumio volumio[1373]: verbose: CURRENT POSITION 0 Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:01:55 volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:55 volumio volumio[1373]: info: ------------------------------ 51ms Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.743+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.744+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.746+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.747+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 27 09:01:55 volumio volumio[1373]: info: ------------------------------ 62ms Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand status took 47 milliseconds Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand playlistinfo took 43 milliseconds Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand playlistinfo took 42 milliseconds Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand playlistinfo took 40 milliseconds Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::servicePushState Mar 27 09:01:55 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:55 volumio volumio[1373]: 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":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 09:01:55 volumio volumio[1373]: verbose: CURRENT POSITION 0 Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:01:55 volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::servicePushState Mar 27 09:01:55 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:55 volumio volumio[1373]: 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":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 09:01:55 volumio volumio[1373]: verbose: CURRENT POSITION 0 Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:01:55 volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::servicePushState Mar 27 09:01:55 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:55 volumio volumio[1373]: 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":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 09:01:55 volumio volumio[1373]: verbose: CURRENT POSITION 0 Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:01:55 volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.791+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.792+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.794+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.794+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.796+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.796+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.798+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.798+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.800+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.800+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.802+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.802+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 27 09:01:55 volumio volumio[1373]: info: ------------------------------ 142ms Mar 27 09:01:55 volumio volumio[1373]: info: ------------------------------ 142ms Mar 27 09:01:55 volumio volumio[1373]: info: ------------------------------ 132ms Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces system playlist update Mar 27 09:01:55 volumio volumio[1373]: info: Ignoring MPD Status Update Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces state update: player Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand playlistinfo took 109 milliseconds Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::getState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::servicePushState Mar 27 09:01:55 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:55 volumio volumio[1373]: 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":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 09:01:55 volumio volumio[1373]: verbose: CURRENT POSITION 0 Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:01:55 volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.888+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.888+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.890+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.890+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:55 volumio volumio[1373]: info: ------------------------------ 189ms Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces system playlist update Mar 27 09:01:55 volumio volumio[1373]: info: Ignoring MPD Status Update Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces state update: player Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::getState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces system playlist update Mar 27 09:01:55 volumio volumio[1373]: info: Ignoring MPD Status Update Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces state update: player Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::getState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces system playlist update Mar 27 09:01:55 volumio volumio[1373]: info: Ignoring MPD Status Update Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces state update: player Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::getState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:01:55 volumio volumio[1373]: info: ------------------------------ 42ms Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand status took 40 milliseconds Mar 27 09:01:55 volumio volumio[1373]: info: ------------------------------ 10ms Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand status took 8 milliseconds Mar 27 09:01:55 volumio volumio[1373]: info: ------------------------------ 8ms Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand status took 7 milliseconds Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces system playlist update Mar 27 09:01:55 volumio volumio[1373]: info: Ignoring MPD Status Update Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces state update: player Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::getState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces system playlist update Mar 27 09:01:55 volumio volumio[1373]: info: Ignoring MPD Status Update Mar 27 09:01:55 volumio volumio[1373]: info: Mar 27 09:01:55 volumio volumio[1373]: ---------------------------- MPD announces state update: player Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::getState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:01:55 volumio volumio[1373]: info: ------------------------------ 30ms Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand status took 26 milliseconds Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand playlistinfo took 22 milliseconds Mar 27 09:01:55 volumio volumio[1373]: info: ------------------------------ 21ms Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand status took 20 milliseconds Mar 27 09:01:55 volumio volumio[1373]: info: ------------------------------ 19ms Mar 27 09:01:55 volumio volumio[1373]: info: sendMpdCommand status took 18 milliseconds Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::parseState Mar 27 09:01:55 volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::servicePushState Mar 27 09:01:55 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:55 volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":206,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 09:01:55 volumio volumio[1373]: verbose: CURRENT POSITION 0 Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:01:55 volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::servicePushState Mar 27 09:01:55 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:55 volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":206,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 09:01:55 volumio volumio[1373]: verbose: CURRENT POSITION 0 Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:01:55 volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:55 volumio volumio[1373]: info: ControllerMpd::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::servicePushState Mar 27 09:01:55 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:55 volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":206,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 09:01:55 volumio volumio[1373]: verbose: CURRENT POSITION 0 Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:01:55 volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:55 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.969+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.970+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.970+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.970+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.972+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.973+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.974+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.975+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.977+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.978+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.979+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:55 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:55.979+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:56 volumio volumio[1373]: info: ------------------------------ 145ms Mar 27 09:01:56 volumio volumio[1373]: info: ------------------------------ 114ms Mar 27 09:01:56 volumio volumio[1373]: info: ------------------------------ 112ms Mar 27 09:01:56 volumio volumio[1373]: info: sendMpdCommand playlistinfo took 100 milliseconds Mar 27 09:01:56 volumio volumio[1373]: info: sendMpdCommand playlistinfo took 98 milliseconds Mar 27 09:01:56 volumio volumio[1373]: info: sendMpdCommand playlistinfo took 98 milliseconds Mar 27 09:01:56 volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:01:56 volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:01:56 volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:01:56 volumio volumio[1373]: info: ControllerMpd::pushState Mar 27 09:01:56 volumio volumio[1373]: info: CoreCommandRouter::servicePushState Mar 27 09:01:56 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:56 volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":206,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 09:01:56 volumio volumio[1373]: verbose: CURRENT POSITION 0 Mar 27 09:01:56 volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Mar 27 09:01:56 volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:01:56 volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Mar 27 09:01:56 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 09:01:56 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:56 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:56 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:56 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:56 volumio volumio[1373]: info: ControllerMpd::pushState Mar 27 09:01:56 volumio volumio[1373]: info: CoreCommandRouter::servicePushState Mar 27 09:01:56 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:56 volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":206,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 09:01:56 volumio volumio[1373]: verbose: CURRENT POSITION 0 Mar 27 09:01:56 volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Mar 27 09:01:56 volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:01:56 volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Mar 27 09:01:56 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 09:01:56 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:56 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:56 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:56 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:56 volumio volumio[1373]: info: ControllerMpd::pushState Mar 27 09:01:56 volumio volumio[1373]: info: CoreCommandRouter::servicePushState Mar 27 09:01:56 volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Mar 27 09:01:56 volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":206,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 09:01:56 volumio volumio[1373]: verbose: CURRENT POSITION 0 Mar 27 09:01:56 volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Mar 27 09:01:56 volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:01:56 volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Mar 27 09:01:56 volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 09:01:56 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:56 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:56 volumio volumio[1373]: info: CoreStateMachine::pushState Mar 27 09:01:56 volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Mar 27 09:01:56 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:56.069+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:56 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:56.072+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:56 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:56.072+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:56 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:56.073+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:56 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:56.075+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:56 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:56.075+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:56 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:56.077+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:56 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:56.078+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:56 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:56.080+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:56 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:56.081+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:56 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:56.083+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.211.143:35934 @ 0x1dc1ec0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:56 volumio volumio5-onboarding[1593]: time=2026-03-27T09:01:56.092+03:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1caccc0" state=STATUS_PLAYING positionMs=286 volume=100 Mar 27 09:01:56 volumio volumio[1373]: info: ------------------------------ 220ms Mar 27 09:01:56 volumio volumio[1373]: info: ------------------------------ 212ms Mar 27 09:01:56 volumio volumio[1373]: info: ------------------------------ 210ms Mar 27 09:01:57 volumio volumio[1373]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 09:01:57 volumio volumio[1373]: TypeError: Cannot read properties of null (reading 'slice') Mar 27 09:01:57 volumio volumio[1373]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 27 09:01:57 volumio volumio[1373]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 27 09:01:57 volumio volumio[1373]: at Object.onceWrapper (node:events:629:26) Mar 27 09:01:57 volumio volumio[1373]: at ClientRequest.emit (node:events:514:28) Mar 27 09:01:57 volumio volumio[1373]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Mar 27 09:01:57 volumio volumio[1373]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Mar 27 09:01:57 volumio volumio[1373]: at TLSSocket.socketOnData (node:_http_client:535:22) Mar 27 09:01:57 volumio volumio[1373]: at TLSSocket.emit (node:events:514:28) Mar 27 09:01:57 volumio volumio[1373]: at addChunk (node:internal/streams/readable:343:12) Mar 27 09:01:57 volumio volumio[1373]: at readableAddChunk (node:internal/streams/readable:316:9) Mar 27 09:01:57 volumio volumio[1373]: at Readable.push (node:internal/streams/readable:253:10) Mar 27 09:01:57 volumio volumio[1373]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Mar 27 09:01:57 volumio volumio[1373]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 09:01:58 volumio sudo[3365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-27 09:00' Mar 27 09:01:58 volumio sudo[3365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"