-- Logs begin at Sat 2025-12-27 16:42:33 UTC, end at Sun 2025-12-28 14:21:13 UTC. -- Dec 28 14:20:03 volumio volumio[1015]: info: CoreCommandRouter::volumioPause Dec 28 14:20:03 volumio volumio[1015]: info: CoreStateMachine::pause Dec 28 14:20:03 volumio volumio[1015]: info: CoreStateMachine::stPlaybackTimer Dec 28 14:20:03 volumio volumio[1015]: info: CoreStateMachine::servicePause Dec 28 14:20:03 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Dec 28 14:20:03 volumio volumio[1015]: info: CoreCommandRouter::servicePause Dec 28 14:20:03 volumio volumio[1015]: info: ControllerMpd::pause Dec 28 14:20:03 volumio volumio[1015]: info: ControllerMpd::sendMpdCommand pause Dec 28 14:20:03 volumio volumio[1015]: info: sending command... Dec 28 14:20:03 volumio volumio[1015]: info: parsing response... Dec 28 14:20:03 volumio volumio[1015]: info: Dec 28 14:20:03 volumio volumio[1015]: ---------------------------- MPD announces state update: player Dec 28 14:20:03 volumio volumio[1015]: info: ControllerMpd::getState Dec 28 14:20:03 volumio volumio[1015]: info: ControllerMpd::sendMpdCommand status Dec 28 14:20:03 volumio volumio[1015]: info: sending command... Dec 28 14:20:03 volumio volumio[1015]: info: parsing response... Dec 28 14:20:03 volumio volumio[1015]: info: ControllerMpd::parseState Dec 28 14:20:03 volumio volumio[1015]: info: ControllerMpd::sendMpdCommand playlistinfo Dec 28 14:20:03 volumio volumio[1015]: info: sending command... Dec 28 14:20:03 volumio volumio[1015]: info: parsing response... Dec 28 14:20:03 volumio volumio[1015]: info: ControllerMpd::parseTrackInfo Dec 28 14:20:03 volumio volumio[1015]: info: ControllerMpd::pushState Dec 28 14:20:03 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Dec 28 14:20:03 volumio volumio[1015]: info: CoreStateMachine::syncState Dec 28 14:20:03 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Dec 28 14:20:03 volumio volumio[1015]: info: STATE SERVICE {"status":"pause","position":0,"seek":89885,"duration":295,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"In My Secret Life","artist":"Leonard Cohen","album":"Ten New Songs","uri":"NAS/HOME_MUSIC/Music FLAC 24-44.1/Leonard Cohen - In My Secret Life.flac","trackType":"flac"} Dec 28 14:20:03 volumio volumio[1015]: info: CURRENT POSITION 0 Dec 28 14:20:03 volumio volumio[1015]: info: CoreStateMachine::syncState stateService pause Dec 28 14:20:03 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus pause Dec 28 14:20:03 volumio volumio[1015]: info: CoreStateMachine::pushState Dec 28 14:20:03 volumio volumio[1015]: info: CoreStateMachine::getState Dec 28 14:20:03 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Dec 28 14:20:03 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Dec 28 14:20:03 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 14:20:03 volumio volumio[1015]: info: CoreStateMachine::stPlaybackTimer Dec 28 14:20:03 volumio volumio[1015]: info: ------------------------------ 54ms Dec 28 14:20:03 volumio sudo[8216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 14:20:03 volumio sudo[8216]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:20:03 volumio sudo[8216]: pam_unix(sudo:session): session closed for user root Dec 28 14:20:06 volumio volumio[1015]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 14:20:06 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 28 14:20:06 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 14:20:06 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 14:20:06 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 14:20:06 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 14:20:06 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 14:20:06 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 14:20:06 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 14:20:06 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 14:20:06 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Dec 28 14:20:28 volumio volumio[1015]: info: Output device has changed, restarting MPD Dec 28 14:20:28 volumio volumio[1015]: info: Output device has changed, restarting Shairport Sync Dec 28 14:20:28 volumio sudo[8400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 14:20:28 volumio volumio[1015]: info: Disable softmixer device for audio device Dec 28 14:20:28 volumio volumio[1015]: info: Volume configurations have been set Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:20:28 volumio sudo[8400]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:20:28 volumio sudo[8402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 14:20:28 volumio sudo[8400]: pam_unix(sudo:session): session closed for user root Dec 28 14:20:28 volumio sudo[8402]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:20:28 volumio systemd[1]: Stopping Music Player Daemon... Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 28 14:20:28 volumio volumio[1015]: info: Updating Volume Controller Parameters: Device: 1 Name: Hifiberry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 14:20:28 volumio volumio[1015]: info: MPD Permissions set Dec 28 14:20:28 volumio volumio[1015]: info: Asound.conf file written Dec 28 14:20:28 volumio systemd[1]: Starting Music Player Daemon... Dec 28 14:20:28 volumio sudo[8422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 28 14:20:28 volumio sudo[8422]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:20:28 volumio sudo[8422]: pam_unix(sudo:session): session closed for user root Dec 28 14:20:28 volumio volumio[1015]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Combo384 Amanero Validity:0' : Operation not permitted Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 28 14:20:28 volumio volumio[1015]: info: Updating Volume Controller Parameters: Device: 1 Name: Hifiberry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Dec 28 14:20:28 volumio volumio[1015]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Combo384 Amanero Validity:0' : Operation not permitted Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: error: Upnp client error: Error: This socket has been ended by the other party Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 14:20:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 14:20:28 volumio sudo[8443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Dec 28 14:20:28 volumio sudo[8443]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:20:28 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Dec 28 14:20:28 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Dec 28 14:20:28 volumio systemd[1]: Started ShairportSync AirTunes receiver. Dec 28 14:20:28 volumio sudo[8443]: pam_unix(sudo:session): session closed for user root Dec 28 14:20:28 volumio shairport-sync[8449]: Successful Startup Dec 28 14:20:28 volumio volumio[1015]: info: Shairport-Sync Started Dec 28 14:20:42 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 28 14:20:42 volumio volumio[1015]: info: CURURI: music-library/NAS/HOME_MUSIC Dec 28 14:20:42 volumio volumio[1015]: error: MPD error: Error: This socket has been ended by the other party Dec 28 14:20:42 volumio volumio[1015]: error: MPD error: Error: This socket has been ended by the other party Dec 28 14:20:42 volumio volumio[1015]: error: MPD error: Error: This socket has been ended by the other party Dec 28 14:20:49 volumio volumio[1015]: info: CoreCommandRouter::volumioPlay Dec 28 14:20:49 volumio volumio[1015]: UNSET VOLATILE Dec 28 14:20:49 volumio volumio[1015]: info: CoreStateMachine::play index undefined Dec 28 14:20:49 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 14:20:49 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Dec 28 14:20:49 volumio volumio[1015]: info: CoreStateMachine::startPlaybackTimer Dec 28 14:20:49 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Dec 28 14:20:49 volumio volumio[1015]: info: ControllerMpd::resume Dec 28 14:20:49 volumio volumio[1015]: info: ControllerMpd::sendMpdCommand play Dec 28 14:21:03 volumio volumio[1015]: info: CoreCommandRouter::volumioPlay Dec 28 14:21:03 volumio volumio[1015]: UNSET VOLATILE Dec 28 14:21:03 volumio volumio[1015]: info: CoreStateMachine::play index undefined Dec 28 14:21:03 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 14:21:03 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Dec 28 14:21:03 volumio volumio[1015]: info: CoreStateMachine::startPlaybackTimer Dec 28 14:21:03 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Dec 28 14:21:03 volumio volumio[1015]: info: ControllerMpd::resume Dec 28 14:21:03 volumio volumio[1015]: info: ControllerMpd::sendMpdCommand play Dec 28 14:21:11 volumio volumio[1015]: info: CoreCommandRouter::volumioSeek Dec 28 14:21:11 volumio volumio[1015]: info: CoreStateMachine::seek Dec 28 14:21:11 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Dec 28 14:21:11 volumio volumio[1015]: info: TRACKBLOCK {"uri":"mnt/NAS/HOME_MUSIC/Music FLAC 24-44.1/Leonard Cohen - In My Secret Life.flac","service":"mpd","name":" - In My Secret Life","artist":"Leonard Cohen","album":"Ten New Songs","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=685&path=%2FNAS%2FHOME_MUSIC%2FMusic%20FLAC%2024-44.1&metadata=false","duration":295,"samplerate":"44.1 KHz","bitdepth":"24 bit","trackType":"flac","channels":2} Dec 28 14:21:11 volumio volumio[1015]: info: CoreStateMachine::startPlaybackTimer Dec 28 14:21:11 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Dec 28 14:21:11 volumio volumio[1015]: info: ControllerMpd::seek Dec 28 14:21:11 volumio volumio[1015]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 14:21:11 volumio volumio[1015]: { AssertionError [ERR_ASSERTION]: false == true Dec 28 14:21:11 volumio volumio[1015]: at MpdClient.sendCommand (/volumio/app/plugins/music_service/mpd/lib/mpd.js:92:9) Dec 28 14:21:11 volumio volumio[1015]: at ControllerMpd.seek (/volumio/app/plugins/music_service/mpd/index.js:2740:24) Dec 28 14:21:11 volumio volumio[1015]: at CoreStateMachine.seek (/volumio/app/statemachine.js:1181:15) Dec 28 14:21:11 volumio volumio[1015]: at CoreCommandRouter.volumioSeek (/volumio/app/index.js:1312:27) Dec 28 14:21:11 volumio volumio[1015]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:151:31) Dec 28 14:21:11 volumio volumio[1015]: at emitTwo (events.js:126:13) Dec 28 14:21:11 volumio volumio[1015]: at Socket.emit (events.js:214:7) Dec 28 14:21:11 volumio volumio[1015]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 28 14:21:11 volumio volumio[1015]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Dec 28 14:21:11 volumio volumio[1015]: at process._tickCallback (internal/process/next_tick.js:180:9) Dec 28 14:21:11 volumio volumio[1015]: generatedMessage: true, Dec 28 14:21:11 volumio volumio[1015]: name: 'AssertionError [ERR_ASSERTION]', Dec 28 14:21:11 volumio volumio[1015]: code: 'ERR_ASSERTION', Dec 28 14:21:11 volumio volumio[1015]: actual: false, Dec 28 14:21:11 volumio volumio[1015]: expected: true, Dec 28 14:21:11 volumio volumio[1015]: operator: '==' } Dec 28 14:21:11 volumio volumio[1015]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 14:21:13 volumio sudo[8473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-28 14:20 Dec 28 14:21:13 volumio sudo[8473]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="c00a19098320053824010d132d6bfb39774a7d2f" VOLUMIO_FE_VERSION="e2f152c30d370c9c7c86508a02c0323bef5c243b" VOLUMIO_BE_VERSION="909cc76a7609428f005f8e2878227062cb5328a6" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Sep 17 00:18:22 CEST 2018" VOLUMIO_VERSION="2.457" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="cdde21c6e3d40ce352c051f661950d1b"