Dec 05 20:06:00 volumio volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 20:06:00 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 05 20:06:00 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 20:06:00 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 20:06:00 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:00 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:00 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:00 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:00 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:00 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:00 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:00 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 05 20:06:00 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 05 20:06:02 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 05 20:06:10 volumio volumio[916]: info: CoreCommandRouter::volumioPlay Dec 05 20:06:10 volumio volumio[916]: info: CoreStateMachine::play index undefined Dec 05 20:06:10 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:06:10 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:10 volumio volumio[916]: info: CoreStateMachine::startPlaybackTimer Dec 05 20:06:10 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:10 volumio volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:06:10 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 20:06:10 volumio volumio[916]: info: sendMpdCommand stop took 10 milliseconds Dec 05 20:06:10 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Dec 05 20:06:10 volumio volumio[916]: info: Dec 05 20:06:10 volumio volumio[916]: ---------------------------- MPD announces system playlist update Dec 05 20:06:10 volumio volumio[916]: info: Ignoring MPD Status Update Dec 05 20:06:10 volumio volumio[916]: info: sendMpdCommand clear took 12 milliseconds Dec 05 20:06:10 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand add "NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac" Dec 05 20:06:10 volumio volumio[916]: error: updateQueue error: null Dec 05 20:06:10 volumio volumio[916]: info: Dec 05 20:06:10 volumio volumio[916]: ---------------------------- MPD announces system playlist update Dec 05 20:06:10 volumio volumio[916]: info: Ignoring MPD Status Update Dec 05 20:06:10 volumio volumio[916]: info: ------------------------------ 23ms Dec 05 20:06:10 volumio volumio[916]: info: sendMpdCommand add "NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac" took 20 milliseconds Dec 05 20:06:10 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand play Dec 05 20:06:10 volumio volumio[916]: info: ------------------------------ 18ms Dec 05 20:06:10 volumio volumio[916]: info: sendMpdCommand play took 7 milliseconds Dec 05 20:06:10 volumio volumio[916]: info: Dec 05 20:06:10 volumio volumio[916]: ---------------------------- MPD announces state update: player Dec 05 20:06:10 volumio volumio[916]: info: ControllerMpd::getState Dec 05 20:06:10 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand status Dec 05 20:06:10 volumio kernel: usb 1-1.2: uac_clock_source_is_valid(): cannot get clock validity for id 40 Dec 05 20:06:10 volumio kernel: usb 1-1.2: clock source 40 is not valid, cannot use Dec 05 20:06:10 volumio volumio[916]: info: Dec 05 20:06:10 volumio volumio[916]: ---------------------------- MPD announces state update: player Dec 05 20:06:10 volumio volumio[916]: info: sendMpdCommand status took 86 milliseconds Dec 05 20:06:10 volumio volumio[916]: info: ControllerMpd::getState Dec 05 20:06:10 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand status Dec 05 20:06:10 volumio volumio[916]: verbose: ControllerMpd::parseState Dec 05 20:06:10 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 20:06:10 volumio volumio[916]: info: sendMpdCommand status took 43 milliseconds Dec 05 20:06:10 volumio volumio[916]: info: sendMpdCommand playlistinfo took 24 milliseconds Dec 05 20:06:10 volumio volumio[916]: verbose: ControllerMpd::parseState Dec 05 20:06:10 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 20:06:10 volumio volumio[916]: verbose: ControllerMpd::parseTrackInfo Dec 05 20:06:10 volumio volumio[916]: info: ControllerMpd::pushState Dec 05 20:06:10 volumio volumio[916]: info: CoreCommandRouter::servicePushState Dec 05 20:06:10 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:10 volumio volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":336,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Electronic Performers","artist":"Air","album":"10000 Hz Legend","uri":"NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac","trackType":"flac"} Dec 05 20:06:10 volumio volumio[916]: verbose: CURRENT POSITION 0 Dec 05 20:06:10 volumio volumio[916]: info: CoreStateMachine::syncState stateService play Dec 05 20:06:10 volumio volumio[916]: info: CoreStateMachine::syncState currentStatus stop Dec 05 20:06:10 volumio volumio[916]: info: ------------------------------ 200ms Dec 05 20:06:10 volumio volumio[916]: info: sendMpdCommand playlistinfo took 65 milliseconds Dec 05 20:06:10 volumio volumio[916]: verbose: ControllerMpd::parseTrackInfo Dec 05 20:06:10 volumio volumio[916]: info: ControllerMpd::pushState Dec 05 20:06:10 volumio volumio[916]: info: CoreCommandRouter::servicePushState Dec 05 20:06:10 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:10 volumio volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":336,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"599 Kbps","isStreaming":false,"title":"Electronic Performers","artist":"Air","album":"10000 Hz Legend","uri":"NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac","trackType":"flac"} Dec 05 20:06:10 volumio volumio[916]: verbose: CURRENT POSITION 0 Dec 05 20:06:10 volumio volumio[916]: info: CoreStateMachine::syncState stateService play Dec 05 20:06:10 volumio volumio[916]: info: CoreStateMachine::syncState currentStatus play Dec 05 20:06:10 volumio volumio[916]: info: Received an update from plugin. extracting info from payload Dec 05 20:06:10 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:06:10 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:10 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:06:10 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:06:10 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:06:10 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:10 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:06:10 volumio volumio[916]: info: ------------------------------ 330ms Dec 05 20:06:11 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 05 20:06:11 volumio volumio[916]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 05 20:06:11 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 05 20:06:11 volumio volumio[916]: info: Received Get System Version Dec 05 20:06:11 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 05 20:06:11 volumio volumio[916]: info: Received Get System Info Dec 05 20:06:11 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 20:06:11 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 20:06:11 volumio volumio[916]: info: Discovery: Getting this device information Dec 05 20:06:11 volumio volumio[916]: info: CoreCommandRouter::volumioGetState Dec 05 20:06:11 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:11 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 20:06:22 volumio kernel: usb 1-1.2: USB disconnect, device number 4 Dec 05 20:06:22 volumio volumio[916]: info: Dec 05 20:06:22 volumio volumio[916]: ---------------------------- MPD announces state update: player Dec 05 20:06:22 volumio volumio[916]: info: ControllerMpd::getState Dec 05 20:06:22 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand status Dec 05 20:06:23 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 05 20:06:23 volumio volumio[916]: info: sendMpdCommand status took 109 milliseconds Dec 05 20:06:23 volumio volumio[916]: verbose: ControllerMpd::parseState Dec 05 20:06:23 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 20:06:23 volumio volumio[916]: info: sendMpdCommand clearerror took 4 milliseconds Dec 05 20:06:23 volumio volumio[916]: info: sendMpdCommand playlistinfo took 68 milliseconds Dec 05 20:06:23 volumio volumio[916]: verbose: ControllerMpd::parseTrackInfo Dec 05 20:06:23 volumio volumio[916]: info: ControllerMpd::pushState Dec 05 20:06:23 volumio volumio[916]: info: CoreCommandRouter::servicePushState Dec 05 20:06:23 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:23 volumio volumio[916]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14809,"duration":336,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"918 Kbps","isStreaming":false,"title":"Electronic Performers","artist":"Air","album":"10000 Hz Legend","uri":"NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac","trackType":"flac"} Dec 05 20:06:23 volumio volumio[916]: verbose: CURRENT POSITION 0 Dec 05 20:06:23 volumio volumio[916]: info: CoreStateMachine::syncState stateService pause Dec 05 20:06:23 volumio volumio[916]: info: CoreStateMachine::syncState currentStatus play Dec 05 20:06:23 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:06:23 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:23 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:06:23 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:06:23 volumio volumio[916]: info: ------------------------------ 390ms Dec 05 20:06:23 volumio volumio[916]: info: Dec 05 20:06:23 volumio volumio[916]: ---------------------------- USB Audio Device Detached Dec 05 20:06:23 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 05 20:06:23 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 20:06:23 volumio kernel: usb 1-1.2: new high-speed USB device number 6 using dwc_otg Dec 05 20:06:23 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 05 20:06:23 volumio kernel: usb 1-1.2: New USB device found, idVendor=21b4, idProduct=0230, bcdDevice= 1.71 Dec 05 20:06:23 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 05 20:06:23 volumio kernel: usb 1-1.2: Product: Ayre USB Interface Dec 05 20:06:23 volumio kernel: usb 1-1.2: Manufacturer: Ayre Acoustics Dec 05 20:06:23 volumio kernel: usb 1-1.2: SerialNumber: Streamlength(tm) Dec 05 20:06:24 volumio volumio[916]: info: Dec 05 20:06:24 volumio volumio[916]: ---------------------------- USB Audio Device Attached Dec 05 20:06:24 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 05 20:06:24 volumio volumio[916]: info: CoreCommandRouter::Close All Modals sent Dec 05 20:06:24 volumio volumio[916]: info: Preparing to save Alsa Options, stopping services first Dec 05 20:06:24 volumio volumio[916]: info: CoreCommandRouter::volumioGetState Dec 05 20:06:24 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:24 volumio volumio[916]: info: CoreCommandRouter::volumioPause Dec 05 20:06:24 volumio volumio[916]: info: CoreStateMachine::pause Dec 05 20:06:24 volumio volumio[916]: info: CoreStateMachine::stPlaybackTimer Dec 05 20:06:24 volumio volumio[916]: info: CoreStateMachine::servicePause Dec 05 20:06:24 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:24 volumio volumio[916]: info: CoreCommandRouter::servicePause Dec 05 20:06:24 volumio volumio[916]: info: ControllerMpd::pause Dec 05 20:06:24 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand pause Dec 05 20:06:24 volumio volumio[916]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Ayre USB Interface","alsacard":"Interface"},"i2s":false} Dec 05 20:06:24 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 20:06:24 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 20:06:24 volumio volumio[916]: info: Device 5 does not have any Mixer Control Available Dec 05 20:06:24 volumio vtcs[1528]: [2025-12-05 20:06:24.510] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 05 20:06:24 volumio vtcs[1528]: [2025-12-05 20:06:24.510] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 05 20:06:24 volumio sudo[1718]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 20:06:24 volumio sudo[1718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:24 volumio volumio[916]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 05 20:06:24 volumio volumio[916]: info: Updating Volume Controller Parameters: Device: 5 Name: Ayre USB Interface Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 05 20:06:24 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 05 20:06:24 volumio volumio[916]: info: Disabling external Volume Control Dec 05 20:06:24 volumio volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 20:06:24 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:06:24 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:24 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:06:24 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:06:24 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 05 20:06:24 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 05 20:06:24 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 05 20:06:24 volumio sudo[1718]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:24 volumio volumio[916]: info: Preparing to generate the ALSA configuration file Dec 05 20:06:25 volumio sudo[1739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 20:06:25 volumio sudo[1739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:25 volumio volumio[916]: info: Dec 05 20:06:25 volumio volumio[916]: ---------------------------- MPD announces state update: player Dec 05 20:06:25 volumio volumio[916]: info: sendMpdCommand pause took 985 milliseconds Dec 05 20:06:25 volumio volumio[916]: info: ControllerMpd::getState Dec 05 20:06:25 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand status Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 05 20:06:25 volumio volumio[916]: info: sendMpdCommand status took 21 milliseconds Dec 05 20:06:25 volumio volumio[916]: verbose: ControllerMpd::parseState Dec 05 20:06:25 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 20:06:25 volumio volumio[916]: info: sendMpdCommand playlistinfo took 41 milliseconds Dec 05 20:06:25 volumio volumio[916]: verbose: ControllerMpd::parseTrackInfo Dec 05 20:06:25 volumio volumio[916]: info: ControllerMpd::pushState Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::servicePushState Dec 05 20:06:25 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:25 volumio volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16070,"duration":336,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"968 Kbps","isStreaming":false,"title":"Electronic Performers","artist":"Air","album":"10000 Hz Legend","uri":"NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac","trackType":"flac"} Dec 05 20:06:25 volumio volumio[916]: verbose: CURRENT POSITION 0 Dec 05 20:06:25 volumio volumio[916]: info: CoreStateMachine::syncState stateService play Dec 05 20:06:25 volumio volumio[916]: info: CoreStateMachine::syncState currentStatus pause Dec 05 20:06:25 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:06:25 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:06:25 volumio sudo[1739]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:25 volumio volumio[916]: info: ------------------------------ 180ms Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 05 20:06:25 volumio volumio[916]: info: Asound.conf file unchanged, so no further update is needed Dec 05 20:06:25 volumio volumio[916]: info: Output device has changed, restarting MPD Dec 05 20:06:25 volumio volumio[916]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 05 20:06:25 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:25 volumio sudo[1749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 05 20:06:25 volumio sudo[1749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:25 volumio sudo[1752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 05 20:06:25 volumio sudo[1749]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:25 volumio sudo[1752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:25 volumio sudo[1755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 20:06:25 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 05 20:06:25 volumio sudo[1758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 20:06:25 volumio sudo[1758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:25 volumio sudo[1755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:25 volumio volumio[916]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 05 20:06:26 volumio volumio[916]: info: MPD Permissions set Dec 05 20:06:26 volumio sudo[1758]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio sudo[1755]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:26 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 05 20:06:26 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 05 20:06:26 volumio systemd[1]: mpd.service: Consumed 4.931s CPU time. Dec 05 20:06:26 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 05 20:06:26 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 05 20:06:26 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 05 20:06:26 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 05 20:06:26 volumio volumio[916]: info: Preparing to generate the ALSA configuration file Dec 05 20:06:26 volumio volumio[916]: info: Asound.conf file unchanged, so no further update is needed Dec 05 20:06:26 volumio volumio[916]: info: Output device has changed, restarting MPD Dec 05 20:06:26 volumio volumio[916]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio sudo[1771]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 05 20:06:26 volumio sudo[1771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 05 20:06:26 volumio sudo[1776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 05 20:06:26 volumio sudo[1776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:26 volumio sudo[1771]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:26 volumio sudo[1774]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 05 20:06:26 volumio sudo[1774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:26 volumio sudo[1774]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:26 volumio sudo[1784]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 20:06:26 volumio sudo[1784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:26 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 05 20:06:26 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 05 20:06:26 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 05 20:06:26 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 05 20:06:26 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 05 20:06:26 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 05 20:06:26 volumio volumio[916]: info: MPD Permissions set Dec 05 20:06:26 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio sudo[1787]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 20:06:26 volumio sudo[1787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 20:06:26 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:26 volumio sudo[1784]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:26 volumio volumio[916]: info: Preparing to generate the ALSA configuration file Dec 05 20:06:26 volumio sudo[1787]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:27 volumio volumio[916]: info: Asound.conf file unchanged, so no further update is needed Dec 05 20:06:27 volumio sudo[1789]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 05 20:06:27 volumio volumio[916]: info: Output device has changed, restarting MPD Dec 05 20:06:27 volumio sudo[1789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 05 20:06:27 volumio sudo[1789]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:27 volumio volumio[916]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 05 20:06:27 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:27 volumio sudo[1803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 05 20:06:27 volumio sudo[1803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:27 volumio sudo[1801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 05 20:06:27 volumio sudo[1801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:27 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:27 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:27 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:27 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:27 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:27 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:27 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:27 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:27 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 05 20:06:27 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 05 20:06:27 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 05 20:06:27 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 05 20:06:27 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 05 20:06:27 volumio sudo[1801]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:27 volumio sudo[1810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 20:06:27 volumio sudo[1810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:27 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 20:06:27 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:27 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 05 20:06:27 volumio volumio[916]: info: MPD Permissions set Dec 05 20:06:27 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 05 20:06:27 volumio sudo[1807]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 20:06:27 volumio sudo[1807]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:27 volumio sudo[1810]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:27 volumio sudo[1807]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:27 volumio sudo[1819]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 05 20:06:27 volumio sudo[1819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 05 20:06:27 volumio sudo[1819]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:27 volumio volumio[916]: info: TidalConnect service stoped! Dec 05 20:06:28 volumio volumio[916]: info: TidalConnect service stoped! Dec 05 20:06:28 volumio volumio[916]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 05 20:06:28 volumio volumio[916]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 05 20:06:28 volumio volumio[916]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 05 20:06:28 volumio volumio[916]: info: CoreStateMachine::getcurrentVolume Dec 05 20:06:28 volumio volumio[916]: info: CoreCommandRouter::volumioRetrievevolume Dec 05 20:06:28 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:06:28 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:28 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:06:28 volumio sudo[1834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 05 20:06:28 volumio sudo[1834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:29 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 05 20:06:29 volumio volumio[916]: info: TidalConnect service stoped! Dec 05 20:06:29 volumio sudo[1834]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:29 volumio volumio[916]: info: TidalConnect service stoped! Dec 05 20:06:29 volumio volumio[916]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 05 20:06:29 volumio volumio[916]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 05 20:06:29 volumio volumio[916]: info: Executing endpoint tc_getconfig Dec 05 20:06:29 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 05 20:06:29 volumio vtcs[1837]: STARTING TidalConnect services, version: 1.5.2.56 Dec 05 20:06:29 volumio vtcs[1837]: STARTED TidalConnect services. Dec 05 20:06:29 volumio volumio[916]: info: Executing endpoint tc_connect Dec 05 20:06:29 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 05 20:06:29 volumio volumio[916]: info: Connecting to TidalConnect Dec 05 20:06:29 volumio volumio[916]: info: CoreCommandRouter::servicePushState Dec 05 20:06:29 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:06:29 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:29 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:06:29 volumio sudo[1854]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 05 20:06:29 volumio sudo[1854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:29 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:29 volumio volumio[916]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 05 20:06:29 volumio volumio[916]: info: CoreCommandRouter::servicePushState Dec 05 20:06:29 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:06:29 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:29 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:06:29 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:29 volumio volumio[916]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 05 20:06:29 volumio sudo[1854]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:29 volumio volumio[916]: info: TidalConnect service stoped! Dec 05 20:06:30 volumio volumio[916]: info: TidalConnect service stoped! Dec 05 20:06:30 volumio volumio[916]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 05 20:06:30 volumio volumio[916]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 05 20:06:30 volumio sudo[1874]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 05 20:06:30 volumio sudo[1874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:30 volumio sudo[1874]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:30 volumio volumio[916]: info: TidalConnect service stoped! Dec 05 20:06:30 volumio volumio[916]: info: TidalConnect service stoped! Dec 05 20:06:30 volumio volumio[916]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 05 20:06:30 volumio volumio[916]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 05 20:06:30 volumio sudo[1888]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 05 20:06:30 volumio sudo[1888]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:31 volumio sudo[1888]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:31 volumio volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 20:06:31 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 05 20:06:31 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 20:06:31 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 20:06:31 volumio mpd[1824]: 2025-12-05T20:06:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 05 20:06:31 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 05 20:06:31 volumio sudo[1776]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:31 volumio sudo[1752]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:31 volumio sudo[1803]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:31 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:31 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:31 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:31 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:31 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:31 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:31 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:06:31 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 05 20:06:31 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 05 20:06:31 volumio volumio[916]: error: MPD error: The expression evaluated to a falsy value: Dec 05 20:06:31 volumio volumio[916]: assert.ok(self.idling) Dec 05 20:06:31 volumio volumio[916]: error: The expression evaluated to a falsy value: Dec 05 20:06:31 volumio volumio[916]: assert.ok(self.idling) Dec 05 20:06:31 volumio volumio[916]: error: MPD error: The expression evaluated to a falsy value: Dec 05 20:06:31 volumio volumio[916]: assert.ok(self.idling) Dec 05 20:06:31 volumio volumio[916]: error: The expression evaluated to a falsy value: Dec 05 20:06:31 volumio volumio[916]: assert.ok(self.idling) Dec 05 20:06:31 volumio volumio[916]: error: updateQueue error: null Dec 05 20:06:32 volumio volumio[916]: info: TidalConnect service started! Dec 05 20:06:32 volumio volumio[916]: info: TidalConnect service started! Dec 05 20:06:33 volumio volumio[916]: info: TidalConnect service started! Dec 05 20:06:34 volumio volumio[916]: info: TidalConnect service started! Dec 05 20:06:34 volumio sudo[1902]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 05 20:06:34 volumio sudo[1902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:34 volumio sudo[1903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 05 20:06:34 volumio sudo[1903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:34 volumio sudo[1905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 05 20:06:34 volumio sudo[1905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:34 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 05 20:06:34 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 05 20:06:34 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 05 20:06:35 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 05 20:06:35 volumio mpd_monitor.sh[1919]: MPD Monitor Service: Starting MPD Monitor Service Dec 05 20:06:35 volumio sudo[1903]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:35 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 05 20:06:35 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 05 20:06:35 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 05 20:06:35 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 05 20:06:35 volumio volumio[916]: info: Successfully started MPD Monitor Dec 05 20:06:35 volumio mpd_monitor.sh[1927]: MPD Monitor Service: Starting MPD Monitor Service Dec 05 20:06:35 volumio sudo[1902]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:35 volumio sudo[1905]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:35 volumio volumio[916]: info: Successfully started MPD Monitor Dec 05 20:06:35 volumio volumio[916]: info: Successfully started MPD Monitor Dec 05 20:06:43 volumio volumio[916]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Dec 05 20:06:43 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Dec 05 20:06:43 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:43 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:43 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:43 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:43 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:43 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:43 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:43 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:43 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 20:06:43 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:43 volumio sudo[1933]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 05 20:06:43 volumio sudo[1933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:43 volumio sudo[1934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 05 20:06:43 volumio sudo[1934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:43 volumio sudo[1934]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:43 volumio volumio[916]: info: MPD Permissions set Dec 05 20:06:43 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 05 20:06:43 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 05 20:06:43 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 05 20:06:43 volumio systemd[1]: mpd.service: Consumed 3.813s CPU time. Dec 05 20:06:43 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 05 20:06:43 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 05 20:06:43 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 05 20:06:43 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 05 20:06:43 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 05 20:06:43 volumio sudo[1943]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 05 20:06:43 volumio sudo[1943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 05 20:06:43 volumio sudo[1943]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:47 volumio mpd[1945]: 2025-12-05T20:06:47 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 05 20:06:47 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 05 20:06:47 volumio sudo[1933]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:47 volumio volumio[916]: error: updateQueue error: null Dec 05 20:06:47 volumio volumio[916]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Dec 05 20:06:47 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Dec 05 20:06:47 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:47 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:47 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:47 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:47 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:47 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:47 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:47 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:47 volumio sudo[1963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 05 20:06:47 volumio sudo[1963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:47 volumio sudo[1963]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:47 volumio sudo[1965]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 05 20:06:47 volumio sudo[1965]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:47 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 20:06:47 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:06:47 volumio volumio[916]: info: MPD Permissions set Dec 05 20:06:47 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 05 20:06:47 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 05 20:06:47 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 05 20:06:47 volumio systemd[1]: mpd.service: Consumed 3.488s CPU time. Dec 05 20:06:47 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 05 20:06:47 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 05 20:06:47 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 05 20:06:47 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 05 20:06:47 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 05 20:06:48 volumio sudo[1974]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 05 20:06:48 volumio sudo[1974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 05 20:06:48 volumio sudo[1974]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:50 volumio sudo[1978]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 05 20:06:50 volumio sudo[1978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:50 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 05 20:06:50 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 05 20:06:50 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 05 20:06:51 volumio mpd[1976]: 2025-12-05T20:06:51 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 05 20:06:51 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 05 20:06:51 volumio sudo[1965]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:51 volumio volumio[916]: error: updateQueue error: null Dec 05 20:06:51 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 05 20:06:51 volumio mpd_monitor.sh[1982]: MPD Monitor Service: Starting MPD Monitor Service Dec 05 20:06:51 volumio sudo[1978]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:51 volumio volumio[916]: info: Successfully started MPD Monitor Dec 05 20:06:52 volumio volumio[916]: info: CoreCommandRouter::volumioPause Dec 05 20:06:52 volumio volumio[916]: info: CoreStateMachine::pause Dec 05 20:06:52 volumio volumio[916]: info: CoreStateMachine::stPlaybackTimer Dec 05 20:06:52 volumio volumio[916]: info: CoreStateMachine::servicePause Dec 05 20:06:52 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:06:52 volumio volumio[916]: info: CoreCommandRouter::servicePause Dec 05 20:06:52 volumio volumio[916]: info: ControllerMpd::pause Dec 05 20:06:52 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand pause Dec 05 20:06:52 volumio volumio[916]: info: sendMpdCommand pause took 1 milliseconds Dec 05 20:06:53 volumio volumio[916]: info: CoreCommandRouter::volumioPause Dec 05 20:06:53 volumio volumio[916]: info: CoreStateMachine::pause Dec 05 20:06:54 volumio sudo[1987]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 05 20:06:54 volumio sudo[1987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:06:54 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 05 20:06:54 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 05 20:06:54 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 05 20:06:54 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 05 20:06:54 volumio sudo[1987]: pam_unix(sudo:session): session closed for user root Dec 05 20:06:54 volumio mpd_monitor.sh[1989]: MPD Monitor Service: Starting MPD Monitor Service Dec 05 20:06:54 volumio volumio[916]: info: Successfully started MPD Monitor Dec 05 20:06:55 volumio volumio[916]: info: CoreCommandRouter::volumioPause Dec 05 20:06:55 volumio volumio[916]: info: CoreStateMachine::pause Dec 05 20:06:55 volumio volumio[916]: info: CoreCommandRouter::volumioPause Dec 05 20:06:55 volumio volumio[916]: info: CoreStateMachine::pause Dec 05 20:06:58 volumio volumio[916]: info: CoreCommandRouter::volumioGetQueue Dec 05 20:06:58 volumio volumio[916]: info: CoreStateMachine::getQueue Dec 05 20:06:58 volumio volumio[916]: info: CorePlayQueue::getQueue Dec 05 20:07:02 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:02 volumio volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::ClearQueue Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:02 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::stPlaybackTimer Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:02 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:02 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:07:02 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::serviceStop Dec 05 20:07:02 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:02 volumio volumio[916]: info: CoreCommandRouter::serviceStop Dec 05 20:07:02 volumio volumio[916]: info: ControllerMpd::stop Dec 05 20:07:02 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 20:07:02 volumio volumio[916]: info: CorePlayQueue::clearPlayQueue Dec 05 20:07:02 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:02 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:02 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:02 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:02 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:02 volumio volumio[916]: info: Exploding uri music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac in service mpd Dec 05 20:07:02 volumio volumio[916]: info: ALBUMART /albumart?cacheid=108&web=Air/10000%20Hz%20Legend/extralarge&path=%2Fmnt%2FNAS%2FDS212%2FAir%20-%2010%20000%20Hz%20Legend%2F01%20-%20Electronic%20Performers.flac&metadata=false Dec 05 20:07:02 volumio volumio[916]: info: URI /mnt/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:02 volumio volumio[916]: info: sendMpdCommand stop took 167 milliseconds Dec 05 20:07:02 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:02 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:02 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:02 volumio volumio[916]: info: CoreCommandRouter::volumioPlay Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::play index 0 Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:02 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:02 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:02 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:02 volumio volumio[916]: info: Exploding uri music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac in service mpd Dec 05 20:07:02 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:02 volumio volumio[916]: info: Exploding uri music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac in service mpd Dec 05 20:07:02 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:02 volumio volumio[916]: info: Exploding uri music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac in service mpd Dec 05 20:07:02 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:02 volumio volumio[916]: info: Exploding uri music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac in service mpd Dec 05 20:07:02 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:02 volumio volumio[916]: info: Exploding uri music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac in service mpd Dec 05 20:07:02 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:02 volumio volumio[916]: info: Exploding uri music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac in service mpd Dec 05 20:07:02 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:02 volumio volumio[916]: info: Exploding uri music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac in service mpd Dec 05 20:07:02 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:02 volumio volumio[916]: info: Exploding uri music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac in service mpd Dec 05 20:07:02 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:02 volumio volumio[916]: info: Exploding uri music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac in service mpd Dec 05 20:07:02 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:02 volumio volumio[916]: info: Exploding uri music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac in service mpd Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::play index undefined Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:02 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:02 volumio volumio[916]: info: CoreStateMachine::startPlaybackTimer Dec 05 20:07:02 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:02 volumio volumio[916]: info: CoreCommandRouter::volumioGetVisibleSources Dec 05 20:07:02 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 05 20:07:02 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 05 20:07:02 volumio volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:02 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 20:07:02 volumio volumio[916]: info: ALBUMART /albumart?cacheid=108&web=Air/10000%20Hz%20Legend/extralarge&path=%2Fmnt%2FNAS%2FDS212%2FAir%20-%2010%20000%20Hz%20Legend%2F02%20-%20How%20Does%20It%20Make%20You%20Feel.flac&metadata=false Dec 05 20:07:02 volumio volumio[916]: info: URI /mnt/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:02 volumio volumio[916]: info: ALBUMART /albumart?cacheid=108&web=Air/10000%20Hz%20Legend/extralarge&path=%2Fmnt%2FNAS%2FDS212%2FAir%20-%2010%20000%20Hz%20Legend%2F03%20-%20Radio%20%231.flac&metadata=false Dec 05 20:07:02 volumio volumio[916]: info: URI /mnt/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:02 volumio volumio[916]: info: ALBUMART /albumart?cacheid=108&web=Air/10000%20Hz%20Legend/extralarge&path=%2Fmnt%2FNAS%2FDS212%2FAir%20-%2010%20000%20Hz%20Legend%2F04%20-%20The%20Vagabond.flac&metadata=false Dec 05 20:07:02 volumio volumio[916]: info: URI /mnt/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:02 volumio volumio[916]: info: ALBUMART /albumart?cacheid=108&web=Air/10000%20Hz%20Legend/extralarge&path=%2Fmnt%2FNAS%2FDS212%2FAir%20-%2010%20000%20Hz%20Legend%2F05%20-%20Radian.flac&metadata=false Dec 05 20:07:02 volumio volumio[916]: info: URI /mnt/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:02 volumio volumio[916]: info: ALBUMART /albumart?cacheid=108&web=Air/10000%20Hz%20Legend/extralarge&path=%2Fmnt%2FNAS%2FDS212%2FAir%20-%2010%20000%20Hz%20Legend%2F06%20-%20Lucky%20and%20Unhappy.flac&metadata=false Dec 05 20:07:02 volumio volumio[916]: info: URI /mnt/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:02 volumio volumio[916]: info: ALBUMART /albumart?cacheid=108&web=Air/10000%20Hz%20Legend/extralarge&path=%2Fmnt%2FNAS%2FDS212%2FAir%20-%2010%20000%20Hz%20Legend%2F07%20-%20Sex%20Born%20Poison.flac&metadata=false Dec 05 20:07:02 volumio volumio[916]: info: URI /mnt/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:02 volumio volumio[916]: info: ALBUMART /albumart?cacheid=108&web=Air/10000%20Hz%20Legend/extralarge&path=%2Fmnt%2FNAS%2FDS212%2FAir%20-%2010%20000%20Hz%20Legend%2F08%20-%20People%20in%20the%20City.flac&metadata=false Dec 05 20:07:02 volumio volumio[916]: info: URI /mnt/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:02 volumio volumio[916]: info: ALBUMART /albumart?cacheid=108&web=Air/10000%20Hz%20Legend/extralarge&path=%2Fmnt%2FNAS%2FDS212%2FAir%20-%2010%20000%20Hz%20Legend%2F09%20-%20Wonder%20Milky%20Bitch.flac&metadata=false Dec 05 20:07:02 volumio volumio[916]: info: URI /mnt/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:02 volumio volumio[916]: info: ALBUMART /albumart?cacheid=108&web=Air/10000%20Hz%20Legend/extralarge&path=%2Fmnt%2FNAS%2FDS212%2FAir%20-%2010%20000%20Hz%20Legend%2F10%20-%20Don't%20Be%20Light.flac&metadata=false Dec 05 20:07:02 volumio volumio[916]: info: URI /mnt/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:02 volumio volumio[916]: info: ALBUMART /albumart?cacheid=108&web=Air/10000%20Hz%20Legend/extralarge&path=%2Fmnt%2FNAS%2FDS212%2FAir%20-%2010%20000%20Hz%20Legend%2F11%20-%20Caramel%20Prisoner.flac&metadata=false Dec 05 20:07:02 volumio volumio[916]: info: URI /mnt/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:02 volumio volumio[916]: info: sendMpdCommand stop took 46 milliseconds Dec 05 20:07:02 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Dec 05 20:07:02 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:02 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:03 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:03 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:03 volumio volumio[916]: info: Dec 05 20:07:03 volumio volumio[916]: ---------------------------- MPD announces system playlist update Dec 05 20:07:03 volumio volumio[916]: info: Ignoring MPD Status Update Dec 05 20:07:03 volumio volumio[916]: info: sendMpdCommand clear took 113 milliseconds Dec 05 20:07:03 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand add "NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac" Dec 05 20:07:03 volumio volumio[916]: error: updateQueue error: null Dec 05 20:07:03 volumio volumio[916]: info: Dec 05 20:07:03 volumio volumio[916]: ---------------------------- MPD announces system playlist update Dec 05 20:07:03 volumio volumio[916]: info: Ignoring MPD Status Update Dec 05 20:07:03 volumio volumio[916]: info: ------------------------------ 30ms Dec 05 20:07:03 volumio volumio[916]: info: sendMpdCommand add "NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac" took 29 milliseconds Dec 05 20:07:03 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand play Dec 05 20:07:03 volumio volumio[916]: info: ------------------------------ 17ms Dec 05 20:07:03 volumio volumio[916]: info: sendMpdCommand play took 16 milliseconds Dec 05 20:07:03 volumio volumio[916]: info: Dec 05 20:07:03 volumio volumio[916]: ---------------------------- MPD announces state update: player Dec 05 20:07:03 volumio volumio[916]: info: ControllerMpd::getState Dec 05 20:07:03 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand status Dec 05 20:07:03 volumio kernel: usb 1-1.2: uac_clock_source_is_valid(): cannot get clock validity for id 40 Dec 05 20:07:03 volumio kernel: usb 1-1.2: clock source 40 is not valid, cannot use Dec 05 20:07:03 volumio kernel: usb 1-1.2: 1:1: cannot get freq (v2/v3): err -71 Dec 05 20:07:03 volumio kernel: usb 1-1.2: 1:1: cannot set freq 44100 (v2/v3): err -71 Dec 05 20:07:03 volumio volumio[916]: info: Dec 05 20:07:03 volumio volumio[916]: ---------------------------- MPD announces state update: player Dec 05 20:07:03 volumio volumio[916]: info: ControllerMpd::getState Dec 05 20:07:03 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand status Dec 05 20:07:03 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 05 20:07:03 volumio volumio[916]: info: sendMpdCommand status took 49 milliseconds Dec 05 20:07:03 volumio volumio[916]: verbose: ControllerMpd::parseState Dec 05 20:07:03 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 20:07:03 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 05 20:07:03 volumio volumio[916]: info: sendMpdCommand status took 97 milliseconds Dec 05 20:07:03 volumio volumio[916]: info: sendMpdCommand clearerror took 59 milliseconds Dec 05 20:07:03 volumio volumio[916]: info: sendMpdCommand playlistinfo took 66 milliseconds Dec 05 20:07:03 volumio volumio[916]: verbose: ControllerMpd::parseState Dec 05 20:07:03 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 20:07:03 volumio volumio[916]: verbose: ControllerMpd::parseTrackInfo Dec 05 20:07:03 volumio volumio[916]: info: ControllerMpd::pushState Dec 05 20:07:03 volumio volumio[916]: info: CoreCommandRouter::servicePushState Dec 05 20:07:03 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:03 volumio volumio[916]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":336,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Electronic Performers","artist":"Air","album":"10000 Hz Legend","uri":"NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac","trackType":"flac"} Dec 05 20:07:03 volumio volumio[916]: verbose: CURRENT POSITION 0 Dec 05 20:07:03 volumio volumio[916]: info: CoreStateMachine::syncState stateService pause Dec 05 20:07:03 volumio volumio[916]: info: CoreStateMachine::syncState currentStatus stop Dec 05 20:07:03 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:03 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:03 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:07:03 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:03 volumio kernel: usb 1-1.2: USB disconnect, device number 6 Dec 05 20:07:03 volumio volumio[916]: info: ------------------------------ 409ms Dec 05 20:07:03 volumio volumio[916]: info: sendMpdCommand clearerror took 402 milliseconds Dec 05 20:07:03 volumio volumio[916]: info: sendMpdCommand playlistinfo took 385 milliseconds Dec 05 20:07:03 volumio volumio[916]: verbose: ControllerMpd::parseTrackInfo Dec 05 20:07:03 volumio volumio[916]: info: ControllerMpd::pushState Dec 05 20:07:03 volumio volumio[916]: info: CoreCommandRouter::servicePushState Dec 05 20:07:03 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:03 volumio volumio[916]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":336,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Electronic Performers","artist":"Air","album":"10000 Hz Legend","uri":"NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac","trackType":"flac"} Dec 05 20:07:03 volumio volumio[916]: verbose: CURRENT POSITION 0 Dec 05 20:07:03 volumio volumio[916]: info: CoreStateMachine::syncState stateService pause Dec 05 20:07:03 volumio volumio[916]: info: CoreStateMachine::syncState currentStatus stop Dec 05 20:07:03 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:03 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:03 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:07:03 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:03 volumio volumio[916]: info: ------------------------------ 601ms Dec 05 20:07:03 volumio volumio[916]: info: Dec 05 20:07:03 volumio volumio[916]: ---------------------------- USB Audio Device Detached Dec 05 20:07:03 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 05 20:07:03 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 20:07:03 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 05 20:07:04 volumio kernel: usb 1-1.2: new high-speed USB device number 7 using dwc_otg Dec 05 20:07:04 volumio kernel: usb 1-1.2: New USB device found, idVendor=21b4, idProduct=0230, bcdDevice= 1.71 Dec 05 20:07:04 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 05 20:07:04 volumio kernel: usb 1-1.2: Product: Ayre USB Interface Dec 05 20:07:04 volumio kernel: usb 1-1.2: Manufacturer: Ayre Acoustics Dec 05 20:07:04 volumio kernel: usb 1-1.2: SerialNumber: Streamlength(tm) Dec 05 20:07:04 volumio volumio[916]: info: Dec 05 20:07:04 volumio volumio[916]: ---------------------------- USB Audio Device Attached Dec 05 20:07:04 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 05 20:07:04 volumio volumio[916]: info: CoreCommandRouter::Close All Modals sent Dec 05 20:07:04 volumio volumio[916]: info: Preparing to save Alsa Options, stopping services first Dec 05 20:07:04 volumio volumio[916]: info: CoreCommandRouter::volumioGetState Dec 05 20:07:04 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:04 volumio volumio[916]: info: CoreCommandRouter::volumioPause Dec 05 20:07:04 volumio volumio[916]: info: CoreStateMachine::pause Dec 05 20:07:04 volumio volumio[916]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Ayre USB Interface","alsacard":"Interface"},"i2s":false} Dec 05 20:07:04 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 20:07:04 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 20:07:05 volumio volumio[916]: info: Device 5 does not have any Mixer Control Available Dec 05 20:07:05 volumio vtcs[1837]: [2025-12-05 20:07:05.014] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 05 20:07:05 volumio vtcs[1837]: [2025-12-05 20:07:05.014] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 05 20:07:05 volumio sudo[2037]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 20:07:05 volumio sudo[2037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 05 20:07:05 volumio volumio[916]: info: Updating Volume Controller Parameters: Device: 5 Name: Ayre USB Interface Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 05 20:07:05 volumio volumio[916]: info: Disabling external Volume Control Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 20:07:05 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 05 20:07:05 volumio systemd[1]: vtcs.service: Killing process 1852 (n/a) with signal SIGKILL. Dec 05 20:07:05 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 05 20:07:05 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:05 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:05 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:05 volumio sudo[2037]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:05 volumio volumio[916]: info: Preparing to generate the ALSA configuration file Dec 05 20:07:05 volumio sudo[2045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 20:07:05 volumio sudo[2045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 20:07:05 volumio sudo[2045]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 05 20:07:05 volumio volumio[916]: info: Asound.conf file unchanged, so no further update is needed Dec 05 20:07:05 volumio volumio[916]: info: Output device has changed, restarting MPD Dec 05 20:07:05 volumio volumio[916]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:05 volumio sudo[2068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 05 20:07:05 volumio sudo[2068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:07:05 volumio sudo[2070]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 05 20:07:05 volumio sudo[2070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 05 20:07:05 volumio sudo[2068]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:05 volumio sudo[2074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 20:07:05 volumio volumio[916]: info: MPD Permissions set Dec 05 20:07:05 volumio sudo[2074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:05 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:05 volumio sudo[2077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 20:07:05 volumio sudo[2077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:07:05 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 05 20:07:06 volumio sudo[2074]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:06 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 05 20:07:06 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 05 20:07:06 volumio systemd[1]: mpd.service: Consumed 3.932s CPU time. Dec 05 20:07:06 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 05 20:07:06 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 05 20:07:06 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 05 20:07:06 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 20:07:06 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 05 20:07:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 05 20:07:06 volumio sudo[2077]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:06 volumio sudo[2088]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 05 20:07:06 volumio sudo[2088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 05 20:07:06 volumio sudo[2088]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:07 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:07 volumio volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 05 20:07:07 volumio volumio[916]: info: CoreStateMachine::ClearQueue Dec 05 20:07:07 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:07 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:07 volumio volumio[916]: info: CorePlayQueue::clearPlayQueue Dec 05 20:07:07 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:07 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:07 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:07 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:07 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:07 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:07 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:07 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:07 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:07 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:07 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:07 volumio volumio[916]: info: CoreCommandRouter::volumioPlay Dec 05 20:07:07 volumio volumio[916]: info: CoreStateMachine::play index 0 Dec 05 20:07:07 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:07 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:07 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:07 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:07 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:07 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:07 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:07 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:07 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:07 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:07 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:07 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:07 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:07 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:07 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:07 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:07 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:07 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:07 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:07 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:07 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:07 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:07 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:07 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:07 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:07 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:07 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:07 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:07 volumio volumio[916]: info: CoreStateMachine::play index undefined Dec 05 20:07:07 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:07 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:07 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:07 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:07 volumio volumio[916]: info: CoreStateMachine::startPlaybackTimer Dec 05 20:07:07 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:07 volumio volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:07 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 20:07:08 volumio volumio[916]: info: TidalConnect service stoped! Dec 05 20:07:08 volumio volumio[916]: info: TidalConnect service stoped! Dec 05 20:07:08 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:08 volumio volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 05 20:07:08 volumio volumio[916]: info: CoreStateMachine::ClearQueue Dec 05 20:07:08 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:08 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:08 volumio volumio[916]: info: CorePlayQueue::clearPlayQueue Dec 05 20:07:08 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:08 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:08 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:08 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:08 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:08 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:08 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:08 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:08 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:08 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:08 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:08 volumio volumio[916]: info: CoreCommandRouter::volumioPlay Dec 05 20:07:08 volumio volumio[916]: info: CoreStateMachine::play index 0 Dec 05 20:07:08 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:08 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:08 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:08 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:08 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:08 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:08 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:08 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:08 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:08 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:08 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:08 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:08 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:08 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:08 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:08 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:08 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:08 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:08 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:08 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:08 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:08 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:08 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:08 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:08 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:08 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:08 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::play index undefined Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::startPlaybackTimer Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:09 volumio volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:09 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 20:07:09 volumio volumio[916]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::getcurrentVolume Dec 05 20:07:09 volumio volumio[916]: info: CoreCommandRouter::volumioRetrievevolume Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:09 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:07:09 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:09 volumio volumio[916]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 05 20:07:09 volumio volumio[916]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 05 20:07:09 volumio volumio[916]: info: TidalConnect service stoped! Dec 05 20:07:09 volumio volumio[916]: info: TidalConnect service stoped! Dec 05 20:07:09 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:09 volumio volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::ClearQueue Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::clearPlayQueue Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:09 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:09 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:09 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:09 volumio volumio[916]: info: CoreCommandRouter::volumioPlay Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::play index 0 Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:09 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:09 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::play index undefined Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::startPlaybackTimer Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:09 volumio volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:09 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 20:07:09 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:09 volumio volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::ClearQueue Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::clearPlayQueue Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:09 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:09 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:09 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:09 volumio volumio[916]: info: CoreCommandRouter::volumioPlay Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::play index 0 Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:09 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:09 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:09 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:09 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::play index undefined Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:09 volumio volumio[916]: info: CoreStateMachine::startPlaybackTimer Dec 05 20:07:09 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:10 volumio volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:10 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 20:07:10 volumio mpd[2091]: 2025-12-05T20:07:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 05 20:07:10 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 05 20:07:10 volumio sudo[2070]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:10 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::ClearQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::clearPlayQueue Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:10 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:10 volumio sudo[2113]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 05 20:07:10 volumio sudo[2113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioPlay Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::play index 0 Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:10 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::play index undefined Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::startPlaybackTimer Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:10 volumio volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:10 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 20:07:10 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::ClearQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::clearPlayQueue Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:10 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 05 20:07:10 volumio sudo[2113]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:10 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioPlay Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::play index 0 Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:10 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::play index undefined Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::startPlaybackTimer Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:10 volumio volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:10 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 20:07:10 volumio volumio[916]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 05 20:07:10 volumio volumio[916]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 05 20:07:10 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::ClearQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::clearPlayQueue Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:10 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:10 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::ClearQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::clearPlayQueue Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:10 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioPlay Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::play index 0 Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:10 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioPlay Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::play index 0 Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:10 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:10 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:10 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:10 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:10 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::play index undefined Dec 05 20:07:10 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::play index undefined Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::startPlaybackTimer Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::startPlaybackTimer Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 20:07:11 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::ClearQueue Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::clearPlayQueue Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:11 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:11 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::ClearQueue Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::clearPlayQueue Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:11 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::volumioPlay Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::play index 0 Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:11 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::volumioPlay Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::play index 0 Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:11 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::play index undefined Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::play index undefined Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::startPlaybackTimer Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::startPlaybackTimer Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 20:07:11 volumio volumio[916]: info: Executing endpoint tc_getconfig Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 05 20:07:11 volumio vtcs[2116]: STARTING TidalConnect services, version: 1.5.2.56 Dec 05 20:07:11 volumio vtcs[2116]: STARTED TidalConnect services. Dec 05 20:07:11 volumio volumio[916]: error: updateQueue error: null Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand stop took 518 milliseconds Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand stop took 517 milliseconds Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand stop took 130 milliseconds Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand stop took 110 milliseconds Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Dec 05 20:07:11 volumio volumio[916]: info: Dec 05 20:07:11 volumio volumio[916]: ---------------------------- MPD announces system playlist update Dec 05 20:07:11 volumio volumio[916]: info: Ignoring MPD Status Update Dec 05 20:07:11 volumio volumio[916]: info: Dec 05 20:07:11 volumio volumio[916]: ---------------------------- MPD announces system playlist update Dec 05 20:07:11 volumio volumio[916]: info: Ignoring MPD Status Update Dec 05 20:07:11 volumio volumio[916]: info: Dec 05 20:07:11 volumio volumio[916]: ---------------------------- MPD announces system playlist update Dec 05 20:07:11 volumio volumio[916]: info: Ignoring MPD Status Update Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand clear took 64 milliseconds Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand clear took 63 milliseconds Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand clear took 64 milliseconds Dec 05 20:07:11 volumio sudo[2125]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 05 20:07:11 volumio sudo[2125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand clear took 64 milliseconds Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand add "NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac" Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand add "NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac" Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand add "NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac" Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand add "NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac" Dec 05 20:07:11 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::ClearQueue Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::clearPlayQueue Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:11 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::volumioPlay Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::play index 0 Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:11 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:11 volumio sudo[2125]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:11 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:11 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::play index undefined Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::startPlaybackTimer Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 20:07:11 volumio volumio[916]: error: updateQueue error: null Dec 05 20:07:11 volumio volumio[916]: error: updateQueue error: null Dec 05 20:07:11 volumio volumio[916]: error: updateQueue error: null Dec 05 20:07:11 volumio volumio[916]: info: Dec 05 20:07:11 volumio volumio[916]: ---------------------------- MPD announces system playlist update Dec 05 20:07:11 volumio volumio[916]: info: Ignoring MPD Status Update Dec 05 20:07:11 volumio volumio[916]: info: Dec 05 20:07:11 volumio volumio[916]: ---------------------------- MPD announces system playlist update Dec 05 20:07:11 volumio volumio[916]: info: Ignoring MPD Status Update Dec 05 20:07:11 volumio volumio[916]: info: Dec 05 20:07:11 volumio volumio[916]: ---------------------------- MPD announces system playlist update Dec 05 20:07:11 volumio volumio[916]: info: Ignoring MPD Status Update Dec 05 20:07:11 volumio volumio[916]: info: Dec 05 20:07:11 volumio volumio[916]: ---------------------------- MPD announces system playlist update Dec 05 20:07:11 volumio volumio[916]: info: Ignoring MPD Status Update Dec 05 20:07:11 volumio volumio[916]: info: ------------------------------ 214ms Dec 05 20:07:11 volumio volumio[916]: info: ------------------------------ 214ms Dec 05 20:07:11 volumio volumio[916]: info: ------------------------------ 214ms Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand add "NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac" took 211 milliseconds Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand add "NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac" took 211 milliseconds Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand add "NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac" took 210 milliseconds Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand add "NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac" took 211 milliseconds Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand stop took 30 milliseconds Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand play Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand play Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand play Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand play Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Dec 05 20:07:11 volumio volumio[916]: info: Executing endpoint tc_connect Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 05 20:07:11 volumio volumio[916]: info: Connecting to TidalConnect Dec 05 20:07:11 volumio volumio[916]: info: Dec 05 20:07:11 volumio volumio[916]: ---------------------------- MPD announces system playlist update Dec 05 20:07:11 volumio volumio[916]: info: Ignoring MPD Status Update Dec 05 20:07:11 volumio volumio[916]: info: Dec 05 20:07:11 volumio volumio[916]: ---------------------------- MPD announces state update: player Dec 05 20:07:11 volumio volumio[916]: info: ------------------------------ 107ms Dec 05 20:07:11 volumio volumio[916]: info: ------------------------------ 89ms Dec 05 20:07:11 volumio volumio[916]: info: ------------------------------ 89ms Dec 05 20:07:11 volumio volumio[916]: info: ------------------------------ 88ms Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand play took 81 milliseconds Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand play took 82 milliseconds Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand play took 82 milliseconds Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand play took 81 milliseconds Dec 05 20:07:11 volumio volumio[916]: info: sendMpdCommand clear took 81 milliseconds Dec 05 20:07:11 volumio volumio[916]: info: ControllerMpd::getState Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand status Dec 05 20:07:11 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand add "NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac" Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::servicePushState Dec 05 20:07:11 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:11 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:07:11 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:12 volumio volumio[916]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::servicePushState Dec 05 20:07:12 volumio kernel: usb 1-1.2: USB disconnect, device number 7 Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:12 volumio volumio[916]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 05 20:07:12 volumio volumio[916]: error: updateQueue error: null Dec 05 20:07:12 volumio volumio[916]: info: Dec 05 20:07:12 volumio volumio[916]: ---------------------------- MPD announces system playlist update Dec 05 20:07:12 volumio volumio[916]: info: Ignoring MPD Status Update Dec 05 20:07:12 volumio volumio[916]: info: ------------------------------ 268ms Dec 05 20:07:12 volumio volumio[916]: info: sendMpdCommand status took 266 milliseconds Dec 05 20:07:12 volumio volumio[916]: info: sendMpdCommand add "NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac" took 266 milliseconds Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::parseState Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand play Dec 05 20:07:12 volumio volumio[916]: info: ControllerMpd::pushState Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::servicePushState Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:12 volumio volumio[916]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 05 20:07:12 volumio volumio[916]: verbose: CURRENT POSITION 0 Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::syncState stateService stop Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::syncState currentStatus stop Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:12 volumio volumio[916]: info: No code Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:12 volumio kernel: usb 1-1.2: new high-speed USB device number 8 using dwc_otg Dec 05 20:07:12 volumio volumio[916]: info: ------------------------------ 480ms Dec 05 20:07:12 volumio volumio[916]: info: Dec 05 20:07:12 volumio volumio[916]: ---------------------------- MPD announces state update: player Dec 05 20:07:12 volumio volumio[916]: info: ------------------------------ 511ms Dec 05 20:07:12 volumio volumio[916]: info: sendMpdCommand play took 498 milliseconds Dec 05 20:07:12 volumio volumio[916]: info: ControllerMpd::getState Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand status Dec 05 20:07:12 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::ClearQueue Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::clearPlayQueue Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:12 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:12 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:12 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::volumioPlay Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::play index 0 Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::addQueueItems Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::addQueueItems Dec 05 20:07:12 volumio volumio[916]: info: Preload queue cleared Dec 05 20:07:12 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:12 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/02 - How Does It Make You Feel.flac Dec 05 20:07:12 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:12 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/03 - Radio #1.flac Dec 05 20:07:12 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:12 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/04 - The Vagabond.flac Dec 05 20:07:12 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:12 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/05 - Radian.flac Dec 05 20:07:12 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:12 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/06 - Lucky and Unhappy.flac Dec 05 20:07:12 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:12 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/07 - Sex Born Poison.flac Dec 05 20:07:12 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:12 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/08 - People in the City.flac Dec 05 20:07:12 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:12 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/09 - Wonder Milky Bitch.flac Dec 05 20:07:12 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:12 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/10 - Don't Be Light.flac Dec 05 20:07:12 volumio volumio[916]: info: Adding Item to queue: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:12 volumio volumio[916]: info: Using cached record of: music-library/NAS/DS212/Air - 10 000 Hz Legend/11 - Caramel Prisoner.flac Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::stop Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::volumioPushQueue Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::saveQueue Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::play index undefined Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::updateTrackBlock Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::getTrackBlock Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::startPlaybackTimer Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 20:07:12 volumio volumio[916]: info: Dec 05 20:07:12 volumio volumio[916]: ---------------------------- MPD announces state update: player Dec 05 20:07:12 volumio volumio[916]: info: Dec 05 20:07:12 volumio volumio[916]: ---------------------------- MPD announces state update: player Dec 05 20:07:12 volumio volumio[916]: info: ControllerMpd::getState Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand status Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 05 20:07:12 volumio volumio[916]: info: sendMpdCommand status took 98 milliseconds Dec 05 20:07:12 volumio volumio[916]: info: sendMpdCommand stop took 27 milliseconds Dec 05 20:07:12 volumio volumio[916]: info: ControllerMpd::getState Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand status Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::parseState Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Dec 05 20:07:12 volumio volumio[916]: info: Dec 05 20:07:12 volumio volumio[916]: ---------------------------- MPD announces system playlist update Dec 05 20:07:12 volumio volumio[916]: info: Ignoring MPD Status Update Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 05 20:07:12 volumio volumio[916]: info: sendMpdCommand status took 37 milliseconds Dec 05 20:07:12 volumio volumio[916]: info: sendMpdCommand clearerror took 27 milliseconds Dec 05 20:07:12 volumio volumio[916]: info: sendMpdCommand status took 27 milliseconds Dec 05 20:07:12 volumio volumio[916]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 05 20:07:12 volumio volumio[916]: info: sendMpdCommand clear took 26 milliseconds Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::parseState Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::parseState Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::parseTrackInfo Dec 05 20:07:12 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand add "NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac" Dec 05 20:07:12 volumio volumio[916]: info: ControllerMpd::pushState Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::servicePushState Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:12 volumio volumio[916]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":336,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Electronic Performers","artist":"Air","album":"10000 Hz Legend","uri":"NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac","trackType":"flac"} Dec 05 20:07:12 volumio volumio[916]: verbose: CURRENT POSITION 0 Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::syncState stateService pause Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::syncState currentStatus stop Dec 05 20:07:12 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:12 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:12 volumio volumio[916]: info: ------------------------------ 180ms Dec 05 20:07:12 volumio volumio[916]: info: Dec 05 20:07:12 volumio volumio[916]: ---------------------------- USB Audio Device Detached Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 20:07:12 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 05 20:07:13 volumio volumio[916]: error: updateQueue error: null Dec 05 20:07:13 volumio volumio[916]: info: Dec 05 20:07:13 volumio volumio[916]: ---------------------------- MPD announces system playlist update Dec 05 20:07:13 volumio volumio[916]: info: Ignoring MPD Status Update Dec 05 20:07:13 volumio volumio[916]: info: sendMpdCommand clearerror took 187 milliseconds Dec 05 20:07:13 volumio volumio[916]: info: ------------------------------ 227ms Dec 05 20:07:13 volumio volumio[916]: info: sendMpdCommand playlistinfo took 214 milliseconds Dec 05 20:07:13 volumio volumio[916]: info: sendMpdCommand playlistinfo took 215 milliseconds Dec 05 20:07:13 volumio volumio[916]: info: sendMpdCommand add "NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac" took 214 milliseconds Dec 05 20:07:13 volumio volumio[916]: verbose: ControllerMpd::parseTrackInfo Dec 05 20:07:13 volumio volumio[916]: verbose: ControllerMpd::parseTrackInfo Dec 05 20:07:13 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand play Dec 05 20:07:13 volumio volumio[916]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 05 20:07:13 volumio volumio[916]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 05 20:07:13 volumio volumio[916]: info: ------------------------------ 273ms Dec 05 20:07:13 volumio volumio[916]: info: ------------------------------ 273ms Dec 05 20:07:13 volumio kernel: usb 1-1.2: device descriptor read/64, error -71 Dec 05 20:07:13 volumio volumio[916]: info: Dec 05 20:07:13 volumio volumio[916]: ---------------------------- MPD announces state update: player Dec 05 20:07:13 volumio volumio[916]: info: ------------------------------ 179ms Dec 05 20:07:13 volumio volumio[916]: info: sendMpdCommand play took 148 milliseconds Dec 05 20:07:13 volumio volumio[916]: info: ControllerMpd::getState Dec 05 20:07:13 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand status Dec 05 20:07:13 volumio volumio[916]: info: Dec 05 20:07:13 volumio volumio[916]: ---------------------------- MPD announces state update: player Dec 05 20:07:13 volumio volumio[916]: info: ControllerMpd::getState Dec 05 20:07:13 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand status Dec 05 20:07:13 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 05 20:07:13 volumio volumio[916]: info: sendMpdCommand status took 71 milliseconds Dec 05 20:07:13 volumio volumio[916]: verbose: ControllerMpd::parseState Dec 05 20:07:13 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 20:07:13 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 05 20:07:13 volumio volumio[916]: info: sendMpdCommand status took 128 milliseconds Dec 05 20:07:13 volumio volumio[916]: info: sendMpdCommand clearerror took 76 milliseconds Dec 05 20:07:13 volumio volumio[916]: info: sendMpdCommand playlistinfo took 70 milliseconds Dec 05 20:07:13 volumio volumio[916]: verbose: ControllerMpd::parseState Dec 05 20:07:13 volumio volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 20:07:13 volumio volumio[916]: verbose: ControllerMpd::parseTrackInfo Dec 05 20:07:13 volumio volumio[916]: info: ControllerMpd::pushState Dec 05 20:07:13 volumio volumio[916]: info: CoreCommandRouter::servicePushState Dec 05 20:07:13 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:13 volumio volumio[916]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":336,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Electronic Performers","artist":"Air","album":"10000 Hz Legend","uri":"NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac","trackType":"flac"} Dec 05 20:07:13 volumio volumio[916]: verbose: CURRENT POSITION 0 Dec 05 20:07:13 volumio volumio[916]: info: CoreStateMachine::syncState stateService pause Dec 05 20:07:13 volumio volumio[916]: info: CoreStateMachine::syncState currentStatus stop Dec 05 20:07:13 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:13 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:13 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:07:13 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:13 volumio volumio[916]: info: ------------------------------ 318ms Dec 05 20:07:13 volumio volumio[916]: info: sendMpdCommand clearerror took 229 milliseconds Dec 05 20:07:13 volumio volumio[916]: info: sendMpdCommand playlistinfo took 211 milliseconds Dec 05 20:07:13 volumio volumio[916]: verbose: ControllerMpd::parseTrackInfo Dec 05 20:07:13 volumio volumio[916]: info: ControllerMpd::pushState Dec 05 20:07:13 volumio volumio[916]: info: CoreCommandRouter::servicePushState Dec 05 20:07:13 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:13 volumio volumio[916]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":336,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Electronic Performers","artist":"Air","album":"10000 Hz Legend","uri":"NAS/DS212/Air - 10 000 Hz Legend/01 - Electronic Performers.flac","trackType":"flac"} Dec 05 20:07:13 volumio volumio[916]: verbose: CURRENT POSITION 0 Dec 05 20:07:13 volumio volumio[916]: info: CoreStateMachine::syncState stateService pause Dec 05 20:07:13 volumio volumio[916]: info: CoreStateMachine::syncState currentStatus stop Dec 05 20:07:13 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:13 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:13 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:07:13 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:13 volumio volumio[916]: info: ------------------------------ 405ms Dec 05 20:07:13 volumio volumio[916]: info: TidalConnect service started! Dec 05 20:07:13 volumio sudo[2150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 05 20:07:13 volumio sudo[2150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:07:13 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 05 20:07:13 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 05 20:07:13 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 05 20:07:13 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 05 20:07:13 volumio mpd_monitor.sh[2152]: MPD Monitor Service: Starting MPD Monitor Service Dec 05 20:07:13 volumio sudo[2150]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:13 volumio kernel: usb 1-1.2: device descriptor read/64, error -71 Dec 05 20:07:13 volumio volumio[916]: info: Successfully started MPD Monitor Dec 05 20:07:14 volumio kernel: usb 1-1.2: new high-speed USB device number 9 using dwc_otg Dec 05 20:07:14 volumio kernel: usb 1-1.2: New USB device found, idVendor=21b4, idProduct=0230, bcdDevice= 1.71 Dec 05 20:07:14 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 05 20:07:14 volumio kernel: usb 1-1.2: Product: Ayre USB Interface Dec 05 20:07:14 volumio kernel: usb 1-1.2: Manufacturer: Ayre Acoustics Dec 05 20:07:14 volumio kernel: usb 1-1.2: SerialNumber: Streamlength(tm) Dec 05 20:07:14 volumio volumio[916]: info: Dec 05 20:07:14 volumio volumio[916]: ---------------------------- USB Audio Device Attached Dec 05 20:07:14 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 05 20:07:14 volumio volumio[916]: info: CoreCommandRouter::Close All Modals sent Dec 05 20:07:14 volumio volumio[916]: info: Preparing to save Alsa Options, stopping services first Dec 05 20:07:14 volumio volumio[916]: info: CoreCommandRouter::volumioGetState Dec 05 20:07:14 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:14 volumio volumio[916]: info: CoreCommandRouter::volumioPause Dec 05 20:07:14 volumio volumio[916]: info: CoreStateMachine::pause Dec 05 20:07:14 volumio volumio[916]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Ayre USB Interface","alsacard":"Interface"},"i2s":false} Dec 05 20:07:14 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 20:07:14 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 20:07:14 volumio volumio[916]: info: Device 5 does not have any Mixer Control Available Dec 05 20:07:14 volumio vtcs[2116]: [2025-12-05 20:07:14.905] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 05 20:07:14 volumio vtcs[2116]: [2025-12-05 20:07:14.908] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Dec 05 20:07:14 volumio vtcs[2116]: [2025-12-05 20:07:14.908] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 05 20:07:14 volumio sudo[2175]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 20:07:14 volumio sudo[2175]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 05 20:07:15 volumio volumio[916]: info: Updating Volume Controller Parameters: Device: 5 Name: Ayre USB Interface Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 05 20:07:15 volumio volumio[916]: info: Disabling external Volume Control Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 20:07:15 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:15 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:15 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 05 20:07:15 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 05 20:07:15 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 05 20:07:15 volumio sudo[2175]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:15 volumio volumio[916]: info: Preparing to generate the ALSA configuration file Dec 05 20:07:15 volumio volumio[916]: info: TidalConnect service started! Dec 05 20:07:15 volumio sudo[2179]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 20:07:15 volumio sudo[2179]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:07:15 volumio sudo[2179]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 05 20:07:15 volumio volumio[916]: info: Asound.conf file unchanged, so no further update is needed Dec 05 20:07:15 volumio volumio[916]: info: Output device has changed, restarting MPD Dec 05 20:07:15 volumio volumio[916]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:15 volumio sudo[2191]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 05 20:07:15 volumio sudo[2191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:07:15 volumio sudo[2191]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 05 20:07:15 volumio sudo[2201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 05 20:07:15 volumio volumio[916]: info: MPD Permissions set Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:15 volumio sudo[2206]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 20:07:15 volumio sudo[2206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:07:15 volumio sudo[2211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 20:07:15 volumio sudo[2201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:07:15 volumio sudo[2211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 20:07:15 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 20:07:15 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 05 20:07:15 volumio sudo[2206]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:16 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 05 20:07:16 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 05 20:07:16 volumio systemd[1]: mpd.service: Consumed 4.466s CPU time. Dec 05 20:07:16 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 05 20:07:16 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 05 20:07:16 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 05 20:07:16 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 05 20:07:16 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 05 20:07:16 volumio sudo[2211]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:16 volumio sudo[2223]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 05 20:07:16 volumio sudo[2223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 05 20:07:16 volumio sudo[2223]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:18 volumio volumio[916]: info: TidalConnect service stoped! Dec 05 20:07:18 volumio volumio[916]: info: TidalConnect service stoped! Dec 05 20:07:18 volumio volumio[916]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 05 20:07:18 volumio volumio[916]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 05 20:07:18 volumio sudo[2236]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 05 20:07:18 volumio sudo[2236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 20:07:19 volumio volumio[916]: info: TidalConnect service stoped! Dec 05 20:07:19 volumio volumio[916]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 05 20:07:19 volumio volumio[916]: info: CoreStateMachine::getcurrentVolume Dec 05 20:07:19 volumio volumio[916]: info: CoreCommandRouter::volumioRetrievevolume Dec 05 20:07:19 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:19 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:19 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:07:19 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:19 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 05 20:07:19 volumio sudo[2236]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:19 volumio volumio[916]: info: TidalConnect service stoped! Dec 05 20:07:19 volumio volumio[916]: info: Executing endpoint tc_getconfig Dec 05 20:07:19 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 05 20:07:19 volumio vtcs[2239]: STARTING TidalConnect services, version: 1.5.2.56 Dec 05 20:07:19 volumio vtcs[2239]: STARTED TidalConnect services. Dec 05 20:07:19 volumio volumio[916]: info: Executing endpoint tc_connect Dec 05 20:07:19 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 05 20:07:19 volumio volumio[916]: info: Connecting to TidalConnect Dec 05 20:07:19 volumio volumio[916]: info: CoreCommandRouter::servicePushState Dec 05 20:07:19 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:19 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:19 volumio volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 20:07:19 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:19 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:19 volumio volumio[916]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 05 20:07:19 volumio volumio[916]: info: CoreCommandRouter::servicePushState Dec 05 20:07:19 volumio volumio[916]: info: CoreStateMachine::pushState Dec 05 20:07:19 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:19 volumio volumio[916]: info: CoreCommandRouter::volumioPushState Dec 05 20:07:19 volumio volumio[916]: info: CorePlayQueue::getTrack 0 Dec 05 20:07:19 volumio volumio[916]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 05 20:07:19 volumio volumio[916]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 05 20:07:19 volumio volumio[916]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 05 20:07:19 volumio mpd[2226]: 2025-12-05T20:07:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 05 20:07:19 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 05 20:07:19 volumio sudo[2201]: pam_unix(sudo:session): session closed for user root Dec 05 20:07:20 volumio volumio[916]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 05 20:07:20 volumio volumio[916]: Error: ENOENT: no such file or directory, unlink '/tmp/XBkEiYlQb3y6BduJ9epxeKgAvswqtbO8975wNk3leaHcVTQXKduHtmn3OjFBEcy41X07iI6eCmzLzrWimPJDUbe7qDZAOOxoJJhE' Dec 05 20:07:20 volumio volumio[916]: at Object.unlinkSync (node:fs:1899:3) Dec 05 20:07:20 volumio volumio[916]: at ControllerTidalconnect.deleteCertificate (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:11238) Dec 05 20:07:20 volumio volumio[916]: at TidalConnectClient. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:2774) Dec 05 20:07:20 volumio volumio[916]: at TidalConnectClient.emit (node:events:514:28) Dec 05 20:07:20 volumio volumio[916]: at TidalConnectClient.parseData (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:44661) Dec 05 20:07:20 volumio volumio[916]: at /myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41631 Dec 05 20:07:20 volumio volumio[916]: at Array.forEach () Dec 05 20:07:20 volumio volumio[916]: at Socket. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41509) Dec 05 20:07:20 volumio volumio[916]: at Socket.emit (node:events:514:28) Dec 05 20:07:20 volumio volumio[916]: at addChunk (node:internal/streams/readable:343:12) { Dec 05 20:07:20 volumio volumio[916]: errno: -2, Dec 05 20:07:20 volumio volumio[916]: syscall: 'unlink', Dec 05 20:07:20 volumio volumio[916]: code: 'ENOENT', Dec 05 20:07:20 volumio volumio[916]: path: '/tmp/XBkEiYlQb3y6BduJ9epxeKgAvswqtbO8975wNk3leaHcVTQXKduHtmn3OjFBEcy41X07iI6eCmzLzrWimPJDUbe7qDZAOOxoJJhE' Dec 05 20:07:20 volumio volumio[916]: } Dec 05 20:07:20 volumio volumio[916]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 05 20:07:29 volumio sudo[2294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-05 20:06' Dec 05 20:07:29 volumio sudo[2294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="3a3cfe435f8bb2bc94a920662e91a4ef35bc8e7c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 2 11:51:19 UTC 2025" VOLUMIO_VERSION="4.072" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e5001581b220b096097cf8f46a54d4f1"