-- Logs begin at Thu 2019-02-14 16:11:59 +06, end at Wed 2025-02-19 14:29:40 +06. -- Feb 19 14:28:00 volumiohome systemd[1]: owntone.service: Service RestartSec=5s expired, scheduling restart. Feb 19 14:28:00 volumiohome systemd[1]: owntone.service: Scheduled restart job, restart counter is at 8. Feb 19 14:28:00 volumiohome systemd[1]: Stopped DAAP/DACP (iTunes), RSP and MPD server, supports AirPlay and Remote. Feb 19 14:28:00 volumiohome volumio[1079]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 14:28:00 volumiohome volumio[1079]: info: Output device has changed, restarting MPD Feb 19 14:28:00 volumiohome systemd[1]: Started DAAP/DACP (iTunes), RSP and MPD server, supports AirPlay and Remote. Feb 19 14:28:00 volumiohome sudo[2101]: volumio : unable to resolve host volumiohome Feb 19 14:28:00 volumiohome volumio[1079]: info: Output device has changed, restarting Shairport Sync Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 14:28:00 volumiohome systemd[1]: Stopping Music Player Daemon... Feb 19 14:28:00 volumiohome sudo[2101]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:00 volumiohome sudo[2101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 14:28:00 volumiohome sudo[2101]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:00 volumiohome sudo[2101]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:00 volumiohome sudo[2103]: volumio : unable to resolve host volumiohome Feb 19 14:28:00 volumiohome sudo[2103]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:00 volumiohome sudo[2103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 14:28:00 volumiohome sudo[2103]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:00 volumiohome systemd[1]: mpd.service: Succeeded. Feb 19 14:28:00 volumiohome systemd[1]: Stopped Music Player Daemon. Feb 19 14:28:00 volumiohome volumio[1079]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 14:28:00 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:28:00 volumiohome volumio[1079]: info: MPD Permissions set Feb 19 14:28:00 volumiohome volumio[1079]: info: MPD Permissions set Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:00 volumiohome volumio[1079]: info: Starting Shairport Sync Feb 19 14:28:00 volumiohome volumio[1079]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:28:00 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:00 volumiohome volumio[1079]: info: mpdhttpout --- Volumio set on pause Feb 19 14:28:00 volumiohome volumio[1079]: info: Starting Shairport Sync Feb 19 14:28:00 volumiohome volumio[1079]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Feb 19 14:28:00 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:28:00 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:00 volumiohome volumio[1079]: info: mpdhttpout --- Volumio set on pause Feb 19 14:28:00 volumiohome owntone[2099]: [2025-02-19 14:28:00] [ LOG] main: OwnTone version 28.11 taking off Feb 19 14:28:00 volumiohome owntone[2099]: [2025-02-19 14:28:00] [ LOG] main: Built with: Feb 19 14:28:00 volumiohome owntone[2099]: [2025-02-19 14:28:00] [ LOG] main: - ffmpeg Feb 19 14:28:00 volumiohome owntone[2099]: [2025-02-19 14:28:00] [ LOG] main: - Spotify Feb 19 14:28:00 volumiohome owntone[2099]: [2025-02-19 14:28:00] [ LOG] main: - librespot-c Feb 19 14:28:00 volumiohome owntone[2099]: [2025-02-19 14:28:00] [ LOG] main: - LastFM Feb 19 14:28:00 volumiohome owntone[2099]: [2025-02-19 14:28:00] [ LOG] main: - Chromecast Feb 19 14:28:00 volumiohome owntone[2099]: [2025-02-19 14:28:00] [ LOG] main: - MPD Feb 19 14:28:00 volumiohome owntone[2099]: [2025-02-19 14:28:00] [ LOG] main: - Websockets Feb 19 14:28:00 volumiohome owntone[2099]: [2025-02-19 14:28:00] [ LOG] main: - ALSA Feb 19 14:28:00 volumiohome owntone[2099]: [2025-02-19 14:28:00] [ LOG] main: - Pulseaudio Feb 19 14:28:00 volumiohome owntone[2099]: [2025-02-19 14:28:00] [ LOG] main: - Webinterface Feb 19 14:28:00 volumiohome owntone[2099]: [2025-02-19 14:28:00] [ LOG] main: - Regex Feb 19 14:28:00 volumiohome owntone[2099]: [2025-02-19 14:28:00] [ LOG] main: mDNS init Feb 19 14:28:00 volumiohome owntone[2099]: [2025-02-19 14:28:00] [ LOG] mdns: Avahi state change: Client running Feb 19 14:28:00 volumiohome owntone[2099]: [2025-02-19 14:28:00] [ LOG] db: Now vacuuming database, this may take some time... Feb 19 14:28:01 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:28:01 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:01 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Feb 19 14:28:01 volumiohome sudo[2124]: volumio : unable to resolve host volumiohome Feb 19 14:28:01 volumiohome sudo[2124]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:01 volumiohome sudo[2124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 14:28:01 volumiohome sudo[2124]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:02 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Feb 19 14:28:02 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Feb 19 14:28:02 volumiohome sudo[2127]: volumio : unable to resolve host volumiohome Feb 19 14:28:02 volumiohome sudo[2127]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:02 volumiohome sudo[2127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 14:28:02 volumiohome sudo[2127]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:02 volumiohome sudo[2130]: volumio : unable to resolve host volumiohome Feb 19 14:28:02 volumiohome sudo[2130]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:02 volumiohome sudo[2130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 14:28:02 volumiohome sudo[2130]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:03 volumiohome owntone[2099]: [2025-02-19 14:28:03] [ LOG] db: Database OK with 0 active files and 6 active playlists Feb 19 14:28:03 volumiohome owntone[2099]: [2025-02-19 14:28:03] [ LOG] laudio: Pulseaudio failed with error: Connection refused Feb 19 14:28:03 volumiohome owntone[2099]: [2025-02-19 14:28:03] [ LOG] laudio: Error initializing Pulseaudio: Connection refused Feb 19 14:28:03 volumiohome volumio[1079]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 19 14:28:03 volumiohome volumio[1079]: info: CoreStateMachine::getcurrentVolume Feb 19 14:28:03 volumiohome volumio[1079]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 14:28:03 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:03 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:03 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:05 volumiohome owntone[2099]: [2025-02-19 14:28:05] [ LOG] misc: Could not create service 'mpd' with address (ANY), port 6600: Address already in use Feb 19 14:28:05 volumiohome owntone[2099]: [2025-02-19 14:28:05] [ LOG] mpd: Could not bind mpd server to port 6600 Feb 19 14:28:05 volumiohome owntone[2099]: [2025-02-19 14:28:05] [FATAL] main: MPD thread failed to start Feb 19 14:28:05 volumiohome owntone[2099]: [2025-02-19 14:28:05] [ LOG] main: HTTPd deinit Feb 19 14:28:05 volumiohome sudo[2120]: volumio : unable to resolve host volumiohome Feb 19 14:28:05 volumiohome sudo[2120]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:05 volumiohome sudo[2110]: root : unable to resolve host volumiohome Feb 19 14:28:05 volumiohome sudo[2110]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:05 volumiohome sudo[2110]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:05 volumiohome sudo[2120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 14:28:05 volumiohome sudo[2110]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:28:05 volumiohome sudo[2120]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:05 volumiohome sudo[2110]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:05 volumiohome sudo[2110]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:05 volumiohome systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 19 14:28:05 volumiohome systemd[1]: mpd.service: Succeeded. Feb 19 14:28:05 volumiohome systemd[1]: Stopped Music Player Daemon. Feb 19 14:28:05 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:28:05 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:05 volumiohome sudo[2122]: volumio : unable to resolve host volumiohome Feb 19 14:28:05 volumiohome sudo[2122]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:05 volumiohome sudo[2122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 14:28:05 volumiohome sudo[2122]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:05 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:28:05 volumiohome systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 19 14:28:05 volumiohome systemd[1]: shairport-sync.service: Succeeded. Feb 19 14:28:05 volumiohome systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 19 14:28:05 volumiohome systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 19 14:28:05 volumiohome sudo[2122]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:05 volumiohome sudo[2120]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:05 volumiohome volumio[1079]: info: Shairport-Sync Started Feb 19 14:28:05 volumiohome volumio[1079]: info: Shairport-Sync Started Feb 19 14:28:05 volumiohome sudo[2164]: root : unable to resolve host volumiohome Feb 19 14:28:05 volumiohome sudo[2164]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:05 volumiohome sudo[2164]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:05 volumiohome sudo[2164]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:28:05 volumiohome sudo[2164]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:05 volumiohome sudo[2164]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:06 volumiohome owntone[2099]: [2025-02-19 14:28:06] [ LOG] scan: Skipping library directory /srv/music, could not dereference: No such file or directory Feb 19 14:28:06 volumiohome owntone[2099]: [2025-02-19 14:28:06] [ LOG] scan: Bulk library scan completed in 2 sec Feb 19 14:28:06 volumiohome volumio[1079]: { Feb 19 14:28:06 volumiohome volumio[1079]: device: '5', Feb 19 14:28:06 volumiohome volumio[1079]: name: 'High-Performance Audio', Feb 19 14:28:06 volumiohome volumio[1079]: mixer: 'PCM', Feb 19 14:28:06 volumiohome volumio[1079]: mixertype: 'Hardware', Feb 19 14:28:06 volumiohome volumio[1079]: maxvolume: '80', Feb 19 14:28:06 volumiohome volumio[1079]: volumecurve: 'logarithmic', Feb 19 14:28:06 volumiohome volumio[1079]: volumestart: '25', Feb 19 14:28:06 volumiohome volumio[1079]: volumesteps: '1', Feb 19 14:28:06 volumiohome volumio[1079]: softvolumenumber: '5' Feb 19 14:28:06 volumiohome volumio[1079]: } Feb 19 14:28:06 volumiohome volumio[1079]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 19 14:28:06 volumiohome volumio[1079]: info: Updating Volume Controller Parameters: Device: 5 Name: High-Performance Audio Mixer: PCM Max Vol: 80 Vol Curve; logarithmic Vol Steps: 1 Feb 19 14:28:06 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 19 14:28:06 volumiohome volumio[1079]: info: Disabling external Volume Control Feb 19 14:28:06 volumiohome volumio[1079]: info: VolumeController:: Volume=25 Mute =false Feb 19 14:28:06 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:06 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:06 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 14:28:06 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:06 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Feb 19 14:28:06 volumiohome volumio[1079]: { Feb 19 14:28:06 volumiohome volumio[1079]: device: '5', Feb 19 14:28:06 volumiohome volumio[1079]: name: 'High-Performance Audio', Feb 19 14:28:06 volumiohome volumio[1079]: mixer: 'PCM', Feb 19 14:28:06 volumiohome volumio[1079]: mixertype: 'Hardware', Feb 19 14:28:06 volumiohome volumio[1079]: maxvolume: '80', Feb 19 14:28:06 volumiohome volumio[1079]: volumecurve: 'logarithmic', Feb 19 14:28:06 volumiohome volumio[1079]: volumestart: '25', Feb 19 14:28:06 volumiohome volumio[1079]: volumesteps: '1', Feb 19 14:28:06 volumiohome volumio[1079]: softvolumenumber: '5' Feb 19 14:28:06 volumiohome volumio[1079]: } Feb 19 14:28:06 volumiohome volumio[1079]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 19 14:28:06 volumiohome volumio[1079]: info: Updating Volume Controller Parameters: Device: 5 Name: High-Performance Audio Mixer: PCM Max Vol: 80 Vol Curve; logarithmic Vol Steps: 1 Feb 19 14:28:06 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 19 14:28:06 volumiohome volumio[1079]: info: Disabling external Volume Control Feb 19 14:28:06 volumiohome volumio[1079]: info: VolumeController:: Volume=25 Mute =false Feb 19 14:28:06 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:06 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:06 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:06 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Feb 19 14:28:06 volumiohome mpd[2169]: Feb 19 14:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 19 14:28:07 volumiohome systemd[1]: Started Music Player Daemon. Feb 19 14:28:07 volumiohome sudo[2127]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:07 volumiohome sudo[2130]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:07 volumiohome sudo[2124]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:07 volumiohome sudo[2093]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:07 volumiohome sudo[2103]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:07 volumiohome volumio[1079]: error: updateQueue error: null Feb 19 14:28:07 volumiohome volumio[1079]: error: MPD error: The expression evaluated to a falsy value: Feb 19 14:28:07 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:28:07 volumiohome volumio[1079]: error: The expression evaluated to a falsy value: Feb 19 14:28:07 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:28:07 volumiohome volumio[1079]: error: MPD error: The expression evaluated to a falsy value: Feb 19 14:28:07 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:28:07 volumiohome volumio[1079]: error: The expression evaluated to a falsy value: Feb 19 14:28:07 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:28:07 volumiohome volumio[1079]: error: MPD error: The expression evaluated to a falsy value: Feb 19 14:28:07 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:28:07 volumiohome volumio[1079]: error: The expression evaluated to a falsy value: Feb 19 14:28:07 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:28:07 volumiohome volumio[1079]: error: updateQueue error: null Feb 19 14:28:08 volumiohome owntone[2099]: [2025-02-19 14:28:08] [ LOG] lib: Library init scan completed in 5 sec (0 changes) Feb 19 14:28:08 volumiohome owntone[2099]: [2025-02-19 14:28:08] [ LOG] main: Player deinit Feb 19 14:28:09 volumiohome owntone[2099]: [2025-02-19 14:28:09] [ LOG] main: Library scanner deinit Feb 19 14:28:09 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPlay Feb 19 14:28:09 volumiohome volumio[1079]: info: CoreStateMachine::play index undefined Feb 19 14:28:09 volumiohome volumio[1079]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 14:28:09 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:09 volumiohome volumio[1079]: info: CoreStateMachine::startPlaybackTimer Feb 19 14:28:09 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:09 volumiohome volumio[1079]: info: ControllerMpd::resume Feb 19 14:28:09 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand play Feb 19 14:28:09 volumiohome volumio[1079]: info: sendMpdCommand play took 1 milliseconds Feb 19 14:28:10 volumiohome owntone[2099]: [2025-02-19 14:28:10] [ LOG] main: Cache deinit Feb 19 14:28:10 volumiohome owntone[2099]: [2025-02-19 14:28:10] [ LOG] main: Worker deinit Feb 19 14:28:10 volumiohome owntone[2099]: [2025-02-19 14:28:10] [ LOG] main: Database deinit Feb 19 14:28:10 volumiohome owntone[2099]: [2025-02-19 14:28:10] [ LOG] main: mDNS deinit Feb 19 14:28:10 volumiohome owntone[2099]: [2025-02-19 14:28:10] [ LOG] main: Exiting. Feb 19 14:28:10 volumiohome systemd[1]: owntone.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:28:10 volumiohome systemd[1]: owntone.service: Failed with result 'exit-code'. Feb 19 14:28:11 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:11 volumiohome volumio[1079]: info: VolumeController:: Setting startup Volume 25 Feb 19 14:28:11 volumiohome volumio[1079]: info: VolumeController::SetAlsaVolume25 Feb 19 14:28:11 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:11 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:11 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 14:28:11 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:11 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Feb 19 14:28:11 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:28:11 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:11 volumiohome volumio[1079]: info: Listing playlists Feb 19 14:28:11 volumiohome volumio[1079]: info: Listing playlists Feb 19 14:28:16 volumiohome systemd[1]: owntone.service: Service RestartSec=5s expired, scheduling restart. Feb 19 14:28:16 volumiohome systemd[1]: owntone.service: Scheduled restart job, restart counter is at 9. Feb 19 14:28:16 volumiohome systemd[1]: Stopped DAAP/DACP (iTunes), RSP and MPD server, supports AirPlay and Remote. Feb 19 14:28:16 volumiohome systemd[1]: Started DAAP/DACP (iTunes), RSP and MPD server, supports AirPlay and Remote. Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] main: OwnTone version 28.11 taking off Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] main: Built with: Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] main: - ffmpeg Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] main: - Spotify Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] main: - librespot-c Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] main: - LastFM Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] main: - Chromecast Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] main: - MPD Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] main: - Websockets Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] main: - ALSA Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] main: - Pulseaudio Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] main: - Webinterface Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] main: - Regex Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] main: mDNS init Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] mdns: Avahi state change: Client running Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] db: Now vacuuming database, this may take some time... Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] db: Database OK with 0 active files and 6 active playlists Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] laudio: Pulseaudio failed with error: Connection refused Feb 19 14:28:16 volumiohome owntone[2207]: [2025-02-19 14:28:16] [ LOG] laudio: Error initializing Pulseaudio: Connection refused Feb 19 14:28:17 volumiohome owntone[2207]: [2025-02-19 14:28:17] [ LOG] misc: Could not create service 'mpd' with address (ANY), port 6600: Address already in use Feb 19 14:28:17 volumiohome owntone[2207]: [2025-02-19 14:28:17] [ LOG] mpd: Could not bind mpd server to port 6600 Feb 19 14:28:17 volumiohome owntone[2207]: [2025-02-19 14:28:17] [FATAL] main: MPD thread failed to start Feb 19 14:28:17 volumiohome owntone[2207]: [2025-02-19 14:28:17] [ LOG] main: HTTPd deinit Feb 19 14:28:20 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetQueue Feb 19 14:28:20 volumiohome volumio[1079]: info: CoreStateMachine::getQueue Feb 19 14:28:20 volumiohome volumio[1079]: info: CorePlayQueue::getQueue Feb 19 14:28:21 volumiohome owntone[2207]: [2025-02-19 14:28:21] [ LOG] scan: Skipping library directory /srv/music, could not dereference: No such file or directory Feb 19 14:28:21 volumiohome owntone[2207]: [2025-02-19 14:28:21] [ LOG] scan: Bulk library scan completed in 4 sec Feb 19 14:28:21 volumiohome owntone[2207]: [2025-02-19 14:28:21] [ LOG] main: Player deinit Feb 19 14:28:21 volumiohome owntone[2207]: [2025-02-19 14:28:21] [ LOG] db: Database deadlocked! Feb 19 14:28:21 volumiohome owntone[2207]: [2025-02-19 14:28:21] [ LOG] db: Error 'step failed: database table is locked' while runnning 'DELETE FROM queue WHERE NOT file_id IN (SELECT id from files WHERE disabled = 0);' Feb 19 14:28:21 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:28:21 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: Preload queue cleared Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::ClearQueue Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::stop Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::updateTrackBlock Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrackBlock Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::stPlaybackTimer Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::serviceStop Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::serviceStop Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::stop Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::clearPlayQueue Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::saveQueue Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushQueue Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::addQueueItems Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::addQueueItems Feb 19 14:28:22 volumiohome volumio[1079]: info: Preload queue cleared Feb 19 14:28:22 volumiohome volumio[1079]: info: Adding Item to queue: music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: Exploding uri music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf in service mpd Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Feb 19 14:28:22 volumiohome volumio[1079]: info: ALBUMART /albumart?cacheid=819&web=Secret%20Service/Sonets%20Guldskiveartister/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FSecret%20Service%20-%20Sonets%20Guldskiueartister%2FA1%20Flash%20In%20The%20Night.dsf&metadata=false Feb 19 14:28:22 volumiohome volumio[1079]: info: URI /mnt/NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand stop took 53 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushQueue Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::saveQueue Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::updateTrackBlock Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrackBlock Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPlay Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::play index 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::addQueueItems Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::addQueueItems Feb 19 14:28:22 volumiohome volumio[1079]: info: Preload queue cleared Feb 19 14:28:22 volumiohome volumio[1079]: info: Adding Item to queue: music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/A2 Oh Susie.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: Exploding uri music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/A2 Oh Susie.dsf in service mpd Feb 19 14:28:22 volumiohome volumio[1079]: info: Adding Item to queue: music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/A3 Cry Softly (Time Is Mourning).dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: Exploding uri music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/A3 Cry Softly (Time Is Mourning).dsf in service mpd Feb 19 14:28:22 volumiohome volumio[1079]: info: Adding Item to queue: music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/A4 Ten O'clock Postman.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: Exploding uri music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/A4 Ten O'clock Postman.dsf in service mpd Feb 19 14:28:22 volumiohome volumio[1079]: info: Adding Item to queue: music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/A5 Ye-Si-Ca.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: Exploding uri music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/A5 Ye-Si-Ca.dsf in service mpd Feb 19 14:28:22 volumiohome volumio[1079]: info: Adding Item to queue: music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/B1 Jupiter Sign.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: Exploding uri music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/B1 Jupiter Sign.dsf in service mpd Feb 19 14:28:22 volumiohome volumio[1079]: info: Adding Item to queue: music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/B2 Like A Morning Song.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: Exploding uri music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/B2 Like A Morning Song.dsf in service mpd Feb 19 14:28:22 volumiohome volumio[1079]: info: Adding Item to queue: music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/B3 Dancing In Madness.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: Exploding uri music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/B3 Dancing In Madness.dsf in service mpd Feb 19 14:28:22 volumiohome volumio[1079]: info: Adding Item to queue: music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/B4 L. A. Goodbye.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: Exploding uri music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/B4 L. A. Goodbye.dsf in service mpd Feb 19 14:28:22 volumiohome volumio[1079]: info: Adding Item to queue: music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/B5 Do It.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: Exploding uri music-library/NAS/Music/Secret Service - Sonets Guldskiueartister/B5 Do It.dsf in service mpd Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::stop Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::play index undefined Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::startPlaybackTimer Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: Setting Volume to 100 automatically for DSD Feb 19 14:28:22 volumiohome volumio[1079]: info: VolumeController::SetAlsaVolume100 Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: SPOTIFY VOLUME 25 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 19 14:28:22 volumiohome volumio[1079]: info: Setting Spotify Volume from Volumio: 80 Feb 19 14:28:22 volumiohome volumio[1079]: info: ALBUMART /albumart?cacheid=819&web=Secret%20Service/Sonets%20Guldskiveartister/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FSecret%20Service%20-%20Sonets%20Guldskiueartister%2FA2%20Oh%20Susie.dsf&metadata=false Feb 19 14:28:22 volumiohome volumio[1079]: info: URI /mnt/NAS/Music/Secret Service - Sonets Guldskiueartister/A2 Oh Susie.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: ALBUMART /albumart?cacheid=819&web=Secret%20Service/Sonets%20Guldskiveartister/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FSecret%20Service%20-%20Sonets%20Guldskiueartister%2FA3%20Cry%20Softly%20(Time%20Is%20Mourning).dsf&metadata=false Feb 19 14:28:22 volumiohome volumio[1079]: info: URI /mnt/NAS/Music/Secret Service - Sonets Guldskiueartister/A3 Cry Softly (Time Is Mourning).dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: ALBUMART /albumart?cacheid=819&web=Secret%20Service/Sonets%20Guldskiveartister/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FSecret%20Service%20-%20Sonets%20Guldskiueartister%2FA4%20Ten%20O'clock%20Postman.dsf&metadata=false Feb 19 14:28:22 volumiohome volumio[1079]: info: URI /mnt/NAS/Music/Secret Service - Sonets Guldskiueartister/A4 Ten O'clock Postman.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: ALBUMART /albumart?cacheid=819&web=Secret%20Service/Sonets%20Guldskiveartister/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FSecret%20Service%20-%20Sonets%20Guldskiueartister%2FA5%20Ye-Si-Ca.dsf&metadata=false Feb 19 14:28:22 volumiohome volumio[1079]: info: URI /mnt/NAS/Music/Secret Service - Sonets Guldskiueartister/A5 Ye-Si-Ca.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: ALBUMART /albumart?cacheid=819&web=Secret%20Service/Sonets%20Guldskiveartister/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FSecret%20Service%20-%20Sonets%20Guldskiueartister%2FB1%20Jupiter%20Sign.dsf&metadata=false Feb 19 14:28:22 volumiohome volumio[1079]: info: URI /mnt/NAS/Music/Secret Service - Sonets Guldskiueartister/B1 Jupiter Sign.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: ALBUMART /albumart?cacheid=819&web=Secret%20Service/Sonets%20Guldskiveartister/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FSecret%20Service%20-%20Sonets%20Guldskiueartister%2FB2%20Like%20A%20Morning%20Song.dsf&metadata=false Feb 19 14:28:22 volumiohome volumio[1079]: info: URI /mnt/NAS/Music/Secret Service - Sonets Guldskiueartister/B2 Like A Morning Song.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: ALBUMART /albumart?cacheid=819&web=Secret%20Service/Sonets%20Guldskiveartister/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FSecret%20Service%20-%20Sonets%20Guldskiueartister%2FB3%20Dancing%20In%20Madness.dsf&metadata=false Feb 19 14:28:22 volumiohome volumio[1079]: info: URI /mnt/NAS/Music/Secret Service - Sonets Guldskiueartister/B3 Dancing In Madness.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: ALBUMART /albumart?cacheid=819&web=Secret%20Service/Sonets%20Guldskiveartister/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FSecret%20Service%20-%20Sonets%20Guldskiueartister%2FB4%20L.%20A.%20Goodbye.dsf&metadata=false Feb 19 14:28:22 volumiohome volumio[1079]: info: URI /mnt/NAS/Music/Secret Service - Sonets Guldskiueartister/B4 L. A. Goodbye.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: ALBUMART /albumart?cacheid=819&web=Secret%20Service/Sonets%20Guldskiveartister/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FSecret%20Service%20-%20Sonets%20Guldskiueartister%2FB5%20Do%20It.dsf&metadata=false Feb 19 14:28:22 volumiohome volumio[1079]: info: URI /mnt/NAS/Music/Secret Service - Sonets Guldskiueartister/B5 Do It.dsf Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand stop took 45 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand clear Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushQueue Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::saveQueue Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::updateTrackBlock Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrackBlock Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces system playlist update Feb 19 14:28:22 volumiohome volumio[1079]: info: Ignoring MPD Status Update Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand clear took 19 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf" Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces system playlist update Feb 19 14:28:22 volumiohome volumio[1079]: info: Ignoring MPD Status Update Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces system playlist update Feb 19 14:28:22 volumiohome volumio[1079]: info: Ignoring MPD Status Update Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces system playlist update Feb 19 14:28:22 volumiohome volumio[1079]: info: Ignoring MPD Status Update Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces system playlist update Feb 19 14:28:22 volumiohome volumio[1079]: info: Ignoring MPD Status Update Feb 19 14:28:22 volumiohome volumio[1079]: error: updateQueue error: null Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces system playlist update Feb 19 14:28:22 volumiohome volumio[1079]: info: Ignoring MPD Status Update Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 11ms Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand add "NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf" took 10 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 9ms Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 8ms Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 7ms Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 5ms Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand play Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces system playlist update Feb 19 14:28:22 volumiohome volumio[1079]: info: Ignoring MPD Status Update Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces system playlist update Feb 19 14:28:22 volumiohome volumio[1079]: info: Ignoring MPD Status Update Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces system playlist update Feb 19 14:28:22 volumiohome volumio[1079]: info: Ignoring MPD Status Update Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces system playlist update Feb 19 14:28:22 volumiohome volumio[1079]: info: Ignoring MPD Status Update Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces state update: player Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 14ms Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand play took 11 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 10ms Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 9ms Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 7ms Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::getState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 7ms Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces state update: player Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::getState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces state update: player Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::getState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces state update: player Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::getState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces state update: player Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::getState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces state update: player Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand status took 22 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand status took 18 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand status took 17 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand status took 16 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand status took 15 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::getState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::parseState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::parseState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::parseState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::parseState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::parseState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces state update: player Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::getState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces state update: player Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::getState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces state update: player Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::getState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Feb 19 14:28:22 volumiohome volumio[1079]: info: Feb 19 14:28:22 volumiohome volumio[1079]: ---------------------------- MPD announces state update: player Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::getState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand status took 17 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand status took 12 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand status took 10 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand status took 9 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::parseState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::parseTrackInfo Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::parseState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::parseState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::parseState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::parseState Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::servicePushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1 Flash In The Night","artist":"Secret Service","album":"Sonets Guldskiveartister","uri":"NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf","trackType":"dsf"} Feb 19 14:28:22 volumiohome volumio[1079]: verbose: CURRENT POSITION 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState stateService play Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::servicePushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1 Flash In The Night","artist":"Secret Service","album":"Sonets Guldskiveartister","uri":"NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf","trackType":"dsf"} Feb 19 14:28:22 volumiohome volumio[1079]: verbose: CURRENT POSITION 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState stateService play Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState currentStatus play Feb 19 14:28:22 volumiohome volumio[1079]: info: Received an update from plugin. extracting info from payload Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::servicePushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1 Flash In The Night","artist":"Secret Service","album":"Sonets Guldskiveartister","uri":"NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf","trackType":"dsf"} Feb 19 14:28:22 volumiohome volumio[1079]: verbose: CURRENT POSITION 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState stateService play Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState currentStatus play Feb 19 14:28:22 volumiohome volumio[1079]: info: Received an update from plugin. extracting info from payload Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::servicePushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1 Flash In The Night","artist":"Secret Service","album":"Sonets Guldskiveartister","uri":"NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf","trackType":"dsf"} Feb 19 14:28:22 volumiohome volumio[1079]: verbose: CURRENT POSITION 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState stateService play Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState currentStatus play Feb 19 14:28:22 volumiohome volumio[1079]: info: Received an update from plugin. extracting info from payload Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::servicePushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1 Flash In The Night","artist":"Secret Service","album":"Sonets Guldskiveartister","uri":"NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf","trackType":"dsf"} Feb 19 14:28:22 volumiohome volumio[1079]: verbose: CURRENT POSITION 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState stateService play Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState currentStatus play Feb 19 14:28:22 volumiohome volumio[1079]: info: Received an update from plugin. extracting info from payload Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 115ms Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 187ms Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 185ms Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 185ms Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 184ms Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand playlistinfo took 146 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand playlistinfo took 144 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand playlistinfo took 144 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand playlistinfo took 143 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: info: sendMpdCommand playlistinfo took 144 milliseconds Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::parseTrackInfo Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::parseTrackInfo Feb 19 14:28:22 volumiohome volumio[1079]: verbose: ControllerMpd::parseTrackInfo Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::servicePushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1 Flash In The Night","artist":"Secret Service","album":"Sonets Guldskiveartister","uri":"NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf","trackType":"dsf"} Feb 19 14:28:22 volumiohome volumio[1079]: verbose: CURRENT POSITION 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState stateService play Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState currentStatus play Feb 19 14:28:22 volumiohome volumio[1079]: info: Received an update from plugin. extracting info from payload Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::servicePushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1 Flash In The Night","artist":"Secret Service","album":"Sonets Guldskiveartister","uri":"NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf","trackType":"dsf"} Feb 19 14:28:22 volumiohome volumio[1079]: verbose: CURRENT POSITION 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState stateService play Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState currentStatus play Feb 19 14:28:22 volumiohome volumio[1079]: info: Received an update from plugin. extracting info from payload Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::servicePushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1 Flash In The Night","artist":"Secret Service","album":"Sonets Guldskiveartister","uri":"NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf","trackType":"dsf"} Feb 19 14:28:22 volumiohome volumio[1079]: verbose: CURRENT POSITION 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState stateService play Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState currentStatus play Feb 19 14:28:22 volumiohome volumio[1079]: info: Received an update from plugin. extracting info from payload Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::servicePushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1 Flash In The Night","artist":"Secret Service","album":"Sonets Guldskiveartister","uri":"NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf","trackType":"dsf"} Feb 19 14:28:22 volumiohome volumio[1079]: verbose: CURRENT POSITION 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState stateService play Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState currentStatus play Feb 19 14:28:22 volumiohome volumio[1079]: info: Received an update from plugin. extracting info from payload Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: ControllerMpd::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::servicePushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1 Flash In The Night","artist":"Secret Service","album":"Sonets Guldskiveartister","uri":"NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf","trackType":"dsf"} Feb 19 14:28:22 volumiohome volumio[1079]: verbose: CURRENT POSITION 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState stateService play Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::syncState currentStatus play Feb 19 14:28:22 volumiohome volumio[1079]: info: Received an update from plugin. extracting info from payload Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:22 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:22 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 421ms Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 412ms Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 412ms Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 412ms Feb 19 14:28:22 volumiohome volumio[1079]: info: ------------------------------ 411ms Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:22 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:23 volumiohome owntone[2207]: [2025-02-19 14:28:23] [ LOG] lib: Library init scan completed in 7 sec (0 changes) Feb 19 14:28:23 volumiohome volumio[1079]: SPOTIFY: SETTING SPOTIFY VOLUME 80 Feb 19 14:28:23 volumiohome volumio[1079]: info: Sending Spotify command with payload to local API: /player/volume Feb 19 14:28:23 volumiohome volumio[1079]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Feb 19 14:28:25 volumiohome owntone[2207]: [2025-02-19 14:28:25] [ LOG] main: Library scanner deinit Feb 19 14:28:25 volumiohome owntone[2207]: [2025-02-19 14:28:25] [ LOG] main: Cache deinit Feb 19 14:28:25 volumiohome owntone[2207]: [2025-02-19 14:28:25] [ LOG] main: Worker deinit Feb 19 14:28:25 volumiohome owntone[2207]: [2025-02-19 14:28:25] [ LOG] main: Database deinit Feb 19 14:28:25 volumiohome owntone[2207]: [2025-02-19 14:28:25] [ LOG] main: mDNS deinit Feb 19 14:28:25 volumiohome owntone[2207]: [2025-02-19 14:28:25] [ LOG] main: Exiting. Feb 19 14:28:25 volumiohome systemd[1]: owntone.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:28:25 volumiohome systemd[1]: owntone.service: Failed with result 'exit-code'. Feb 19 14:28:30 volumiohome systemd[1]: owntone.service: Service RestartSec=5s expired, scheduling restart. Feb 19 14:28:30 volumiohome systemd[1]: owntone.service: Scheduled restart job, restart counter is at 10. Feb 19 14:28:30 volumiohome systemd[1]: Stopped DAAP/DACP (iTunes), RSP and MPD server, supports AirPlay and Remote. Feb 19 14:28:30 volumiohome systemd[1]: Started DAAP/DACP (iTunes), RSP and MPD server, supports AirPlay and Remote. Feb 19 14:28:31 volumiohome owntone[2252]: [2025-02-19 14:28:31] [ LOG] main: OwnTone version 28.11 taking off Feb 19 14:28:31 volumiohome owntone[2252]: [2025-02-19 14:28:31] [ LOG] main: Built with: Feb 19 14:28:31 volumiohome owntone[2252]: [2025-02-19 14:28:31] [ LOG] main: - ffmpeg Feb 19 14:28:31 volumiohome owntone[2252]: [2025-02-19 14:28:31] [ LOG] main: - Spotify Feb 19 14:28:31 volumiohome owntone[2252]: [2025-02-19 14:28:31] [ LOG] main: - librespot-c Feb 19 14:28:31 volumiohome owntone[2252]: [2025-02-19 14:28:31] [ LOG] main: - LastFM Feb 19 14:28:31 volumiohome owntone[2252]: [2025-02-19 14:28:31] [ LOG] main: - Chromecast Feb 19 14:28:31 volumiohome owntone[2252]: [2025-02-19 14:28:31] [ LOG] main: - MPD Feb 19 14:28:31 volumiohome owntone[2252]: [2025-02-19 14:28:31] [ LOG] main: - Websockets Feb 19 14:28:31 volumiohome owntone[2252]: [2025-02-19 14:28:31] [ LOG] main: - ALSA Feb 19 14:28:31 volumiohome owntone[2252]: [2025-02-19 14:28:31] [ LOG] main: - Pulseaudio Feb 19 14:28:31 volumiohome owntone[2252]: [2025-02-19 14:28:31] [ LOG] main: - Webinterface Feb 19 14:28:31 volumiohome owntone[2252]: [2025-02-19 14:28:31] [ LOG] main: - Regex Feb 19 14:28:31 volumiohome owntone[2252]: [2025-02-19 14:28:31] [ LOG] main: mDNS init Feb 19 14:28:31 volumiohome owntone[2252]: [2025-02-19 14:28:31] [ LOG] mdns: Avahi state change: Client running Feb 19 14:28:31 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:28:31 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:31 volumiohome volumio[1079]: info: Listing playlists Feb 19 14:28:31 volumiohome volumio[1079]: info: Listing playlists Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPause Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::pause Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::stPlaybackTimer Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::servicePause Feb 19 14:28:32 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreCommandRouter::servicePause Feb 19 14:28:32 volumiohome volumio[1079]: info: ControllerMpd::pause Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand pause Feb 19 14:28:32 volumiohome volumio[1079]: info: Feb 19 14:28:32 volumiohome volumio[1079]: ---------------------------- MPD announces state update: player Feb 19 14:28:32 volumiohome volumio[1079]: info: sendMpdCommand pause took 4 milliseconds Feb 19 14:28:32 volumiohome volumio[1079]: info: ControllerMpd::getState Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Feb 19 14:28:32 volumiohome volumio[1079]: info: Feb 19 14:28:32 volumiohome volumio[1079]: ---------------------------- MPD announces state update: player Feb 19 14:28:32 volumiohome volumio[1079]: info: ControllerMpd::getState Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Feb 19 14:28:32 volumiohome volumio[1079]: info: Feb 19 14:28:32 volumiohome volumio[1079]: ---------------------------- MPD announces state update: player Feb 19 14:28:32 volumiohome volumio[1079]: info: ControllerMpd::getState Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Feb 19 14:28:32 volumiohome volumio[1079]: info: Feb 19 14:28:32 volumiohome volumio[1079]: ---------------------------- MPD announces state update: player Feb 19 14:28:32 volumiohome volumio[1079]: info: ControllerMpd::getState Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Feb 19 14:28:32 volumiohome volumio[1079]: info: Feb 19 14:28:32 volumiohome volumio[1079]: ---------------------------- MPD announces state update: player Feb 19 14:28:32 volumiohome volumio[1079]: info: ControllerMpd::getState Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Feb 19 14:28:32 volumiohome volumio[1079]: info: sendMpdCommand status took 9 milliseconds Feb 19 14:28:32 volumiohome volumio[1079]: info: sendMpdCommand status took 8 milliseconds Feb 19 14:28:32 volumiohome volumio[1079]: info: sendMpdCommand status took 6 milliseconds Feb 19 14:28:32 volumiohome volumio[1079]: info: sendMpdCommand status took 4 milliseconds Feb 19 14:28:32 volumiohome volumio[1079]: info: sendMpdCommand status took 4 milliseconds Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::parseState Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::parseState Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::parseState Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::parseState Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::parseState Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 14:28:32 volumiohome volumio[1079]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 19 14:28:32 volumiohome volumio[1079]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 19 14:28:32 volumiohome volumio[1079]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 19 14:28:32 volumiohome volumio[1079]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 19 14:28:32 volumiohome volumio[1079]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::parseTrackInfo Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::parseTrackInfo Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::parseTrackInfo Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::parseTrackInfo Feb 19 14:28:32 volumiohome volumio[1079]: verbose: ControllerMpd::parseTrackInfo Feb 19 14:28:32 volumiohome volumio[1079]: info: ControllerMpd::pushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreCommandRouter::servicePushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:32 volumiohome volumio[1079]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9380,"duration":231,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A1 Flash In The Night","artist":"Secret Service","album":"Sonets Guldskiveartister","uri":"NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf","trackType":"dsf"} Feb 19 14:28:32 volumiohome volumio[1079]: verbose: CURRENT POSITION 0 Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::syncState stateService pause Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::syncState currentStatus pause Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::stPlaybackTimer Feb 19 14:28:32 volumiohome volumio[1079]: info: ControllerMpd::pushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreCommandRouter::servicePushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:32 volumiohome volumio[1079]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9380,"duration":231,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A1 Flash In The Night","artist":"Secret Service","album":"Sonets Guldskiveartister","uri":"NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf","trackType":"dsf"} Feb 19 14:28:32 volumiohome volumio[1079]: verbose: CURRENT POSITION 0 Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::syncState stateService pause Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::syncState currentStatus pause Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::stPlaybackTimer Feb 19 14:28:32 volumiohome volumio[1079]: info: ControllerMpd::pushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreCommandRouter::servicePushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:32 volumiohome volumio[1079]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9380,"duration":231,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A1 Flash In The Night","artist":"Secret Service","album":"Sonets Guldskiveartister","uri":"NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf","trackType":"dsf"} Feb 19 14:28:32 volumiohome volumio[1079]: verbose: CURRENT POSITION 0 Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::syncState stateService pause Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::syncState currentStatus pause Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::stPlaybackTimer Feb 19 14:28:32 volumiohome volumio[1079]: info: ControllerMpd::pushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreCommandRouter::servicePushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:32 volumiohome volumio[1079]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9380,"duration":231,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A1 Flash In The Night","artist":"Secret Service","album":"Sonets Guldskiveartister","uri":"NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf","trackType":"dsf"} Feb 19 14:28:32 volumiohome volumio[1079]: verbose: CURRENT POSITION 0 Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::syncState stateService pause Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::syncState currentStatus pause Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::stPlaybackTimer Feb 19 14:28:32 volumiohome volumio[1079]: info: ControllerMpd::pushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreCommandRouter::servicePushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:32 volumiohome volumio[1079]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9380,"duration":231,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A1 Flash In The Night","artist":"Secret Service","album":"Sonets Guldskiveartister","uri":"NAS/Music/Secret Service - Sonets Guldskiueartister/A1 Flash In The Night.dsf","trackType":"dsf"} Feb 19 14:28:32 volumiohome volumio[1079]: verbose: CURRENT POSITION 0 Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::syncState stateService pause Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::syncState currentStatus pause Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:32 volumiohome volumio[1079]: info: CoreStateMachine::stPlaybackTimer Feb 19 14:28:32 volumiohome volumio[1079]: info: ------------------------------ 91ms Feb 19 14:28:32 volumiohome volumio[1079]: info: ------------------------------ 90ms Feb 19 14:28:32 volumiohome volumio[1079]: info: ------------------------------ 94ms Feb 19 14:28:32 volumiohome volumio[1079]: info: ------------------------------ 93ms Feb 19 14:28:32 volumiohome volumio[1079]: info: ------------------------------ 92ms Feb 19 14:28:32 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:32 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:32 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:32 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:32 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 19 14:28:34 volumiohome owntone[2252]: [2025-02-19 14:28:34] [ LOG] db: Now vacuuming database, this may take some time... Feb 19 14:28:36 volumiohome volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 19 14:28:36 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 19 14:28:36 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 14:28:36 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 19 14:28:36 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:28:36 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:28:36 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:28:36 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:28:36 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:28:36 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:28:36 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:28:36 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 19 14:28:36 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 19 14:28:36 volumiohome owntone[2252]: [2025-02-19 14:28:36] [ LOG] db: Database OK with 0 active files and 6 active playlists Feb 19 14:28:37 volumiohome owntone[2252]: [2025-02-19 14:28:37] [ LOG] laudio: Pulseaudio failed with error: Connection refused Feb 19 14:28:37 volumiohome owntone[2252]: [2025-02-19 14:28:37] [ LOG] laudio: Error initializing Pulseaudio: Connection refused Feb 19 14:28:39 volumiohome owntone[2252]: [2025-02-19 14:28:39] [ LOG] misc: Could not create service 'mpd' with address (ANY), port 6600: Address already in use Feb 19 14:28:39 volumiohome owntone[2252]: [2025-02-19 14:28:39] [ LOG] mpd: Could not bind mpd server to port 6600 Feb 19 14:28:39 volumiohome owntone[2252]: [2025-02-19 14:28:39] [FATAL] main: MPD thread failed to start Feb 19 14:28:39 volumiohome owntone[2252]: [2025-02-19 14:28:39] [ LOG] main: HTTPd deinit Feb 19 14:28:41 volumiohome owntone[2252]: [2025-02-19 14:28:41] [ LOG] scan: Skipping library directory /srv/music, could not dereference: No such file or directory Feb 19 14:28:41 volumiohome owntone[2252]: [2025-02-19 14:28:41] [ LOG] scan: Bulk library scan completed in 2 sec Feb 19 14:28:41 volumiohome owntone[2252]: [2025-02-19 14:28:41] [ LOG] main: Player deinit Feb 19 14:28:41 volumiohome owntone[2252]: [2025-02-19 14:28:41] [ LOG] db: Database deadlocked! Feb 19 14:28:41 volumiohome owntone[2252]: [2025-02-19 14:28:41] [ LOG] db: Error 'step failed: database table is locked' while runnning 'DELETE FROM queue WHERE NOT file_id IN (SELECT id from files WHERE disabled = 0);' Feb 19 14:28:41 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:28:41 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:41 volumiohome volumio[1079]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 19 14:28:41 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 19 14:28:41 volumiohome volumio[1079]: info: Preparing to save Alsa Options, stopping services first Feb 19 14:28:41 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:28:41 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:41 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPause Feb 19 14:28:41 volumiohome volumio[1079]: info: CoreStateMachine::pause Feb 19 14:28:41 volumiohome volumio[1079]: info: Saving Audio Output to: {"output_device":{"value":"7","label":"Loopback"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 19 14:28:41 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 19 14:28:41 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 19 14:28:41 volumiohome volumio[1079]: info: Device 7 does not have any Mixer Control Available Feb 19 14:28:41 volumiohome volumio[1079]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 19 14:28:41 volumiohome volumio[1079]: info: Updating Volume Controller Parameters: Device: 7 Name: Loopback Mixer: Max Vol: 80 Vol Curve; logarithmic Vol Steps: 1 Feb 19 14:28:41 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 19 14:28:41 volumiohome volumio[1079]: info: Disabling external Volume Control Feb 19 14:28:41 volumiohome volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 19 14:28:41 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:41 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:41 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 14:28:41 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:41 volumiohome volumio[1079]: info: Preparing to generate the ALSA configuration file Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 19 14:28:42 volumiohome volumio[1079]: info: Asound.conf file unchanged, so no further update is needed Feb 19 14:28:42 volumiohome volumio[1079]: info: Output device has changed, restarting MPD Feb 19 14:28:42 volumiohome sudo[2304]: volumio : unable to resolve host volumiohome Feb 19 14:28:42 volumiohome volumio[1079]: info: Output device has changed, restarting Shairport Sync Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 14:28:42 volumiohome sudo[2304]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:42 volumiohome sudo[2304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 14:28:42 volumiohome sudo[2304]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:42 volumiohome sudo[2304]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:42 volumiohome sudo[2306]: volumio : unable to resolve host volumiohome Feb 19 14:28:42 volumiohome sudo[2306]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:42 volumiohome sudo[2306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 14:28:42 volumiohome sudo[2306]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:42 volumiohome systemd[1]: Stopping Music Player Daemon... Feb 19 14:28:42 volumiohome volumio[1079]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 14:28:42 volumiohome volumio[1079]: info: MPD Permissions set Feb 19 14:28:42 volumiohome volumio[1079]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:28:42 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:42 volumiohome volumio[1079]: info: mpdhttpout --- Volumio set on pause Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:42 volumiohome systemd[1]: mpd.service: Succeeded. Feb 19 14:28:42 volumiohome systemd[1]: Stopped Music Player Daemon. Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:42 volumiohome volumio[1079]: info: Starting Shairport Sync Feb 19 14:28:42 volumiohome sudo[2318]: volumio : unable to resolve host volumiohome Feb 19 14:28:42 volumiohome sudo[2318]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:42 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:28:42 volumiohome sudo[2318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 14:28:42 volumiohome sudo[2318]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:42 volumiohome systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 19 14:28:42 volumiohome systemd[1]: shairport-sync.service: Succeeded. Feb 19 14:28:42 volumiohome systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 19 14:28:42 volumiohome systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 19 14:28:42 volumiohome sudo[2318]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:42 volumiohome volumio[1079]: info: Shairport-Sync Started Feb 19 14:28:42 volumiohome sudo[2316]: root : unable to resolve host volumiohome Feb 19 14:28:42 volumiohome sudo[2316]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:42 volumiohome sudo[2316]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:42 volumiohome sudo[2316]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:28:42 volumiohome sudo[2316]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:42 volumiohome sudo[2316]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:42 volumiohome owntone[2252]: [2025-02-19 14:28:42] [ LOG] lib: Library init scan completed in 5 sec (0 changes) Feb 19 14:28:42 volumiohome volumio[1079]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Feb 19 14:28:42 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:28:42 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:42 volumiohome volumio[1079]: info: mpdhttpout --- Volumio set on pause Feb 19 14:28:42 volumiohome owntone[2252]: [2025-02-19 14:28:42] [ LOG] main: Library scanner deinit Feb 19 14:28:43 volumiohome mpd[2324]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:28:43 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:28:43 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:28:43 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:28:43 volumiohome sudo[2306]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:43 volumiohome volumio[1079]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Feb 19 14:28:43 volumiohome volumio[1079]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:43 volumiohome volumio[1079]: Job for mpd.service failed because the control process exited with error code. Feb 19 14:28:43 volumiohome volumio[1079]: See "systemctl status mpd.service" and "journalctl -xe" for details. Feb 19 14:28:43 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:28:44 volumiohome sudo[2325]: root : unable to resolve host volumiohome Feb 19 14:28:44 volumiohome sudo[2325]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:44 volumiohome sudo[2325]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:44 volumiohome sudo[2325]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:28:44 volumiohome sudo[2325]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:44 volumiohome sudo[2325]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:44 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Feb 19 14:28:44 volumiohome sudo[2330]: volumio : unable to resolve host volumiohome Feb 19 14:28:44 volumiohome sudo[2330]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:44 volumiohome sudo[2330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 14:28:44 volumiohome sudo[2330]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:44 volumiohome systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 19 14:28:44 volumiohome systemd[1]: mpd.service: Succeeded. Feb 19 14:28:44 volumiohome systemd[1]: Stopped Music Player Daemon. Feb 19 14:28:44 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:28:44 volumiohome sudo[2332]: root : unable to resolve host volumiohome Feb 19 14:28:44 volumiohome sudo[2332]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:44 volumiohome sudo[2332]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:44 volumiohome sudo[2332]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:28:44 volumiohome sudo[2332]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:44 volumiohome sudo[2332]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:44 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Feb 19 14:28:44 volumiohome sudo[2350]: volumio : unable to resolve host volumiohome Feb 19 14:28:44 volumiohome sudo[2350]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:44 volumiohome sudo[2350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 14:28:44 volumiohome sudo[2350]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:44 volumiohome systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 19 14:28:44 volumiohome systemd[1]: mpd.service: Succeeded. Feb 19 14:28:44 volumiohome systemd[1]: Stopped Music Player Daemon. Feb 19 14:28:45 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:28:45 volumiohome sudo[2352]: root : unable to resolve host volumiohome Feb 19 14:28:45 volumiohome sudo[2352]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:45 volumiohome sudo[2352]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:45 volumiohome sudo[2352]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:28:45 volumiohome sudo[2352]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:45 volumiohome sudo[2352]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:45 volumiohome owntone[2252]: [2025-02-19 14:28:45] [ LOG] main: Cache deinit Feb 19 14:28:45 volumiohome owntone[2252]: [2025-02-19 14:28:45] [ LOG] main: Worker deinit Feb 19 14:28:45 volumiohome owntone[2252]: [2025-02-19 14:28:45] [ LOG] main: Database deinit Feb 19 14:28:45 volumiohome owntone[2252]: [2025-02-19 14:28:45] [ LOG] main: mDNS deinit Feb 19 14:28:45 volumiohome owntone[2252]: [2025-02-19 14:28:45] [ LOG] main: Exiting. Feb 19 14:28:45 volumiohome systemd[1]: owntone.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:28:45 volumiohome systemd[1]: owntone.service: Failed with result 'exit-code'. Feb 19 14:28:45 volumiohome volumio[1079]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 19 14:28:45 volumiohome volumio[1079]: info: CoreStateMachine::getcurrentVolume Feb 19 14:28:45 volumiohome volumio[1079]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 14:28:45 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:45 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:45 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:46 volumiohome mpd[2354]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:28:46 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:28:46 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:28:46 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:28:46 volumiohome sudo[2330]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:46 volumiohome volumio[1079]: error: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Feb 19 14:28:46 volumiohome volumio[1079]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:46 volumiohome volumio[1079]: Job for mpd.service failed because the control process exited with error code. Feb 19 14:28:46 volumiohome volumio[1079]: See "systemctl status mpd.service" and "journalctl -xe" for details. Feb 19 14:28:46 volumiohome sudo[2350]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:46 volumiohome volumio[1079]: error: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Feb 19 14:28:46 volumiohome volumio[1079]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:46 volumiohome volumio[1079]: Job for mpd.service failed because the control process exited with error code. Feb 19 14:28:46 volumiohome volumio[1079]: See "systemctl status mpd.service" and "journalctl -xe" for details. Feb 19 14:28:46 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:28:46 volumiohome sudo[2360]: root : unable to resolve host volumiohome Feb 19 14:28:46 volumiohome sudo[2360]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:46 volumiohome sudo[2360]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:46 volumiohome sudo[2360]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:28:46 volumiohome sudo[2360]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:46 volumiohome sudo[2360]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:48 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:28:48 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:48 volumiohome mpd[2362]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:28:48 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:28:48 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:28:48 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:28:48 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:28:48 volumiohome sudo[2363]: root : unable to resolve host volumiohome Feb 19 14:28:48 volumiohome sudo[2363]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:48 volumiohome sudo[2363]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:48 volumiohome sudo[2363]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:28:48 volumiohome sudo[2363]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:48 volumiohome sudo[2363]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:48 volumiohome volumio[1079]: { Feb 19 14:28:48 volumiohome volumio[1079]: device: '5', Feb 19 14:28:48 volumiohome volumio[1079]: name: 'High-Performance Audio', Feb 19 14:28:48 volumiohome volumio[1079]: mixer: 'PCM', Feb 19 14:28:48 volumiohome volumio[1079]: mixertype: 'Hardware', Feb 19 14:28:48 volumiohome volumio[1079]: maxvolume: '80', Feb 19 14:28:48 volumiohome volumio[1079]: volumecurve: 'logarithmic', Feb 19 14:28:48 volumiohome volumio[1079]: volumestart: '25', Feb 19 14:28:48 volumiohome volumio[1079]: volumesteps: '1', Feb 19 14:28:48 volumiohome volumio[1079]: softvolumenumber: '5' Feb 19 14:28:48 volumiohome volumio[1079]: } Feb 19 14:28:48 volumiohome volumio[1079]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 19 14:28:48 volumiohome volumio[1079]: info: Updating Volume Controller Parameters: Device: 5 Name: High-Performance Audio Mixer: PCM Max Vol: 80 Vol Curve; logarithmic Vol Steps: 1 Feb 19 14:28:48 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 19 14:28:48 volumiohome volumio[1079]: info: Disabling external Volume Control Feb 19 14:28:48 volumiohome volumio[1079]: info: VolumeController:: Volume=100 Mute =false Feb 19 14:28:48 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:48 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:48 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 14:28:48 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:48 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 19 14:28:48 volumiohome volumio[1079]: SPOTIFY: SPOTIFY VOLUME 80 Feb 19 14:28:48 volumiohome volumio[1079]: SPOTIFY: VOLUMIO VOLUME 100 Feb 19 14:28:48 volumiohome volumio[1079]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 19 14:28:48 volumiohome volumio[1079]: info: Setting Spotify Volume from Volumio: 100 Feb 19 14:28:49 volumiohome mpd[2365]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:28:49 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:28:49 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:28:49 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:28:49 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:28:50 volumiohome sudo[2370]: root : unable to resolve host volumiohome Feb 19 14:28:50 volumiohome sudo[2370]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:50 volumiohome sudo[2370]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:50 volumiohome sudo[2370]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:28:50 volumiohome sudo[2370]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:50 volumiohome sudo[2370]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:50 volumiohome volumio[1079]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Feb 19 14:28:50 volumiohome volumio[1079]: info: Sending Spotify command with payload to local API: /player/volume Feb 19 14:28:50 volumiohome systemd[1]: owntone.service: Service RestartSec=5s expired, scheduling restart. Feb 19 14:28:50 volumiohome systemd[1]: owntone.service: Scheduled restart job, restart counter is at 11. Feb 19 14:28:50 volumiohome systemd[1]: Stopped DAAP/DACP (iTunes), RSP and MPD server, supports AirPlay and Remote. Feb 19 14:28:50 volumiohome systemd[1]: Started DAAP/DACP (iTunes), RSP and MPD server, supports AirPlay and Remote. Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] main: OwnTone version 28.11 taking off Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] main: Built with: Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] main: - ffmpeg Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] main: - Spotify Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] main: - librespot-c Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] main: - LastFM Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] main: - Chromecast Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] main: - MPD Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] main: - Websockets Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] main: - ALSA Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] main: - Pulseaudio Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] main: - Webinterface Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] main: - Regex Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] main: mDNS init Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] mdns: Avahi state change: Client running Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] db: Now vacuuming database, this may take some time... Feb 19 14:28:51 volumiohome systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 19 14:28:51 volumiohome systemd[1]: mpd.service: Failed with result 'protocol'. Feb 19 14:28:51 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:28:51 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:28:51 volumiohome systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 19 14:28:51 volumiohome systemd[1]: mpd.service: Succeeded. Feb 19 14:28:51 volumiohome systemd[1]: Stopped Music Player Daemon. Feb 19 14:28:51 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:28:51 volumiohome sudo[2383]: root : unable to resolve host volumiohome Feb 19 14:28:51 volumiohome sudo[2383]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:51 volumiohome sudo[2383]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:51 volumiohome sudo[2383]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:28:51 volumiohome sudo[2383]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:51 volumiohome sudo[2383]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] db: Database OK with 0 active files and 6 active playlists Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] laudio: Pulseaudio failed with error: Connection refused Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] laudio: Error initializing Pulseaudio: Connection refused Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] misc: Could not create service 'mpd' with address (ANY), port 6600: Address already in use Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] mpd: Could not bind mpd server to port 6600 Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [FATAL] main: MPD thread failed to start Feb 19 14:28:51 volumiohome owntone[2379]: [2025-02-19 14:28:51] [ LOG] main: HTTPd deinit Feb 19 14:28:51 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:28:51 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:51 volumiohome volumio[1079]: info: Listing playlists Feb 19 14:28:51 volumiohome volumio[1079]: info: Listing playlists Feb 19 14:28:52 volumiohome mpd[2385]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:28:52 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:28:52 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:28:52 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:28:52 volumiohome mpd_monitor.sh[622]: Job for mpd.service failed because the control process exited with error code. Feb 19 14:28:52 volumiohome mpd_monitor.sh[622]: See "systemctl status mpd.service" and "journalctl -xe" for details. Feb 19 14:28:52 volumiohome mpd_monitor.sh[622]: MPD restarted due to no mpc output. Feb 19 14:28:52 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:28:53 volumiohome sudo[2449]: root : unable to resolve host volumiohome Feb 19 14:28:53 volumiohome sudo[2449]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:53 volumiohome sudo[2449]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:53 volumiohome sudo[2449]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:28:53 volumiohome sudo[2449]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:53 volumiohome sudo[2449]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:53 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:28:53 volumiohome volumio[1079]: info: VolumeController:: Setting startup Volume 25 Feb 19 14:28:53 volumiohome volumio[1079]: info: VolumeController::SetAlsaVolume25 Feb 19 14:28:53 volumiohome volumio[1079]: info: CoreStateMachine::pushState Feb 19 14:28:53 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:28:53 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 14:28:53 volumiohome volumio[1079]: info: CoreCommandRouter::volumioPushState Feb 19 14:28:53 volumiohome volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Feb 19 14:28:53 volumiohome volumio[1079]: SPOTIFY: SPOTIFY VOLUME 100 Feb 19 14:28:53 volumiohome volumio[1079]: SPOTIFY: VOLUMIO VOLUME 25 Feb 19 14:28:53 volumiohome volumio[1079]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 19 14:28:53 volumiohome volumio[1079]: info: Setting Spotify Volume from Volumio: 25 Feb 19 14:28:53 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 19 14:28:53 volumiohome volumio[1079]: info: CURURI: music-library/NAS/Music/Deezer Feb 19 14:28:54 volumiohome mpd[2451]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:28:54 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:28:54 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:28:54 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:28:54 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:28:54 volumiohome sudo[2453]: root : unable to resolve host volumiohome Feb 19 14:28:54 volumiohome sudo[2453]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:54 volumiohome sudo[2453]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:54 volumiohome sudo[2453]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:28:54 volumiohome sudo[2453]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:54 volumiohome sudo[2453]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:55 volumiohome volumio[1079]: SPOTIFY: SETTING SPOTIFY VOLUME 25 Feb 19 14:28:55 volumiohome volumio[1079]: info: Sending Spotify command with payload to local API: /player/volume Feb 19 14:28:55 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 19 14:28:55 volumiohome volumio[1079]: info: CURURI: music-library/NAS/Music/Deezer Feb 19 14:28:55 volumiohome owntone[2379]: [2025-02-19 14:28:55] [ LOG] scan: Skipping library directory /srv/music, could not dereference: No such file or directory Feb 19 14:28:55 volumiohome owntone[2379]: [2025-02-19 14:28:55] [ LOG] scan: Bulk library scan completed in 3 sec Feb 19 14:28:56 volumiohome mpd[2469]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:28:56 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:28:56 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:28:56 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:28:56 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:28:56 volumiohome sudo[2470]: root : unable to resolve host volumiohome Feb 19 14:28:56 volumiohome sudo[2470]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:56 volumiohome sudo[2470]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:56 volumiohome sudo[2470]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:28:56 volumiohome sudo[2470]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:56 volumiohome sudo[2470]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:56 volumiohome owntone[2379]: [2025-02-19 14:28:56] [ LOG] lib: Library init scan completed in 5 sec (0 changes) Feb 19 14:28:56 volumiohome owntone[2379]: [2025-02-19 14:28:56] [ LOG] main: Player deinit Feb 19 14:28:57 volumiohome owntone[2379]: [2025-02-19 14:28:57] [ LOG] main: Library scanner deinit Feb 19 14:28:57 volumiohome mpd[2472]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:28:57 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:28:57 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:28:57 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:28:57 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:28:58 volumiohome sudo[2473]: root : unable to resolve host volumiohome Feb 19 14:28:58 volumiohome sudo[2473]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:58 volumiohome sudo[2473]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:58 volumiohome sudo[2473]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:28:58 volumiohome sudo[2473]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:58 volumiohome sudo[2473]: pam_unix(sudo:session): session closed for user root Feb 19 14:28:59 volumiohome mpd[2475]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:28:59 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:28:59 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:28:59 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:28:59 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:28:59 volumiohome sudo[2476]: root : unable to resolve host volumiohome Feb 19 14:28:59 volumiohome sudo[2476]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:28:59 volumiohome sudo[2476]: sudo: unable to resolve host volumiohome: System error Feb 19 14:28:59 volumiohome sudo[2476]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:28:59 volumiohome sudo[2476]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:28:59 volumiohome sudo[2476]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:00 volumiohome owntone[2379]: [2025-02-19 14:29:00] [ LOG] main: Cache deinit Feb 19 14:29:00 volumiohome owntone[2379]: [2025-02-19 14:29:00] [ LOG] main: Worker deinit Feb 19 14:29:00 volumiohome owntone[2379]: [2025-02-19 14:29:00] [ LOG] main: Database deinit Feb 19 14:29:00 volumiohome owntone[2379]: [2025-02-19 14:29:00] [ LOG] main: mDNS deinit Feb 19 14:29:00 volumiohome owntone[2379]: [2025-02-19 14:29:00] [ LOG] main: Exiting. Feb 19 14:29:00 volumiohome systemd[1]: owntone.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:00 volumiohome systemd[1]: owntone.service: Failed with result 'exit-code'. Feb 19 14:29:01 volumiohome mpd[2478]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:01 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:01 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:01 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:01 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:01 volumiohome sudo[2479]: root : unable to resolve host volumiohome Feb 19 14:29:01 volumiohome sudo[2479]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:01 volumiohome sudo[2479]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:01 volumiohome sudo[2479]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:01 volumiohome sudo[2479]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:01 volumiohome sudo[2479]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:01 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:29:01 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:29:02 volumiohome mpd[2481]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:02 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:02 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:02 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:02 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:02 volumiohome sudo[2482]: root : unable to resolve host volumiohome Feb 19 14:29:02 volumiohome sudo[2482]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:02 volumiohome sudo[2482]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:02 volumiohome sudo[2482]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:02 volumiohome sudo[2482]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:02 volumiohome sudo[2482]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:02 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:29:02 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:29:04 volumiohome mpd[2484]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:04 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:04 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:04 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:04 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:04 volumiohome sudo[2487]: root : unable to resolve host volumiohome Feb 19 14:29:04 volumiohome sudo[2487]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:04 volumiohome sudo[2487]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:04 volumiohome sudo[2487]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:04 volumiohome sudo[2487]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:04 volumiohome sudo[2487]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:04 volumiohome systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 19 14:29:04 volumiohome systemd[1]: mpd.service: Failed with result 'protocol'. Feb 19 14:29:04 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:05 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:05 volumiohome systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 19 14:29:05 volumiohome systemd[1]: mpd.service: Succeeded. Feb 19 14:29:05 volumiohome systemd[1]: Stopped Music Player Daemon. Feb 19 14:29:05 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:05 volumiohome sudo[2508]: root : unable to resolve host volumiohome Feb 19 14:29:05 volumiohome sudo[2508]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:05 volumiohome sudo[2508]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:05 volumiohome sudo[2508]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:05 volumiohome sudo[2508]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:05 volumiohome sudo[2508]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:05 volumiohome systemd[1]: owntone.service: Service RestartSec=5s expired, scheduling restart. Feb 19 14:29:05 volumiohome systemd[1]: owntone.service: Scheduled restart job, restart counter is at 12. Feb 19 14:29:05 volumiohome systemd[1]: Stopped DAAP/DACP (iTunes), RSP and MPD server, supports AirPlay and Remote. Feb 19 14:29:05 volumiohome systemd[1]: Started DAAP/DACP (iTunes), RSP and MPD server, supports AirPlay and Remote. Feb 19 14:29:05 volumiohome owntone[2511]: [2025-02-19 14:29:05] [ LOG] main: OwnTone version 28.11 taking off Feb 19 14:29:05 volumiohome owntone[2511]: [2025-02-19 14:29:05] [ LOG] main: Built with: Feb 19 14:29:05 volumiohome owntone[2511]: [2025-02-19 14:29:05] [ LOG] main: - ffmpeg Feb 19 14:29:05 volumiohome owntone[2511]: [2025-02-19 14:29:05] [ LOG] main: - Spotify Feb 19 14:29:05 volumiohome owntone[2511]: [2025-02-19 14:29:05] [ LOG] main: - librespot-c Feb 19 14:29:05 volumiohome owntone[2511]: [2025-02-19 14:29:05] [ LOG] main: - LastFM Feb 19 14:29:05 volumiohome owntone[2511]: [2025-02-19 14:29:05] [ LOG] main: - Chromecast Feb 19 14:29:05 volumiohome owntone[2511]: [2025-02-19 14:29:05] [ LOG] main: - MPD Feb 19 14:29:05 volumiohome owntone[2511]: [2025-02-19 14:29:05] [ LOG] main: - Websockets Feb 19 14:29:05 volumiohome owntone[2511]: [2025-02-19 14:29:05] [ LOG] main: - ALSA Feb 19 14:29:05 volumiohome owntone[2511]: [2025-02-19 14:29:05] [ LOG] main: - Pulseaudio Feb 19 14:29:05 volumiohome owntone[2511]: [2025-02-19 14:29:05] [ LOG] main: - Webinterface Feb 19 14:29:05 volumiohome owntone[2511]: [2025-02-19 14:29:05] [ LOG] main: - Regex Feb 19 14:29:05 volumiohome owntone[2511]: [2025-02-19 14:29:05] [ LOG] main: mDNS init Feb 19 14:29:05 volumiohome owntone[2511]: [2025-02-19 14:29:05] [ LOG] mdns: Avahi state change: Client running Feb 19 14:29:05 volumiohome owntone[2511]: [2025-02-19 14:29:05] [ LOG] db: Now vacuuming database, this may take some time... Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 19 14:29:06 volumiohome volumio[1079]: info: Retrieving Cloud Streaming UI Feb 19 14:29:06 volumiohome volumio[1079]: info: Getting Tidal Cloud Configuration Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 19 14:29:06 volumiohome volumio[1079]: info: Getting Qobuz Cloud Configuration Feb 19 14:29:06 volumiohome volumio[1079]: info: Asking plugin for UI Config Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 19 14:29:06 volumiohome volumio[1079]: info: Getting Spotify Cloud Configuration Feb 19 14:29:06 volumiohome volumio[1079]: info: Asking plugin for UI Config Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 19 14:29:06 volumiohome volumio[1079]: info: Saving Spotify Acccount Feb 19 14:29:06 volumiohome volumio[1079]: info: Got Tidal Cloud Configuration Feb 19 14:29:06 volumiohome volumio[1079]: info: Got it Feb 19 14:29:06 volumiohome volumio[1079]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 19 14:29:06 volumiohome volumio[1079]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetBrowseSources Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetBrowseSources Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetBrowseSources Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 19 14:29:06 volumiohome volumio[1079]: error: MPD error: The expression evaluated to a falsy value: Feb 19 14:29:06 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:29:06 volumiohome volumio[1079]: error: The expression evaluated to a falsy value: Feb 19 14:29:06 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:29:06 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 19 14:29:06 volumiohome mpd[2510]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:06 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:06 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:06 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:06 volumiohome mpd_monitor.sh[622]: Job for mpd.service failed because the control process exited with error code. Feb 19 14:29:06 volumiohome mpd_monitor.sh[622]: See "systemctl status mpd.service" and "journalctl -xe" for details. Feb 19 14:29:06 volumiohome mpd_monitor.sh[622]: MPD restarted due to no mpc output. Feb 19 14:29:06 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:07 volumiohome owntone[2511]: [2025-02-19 14:29:07] [ LOG] db: Database OK with 0 active files and 6 active playlists Feb 19 14:29:07 volumiohome owntone[2511]: [2025-02-19 14:29:07] [ LOG] laudio: Pulseaudio failed with error: Connection refused Feb 19 14:29:07 volumiohome owntone[2511]: [2025-02-19 14:29:07] [ LOG] laudio: Error initializing Pulseaudio: Connection refused Feb 19 14:29:07 volumiohome owntone[2511]: [2025-02-19 14:29:07] [ LOG] misc: Could not create service 'mpd' with address (ANY), port 6600: Address already in use Feb 19 14:29:07 volumiohome owntone[2511]: [2025-02-19 14:29:07] [ LOG] mpd: Could not bind mpd server to port 6600 Feb 19 14:29:07 volumiohome owntone[2511]: [2025-02-19 14:29:07] [FATAL] main: MPD thread failed to start Feb 19 14:29:07 volumiohome owntone[2511]: [2025-02-19 14:29:07] [ LOG] main: HTTPd deinit Feb 19 14:29:10 volumiohome owntone[2511]: [2025-02-19 14:29:10] [ LOG] scan: Skipping library directory /srv/music, could not dereference: No such file or directory Feb 19 14:29:10 volumiohome owntone[2511]: [2025-02-19 14:29:10] [ LOG] scan: Bulk library scan completed in 3 sec Feb 19 14:29:10 volumiohome owntone[2511]: [2025-02-19 14:29:10] [ LOG] lib: Library init scan completed in 3 sec (0 changes) Feb 19 14:29:10 volumiohome owntone[2511]: [2025-02-19 14:29:10] [ LOG] main: Player deinit Feb 19 14:29:10 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 19 14:29:10 volumiohome volumio[1079]: error: MPD error: The expression evaluated to a falsy value: Feb 19 14:29:10 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:29:10 volumiohome volumio[1079]: error: The expression evaluated to a falsy value: Feb 19 14:29:10 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:29:11 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:29:11 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:29:11 volumiohome volumio[1079]: info: Listing playlists Feb 19 14:29:11 volumiohome volumio[1079]: info: Listing playlists Feb 19 14:29:11 volumiohome sudo[2516]: root : unable to resolve host volumiohome Feb 19 14:29:11 volumiohome sudo[2516]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:11 volumiohome sudo[2516]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:11 volumiohome sudo[2516]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:11 volumiohome sudo[2516]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:11 volumiohome sudo[2516]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:11 volumiohome owntone[2511]: [2025-02-19 14:29:11] [ LOG] main: Library scanner deinit Feb 19 14:29:13 volumiohome mpd[2534]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:13 volumiohome owntone[2511]: [2025-02-19 14:29:13] [ LOG] main: Cache deinit Feb 19 14:29:13 volumiohome owntone[2511]: [2025-02-19 14:29:13] [ LOG] main: Worker deinit Feb 19 14:29:13 volumiohome owntone[2511]: [2025-02-19 14:29:13] [ LOG] main: Database deinit Feb 19 14:29:13 volumiohome owntone[2511]: [2025-02-19 14:29:13] [ LOG] main: mDNS deinit Feb 19 14:29:13 volumiohome owntone[2511]: [2025-02-19 14:29:13] [ LOG] main: Exiting. Feb 19 14:29:13 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:13 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:13 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:13 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:13 volumiohome systemd[1]: owntone.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:13 volumiohome systemd[1]: owntone.service: Failed with result 'exit-code'. Feb 19 14:29:13 volumiohome sudo[2535]: root : unable to resolve host volumiohome Feb 19 14:29:13 volumiohome sudo[2535]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:13 volumiohome sudo[2535]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:13 volumiohome sudo[2535]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:13 volumiohome sudo[2535]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:13 volumiohome sudo[2535]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:14 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 19 14:29:14 volumiohome volumio[1079]: error: MPD error: The expression evaluated to a falsy value: Feb 19 14:29:14 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:29:14 volumiohome volumio[1079]: error: The expression evaluated to a falsy value: Feb 19 14:29:14 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:29:14 volumiohome mpd[2537]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:14 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:14 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:14 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:15 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:15 volumiohome sudo[2552]: root : unable to resolve host volumiohome Feb 19 14:29:15 volumiohome sudo[2552]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:15 volumiohome sudo[2552]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:15 volumiohome sudo[2552]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:15 volumiohome sudo[2552]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:15 volumiohome sudo[2552]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:16 volumiohome mpd[2554]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:16 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:16 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:16 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:16 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:16 volumiohome sudo[2555]: root : unable to resolve host volumiohome Feb 19 14:29:16 volumiohome sudo[2555]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:16 volumiohome sudo[2555]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:16 volumiohome sudo[2555]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:16 volumiohome sudo[2555]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:16 volumiohome sudo[2555]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:18 volumiohome mpd[2559]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:18 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:18 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:18 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:18 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:18 volumiohome sudo[2560]: root : unable to resolve host volumiohome Feb 19 14:29:18 volumiohome sudo[2560]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:18 volumiohome sudo[2560]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:18 volumiohome sudo[2560]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:18 volumiohome sudo[2560]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:18 volumiohome sudo[2560]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:18 volumiohome systemd[1]: owntone.service: Service RestartSec=5s expired, scheduling restart. Feb 19 14:29:18 volumiohome systemd[1]: owntone.service: Scheduled restart job, restart counter is at 13. Feb 19 14:29:18 volumiohome systemd[1]: Stopped DAAP/DACP (iTunes), RSP and MPD server, supports AirPlay and Remote. Feb 19 14:29:18 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 19 14:29:18 volumiohome volumio[1079]: error: MPD error: The expression evaluated to a falsy value: Feb 19 14:29:18 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:29:18 volumiohome volumio[1079]: error: The expression evaluated to a falsy value: Feb 19 14:29:18 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:29:18 volumiohome systemd[1]: Started DAAP/DACP (iTunes), RSP and MPD server, supports AirPlay and Remote. Feb 19 14:29:18 volumiohome systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 19 14:29:18 volumiohome systemd[1]: mpd.service: Failed with result 'protocol'. Feb 19 14:29:18 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:18 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:18 volumiohome systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 19 14:29:18 volumiohome systemd[1]: mpd.service: Succeeded. Feb 19 14:29:18 volumiohome systemd[1]: Stopped Music Player Daemon. Feb 19 14:29:18 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:18 volumiohome sudo[2567]: root : unable to resolve host volumiohome Feb 19 14:29:18 volumiohome sudo[2567]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:18 volumiohome sudo[2567]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:18 volumiohome sudo[2567]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:18 volumiohome sudo[2567]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:18 volumiohome sudo[2567]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] main: OwnTone version 28.11 taking off Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] main: Built with: Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] main: - ffmpeg Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] main: - Spotify Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] main: - librespot-c Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] main: - LastFM Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] main: - Chromecast Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] main: - MPD Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] main: - Websockets Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] main: - ALSA Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] main: - Pulseaudio Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] main: - Webinterface Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] main: - Regex Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] main: mDNS init Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] mdns: Avahi state change: Client running Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] db: Now vacuuming database, this may take some time... Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] db: Database OK with 0 active files and 6 active playlists Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] laudio: Pulseaudio failed with error: Connection refused Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] laudio: Error initializing Pulseaudio: Connection refused Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] misc: Could not create service 'mpd' with address (ANY), port 6600: Address already in use Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] mpd: Could not bind mpd server to port 6600 Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [FATAL] main: MPD thread failed to start Feb 19 14:29:19 volumiohome owntone[2563]: [2025-02-19 14:29:19] [ LOG] main: HTTPd deinit Feb 19 14:29:20 volumiohome mpd[2569]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:20 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:20 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:20 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:20 volumiohome mpd_monitor.sh[622]: Job for mpd.service failed because the control process exited with error code. Feb 19 14:29:20 volumiohome mpd_monitor.sh[622]: See "systemctl status mpd.service" and "journalctl -xe" for details. Feb 19 14:29:20 volumiohome mpd_monitor.sh[622]: MPD restarted due to no mpc output. Feb 19 14:29:20 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:20 volumiohome sudo[2587]: root : unable to resolve host volumiohome Feb 19 14:29:20 volumiohome sudo[2587]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:20 volumiohome sudo[2587]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:20 volumiohome sudo[2587]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:20 volumiohome sudo[2587]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:20 volumiohome sudo[2587]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:21 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:29:21 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:29:22 volumiohome mpd[2589]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:22 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:22 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:22 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:22 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:22 volumiohome sudo[2590]: root : unable to resolve host volumiohome Feb 19 14:29:22 volumiohome sudo[2590]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:22 volumiohome sudo[2590]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:22 volumiohome sudo[2590]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:22 volumiohome sudo[2590]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:22 volumiohome sudo[2590]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:22 volumiohome owntone[2563]: [2025-02-19 14:29:22] [ LOG] scan: Skipping library directory /srv/music, could not dereference: No such file or directory Feb 19 14:29:22 volumiohome owntone[2563]: [2025-02-19 14:29:22] [ LOG] main: Player deinit Feb 19 14:29:22 volumiohome owntone[2563]: [2025-02-19 14:29:22] [ LOG] scan: Bulk library scan completed in 3 sec Feb 19 14:29:22 volumiohome owntone[2563]: [2025-02-19 14:29:22] [ LOG] db: Database deadlocked! Feb 19 14:29:22 volumiohome owntone[2563]: [2025-02-19 14:29:22] [ LOG] db: Error 'step failed: database table is locked' while runnning 'DELETE FROM queue WHERE NOT file_id IN (SELECT id from files WHERE disabled = 0);' Feb 19 14:29:22 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 19 14:29:22 volumiohome volumio[1079]: error: MPD error: The expression evaluated to a falsy value: Feb 19 14:29:22 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:29:22 volumiohome volumio[1079]: error: The expression evaluated to a falsy value: Feb 19 14:29:22 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:29:23 volumiohome mpd[2592]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:23 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:23 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:23 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:23 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:23 volumiohome sudo[2593]: root : unable to resolve host volumiohome Feb 19 14:29:23 volumiohome sudo[2593]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:23 volumiohome sudo[2593]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:23 volumiohome sudo[2593]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:23 volumiohome sudo[2593]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:23 volumiohome sudo[2593]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:24 volumiohome owntone[2563]: [2025-02-19 14:29:24] [ LOG] lib: Library init scan completed in 5 sec (0 changes) Feb 19 14:29:25 volumiohome mpd[2595]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:25 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:25 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:25 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:25 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:25 volumiohome owntone[2563]: [2025-02-19 14:29:25] [ LOG] main: Library scanner deinit Feb 19 14:29:25 volumiohome sudo[2611]: root : unable to resolve host volumiohome Feb 19 14:29:25 volumiohome sudo[2611]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:25 volumiohome sudo[2611]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:25 volumiohome sudo[2611]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:25 volumiohome sudo[2611]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:25 volumiohome sudo[2611]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:26 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 19 14:29:26 volumiohome volumio[1079]: error: MPD error: The expression evaluated to a falsy value: Feb 19 14:29:26 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:29:26 volumiohome volumio[1079]: error: The expression evaluated to a falsy value: Feb 19 14:29:26 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:29:26 volumiohome mpd[2613]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:26 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:26 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:26 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:26 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:27 volumiohome sudo[2614]: root : unable to resolve host volumiohome Feb 19 14:29:27 volumiohome sudo[2614]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:27 volumiohome sudo[2614]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:27 volumiohome sudo[2614]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:27 volumiohome sudo[2614]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:27 volumiohome sudo[2614]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:28 volumiohome owntone[2563]: [2025-02-19 14:29:28] [ LOG] main: Cache deinit Feb 19 14:29:28 volumiohome owntone[2563]: [2025-02-19 14:29:28] [ LOG] main: Worker deinit Feb 19 14:29:28 volumiohome owntone[2563]: [2025-02-19 14:29:28] [ LOG] main: Database deinit Feb 19 14:29:28 volumiohome owntone[2563]: [2025-02-19 14:29:28] [ LOG] main: mDNS deinit Feb 19 14:29:28 volumiohome owntone[2563]: [2025-02-19 14:29:28] [ LOG] main: Exiting. Feb 19 14:29:28 volumiohome systemd[1]: owntone.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:28 volumiohome systemd[1]: owntone.service: Failed with result 'exit-code'. Feb 19 14:29:28 volumiohome mpd[2616]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:28 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:28 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:28 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:28 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:28 volumiohome sudo[2617]: root : unable to resolve host volumiohome Feb 19 14:29:28 volumiohome sudo[2617]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:28 volumiohome sudo[2617]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:28 volumiohome sudo[2617]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:28 volumiohome sudo[2617]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:28 volumiohome sudo[2617]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:30 volumiohome mpd[2619]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:30 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:30 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:30 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:30 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:30 volumiohome sudo[2620]: root : unable to resolve host volumiohome Feb 19 14:29:30 volumiohome sudo[2620]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:30 volumiohome sudo[2620]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:30 volumiohome sudo[2620]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:30 volumiohome sudo[2620]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:30 volumiohome sudo[2620]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:30 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 19 14:29:30 volumiohome volumio[1079]: error: MPD error: The expression evaluated to a falsy value: Feb 19 14:29:30 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:29:30 volumiohome volumio[1079]: error: The expression evaluated to a falsy value: Feb 19 14:29:30 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:29:31 volumiohome volumio[1079]: info: CoreCommandRouter::volumioGetState Feb 19 14:29:31 volumiohome volumio[1079]: info: CorePlayQueue::getTrack 0 Feb 19 14:29:31 volumiohome volumio[1079]: info: Listing playlists Feb 19 14:29:31 volumiohome volumio[1079]: info: Listing playlists Feb 19 14:29:31 volumiohome mpd[2624]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:31 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:31 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:31 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:31 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:32 volumiohome sudo[2625]: root : unable to resolve host volumiohome Feb 19 14:29:32 volumiohome sudo[2625]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:32 volumiohome sudo[2625]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:32 volumiohome sudo[2625]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:32 volumiohome sudo[2625]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:32 volumiohome sudo[2625]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:32 volumiohome systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 19 14:29:32 volumiohome systemd[1]: mpd.service: Failed with result 'protocol'. Feb 19 14:29:32 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:32 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 19 14:29:32 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 19 14:29:32 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:32 volumiohome systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 19 14:29:32 volumiohome systemd[1]: mpd.service: Succeeded. Feb 19 14:29:32 volumiohome systemd[1]: Stopped Music Player Daemon. Feb 19 14:29:32 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:32 volumiohome sudo[2634]: root : unable to resolve host volumiohome Feb 19 14:29:32 volumiohome sudo[2634]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:32 volumiohome sudo[2634]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:32 volumiohome sudo[2634]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:32 volumiohome sudo[2634]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:32 volumiohome sudo[2634]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:33 volumiohome systemd[1]: owntone.service: Service RestartSec=5s expired, scheduling restart. Feb 19 14:29:33 volumiohome systemd[1]: owntone.service: Scheduled restart job, restart counter is at 14. Feb 19 14:29:33 volumiohome systemd[1]: Stopped DAAP/DACP (iTunes), RSP and MPD server, supports AirPlay and Remote. Feb 19 14:29:33 volumiohome systemd[1]: Started DAAP/DACP (iTunes), RSP and MPD server, supports AirPlay and Remote. Feb 19 14:29:33 volumiohome owntone[2643]: [2025-02-19 14:29:33] [ LOG] main: OwnTone version 28.11 taking off Feb 19 14:29:33 volumiohome owntone[2643]: [2025-02-19 14:29:33] [ LOG] main: Built with: Feb 19 14:29:33 volumiohome owntone[2643]: [2025-02-19 14:29:33] [ LOG] main: - ffmpeg Feb 19 14:29:33 volumiohome owntone[2643]: [2025-02-19 14:29:33] [ LOG] main: - Spotify Feb 19 14:29:33 volumiohome owntone[2643]: [2025-02-19 14:29:33] [ LOG] main: - librespot-c Feb 19 14:29:33 volumiohome owntone[2643]: [2025-02-19 14:29:33] [ LOG] main: - LastFM Feb 19 14:29:33 volumiohome owntone[2643]: [2025-02-19 14:29:33] [ LOG] main: - Chromecast Feb 19 14:29:33 volumiohome owntone[2643]: [2025-02-19 14:29:33] [ LOG] main: - MPD Feb 19 14:29:33 volumiohome owntone[2643]: [2025-02-19 14:29:33] [ LOG] main: - Websockets Feb 19 14:29:33 volumiohome owntone[2643]: [2025-02-19 14:29:33] [ LOG] main: - ALSA Feb 19 14:29:33 volumiohome owntone[2643]: [2025-02-19 14:29:33] [ LOG] main: - Pulseaudio Feb 19 14:29:33 volumiohome owntone[2643]: [2025-02-19 14:29:33] [ LOG] main: - Webinterface Feb 19 14:29:33 volumiohome owntone[2643]: [2025-02-19 14:29:33] [ LOG] main: - Regex Feb 19 14:29:33 volumiohome owntone[2643]: [2025-02-19 14:29:33] [ LOG] main: mDNS init Feb 19 14:29:33 volumiohome owntone[2643]: [2025-02-19 14:29:33] [ LOG] mdns: Avahi state change: Client running Feb 19 14:29:33 volumiohome owntone[2643]: [2025-02-19 14:29:33] [ LOG] db: Now vacuuming database, this may take some time... Feb 19 14:29:34 volumiohome mpd[2639]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:34 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:34 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:34 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:34 volumiohome mpd_monitor.sh[622]: Job for mpd.service failed because the control process exited with error code. Feb 19 14:29:34 volumiohome mpd_monitor.sh[622]: See "systemctl status mpd.service" and "journalctl -xe" for details. Feb 19 14:29:34 volumiohome mpd_monitor.sh[622]: MPD restarted due to no mpc output. Feb 19 14:29:34 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:34 volumiohome volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 19 14:29:34 volumiohome volumio[1079]: error: MPD error: The expression evaluated to a falsy value: Feb 19 14:29:34 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:29:34 volumiohome volumio[1079]: error: The expression evaluated to a falsy value: Feb 19 14:29:34 volumiohome volumio[1079]: assert.ok(self.idling) Feb 19 14:29:35 volumiohome sudo[2652]: root : unable to resolve host volumiohome Feb 19 14:29:35 volumiohome sudo[2652]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:35 volumiohome sudo[2652]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:35 volumiohome sudo[2652]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:35 volumiohome sudo[2652]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:35 volumiohome sudo[2652]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:36 volumiohome owntone[2643]: [2025-02-19 14:29:36] [ LOG] db: Database OK with 0 active files and 6 active playlists Feb 19 14:29:36 volumiohome mpd[2668]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:36 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:36 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:36 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:36 volumiohome owntone[2643]: [2025-02-19 14:29:36] [ LOG] laudio: Pulseaudio failed with error: Connection refused Feb 19 14:29:36 volumiohome owntone[2643]: [2025-02-19 14:29:36] [ LOG] laudio: Error initializing Pulseaudio: Connection refused Feb 19 14:29:37 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:37 volumiohome owntone[2643]: [2025-02-19 14:29:37] [ LOG] misc: Could not create service 'mpd' with address (ANY), port 6600: Address already in use Feb 19 14:29:37 volumiohome owntone[2643]: [2025-02-19 14:29:37] [ LOG] mpd: Could not bind mpd server to port 6600 Feb 19 14:29:37 volumiohome owntone[2643]: [2025-02-19 14:29:37] [FATAL] main: MPD thread failed to start Feb 19 14:29:37 volumiohome owntone[2643]: [2025-02-19 14:29:37] [ LOG] main: HTTPd deinit Feb 19 14:29:37 volumiohome sudo[2678]: root : unable to resolve host volumiohome Feb 19 14:29:37 volumiohome sudo[2678]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:37 volumiohome sudo[2678]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:37 volumiohome sudo[2678]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:37 volumiohome sudo[2678]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:37 volumiohome sudo[2678]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:37 volumiohome volumio[1079]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 14:29:37 volumiohome volumio[1079]: Error: dns service error: unknown Feb 19 14:29:37 volumiohome volumio[1079]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Feb 19 14:29:37 volumiohome volumio[1079]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Feb 19 14:29:37 volumiohome volumio[1079]: errorCode: -65537 Feb 19 14:29:37 volumiohome volumio[1079]: } Feb 19 14:29:37 volumiohome volumio[1079]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 14:29:38 volumiohome owntone[2643]: [2025-02-19 14:29:38] [ LOG] scan: Skipping library directory /srv/music, could not dereference: No such file or directory Feb 19 14:29:38 volumiohome owntone[2643]: [2025-02-19 14:29:38] [ LOG] scan: Bulk library scan completed in 1 sec Feb 19 14:29:38 volumiohome owntone[2643]: [2025-02-19 14:29:38] [ LOG] main: Player deinit Feb 19 14:29:38 volumiohome owntone[2643]: [2025-02-19 14:29:38] [ LOG] db: Database deadlocked! Feb 19 14:29:38 volumiohome owntone[2643]: [2025-02-19 14:29:38] [ LOG] db: Error 'step failed: database table is locked' while runnning 'DELETE FROM queue WHERE NOT file_id IN (SELECT id from files WHERE disabled = 0);' Feb 19 14:29:38 volumiohome mpd[2691]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:38 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:38 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:38 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:38 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:38 volumiohome sudo[2700]: root : unable to resolve host volumiohome Feb 19 14:29:38 volumiohome sudo[2700]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:38 volumiohome sudo[2700]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:38 volumiohome sudo[2700]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:38 volumiohome sudo[2700]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:38 volumiohome sudo[2700]: pam_unix(sudo:session): session closed for user root Feb 19 14:29:38 volumiohome sudo[2702]: volumio : unable to resolve host volumiohome Feb 19 14:29:38 volumiohome sudo[2702]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:38 volumiohome sudo[2702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-19 14:28 Feb 19 14:29:38 volumiohome sudo[2702]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:40 volumiohome mpd[2704]: exception: Error in /etc/mpd.conf line 103; unrecognized parameter: lesystem_charset Feb 19 14:29:40 volumiohome owntone[2643]: [2025-02-19 14:29:40] [ LOG] lib: Library init scan completed in 4 sec (0 changes) Feb 19 14:29:40 volumiohome systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 19 14:29:40 volumiohome systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 19 14:29:40 volumiohome systemd[1]: Failed to start Music Player Daemon. Feb 19 14:29:40 volumiohome systemd[1]: Starting Music Player Daemon... Feb 19 14:29:40 volumiohome owntone[2643]: [2025-02-19 14:29:40] [ LOG] main: Library scanner deinit Feb 19 14:29:40 volumiohome sudo[2706]: root : unable to resolve host volumiohome Feb 19 14:29:40 volumiohome sudo[2706]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 14:29:40 volumiohome sudo[2706]: sudo: unable to resolve host volumiohome: System error Feb 19 14:29:40 volumiohome sudo[2706]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:29:40 volumiohome sudo[2706]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:29:40 volumiohome sudo[2706]: pam_unix(sudo:session): session closed for user root PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"