-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Tue 2025-05-06 07:15:32 CEST. -- May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 06 07:14:00 volumio systemd[1]: Starting Music Player Daemon... May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 06 07:14:00 volumio volumio[937]: info: Alsa Settings successfully stored May 06 07:14:00 volumio volumio[937]: info: MPD Permissions set May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 07:14:00 volumio sudo[1988]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 07:14:00 volumio sudo[1988]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 07:14:00 volumio volumio[937]: info: Starting Shairport Sync May 06 07:14:00 volumio sudo[1988]: pam_unix(sudo:session): session closed for user root May 06 07:14:00 volumio sudo[2002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 06 07:14:00 volumio sudo[2002]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 07:14:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 06 07:14:00 volumio systemd[1]: shairport-sync.service: Succeeded. May 06 07:14:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 06 07:14:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 06 07:14:00 volumio sudo[2002]: pam_unix(sudo:session): session closed for user root May 06 07:14:00 volumio volumio[937]: info: Shairport-Sync Started May 06 07:14:00 volumio sudo[1967]: pam_unix(sudo:session): session closed for user root May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin May 06 07:14:00 volumio volumio[937]: info: Retrieving Cloud Streaming UI May 06 07:14:00 volumio volumio[937]: info: Getting Tidal Cloud Configuration May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin May 06 07:14:00 volumio volumio[937]: info: Getting Qobuz Cloud Configuration May 06 07:14:00 volumio volumio[937]: info: Asking plugin for UI Config May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin May 06 07:14:00 volumio volumio[937]: info: Getting Spotify Cloud Configuration May 06 07:14:00 volumio volumio[937]: info: Asking plugin for UI Config May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin May 06 07:14:00 volumio volumio[937]: info: Saving Spotify Acccount May 06 07:14:00 volumio volumio[937]: info: Got Tidal Cloud Configuration May 06 07:14:00 volumio volumio[937]: info: Got it May 06 07:14:00 volumio volumio[937]: info: Got it May 06 07:14:00 volumio volumio[937]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 06 07:14:00 volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin May 06 07:14:00 volumio volumio[937]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 06 07:14:01 volumio mpd[2001]: May 06 07:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 06 07:14:01 volumio systemd[1]: Started Music Player Daemon. May 06 07:14:01 volumio sudo[1982]: pam_unix(sudo:session): session closed for user root May 06 07:14:01 volumio volumio[937]: error: updateQueue error: null May 06 07:14:01 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation May 06 07:14:01 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware May 06 07:14:01 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray May 06 07:14:01 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage May 06 07:14:01 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware May 06 07:14:03 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 06 07:14:03 volumio volumio[937]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 06 07:14:03 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 06 07:14:03 volumio volumio[937]: info: Received Get System Version May 06 07:14:03 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 06 07:14:03 volumio volumio[937]: info: Received Get System Info May 06 07:14:03 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 07:14:03 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 07:14:03 volumio volumio[937]: info: Discovery: Getting this device information May 06 07:14:03 volumio volumio[937]: info: CoreCommandRouter::volumioGetState May 06 07:14:03 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:03 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 07:14:03 volumio volumio[937]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 06 07:14:03 volumio volumio[937]: info: CoreStateMachine::getcurrentVolume May 06 07:14:03 volumio volumio[937]: info: CoreCommandRouter::volumioRetrievevolume May 06 07:14:03 volumio volumio[937]: info: VolumeController:: Volume=86 Mute =false May 06 07:14:03 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:14:03 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:03 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:14:03 volumio volumio[937]: info: CoreStateMachine::updateTrackBlock May 06 07:14:03 volumio volumio[937]: info: CorePlayQueue::getTrackBlock May 06 07:14:03 volumio volumio[937]: info: CoreCommandRouter::volumioRetrievevolume May 06 07:14:03 volumio volumio[937]: info: VolumeController:: Volume=86 Mute =false May 06 07:14:03 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:14:03 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:03 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:14:04 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction May 06 07:14:04 volumio volumio[937]: info: CoreCommandRouter::Reload Ui May 06 07:14:04 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard May 06 07:14:04 volumio volumio[937]: info: Wizard terminated Successfully May 06 07:14:04 volumio volumio[937]: info: CoreCommandRouter::volumiosetStartupVolume May 06 07:14:04 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 07:14:05 volumio sudo[2020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 06 07:14:05 volumio sudo[2020]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 07:14:05 volumio sudo[2020]: pam_unix(sudo:session): session closed for user root May 06 07:14:05 volumio sudo[2022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 06 07:14:05 volumio sudo[2022]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 07:14:05 volumio sudo[2022]: pam_unix(sudo:session): session closed for user root May 06 07:14:05 volumio volumio[937]: verbose: New Socket.io Connection to 192.168.1.129 from 192.168.1.136 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 06 07:14:05 volumio sudo[2026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 06 07:14:05 volumio sudo[2026]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 07:14:05 volumio sudo[2026]: pam_unix(sudo:session): session closed for user root May 06 07:14:05 volumio sudo[2028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 06 07:14:05 volumio sudo[2028]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 07:14:05 volumio sudo[2028]: pam_unix(sudo:session): session closed for user root May 06 07:14:05 volumio volumio[937]: verbose: New Socket.io Connection to 192.168.1.129 from 192.168.1.136 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 06 07:14:05 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 06 07:14:05 volumio volumio[937]: info: CoreCommandRouter::volumioGetVisibleSources May 06 07:14:05 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 07:14:05 volumio volumio[937]: info: CoreCommandRouter::volumioGetState May 06 07:14:05 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:05 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 06 07:14:05 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 06 07:14:05 volumio volumio[937]: info: Received Get System Info May 06 07:14:05 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 07:14:05 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 07:14:05 volumio volumio[937]: info: Discovery: Getting this device information May 06 07:14:05 volumio volumio[937]: info: CoreCommandRouter::volumioGetState May 06 07:14:05 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:05 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 07:14:05 volumio volumio[937]: info: CoreCommandRouter::volumioGetState May 06 07:14:05 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:05 volumio volumio[937]: info: Listing playlists May 06 07:14:05 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 06 07:14:05 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 06 07:14:05 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 06 07:14:06 volumio volumio[937]: info: CoreCommandRouter::volumioGetState May 06 07:14:06 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:07 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 06 07:14:07 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 06 07:14:07 volumio volumio[937]: info: Received Get System Info May 06 07:14:07 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 07:14:07 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 07:14:07 volumio volumio[937]: info: Discovery: Getting this device information May 06 07:14:07 volumio volumio[937]: info: CoreCommandRouter::volumioGetState May 06 07:14:07 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:07 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 07:14:08 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 06 07:14:08 volumio volumio[937]: info: Received Get System Info May 06 07:14:08 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 07:14:08 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 07:14:08 volumio volumio[937]: info: Discovery: Getting this device information May 06 07:14:08 volumio volumio[937]: info: CoreCommandRouter::volumioGetState May 06 07:14:08 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:08 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 07:14:08 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse May 06 07:14:10 volumio volumio[937]: info: CoreCommandRouter::volumioGetQueue May 06 07:14:10 volumio volumio[937]: info: CoreStateMachine::getQueue May 06 07:14:10 volumio volumio[937]: info: CorePlayQueue::getQueue May 06 07:14:15 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 06 07:14:17 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 06 07:14:17 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 06 07:14:17 volumio volumio-remote-updater[635]: No test mode May 06 07:14:17 volumio volumio-remote-updater[635]: No alpha test mode May 06 07:14:17 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 06 07:14:17 volumio volumio[937]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 06 07:14:17 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 06 07:14:17 volumio volumio[937]: info: CoreCommandRouter::Close All Modals sent May 06 07:14:17 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted May 06 07:14:21 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 06 07:14:21 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 06 07:14:21 volumio volumio-remote-updater[635]: No test mode May 06 07:14:21 volumio volumio-remote-updater[635]: No alpha test mode May 06 07:14:21 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 06 07:14:21 volumio volumio[937]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 06 07:14:21 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 06 07:14:23 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 06 07:14:23 volumio volumio[937]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 06 07:14:23 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 06 07:14:23 volumio volumio[937]: info: Received Get System Version May 06 07:14:23 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 06 07:14:23 volumio volumio[937]: info: Received Get System Info May 06 07:14:23 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 07:14:23 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 07:14:23 volumio volumio[937]: info: Discovery: Getting this device information May 06 07:14:23 volumio volumio[937]: info: CoreCommandRouter::volumioGetState May 06 07:14:23 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:23 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 07:14:25 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 06 07:14:25 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 06 07:14:25 volumio systemd[1]: systemd-timedated.service: Succeeded. May 06 07:14:26 volumio volumio-remote-updater[635]: No test mode May 06 07:14:26 volumio volumio-remote-updater[635]: No alpha test mode May 06 07:14:26 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 06 07:14:26 volumio volumio[937]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 06 07:14:26 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 06 07:14:32 volumio volumio[937]: info: Preload queue cleared May 06 07:14:32 volumio volumio[937]: info: CoreCommandRouter::volumioReplaceandPlayItems May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::ClearQueue May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::stop May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:14:32 volumio volumio[937]: info: CorePlayQueue::clearPlayQueue May 06 07:14:32 volumio volumio[937]: info: CorePlayQueue::saveQueue May 06 07:14:32 volumio volumio[937]: info: CoreCommandRouter::volumioPushQueue May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::addQueueItems May 06 07:14:32 volumio volumio[937]: info: CorePlayQueue::addQueueItems May 06 07:14:32 volumio volumio[937]: info: Preload queue cleared May 06 07:14:32 volumio volumio[937]: info: Adding Item to queue: artists://All%20Time%20Low May 06 07:14:32 volumio volumio[937]: info: Exploding uri artists://All%20Time%20Low in service mpd May 06 07:14:32 volumio volumio[937]: info: CoreCommandRouter::volumioPushQueue May 06 07:14:32 volumio volumio[937]: info: CorePlayQueue::saveQueue May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::updateTrackBlock May 06 07:14:32 volumio volumio[937]: info: CorePlayQueue::getTrackBlock May 06 07:14:32 volumio volumio[937]: info: CoreCommandRouter::volumioPlay May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::play index 0 May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::stop May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::play index undefined May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:14:32 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::startPlaybackTimer May 06 07:14:32 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:32 volumio volumio[937]: verbose: ControllerMpd::clearAddPlayTracks USB/ESD-USB/All Time Low/Dirty Laundry/1-01 Dirty Laundry.flac May 06 07:14:32 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand stop May 06 07:14:32 volumio volumio[937]: info: sendMpdCommand stop took 3 milliseconds May 06 07:14:32 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand clear May 06 07:14:32 volumio volumio[937]: info: May 06 07:14:32 volumio volumio[937]: ---------------------------- MPD announces system playlist update May 06 07:14:32 volumio volumio[937]: info: Ignoring MPD Status Update May 06 07:14:32 volumio volumio[937]: info: sendMpdCommand clear took 6 milliseconds May 06 07:14:32 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand add "USB/ESD-USB/All Time Low/Dirty Laundry/1-01 Dirty Laundry.flac" May 06 07:14:32 volumio volumio[937]: error: updateQueue error: null May 06 07:14:32 volumio volumio[937]: info: May 06 07:14:32 volumio volumio[937]: ---------------------------- MPD announces system playlist update May 06 07:14:32 volumio volumio[937]: info: Ignoring MPD Status Update May 06 07:14:32 volumio volumio[937]: info: ------------------------------ 9ms May 06 07:14:32 volumio volumio[937]: info: sendMpdCommand add "USB/ESD-USB/All Time Low/Dirty Laundry/1-01 Dirty Laundry.flac" took 7 milliseconds May 06 07:14:32 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand play May 06 07:14:32 volumio volumio[937]: info: ------------------------------ 6ms May 06 07:14:32 volumio volumio[937]: info: May 06 07:14:32 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:14:32 volumio volumio[937]: info: sendMpdCommand play took 8 milliseconds May 06 07:14:32 volumio volumio[937]: info: ControllerMpd::getState May 06 07:14:32 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:14:32 volumio volumio[937]: info: May 06 07:14:32 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:14:32 volumio volumio[937]: info: ControllerMpd::getState May 06 07:14:32 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:14:32 volumio volumio[937]: info: sendMpdCommand status took 5 milliseconds May 06 07:14:32 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:14:32 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:14:32 volumio volumio[937]: info: sendMpdCommand status took 10 milliseconds May 06 07:14:32 volumio volumio[937]: info: sendMpdCommand playlistinfo took 4 milliseconds May 06 07:14:32 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:14:32 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:14:32 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:14:32 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:14:32 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:14:32 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:32 volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dirty Laundry","artist":"All Time Low","album":"Dirty Laundry","uri":"USB/ESD-USB/All Time Low/Dirty Laundry/1-01 Dirty Laundry.flac","trackType":"flac"} May 06 07:14:32 volumio volumio[937]: verbose: CURRENT POSITION 0 May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::syncState stateService play May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus stop May 06 07:14:32 volumio volumio[937]: info: ------------------------------ 31ms May 06 07:14:32 volumio volumio[937]: info: sendMpdCommand playlistinfo took 13 milliseconds May 06 07:14:32 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:14:32 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:14:32 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:14:32 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:32 volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"539 Kbps","isStreaming":false,"title":"Dirty Laundry","artist":"All Time Low","album":"Dirty Laundry","uri":"USB/ESD-USB/All Time Low/Dirty Laundry/1-01 Dirty Laundry.flac","trackType":"flac"} May 06 07:14:32 volumio volumio[937]: verbose: CURRENT POSITION 0 May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::syncState stateService play May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play May 06 07:14:32 volumio volumio[937]: info: Received an update from plugin. extracting info from payload May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:14:32 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:32 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 07:14:32 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:14:32 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:14:32 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:32 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:14:32 volumio volumio[937]: info: ------------------------------ 62ms May 06 07:14:47 volumio volumio[937]: info: CoreCommandRouter::volumioNext May 06 07:14:47 volumio volumio[937]: info: CoreStateMachine::next May 06 07:14:47 volumio volumio[937]: info: CoreStateMachine::stop May 06 07:14:47 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:14:47 volumio volumio[937]: info: CoreStateMachine::stPlaybackTimer May 06 07:14:47 volumio volumio[937]: info: CoreStateMachine::updateTrackBlock May 06 07:14:47 volumio volumio[937]: info: CorePlayQueue::getTrackBlock May 06 07:14:47 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:14:47 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:47 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 07:14:47 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:14:47 volumio volumio[937]: info: CoreStateMachine::serviceStop May 06 07:14:47 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:47 volumio volumio[937]: info: CoreCommandRouter::serviceStop May 06 07:14:47 volumio volumio[937]: info: ControllerMpd::stop May 06 07:14:47 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand stop May 06 07:14:47 volumio volumio[937]: info: sendMpdCommand stop took 12 milliseconds May 06 07:14:47 volumio volumio[937]: info: CoreStateMachine::play index undefined May 06 07:14:47 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:14:47 volumio volumio[937]: info: CorePlayQueue::getTrack 1 May 06 07:14:47 volumio volumio[937]: info: May 06 07:14:47 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:14:47 volumio volumio[937]: info: ControllerMpd::getState May 06 07:14:47 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:14:47 volumio volumio[937]: info: sendMpdCommand status took 1 milliseconds May 06 07:14:47 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:14:47 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:14:47 volumio volumio[937]: info: sendMpdCommand playlistinfo took 1 milliseconds May 06 07:14:47 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:14:47 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:14:47 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:14:47 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:14:47 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:47 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:14:47 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:47 volumio volumio[937]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dirty Laundry","artist":"All Time Low","album":"Dirty Laundry","uri":"USB/ESD-USB/All Time Low/Dirty Laundry/1-01 Dirty Laundry.flac","trackType":"flac"} May 06 07:14:47 volumio volumio[937]: verbose: CURRENT POSITION 0 May 06 07:14:47 volumio volumio[937]: info: CoreStateMachine::syncState stateService stop May 06 07:14:47 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus stop May 06 07:14:47 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:14:47 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:47 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:14:47 volumio volumio[937]: info: No code May 06 07:14:47 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:14:47 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:47 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:14:47 volumio volumio[937]: info: ------------------------------ 38ms May 06 07:14:49 volumio volumio[937]: info: Preload queue cleared May 06 07:14:49 volumio volumio[937]: info: CoreCommandRouter::volumioReplaceandPlayItems May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::ClearQueue May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::stop May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:14:49 volumio volumio[937]: info: CorePlayQueue::clearPlayQueue May 06 07:14:49 volumio volumio[937]: info: CorePlayQueue::saveQueue May 06 07:14:49 volumio volumio[937]: info: CoreCommandRouter::volumioPushQueue May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::addQueueItems May 06 07:14:49 volumio volumio[937]: info: CorePlayQueue::addQueueItems May 06 07:14:49 volumio volumio[937]: info: Preload queue cleared May 06 07:14:49 volumio volumio[937]: info: Adding Item to queue: artists://%5Bre%3Ajazz%5D May 06 07:14:49 volumio volumio[937]: info: Exploding uri artists://%5Bre%3Ajazz%5D in service mpd May 06 07:14:49 volumio volumio[937]: info: CoreCommandRouter::volumioPushQueue May 06 07:14:49 volumio volumio[937]: info: CorePlayQueue::saveQueue May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::updateTrackBlock May 06 07:14:49 volumio volumio[937]: info: CorePlayQueue::getTrackBlock May 06 07:14:49 volumio volumio[937]: info: CoreCommandRouter::volumioPlay May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::play index 0 May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::stop May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::play index undefined May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:14:49 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::startPlaybackTimer May 06 07:14:49 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:49 volumio volumio[937]: verbose: ControllerMpd::clearAddPlayTracks USB/ESD-USB/[re jazz]/(Re Mix)/1-01 Quiet Nights (Nicola Conte Out of the Cool Version).flac May 06 07:14:49 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand stop May 06 07:14:49 volumio volumio[937]: info: sendMpdCommand stop took 4 milliseconds May 06 07:14:49 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand clear May 06 07:14:49 volumio volumio[937]: info: May 06 07:14:49 volumio volumio[937]: ---------------------------- MPD announces system playlist update May 06 07:14:49 volumio volumio[937]: info: Ignoring MPD Status Update May 06 07:14:49 volumio volumio[937]: info: sendMpdCommand clear took 2 milliseconds May 06 07:14:49 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand add "USB/ESD-USB/[re jazz]/(Re Mix)/1-01 Quiet Nights (Nicola Conte Out of the Cool Version).flac" May 06 07:14:49 volumio volumio[937]: error: updateQueue error: null May 06 07:14:49 volumio volumio[937]: info: May 06 07:14:49 volumio volumio[937]: ---------------------------- MPD announces system playlist update May 06 07:14:49 volumio volumio[937]: info: Ignoring MPD Status Update May 06 07:14:49 volumio volumio[937]: info: ------------------------------ 5ms May 06 07:14:49 volumio volumio[937]: info: sendMpdCommand add "USB/ESD-USB/[re jazz]/(Re Mix)/1-01 Quiet Nights (Nicola Conte Out of the Cool Version).flac" took 3 milliseconds May 06 07:14:49 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand play May 06 07:14:49 volumio volumio[937]: info: ------------------------------ 5ms May 06 07:14:49 volumio volumio[937]: info: sendMpdCommand play took 2 milliseconds May 06 07:14:49 volumio volumio[937]: info: May 06 07:14:49 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:14:49 volumio volumio[937]: info: ControllerMpd::getState May 06 07:14:49 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:14:49 volumio volumio[937]: info: May 06 07:14:49 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:14:49 volumio volumio[937]: info: ControllerMpd::getState May 06 07:14:49 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:14:49 volumio volumio[937]: info: sendMpdCommand status took 6 milliseconds May 06 07:14:49 volumio volumio[937]: info: sendMpdCommand status took 3 milliseconds May 06 07:14:49 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:14:49 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:14:49 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:14:49 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:14:49 volumio volumio[937]: info: sendMpdCommand playlistinfo took 4 milliseconds May 06 07:14:49 volumio volumio[937]: info: sendMpdCommand playlistinfo took 4 milliseconds May 06 07:14:49 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:14:49 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:14:49 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:14:49 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:14:49 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:49 volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":304,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"930 Kbps","isStreaming":false,"title":"Quiet Nights (Nicola Conte Out of the Cool Version)","artist":"Lisa Bassenge","album":"(Re:Mix)","uri":"USB/ESD-USB/[re jazz]/(Re Mix)/1-01 Quiet Nights (Nicola Conte Out of the Cool Version).flac","trackType":"flac"} May 06 07:14:49 volumio volumio[937]: verbose: CURRENT POSITION 0 May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::syncState stateService play May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus stop May 06 07:14:49 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:14:49 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:14:49 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:49 volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":304,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"930 Kbps","isStreaming":false,"title":"Quiet Nights (Nicola Conte Out of the Cool Version)","artist":"Lisa Bassenge","album":"(Re:Mix)","uri":"USB/ESD-USB/[re jazz]/(Re Mix)/1-01 Quiet Nights (Nicola Conte Out of the Cool Version).flac","trackType":"flac"} May 06 07:14:49 volumio volumio[937]: verbose: CURRENT POSITION 0 May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::syncState stateService play May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play May 06 07:14:49 volumio volumio[937]: info: Received an update from plugin. extracting info from payload May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:14:49 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:49 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 07:14:49 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:14:49 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:14:49 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:49 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:14:49 volumio volumio[937]: info: ------------------------------ 40ms May 06 07:14:49 volumio volumio[937]: info: ------------------------------ 50ms May 06 07:14:56 volumio volumio[937]: info: CoreCommandRouter::volumioGetState May 06 07:14:56 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:57 volumio volumio[937]: info: CoreCommandRouter::volumioNext May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::next May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::stop May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::stPlaybackTimer May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::updateTrackBlock May 06 07:14:57 volumio volumio[937]: info: CorePlayQueue::getTrackBlock May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:14:57 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:57 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 07:14:57 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::serviceStop May 06 07:14:57 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:14:57 volumio volumio[937]: info: CoreCommandRouter::serviceStop May 06 07:14:57 volumio volumio[937]: info: ControllerMpd::stop May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand stop May 06 07:14:57 volumio volumio[937]: info: sendMpdCommand stop took 9 milliseconds May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::play index undefined May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:14:57 volumio volumio[937]: info: CorePlayQueue::getTrack 1 May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::startPlaybackTimer May 06 07:14:57 volumio volumio[937]: info: CorePlayQueue::getTrack 1 May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::clearAddPlayTracks USB/ESD-USB/[re jazz]/(Re Mix)/1-02 My Love Is Higher (Russ Gabriel Remix).flac May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand stop May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::updateTrackBlock May 06 07:14:57 volumio volumio[937]: info: CorePlayQueue::getTrackBlock May 06 07:14:57 volumio volumio[937]: info: May 06 07:14:57 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:14:57 volumio volumio[937]: info: ControllerMpd::getState May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:14:57 volumio volumio[937]: info: sendMpdCommand stop took 4 milliseconds May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand clear May 06 07:14:57 volumio volumio[937]: info: May 06 07:14:57 volumio volumio[937]: ---------------------------- MPD announces system playlist update May 06 07:14:57 volumio volumio[937]: info: Ignoring MPD Status Update May 06 07:14:57 volumio volumio[937]: info: sendMpdCommand status took 4 milliseconds May 06 07:14:57 volumio volumio[937]: info: sendMpdCommand clear took 3 milliseconds May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand add "USB/ESD-USB/[re jazz]/(Re Mix)/1-02 My Love Is Higher (Russ Gabriel Remix).flac" May 06 07:14:57 volumio volumio[937]: error: updateQueue error: null May 06 07:14:57 volumio volumio[937]: info: May 06 07:14:57 volumio volumio[937]: ---------------------------- MPD announces system playlist update May 06 07:14:57 volumio volumio[937]: info: Ignoring MPD Status Update May 06 07:14:57 volumio volumio[937]: info: ------------------------------ 6ms May 06 07:14:57 volumio volumio[937]: info: sendMpdCommand playlistinfo took 4 milliseconds May 06 07:14:57 volumio volumio[937]: info: sendMpdCommand add "USB/ESD-USB/[re jazz]/(Re Mix)/1-02 My Love Is Higher (Russ Gabriel Remix).flac" took 4 milliseconds May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand play May 06 07:14:57 volumio volumio[937]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 06 07:14:57 volumio volumio[937]: info: ------------------------------ 15ms May 06 07:14:57 volumio volumio[937]: info: May 06 07:14:57 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:14:57 volumio volumio[937]: info: ------------------------------ 9ms May 06 07:14:57 volumio volumio[937]: info: sendMpdCommand play took 5 milliseconds May 06 07:14:57 volumio volumio[937]: info: ControllerMpd::getState May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:14:57 volumio volumio[937]: info: May 06 07:14:57 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:14:57 volumio volumio[937]: info: ControllerMpd::getState May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:14:57 volumio volumio[937]: info: sendMpdCommand status took 10 milliseconds May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:14:57 volumio volumio[937]: info: sendMpdCommand status took 14 milliseconds May 06 07:14:57 volumio volumio[937]: info: sendMpdCommand playlistinfo took 3 milliseconds May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:14:57 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:14:57 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:14:57 volumio volumio[937]: info: CorePlayQueue::getTrack 1 May 06 07:14:57 volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My Love Is Higher (Russ Gabriel Remix)","artist":"[re:jazz]","album":"(Re:Mix)","uri":"USB/ESD-USB/[re jazz]/(Re Mix)/1-02 My Love Is Higher (Russ Gabriel Remix).flac","trackType":"flac"} May 06 07:14:57 volumio volumio[937]: verbose: CURRENT POSITION 1 May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::syncState stateService play May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus stop May 06 07:14:57 volumio volumio[937]: info: ------------------------------ 30ms May 06 07:14:57 volumio volumio[937]: info: sendMpdCommand playlistinfo took 8 milliseconds May 06 07:14:57 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:14:57 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:14:57 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:14:57 volumio volumio[937]: info: CorePlayQueue::getTrack 1 May 06 07:14:57 volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"564 Kbps","isStreaming":false,"title":"My Love Is Higher (Russ Gabriel Remix)","artist":"[re:jazz]","album":"(Re:Mix)","uri":"USB/ESD-USB/[re jazz]/(Re Mix)/1-02 My Love Is Higher (Russ Gabriel Remix).flac","trackType":"flac"} May 06 07:14:57 volumio volumio[937]: verbose: CURRENT POSITION 1 May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::syncState stateService play May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play May 06 07:14:57 volumio volumio[937]: info: Received an update from plugin. extracting info from payload May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:14:57 volumio volumio[937]: info: CorePlayQueue::getTrack 1 May 06 07:14:57 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 07:14:57 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:14:57 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:14:57 volumio volumio[937]: info: CorePlayQueue::getTrack 1 May 06 07:14:57 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:14:57 volumio volumio[937]: info: ------------------------------ 60ms May 06 07:14:58 volumio volumio[937]: info: CoreCommandRouter::volumioNext May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::next May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::stop May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::stPlaybackTimer May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::updateTrackBlock May 06 07:14:58 volumio volumio[937]: info: CorePlayQueue::getTrackBlock May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:14:58 volumio volumio[937]: info: CorePlayQueue::getTrack 1 May 06 07:14:58 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 07:14:58 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::serviceStop May 06 07:14:58 volumio volumio[937]: info: CorePlayQueue::getTrack 1 May 06 07:14:58 volumio volumio[937]: info: CoreCommandRouter::serviceStop May 06 07:14:58 volumio volumio[937]: info: ControllerMpd::stop May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand stop May 06 07:14:58 volumio volumio[937]: info: sendMpdCommand stop took 11 milliseconds May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::play index undefined May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:14:58 volumio volumio[937]: info: CorePlayQueue::getTrack 2 May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::startPlaybackTimer May 06 07:14:58 volumio volumio[937]: info: CorePlayQueue::getTrack 2 May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::clearAddPlayTracks USB/ESD-USB/[re jazz]/(Re Mix)/1-03 Mental Strength (Akufen Interpretation).flac May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand stop May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::updateTrackBlock May 06 07:14:58 volumio volumio[937]: info: CorePlayQueue::getTrackBlock May 06 07:14:58 volumio volumio[937]: info: May 06 07:14:58 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:14:58 volumio volumio[937]: info: ControllerMpd::getState May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:14:58 volumio volumio[937]: info: sendMpdCommand stop took 3 milliseconds May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand clear May 06 07:14:58 volumio volumio[937]: info: May 06 07:14:58 volumio volumio[937]: ---------------------------- MPD announces system playlist update May 06 07:14:58 volumio volumio[937]: info: Ignoring MPD Status Update May 06 07:14:58 volumio volumio[937]: info: sendMpdCommand status took 3 milliseconds May 06 07:14:58 volumio volumio[937]: info: sendMpdCommand clear took 3 milliseconds May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand add "USB/ESD-USB/[re jazz]/(Re Mix)/1-03 Mental Strength (Akufen Interpretation).flac" May 06 07:14:58 volumio volumio[937]: error: updateQueue error: null May 06 07:14:58 volumio volumio[937]: info: May 06 07:14:58 volumio volumio[937]: ---------------------------- MPD announces system playlist update May 06 07:14:58 volumio volumio[937]: info: Ignoring MPD Status Update May 06 07:14:58 volumio volumio[937]: info: ------------------------------ 6ms May 06 07:14:58 volumio volumio[937]: info: sendMpdCommand playlistinfo took 4 milliseconds May 06 07:14:58 volumio volumio[937]: info: sendMpdCommand add "USB/ESD-USB/[re jazz]/(Re Mix)/1-03 Mental Strength (Akufen Interpretation).flac" took 4 milliseconds May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand play May 06 07:14:58 volumio volumio[937]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 06 07:14:58 volumio volumio[937]: info: ------------------------------ 13ms May 06 07:14:58 volumio volumio[937]: info: May 06 07:14:58 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:14:58 volumio volumio[937]: info: ------------------------------ 8ms May 06 07:14:58 volumio volumio[937]: info: sendMpdCommand play took 4 milliseconds May 06 07:14:58 volumio volumio[937]: info: ControllerMpd::getState May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:14:58 volumio volumio[937]: info: May 06 07:14:58 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:14:58 volumio volumio[937]: info: ControllerMpd::getState May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:14:58 volumio volumio[937]: info: sendMpdCommand status took 4 milliseconds May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:14:58 volumio volumio[937]: info: sendMpdCommand status took 3 milliseconds May 06 07:14:58 volumio volumio[937]: info: sendMpdCommand playlistinfo took 2 milliseconds May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:14:58 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:14:58 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:14:58 volumio volumio[937]: info: CorePlayQueue::getTrack 2 May 06 07:14:58 volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":375,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"674 Kbps","isStreaming":false,"title":"Mental Strength (Akufen Interpretation)","artist":"Christine Maisonneuve","album":"(Re:Mix)","uri":"USB/ESD-USB/[re jazz]/(Re Mix)/1-03 Mental Strength (Akufen Interpretation).flac","trackType":"flac"} May 06 07:14:58 volumio volumio[937]: verbose: CURRENT POSITION 2 May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::syncState stateService play May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus stop May 06 07:14:58 volumio volumio[937]: info: ------------------------------ 19ms May 06 07:14:58 volumio volumio[937]: info: sendMpdCommand playlistinfo took 7 milliseconds May 06 07:14:58 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:14:58 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:14:58 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:14:58 volumio volumio[937]: info: CorePlayQueue::getTrack 2 May 06 07:14:58 volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":375,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"584 Kbps","isStreaming":false,"title":"Mental Strength (Akufen Interpretation)","artist":"Christine Maisonneuve","album":"(Re:Mix)","uri":"USB/ESD-USB/[re jazz]/(Re Mix)/1-03 Mental Strength (Akufen Interpretation).flac","trackType":"flac"} May 06 07:14:58 volumio volumio[937]: verbose: CURRENT POSITION 2 May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::syncState stateService play May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play May 06 07:14:58 volumio volumio[937]: info: Received an update from plugin. extracting info from payload May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:14:58 volumio volumio[937]: info: CorePlayQueue::getTrack 2 May 06 07:14:58 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 07:14:58 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:14:58 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:14:58 volumio volumio[937]: info: CorePlayQueue::getTrack 2 May 06 07:14:58 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:14:58 volumio volumio[937]: info: ------------------------------ 43ms May 06 07:15:10 volumio volumio[937]: info: CoreCommandRouter::volumioNext May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::next May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::stop May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::stPlaybackTimer May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::updateTrackBlock May 06 07:15:10 volumio volumio[937]: info: CorePlayQueue::getTrackBlock May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:15:10 volumio volumio[937]: info: CorePlayQueue::getTrack 2 May 06 07:15:10 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 07:15:10 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::serviceStop May 06 07:15:10 volumio volumio[937]: info: CorePlayQueue::getTrack 2 May 06 07:15:10 volumio volumio[937]: info: CoreCommandRouter::serviceStop May 06 07:15:10 volumio volumio[937]: info: ControllerMpd::stop May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand stop May 06 07:15:10 volumio volumio[937]: info: sendMpdCommand stop took 13 milliseconds May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::play index undefined May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:15:10 volumio volumio[937]: info: CorePlayQueue::getTrack 3 May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::startPlaybackTimer May 06 07:15:10 volumio volumio[937]: info: CorePlayQueue::getTrack 3 May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::clearAddPlayTracks USB/ESD-USB/[re jazz]/(Re Mix)/1-04 Swoundosophy (Ras Remix).flac May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand stop May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::updateTrackBlock May 06 07:15:10 volumio volumio[937]: info: CorePlayQueue::getTrackBlock May 06 07:15:10 volumio volumio[937]: info: May 06 07:15:10 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:15:10 volumio volumio[937]: info: ControllerMpd::getState May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:15:10 volumio volumio[937]: info: sendMpdCommand stop took 3 milliseconds May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand clear May 06 07:15:10 volumio volumio[937]: info: May 06 07:15:10 volumio volumio[937]: ---------------------------- MPD announces system playlist update May 06 07:15:10 volumio volumio[937]: info: Ignoring MPD Status Update May 06 07:15:10 volumio volumio[937]: info: sendMpdCommand status took 3 milliseconds May 06 07:15:10 volumio volumio[937]: info: sendMpdCommand clear took 3 milliseconds May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand add "USB/ESD-USB/[re jazz]/(Re Mix)/1-04 Swoundosophy (Ras Remix).flac" May 06 07:15:10 volumio volumio[937]: error: updateQueue error: null May 06 07:15:10 volumio volumio[937]: info: May 06 07:15:10 volumio volumio[937]: ---------------------------- MPD announces system playlist update May 06 07:15:10 volumio volumio[937]: info: Ignoring MPD Status Update May 06 07:15:10 volumio volumio[937]: info: ------------------------------ 6ms May 06 07:15:10 volumio volumio[937]: info: sendMpdCommand playlistinfo took 4 milliseconds May 06 07:15:10 volumio volumio[937]: info: sendMpdCommand add "USB/ESD-USB/[re jazz]/(Re Mix)/1-04 Swoundosophy (Ras Remix).flac" took 4 milliseconds May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand play May 06 07:15:10 volumio volumio[937]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 06 07:15:10 volumio volumio[937]: info: ------------------------------ 15ms May 06 07:15:10 volumio volumio[937]: info: May 06 07:15:10 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:15:10 volumio volumio[937]: info: ------------------------------ 9ms May 06 07:15:10 volumio volumio[937]: info: sendMpdCommand play took 5 milliseconds May 06 07:15:10 volumio volumio[937]: info: ControllerMpd::getState May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:15:10 volumio volumio[937]: info: May 06 07:15:10 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:15:10 volumio volumio[937]: info: ControllerMpd::getState May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:15:10 volumio volumio[937]: info: sendMpdCommand status took 16 milliseconds May 06 07:15:10 volumio volumio[937]: info: sendMpdCommand status took 3 milliseconds May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:15:10 volumio volumio[937]: info: sendMpdCommand playlistinfo took 5 milliseconds May 06 07:15:10 volumio volumio[937]: info: sendMpdCommand playlistinfo took 5 milliseconds May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:15:10 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:15:10 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:15:10 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:15:10 volumio volumio[937]: info: CorePlayQueue::getTrack 3 May 06 07:15:10 volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Swoundosophy (Ras Remix)","artist":"[re:jazz]","album":"(Re:Mix)","uri":"USB/ESD-USB/[re jazz]/(Re Mix)/1-04 Swoundosophy (Ras Remix).flac","trackType":"flac"} May 06 07:15:10 volumio volumio[937]: verbose: CURRENT POSITION 3 May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::syncState stateService play May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus stop May 06 07:15:10 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:15:10 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:15:10 volumio volumio[937]: info: CorePlayQueue::getTrack 3 May 06 07:15:10 volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"681 Kbps","isStreaming":false,"title":"Swoundosophy (Ras Remix)","artist":"[re:jazz]","album":"(Re:Mix)","uri":"USB/ESD-USB/[re jazz]/(Re Mix)/1-04 Swoundosophy (Ras Remix).flac","trackType":"flac"} May 06 07:15:10 volumio volumio[937]: verbose: CURRENT POSITION 3 May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::syncState stateService play May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play May 06 07:15:10 volumio volumio[937]: info: Received an update from plugin. extracting info from payload May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:15:10 volumio volumio[937]: info: CorePlayQueue::getTrack 3 May 06 07:15:10 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 07:15:10 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:15:10 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:15:10 volumio volumio[937]: info: CorePlayQueue::getTrack 3 May 06 07:15:10 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:15:10 volumio volumio[937]: info: ------------------------------ 52ms May 06 07:15:10 volumio volumio[937]: info: ------------------------------ 67ms May 06 07:15:19 volumio volumio[937]: info: CoreCommandRouter::volumioNext May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::next May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::stop May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::stPlaybackTimer May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::updateTrackBlock May 06 07:15:19 volumio volumio[937]: info: CorePlayQueue::getTrackBlock May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:15:19 volumio volumio[937]: info: CorePlayQueue::getTrack 3 May 06 07:15:19 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 07:15:19 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::serviceStop May 06 07:15:19 volumio volumio[937]: info: CorePlayQueue::getTrack 3 May 06 07:15:19 volumio volumio[937]: info: CoreCommandRouter::serviceStop May 06 07:15:19 volumio volumio[937]: info: ControllerMpd::stop May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand stop May 06 07:15:19 volumio volumio[937]: info: sendMpdCommand stop took 15 milliseconds May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::play index undefined May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:15:19 volumio volumio[937]: info: CorePlayQueue::getTrack 4 May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::startPlaybackTimer May 06 07:15:19 volumio volumio[937]: info: CorePlayQueue::getTrack 4 May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::clearAddPlayTracks USB/ESD-USB/[re jazz]/(Re Mix)/1-05 Torch of Freedom (Frost & Wagner Horn Rub).flac May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand stop May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::updateTrackBlock May 06 07:15:19 volumio volumio[937]: info: CorePlayQueue::getTrackBlock May 06 07:15:19 volumio volumio[937]: info: May 06 07:15:19 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:15:19 volumio volumio[937]: info: ControllerMpd::getState May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:15:19 volumio volumio[937]: info: sendMpdCommand stop took 4 milliseconds May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand clear May 06 07:15:19 volumio volumio[937]: info: May 06 07:15:19 volumio volumio[937]: ---------------------------- MPD announces system playlist update May 06 07:15:19 volumio volumio[937]: info: Ignoring MPD Status Update May 06 07:15:19 volumio volumio[937]: info: sendMpdCommand status took 4 milliseconds May 06 07:15:19 volumio volumio[937]: info: sendMpdCommand clear took 2 milliseconds May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand add "USB/ESD-USB/[re jazz]/(Re Mix)/1-05 Torch of Freedom (Frost & Wagner Horn Rub).flac" May 06 07:15:19 volumio volumio[937]: error: updateQueue error: null May 06 07:15:19 volumio volumio[937]: info: May 06 07:15:19 volumio volumio[937]: ---------------------------- MPD announces system playlist update May 06 07:15:19 volumio volumio[937]: info: Ignoring MPD Status Update May 06 07:15:19 volumio volumio[937]: info: ------------------------------ 5ms May 06 07:15:19 volumio volumio[937]: info: sendMpdCommand playlistinfo took 3 milliseconds May 06 07:15:19 volumio volumio[937]: info: sendMpdCommand add "USB/ESD-USB/[re jazz]/(Re Mix)/1-05 Torch of Freedom (Frost & Wagner Horn Rub).flac" took 4 milliseconds May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand play May 06 07:15:19 volumio volumio[937]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 06 07:15:19 volumio volumio[937]: info: ------------------------------ 13ms May 06 07:15:19 volumio volumio[937]: info: May 06 07:15:19 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:15:19 volumio volumio[937]: info: ------------------------------ 7ms May 06 07:15:19 volumio volumio[937]: info: sendMpdCommand play took 4 milliseconds May 06 07:15:19 volumio volumio[937]: info: ControllerMpd::getState May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:15:19 volumio volumio[937]: info: May 06 07:15:19 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:15:19 volumio volumio[937]: info: ControllerMpd::getState May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:15:19 volumio volumio[937]: info: sendMpdCommand status took 3 milliseconds May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:15:19 volumio volumio[937]: info: sendMpdCommand status took 4 milliseconds May 06 07:15:19 volumio volumio[937]: info: sendMpdCommand playlistinfo took 2 milliseconds May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:15:19 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:15:19 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:15:19 volumio volumio[937]: info: CorePlayQueue::getTrack 4 May 06 07:15:19 volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":322,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Torch of Freedom (Frost & Wagner Horn Rub)","artist":"[re:jazz]","album":"(Re:Mix)","uri":"USB/ESD-USB/[re jazz]/(Re Mix)/1-05 Torch of Freedom (Frost & Wagner Horn Rub).flac","trackType":"flac"} May 06 07:15:19 volumio volumio[937]: verbose: CURRENT POSITION 4 May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::syncState stateService play May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus stop May 06 07:15:19 volumio volumio[937]: info: ------------------------------ 16ms May 06 07:15:19 volumio volumio[937]: info: sendMpdCommand playlistinfo took 5 milliseconds May 06 07:15:19 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:15:19 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:15:19 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:15:19 volumio volumio[937]: info: CorePlayQueue::getTrack 4 May 06 07:15:19 volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":322,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"817 Kbps","isStreaming":false,"title":"Torch of Freedom (Frost & Wagner Horn Rub)","artist":"[re:jazz]","album":"(Re:Mix)","uri":"USB/ESD-USB/[re jazz]/(Re Mix)/1-05 Torch of Freedom (Frost & Wagner Horn Rub).flac","trackType":"flac"} May 06 07:15:19 volumio volumio[937]: verbose: CURRENT POSITION 4 May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::syncState stateService play May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play May 06 07:15:19 volumio volumio[937]: info: Received an update from plugin. extracting info from payload May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:15:19 volumio volumio[937]: info: CorePlayQueue::getTrack 4 May 06 07:15:19 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 07:15:19 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:15:19 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:15:19 volumio volumio[937]: info: CorePlayQueue::getTrack 4 May 06 07:15:19 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:15:19 volumio volumio[937]: info: ------------------------------ 55ms May 06 07:15:24 volumio volumio[937]: info: CoreCommandRouter::volumioNext May 06 07:15:24 volumio volumio[937]: info: CoreStateMachine::next May 06 07:15:24 volumio volumio[937]: info: CoreStateMachine::stop May 06 07:15:24 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:15:24 volumio volumio[937]: info: CoreStateMachine::stPlaybackTimer May 06 07:15:24 volumio volumio[937]: info: CoreStateMachine::updateTrackBlock May 06 07:15:24 volumio volumio[937]: info: CorePlayQueue::getTrackBlock May 06 07:15:24 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:15:24 volumio volumio[937]: info: CorePlayQueue::getTrack 4 May 06 07:15:24 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 07:15:24 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:15:24 volumio volumio[937]: info: CoreStateMachine::serviceStop May 06 07:15:24 volumio volumio[937]: info: CorePlayQueue::getTrack 4 May 06 07:15:24 volumio volumio[937]: info: CoreCommandRouter::serviceStop May 06 07:15:24 volumio volumio[937]: info: ControllerMpd::stop May 06 07:15:24 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand stop May 06 07:15:24 volumio volumio[937]: info: sendMpdCommand stop took 11 milliseconds May 06 07:15:24 volumio volumio[937]: info: CoreStateMachine::play index undefined May 06 07:15:24 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:15:24 volumio volumio[937]: info: CorePlayQueue::getTrack 5 May 06 07:15:24 volumio volumio[937]: info: CoreStateMachine::startPlaybackTimer May 06 07:15:24 volumio volumio[937]: info: CorePlayQueue::getTrack 5 May 06 07:15:24 volumio volumio[937]: verbose: ControllerMpd::clearAddPlayTracks USB/ESD-USB/[re jazz]/(Re Mix)/1-06 Release Your Mind (Swell Session Remix).flac May 06 07:15:24 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand stop May 06 07:15:24 volumio volumio[937]: info: CoreStateMachine::updateTrackBlock May 06 07:15:24 volumio volumio[937]: info: CorePlayQueue::getTrackBlock May 06 07:15:24 volumio volumio[937]: info: May 06 07:15:24 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:15:24 volumio volumio[937]: info: ControllerMpd::getState May 06 07:15:24 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:15:24 volumio volumio[937]: info: sendMpdCommand stop took 3 milliseconds May 06 07:15:24 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand clear May 06 07:15:24 volumio volumio[937]: info: May 06 07:15:24 volumio volumio[937]: ---------------------------- MPD announces system playlist update May 06 07:15:24 volumio volumio[937]: info: Ignoring MPD Status Update May 06 07:15:24 volumio volumio[937]: info: sendMpdCommand status took 3 milliseconds May 06 07:15:24 volumio volumio[937]: info: sendMpdCommand clear took 2 milliseconds May 06 07:15:24 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:15:24 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:15:24 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand add "USB/ESD-USB/[re jazz]/(Re Mix)/1-06 Release Your Mind (Swell Session Remix).flac" May 06 07:15:24 volumio volumio[937]: error: updateQueue error: null May 06 07:15:24 volumio volumio[937]: info: May 06 07:15:24 volumio volumio[937]: ---------------------------- MPD announces system playlist update May 06 07:15:24 volumio volumio[937]: info: Ignoring MPD Status Update May 06 07:15:24 volumio volumio[937]: info: ------------------------------ 5ms May 06 07:15:24 volumio volumio[937]: info: sendMpdCommand playlistinfo took 4 milliseconds May 06 07:15:24 volumio volumio[937]: info: sendMpdCommand add "USB/ESD-USB/[re jazz]/(Re Mix)/1-06 Release Your Mind (Swell Session Remix).flac" took 6 milliseconds May 06 07:15:24 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:15:24 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand play May 06 07:15:25 volumio volumio[937]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 06 07:15:25 volumio volumio[937]: info: ------------------------------ 15ms May 06 07:15:25 volumio volumio[937]: info: May 06 07:15:25 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:15:25 volumio volumio[937]: info: ------------------------------ 10ms May 06 07:15:25 volumio volumio[937]: info: sendMpdCommand play took 4 milliseconds May 06 07:15:25 volumio volumio[937]: info: ControllerMpd::getState May 06 07:15:25 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:15:25 volumio volumio[937]: info: May 06 07:15:25 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:15:25 volumio volumio[937]: info: ControllerMpd::getState May 06 07:15:25 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:15:25 volumio volumio[937]: info: sendMpdCommand status took 3 milliseconds May 06 07:15:25 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:15:25 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:15:25 volumio volumio[937]: info: sendMpdCommand status took 4 milliseconds May 06 07:15:25 volumio volumio[937]: info: sendMpdCommand playlistinfo took 2 milliseconds May 06 07:15:25 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:15:25 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:15:25 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:15:25 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:15:25 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:15:25 volumio volumio[937]: info: CorePlayQueue::getTrack 5 May 06 07:15:25 volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":515,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Release Your Mind (Swell Session Remix)","artist":"[re:jazz]","album":"(Re:Mix)","uri":"USB/ESD-USB/[re jazz]/(Re Mix)/1-06 Release Your Mind (Swell Session Remix).flac","trackType":"flac"} May 06 07:15:25 volumio volumio[937]: verbose: CURRENT POSITION 5 May 06 07:15:25 volumio volumio[937]: info: CoreStateMachine::syncState stateService play May 06 07:15:25 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus stop May 06 07:15:25 volumio volumio[937]: info: ------------------------------ 15ms May 06 07:15:25 volumio volumio[937]: info: sendMpdCommand playlistinfo took 4 milliseconds May 06 07:15:25 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:15:25 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:15:25 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:15:25 volumio volumio[937]: info: CorePlayQueue::getTrack 5 May 06 07:15:25 volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":515,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"319 Kbps","isStreaming":false,"title":"Release Your Mind (Swell Session Remix)","artist":"[re:jazz]","album":"(Re:Mix)","uri":"USB/ESD-USB/[re jazz]/(Re Mix)/1-06 Release Your Mind (Swell Session Remix).flac","trackType":"flac"} May 06 07:15:25 volumio volumio[937]: verbose: CURRENT POSITION 5 May 06 07:15:25 volumio volumio[937]: info: CoreStateMachine::syncState stateService play May 06 07:15:25 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play May 06 07:15:25 volumio volumio[937]: info: Received an update from plugin. extracting info from payload May 06 07:15:25 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:15:25 volumio volumio[937]: info: CorePlayQueue::getTrack 5 May 06 07:15:25 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 07:15:25 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:15:25 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:15:25 volumio volumio[937]: info: CorePlayQueue::getTrack 5 May 06 07:15:25 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:15:25 volumio volumio[937]: info: ------------------------------ 43ms May 06 07:15:30 volumio volumio[937]: info: Preload queue cleared May 06 07:15:30 volumio volumio[937]: info: CoreCommandRouter::volumioReplaceandPlayItems May 06 07:15:30 volumio volumio[937]: info: CoreStateMachine::ClearQueue May 06 07:15:30 volumio volumio[937]: info: CoreStateMachine::stop May 06 07:15:30 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:15:30 volumio volumio[937]: info: CoreStateMachine::stPlaybackTimer May 06 07:15:30 volumio volumio[937]: info: CoreStateMachine::updateTrackBlock May 06 07:15:30 volumio volumio[937]: info: CorePlayQueue::getTrackBlock May 06 07:15:30 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:15:30 volumio volumio[937]: info: CorePlayQueue::getTrack 5 May 06 07:15:30 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 07:15:30 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:15:30 volumio volumio[937]: info: CoreStateMachine::serviceStop May 06 07:15:30 volumio volumio[937]: info: CorePlayQueue::getTrack 5 May 06 07:15:30 volumio volumio[937]: info: CoreCommandRouter::serviceStop May 06 07:15:30 volumio volumio[937]: info: ControllerMpd::stop May 06 07:15:30 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand stop May 06 07:15:30 volumio volumio[937]: info: CorePlayQueue::clearPlayQueue May 06 07:15:30 volumio volumio[937]: info: CorePlayQueue::saveQueue May 06 07:15:30 volumio volumio[937]: info: CoreCommandRouter::volumioPushQueue May 06 07:15:30 volumio volumio[937]: info: CoreStateMachine::addQueueItems May 06 07:15:30 volumio volumio[937]: info: CorePlayQueue::addQueueItems May 06 07:15:30 volumio volumio[937]: info: Preload queue cleared May 06 07:15:30 volumio volumio[937]: info: Adding Item to queue: artists://Alixandrea%20Corvyn May 06 07:15:30 volumio volumio[937]: info: Exploding uri artists://Alixandrea%20Corvyn in service mpd May 06 07:15:30 volumio volumio[937]: info: May 06 07:15:30 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:15:30 volumio volumio[937]: info: sendMpdCommand stop took 15 milliseconds May 06 07:15:30 volumio volumio[937]: info: ControllerMpd::getState May 06 07:15:30 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:15:30 volumio volumio[937]: info: CoreCommandRouter::volumioPushQueue May 06 07:15:31 volumio volumio[937]: info: CorePlayQueue::saveQueue May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::updateTrackBlock May 06 07:15:31 volumio volumio[937]: info: CorePlayQueue::getTrackBlock May 06 07:15:31 volumio volumio[937]: info: CoreCommandRouter::volumioPlay May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::play index 0 May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::stop May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::play index undefined May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 07:15:31 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::startPlaybackTimer May 06 07:15:31 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:15:31 volumio volumio[937]: verbose: ControllerMpd::clearAddPlayTracks USB/ESD-USB/Alixandrea Corvyn/Spellbound - Ultimate Goth/1-01 Don’t Fear the Reaper.flac May 06 07:15:31 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand stop May 06 07:15:31 volumio volumio[937]: info: sendMpdCommand status took 16 milliseconds May 06 07:15:31 volumio volumio[937]: info: sendMpdCommand stop took 3 milliseconds May 06 07:15:31 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:15:31 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:15:31 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand clear May 06 07:15:31 volumio volumio[937]: info: May 06 07:15:31 volumio volumio[937]: ---------------------------- MPD announces system playlist update May 06 07:15:31 volumio volumio[937]: info: Ignoring MPD Status Update May 06 07:15:31 volumio volumio[937]: info: sendMpdCommand playlistinfo took 4 milliseconds May 06 07:15:31 volumio volumio[937]: info: sendMpdCommand clear took 3 milliseconds May 06 07:15:31 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:15:31 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand add "USB/ESD-USB/Alixandrea Corvyn/Spellbound - Ultimate Goth/1-01 Don’t Fear the Reaper.flac" May 06 07:15:31 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:15:31 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:15:31 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:15:31 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 07:15:31 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:15:31 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:15:31 volumio volumio[937]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Release Your Mind (Swell Session Remix)","artist":"[re:jazz]","album":"(Re:Mix)","uri":"USB/ESD-USB/[re jazz]/(Re Mix)/1-06 Release Your Mind (Swell Session Remix).flac","trackType":"flac"} May 06 07:15:31 volumio volumio[937]: verbose: CURRENT POSITION 0 May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::syncState stateService stop May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus stop May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:15:31 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:15:31 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:15:31 volumio volumio[937]: info: No code May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:15:31 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:15:31 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:15:31 volumio volumio[937]: info: ------------------------------ 61ms May 06 07:15:31 volumio volumio[937]: error: updateQueue error: null May 06 07:15:31 volumio volumio[937]: info: May 06 07:15:31 volumio volumio[937]: ---------------------------- MPD announces system playlist update May 06 07:15:31 volumio volumio[937]: info: Ignoring MPD Status Update May 06 07:15:31 volumio volumio[937]: info: ------------------------------ 45ms May 06 07:15:31 volumio volumio[937]: info: sendMpdCommand add "USB/ESD-USB/Alixandrea Corvyn/Spellbound - Ultimate Goth/1-01 Don’t Fear the Reaper.flac" took 42 milliseconds May 06 07:15:31 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand play May 06 07:15:31 volumio volumio[937]: info: May 06 07:15:31 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:15:31 volumio volumio[937]: info: ------------------------------ 10ms May 06 07:15:31 volumio volumio[937]: info: sendMpdCommand play took 8 milliseconds May 06 07:15:31 volumio volumio[937]: info: ControllerMpd::getState May 06 07:15:31 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:15:31 volumio volumio[937]: info: May 06 07:15:31 volumio volumio[937]: ---------------------------- MPD announces state update: player May 06 07:15:31 volumio volumio[937]: info: ControllerMpd::getState May 06 07:15:31 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status May 06 07:15:31 volumio volumio[937]: info: sendMpdCommand status took 5 milliseconds May 06 07:15:31 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:15:31 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:15:31 volumio volumio[937]: info: sendMpdCommand status took 6 milliseconds May 06 07:15:31 volumio volumio[937]: info: sendMpdCommand playlistinfo took 5 milliseconds May 06 07:15:31 volumio volumio[937]: verbose: ControllerMpd::parseState May 06 07:15:31 volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 07:15:31 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:15:31 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:15:31 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:15:31 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:15:31 volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":289,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don’t Fear the Reaper","artist":"Alixandrea Corvyn","album":"Spellbound - Ultimate Goth","uri":"USB/ESD-USB/Alixandrea Corvyn/Spellbound - Ultimate Goth/1-01 Don’t Fear the Reaper.flac","trackType":"flac"} May 06 07:15:31 volumio volumio[937]: verbose: CURRENT POSITION 0 May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::syncState stateService play May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus stop May 06 07:15:31 volumio volumio[937]: info: ------------------------------ 20ms May 06 07:15:31 volumio volumio[937]: info: sendMpdCommand playlistinfo took 164 milliseconds May 06 07:15:31 volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo May 06 07:15:31 volumio volumio[937]: info: ControllerMpd::pushState May 06 07:15:31 volumio volumio[937]: info: CoreCommandRouter::servicePushState May 06 07:15:31 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:15:31 volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":297,"duration":289,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1012 Kbps","isStreaming":false,"title":"Don’t Fear the Reaper","artist":"Alixandrea Corvyn","album":"Spellbound - Ultimate Goth","uri":"USB/ESD-USB/Alixandrea Corvyn/Spellbound - Ultimate Goth/1-01 Don’t Fear the Reaper.flac","trackType":"flac"} May 06 07:15:31 volumio volumio[937]: verbose: CURRENT POSITION 0 May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::syncState stateService play May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play May 06 07:15:31 volumio volumio[937]: info: Received an update from plugin. extracting info from payload May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:15:31 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:15:31 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 07:15:31 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:15:31 volumio volumio[937]: info: CoreStateMachine::pushState May 06 07:15:31 volumio volumio[937]: info: CorePlayQueue::getTrack 0 May 06 07:15:31 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 06 07:15:31 volumio volumio[937]: info: ------------------------------ 200ms May 06 07:15:31 volumio volumio[937]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 07:15:31 volumio volumio[937]: Error: self signed certificate May 06 07:15:31 volumio volumio[937]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) May 06 07:15:31 volumio volumio[937]: at TLSSocket.emit (events.js:315:20) May 06 07:15:31 volumio volumio[937]: at TLSSocket._finishInit (_tls_wrap.js:932:8) May 06 07:15:31 volumio volumio[937]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { May 06 07:15:31 volumio volumio[937]: code: 'DEPTH_ZERO_SELF_SIGNED_CERT' May 06 07:15:31 volumio volumio[937]: } May 06 07:15:31 volumio volumio[937]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 07:15:32 volumio sudo[2277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-06 07:14 May 06 07:15:32 volumio sudo[2277]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"