-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Thu 2025-10-16 18:26:17 UTC. -- Oct 16 18:25:20 volumio volumio[1133]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Oct 16 18:25:20 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Oct 16 18:25:20 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Oct 16 18:25:20 volumio sudo[13185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 18:25:20 volumio sudo[13185]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:25:20 volumio sudo[13185]: pam_unix(sudo:session): session closed for user root Oct 16 18:25:20 volumio sudo[13188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 18:25:20 volumio sudo[13188]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:25:20 volumio volumio[1133]: info: MPD Permissions set Oct 16 18:25:20 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:25:20 volumio systemd[1]: Stopping Music Player Daemon... Oct 16 18:25:20 volumio systemd[1]: mpd.service: Succeeded. Oct 16 18:25:20 volumio systemd[1]: Stopped Music Player Daemon. Oct 16 18:25:20 volumio systemd[1]: Starting Music Player Daemon... Oct 16 18:25:20 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 18:25:20 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 16 18:25:20 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:25:20 volumio sudo[13191]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 16 18:25:20 volumio sudo[13191]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:25:20 volumio sudo[13191]: pam_unix(sudo:session): session closed for user root Oct 16 18:25:21 volumio mpd[13198]: Oct 16 18:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 16 18:25:21 volumio systemd[1]: Started Music Player Daemon. Oct 16 18:25:21 volumio sudo[13188]: pam_unix(sudo:session): session closed for user root Oct 16 18:25:21 volumio volumio[1133]: error: updateQueue error: null Oct 16 18:25:33 volumio volumio[1133]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Oct 16 18:25:33 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Oct 16 18:25:33 volumio sudo[13235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 18:25:33 volumio sudo[13235]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:25:33 volumio sudo[13235]: pam_unix(sudo:session): session closed for user root Oct 16 18:25:33 volumio volumio[1133]: info: MPD Permissions set Oct 16 18:25:33 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:25:33 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:25:33 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:25:33 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:25:33 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:25:33 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:25:33 volumio sudo[13238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 18:25:33 volumio sudo[13238]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:25:33 volumio systemd[1]: Stopping Music Player Daemon... Oct 16 18:25:33 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 18:25:33 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 16 18:25:33 volumio systemd[1]: mpd.service: Succeeded. Oct 16 18:25:33 volumio systemd[1]: Stopped Music Player Daemon. Oct 16 18:25:33 volumio systemd[1]: Starting Music Player Daemon... Oct 16 18:25:33 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:25:33 volumio sudo[13245]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 16 18:25:33 volumio sudo[13245]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:25:33 volumio sudo[13245]: pam_unix(sudo:session): session closed for user root Oct 16 18:25:34 volumio mpd[13248]: Oct 16 18:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 16 18:25:34 volumio systemd[1]: Started Music Player Daemon. Oct 16 18:25:34 volumio sudo[13238]: pam_unix(sudo:session): session closed for user root Oct 16 18:25:34 volumio volumio[1133]: error: updateQueue error: null Oct 16 18:25:42 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Oct 16 18:25:42 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Oct 16 18:25:42 volumio kernel: usb 1-3: USB disconnect, device number 6 Oct 16 18:25:47 volumio kernel: usb 1-4: new high-speed USB device number 7 using ehci-pci Oct 16 18:25:47 volumio kernel: usb 1-4: New USB device found, idVendor=058f, idProduct=6387, bcdDevice= 1.02 Oct 16 18:25:47 volumio kernel: usb 1-4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 16 18:25:47 volumio kernel: usb 1-4: Product: Mass Storage Oct 16 18:25:47 volumio kernel: usb 1-4: Manufacturer: Generic Oct 16 18:25:47 volumio kernel: usb 1-4: SerialNumber: 6847B1A2 Oct 16 18:25:47 volumio kernel: usb-storage 1-4:1.0: USB Mass Storage device detected Oct 16 18:25:47 volumio kernel: scsi host7: usb-storage 1-4:1.0 Oct 16 18:25:48 volumio kernel: scsi 7:0:0:0: Direct-Access Generic Flash Disk 8.07 PQ: 0 ANSI: 2 Oct 16 18:25:48 volumio kernel: sd 7:0:0:0: Attached scsi generic sg3 type 0 Oct 16 18:25:48 volumio kernel: sd 7:0:0:0: [sdc] 3893248 512-byte logical blocks: (1.99 GB/1.86 GiB) Oct 16 18:25:48 volumio kernel: sd 7:0:0:0: [sdc] Write Protect is off Oct 16 18:25:48 volumio kernel: sd 7:0:0:0: [sdc] Mode Sense: 03 00 00 00 Oct 16 18:25:48 volumio kernel: sd 7:0:0:0: [sdc] No Caching mode page found Oct 16 18:25:48 volumio kernel: sd 7:0:0:0: [sdc] Assuming drive cache: write through Oct 16 18:25:48 volumio kernel: sdc: Oct 16 18:25:48 volumio kernel: sd 7:0:0:0: [sdc] Attached SCSI removable disk Oct 16 18:25:56 volumio volumio[1133]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 18:25:56 volumio volumio[1133]: info: Retrieving Cloud Streaming UI Oct 16 18:25:56 volumio volumio[1133]: info: Getting Tidal Cloud Configuration Oct 16 18:25:56 volumio volumio[1133]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 18:25:56 volumio volumio[1133]: info: Getting Qobuz Cloud Configuration Oct 16 18:25:56 volumio volumio[1133]: info: Asking plugin for UI Config Oct 16 18:25:56 volumio volumio[1133]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 18:25:56 volumio volumio[1133]: info: Getting Spotify Cloud Configuration Oct 16 18:25:56 volumio volumio[1133]: info: Asking plugin for UI Config Oct 16 18:25:56 volumio volumio[1133]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 18:25:56 volumio volumio[1133]: info: Saving Spotify Acccount Oct 16 18:25:56 volumio volumio[1133]: info: Got Tidal Cloud Configuration Oct 16 18:25:56 volumio volumio[1133]: info: Got it Oct 16 18:25:56 volumio volumio[1133]: info: Got it Oct 16 18:25:56 volumio volumio[1133]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 16 18:25:56 volumio volumio[1133]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 18:25:56 volumio volumio[1133]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 16 18:25:56 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 18:25:56 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 18:25:56 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 18:25:56 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 18:25:56 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 18:25:56 volumio volumio[1133]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 18:25:56 volumio volumio[1133]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 18:25:56 volumio volumio[1133]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 18:25:56 volumio kernel: perf: interrupt took too long (2508 > 2500), lowering kernel.perf_event_max_sample_rate to 79500 Oct 16 18:25:56 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 18:25:56 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 16 18:26:00 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 18:26:04 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 18:26:06 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 16 18:26:06 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand rescan Oct 16 18:26:06 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:26:06 volumio volumio[1133]: info: Oct 16 18:26:06 volumio volumio[1133]: ---------------------------- MPD announces state update: update Oct 16 18:26:06 volumio volumio[1133]: info: sendMpdCommand rescan took 10 milliseconds Oct 16 18:26:06 volumio volumio[1133]: info: ControllerMpd::getState Oct 16 18:26:06 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:26:06 volumio volumio[1133]: info: sendMpdCommand status took 11 milliseconds Oct 16 18:26:06 volumio volumio[1133]: info: sendMpdCommand status took 3 milliseconds Oct 16 18:26:06 volumio volumio[1133]: verbose: ControllerMpd::parseState Oct 16 18:26:06 volumio volumio[1133]: info: Command Router : Notfying DB Updatetrue Oct 16 18:26:06 volumio volumio[1133]: verbose: ControllerMpd::parseState Oct 16 18:26:06 volumio volumio[1133]: info: ControllerMpd::pushState Oct 16 18:26:06 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Oct 16 18:26:06 volumio volumio[1133]: info: CoreStateMachine::pushState Oct 16 18:26:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Oct 16 18:26:06 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Oct 16 18:26:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Oct 16 18:26:06 volumio volumio[1133]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 18:26:06 volumio volumio[1133]: verbose: CURRENT POSITION 0 Oct 16 18:26:06 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop Oct 16 18:26:06 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Oct 16 18:26:06 volumio volumio[1133]: info: CoreStateMachine::pushState Oct 16 18:26:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Oct 16 18:26:06 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:26:06 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Oct 16 18:26:06 volumio volumio[1133]: info: No code Oct 16 18:26:06 volumio volumio[1133]: info: CoreStateMachine::pushState Oct 16 18:26:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Oct 16 18:26:06 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Oct 16 18:26:06 volumio volumio[1133]: info: ------------------------------ 326ms Oct 16 18:26:06 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:26:06 volumio volumio[1133]: info: Oct 16 18:26:06 volumio volumio[1133]: ---------------------------- MPD announces state update: update Oct 16 18:26:06 volumio volumio[1133]: info: ControllerMpd::getState Oct 16 18:26:06 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:26:06 volumio volumio[1133]: info: sendMpdCommand status took 9 milliseconds Oct 16 18:26:06 volumio volumio[1133]: info: sendMpdCommand status took 8 milliseconds Oct 16 18:26:06 volumio volumio[1133]: verbose: ControllerMpd::parseState Oct 16 18:26:06 volumio volumio[1133]: info: Command Router : Notfying DB Updatefalse Oct 16 18:26:06 volumio volumio[1133]: info: CoreCommandRouter::Close All Modals sent Oct 16 18:26:06 volumio volumio[1133]: verbose: ControllerMpd::parseState Oct 16 18:26:06 volumio volumio[1133]: info: ControllerMpd::pushState Oct 16 18:26:06 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Oct 16 18:26:06 volumio volumio[1133]: info: CoreStateMachine::pushState Oct 16 18:26:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Oct 16 18:26:06 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Oct 16 18:26:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Oct 16 18:26:06 volumio volumio[1133]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 18:26:06 volumio volumio[1133]: verbose: CURRENT POSITION 0 Oct 16 18:26:06 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop Oct 16 18:26:06 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Oct 16 18:26:06 volumio volumio[1133]: info: CoreStateMachine::pushState Oct 16 18:26:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Oct 16 18:26:06 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:26:06 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Oct 16 18:26:06 volumio volumio[1133]: info: No code Oct 16 18:26:06 volumio volumio[1133]: info: CoreStateMachine::pushState Oct 16 18:26:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Oct 16 18:26:06 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Oct 16 18:26:06 volumio volumio[1133]: info: ------------------------------ 133ms Oct 16 18:26:08 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 18:26:12 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 18:26:16 volumio volumio[1133]: info: Disabling MyMusic plugin upnp Oct 16 18:26:16 volumio sudo[13376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 16 18:26:16 volumio sudo[13376]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:26:16 volumio volumio[1133]: error: Upnp client error: Error: This socket has been ended by the other party Oct 16 18:26:16 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 16 18:26:16 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 18:26:17 volumio volumio[1133]: info: Enabling MyMusic plugin upnp Oct 16 18:26:17 volumio volumio[1133]: info: Enabling plugin upnp Oct 16 18:26:17 volumio volumio[1133]: info: Loading plugin "upnp"... Oct 16 18:26:17 volumio volumio[1133]: info: [1760639177165] Starting Upmpd Daemon Oct 16 18:26:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 18:26:17 volumio volumio[1133]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 18:26:17 volumio volumio[1133]: Error: listen EADDRINUSE: address already in use :::6599 Oct 16 18:26:17 volumio volumio[1133]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Oct 16 18:26:17 volumio volumio[1133]: at listenInCluster (net.js:1379:12) Oct 16 18:26:17 volumio volumio[1133]: at Server.listen (net.js:1465:7) Oct 16 18:26:17 volumio volumio[1133]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 16 18:26:17 volumio volumio[1133]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 16 18:26:17 volumio volumio[1133]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 16 18:26:17 volumio volumio[1133]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 16 18:26:17 volumio volumio[1133]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Oct 16 18:26:17 volumio volumio[1133]: code: 'EADDRINUSE', Oct 16 18:26:17 volumio volumio[1133]: errno: -98, Oct 16 18:26:17 volumio volumio[1133]: syscall: 'listen', Oct 16 18:26:17 volumio volumio[1133]: address: '::', Oct 16 18:26:17 volumio volumio[1133]: port: 6599 Oct 16 18:26:17 volumio volumio[1133]: } Oct 16 18:26:17 volumio volumio[1133]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 18:26:17 volumio sudo[13387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-16 18:25 Oct 16 18:26:17 volumio sudo[13387]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"