-- Logs begin at Fri 2025-10-24 18:35:09 UTC, end at Sat 2025-10-25 15:05:14 UTC. -- Oct 25 15:05:07 musical-fidelity systemd[1]: Time has been changed Oct 25 15:05:07 musical-fidelity volumio[1038]: Starting albumart workers Oct 25 15:05:07 musical-fidelity volumio[1038]: Starting albumart workers Oct 25 15:05:07 musical-fidelity volumio[1038]: Starting albumart workers Oct 25 15:05:09 musical-fidelity volumio[1038]: info: Loading plugin "spop"... Oct 25 15:05:10 musical-fidelity volumio[1038]: info: Loading plugin "tidal"... Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Loading plugin "gpios"... Oct 25 15:05:12 musical-fidelity volumio[1038]: error: ATTENTION!!!: Plugin gpios does not return adequate promise from onVolumioStart: please update! Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Loading plugin "updater_comm"... Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Plugin mpdemulation is not enabled Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Loading plugin "rest_api"... Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Loading plugin "websocket"... Oct 25 15:05:12 musical-fidelity volumio[1038]: info: ___________ START PLUGINS ___________ Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Adding METAVOLUMIO REST API Endpoints Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 25 15:05:12 musical-fidelity volumio[1038]: info: [1761404712330] CoreMusicLibrary::Adding element Shairport-Sync Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 15:05:12 musical-fidelity sudo[1269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name undefined Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Preparing CD Folders Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Adding CD REST API Endpoints Oct 25 15:05:12 musical-fidelity sudo[1269]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Detecting CD presence with Flag file Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Starting RAAT Plugin Oct 25 15:05:12 musical-fidelity sudo[1269]: pam_unix(sudo:session): session closed for user root Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Additional UI Settings Added for plugin music_service/raat Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Streaming services startup Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Starting Streaming Daemon Oct 25 15:05:12 musical-fidelity sudo[1284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 25 15:05:12 musical-fidelity sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 25 15:05:12 musical-fidelity volumio[1038]: info: [1761404712458] CoreMusicLibrary::Adding element Webradio Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 15:05:12 musical-fidelity systemd[1]: Stopping Volumio Streaming Daemon... Oct 25 15:05:12 musical-fidelity volumio[1038]: error: Hi Res Audio Failed Login: Missing Login Data Oct 25 15:05:12 musical-fidelity systemd[1]: Starting Volumio Streaming Daemon... Oct 25 15:05:12 musical-fidelity systemd[1]: Started Volumio Streaming Daemon. Oct 25 15:05:12 musical-fidelity sudo[1284]: pam_unix(sudo:session): session closed for user root Oct 25 15:05:12 musical-fidelity volumio-streaming-daemon[1294]: ############################ Oct 25 15:05:12 musical-fidelity volumio-streaming-daemon[1294]: # Volumio Streaming Daemon # Oct 25 15:05:12 musical-fidelity volumio-streaming-daemon[1294]: # Running on port 7777 # Oct 25 15:05:12 musical-fidelity volumio-streaming-daemon[1294]: ############################ Oct 25 15:05:12 musical-fidelity volumio-streaming-daemon[1294]: Checking variant in static cache Oct 25 15:05:12 musical-fidelity volumio-streaming-daemon[1294]: Enabled services: Oct 25 15:05:12 musical-fidelity volumio-streaming-daemon[1294]: 1) tidal Oct 25 15:05:12 musical-fidelity volumio-streaming-daemon[1294]: 2) qobuz Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Refreshing QOBUZ token Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 25 15:05:12 musical-fidelity volumio[1038]: [SpotifyConnect] Creating VLS config file Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: [SpotifyConnect] SpotifyConnect Daemon config issues! Oct 25 15:05:12 musical-fidelity volumio[1038]: [SpotifyConnect] volspotify config error: [ 'device-name = "undefined" # Name of the Spotify connect device', Oct 25 15:05:12 musical-fidelity volumio[1038]: [length]: 1 ] Oct 25 15:05:12 musical-fidelity volumio[1038]: [SpotifyConnect] Starting metadata listener Oct 25 15:05:12 musical-fidelity volumio[1038]: error: Plugin gpios does not return adequate promise from onStart: please update! Oct 25 15:05:12 musical-fidelity sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Loading i18n strings for locale de Oct 25 15:05:12 musical-fidelity sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 15:05:12 musical-fidelity volumio[1038]: Updating browse sources language Oct 25 15:05:12 musical-fidelity volumio[1038]: Cannot find translation for sourceBluetooth Oct 25 15:05:12 musical-fidelity volumio[1038]: Cannot find translation for sourceUSB/PC Oct 25 15:05:12 musical-fidelity volumio[1038]: Cannot find translation for sourceShairport-Sync Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 15:05:12 musical-fidelity systemd[1]: Starting Volspotconnect2 Daemon... Oct 25 15:05:12 musical-fidelity systemd[1]: Started Volspotconnect2 Daemon. Oct 25 15:05:12 musical-fidelity sudo[1304]: pam_unix(sudo:session): session closed for user root Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::initPlayerControls Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: BOOT COMPLETED Oct 25 15:05:12 musical-fidelity volumio[1038]: [Metrics] CommandRouter: 12s 473.59ms Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::Close All Modals sent Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::Close All Modals sent Oct 25 15:05:12 musical-fidelity volumio[1038]: Express server listening on port 3000 Oct 25 15:05:12 musical-fidelity volumio[1038]: [Metrics] WebUI: 13s 478.53ms Oct 25 15:05:12 musical-fidelity volumio[1038]: Volumio Calling Home Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreStateMachine::resetVolumioState Oct 25 15:05:12 musical-fidelity volumio[1309]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Oct 25 15:05:12 musical-fidelity volumio[1309]: Reading Config from "/tmp/volspotify.toml" Oct 25 15:05:12 musical-fidelity volumio[1309]: Malformed config key: missing field `Authentication` Oct 25 15:05:12 musical-fidelity systemd[1]: volspotconnect.service: main process exited, code=exited, status=1/FAILURE Oct 25 15:05:12 musical-fidelity systemd[1]: Unit volspotconnect.service entered failed state. Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreStateMachine::getcurrentVolume Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::volumioRetrievevolume Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Stopping AccessToken refresher cron Oct 25 15:05:12 musical-fidelity volumio[1038]: info: AccessToken refresher cron started Oct 25 15:05:12 musical-fidelity kernel: uart-pl011 3f201000.serial: no DMA platform data Oct 25 15:05:12 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 15:05:12 musical-fidelity volumio[1038]: info: Serial port opened successfully Oct 25 15:05:13 musical-fidelity sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart leddaemon.service Oct 25 15:05:13 musical-fidelity sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 15:05:13 musical-fidelity systemd[1]: Starting LED Daemon Service... Oct 25 15:05:13 musical-fidelity systemd[1]: Started LED Daemon Service. Oct 25 15:05:13 musical-fidelity sudo[1337]: pam_unix(sudo:session): session closed for user root Oct 25 15:05:13 musical-fidelity led-daemon[1342]: Starting Network LED Daemon Oct 25 15:05:13 musical-fidelity volumio[1038]: info: CoreStateMachine::pushState Oct 25 15:05:13 musical-fidelity volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 25 15:05:13 musical-fidelity volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 25 15:05:13 musical-fidelity volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 15:05:13 musical-fidelity volumio[1038]: info: CoreStateMachine::updateTrackBlock Oct 25 15:05:13 musical-fidelity volumio[1038]: info: CorePlayQueue::getTrackBlock Oct 25 15:05:13 musical-fidelity volumio[1038]: info: CoreCommandRouter::volumioRetrievevolume Oct 25 15:05:13 musical-fidelity volumio[1038]: info: Volumio called home Oct 25 15:05:13 musical-fidelity volumio[1038]: info: Setting Device type: Raspberry PI Oct 25 15:05:13 musical-fidelity volumio[1038]: [SpotifyConnect] Vollibrespot Daemon service started! Oct 25 15:05:13 musical-fidelity volumio[1038]: [Metrics] SpotifyConnect: 0s 663.21ms Oct 25 15:05:13 musical-fidelity volumio[1038]: info: RAAT Albumart path created successfully Oct 25 15:05:13 musical-fidelity volumio[1038]: info: Bluetooth name changed to undefined Oct 25 15:05:13 musical-fidelity volumio[1038]: info: Shairport-Sync Stopped Oct 25 15:05:13 musical-fidelity volumio[1038]: info: MPD running with PID671 Oct 25 15:05:13 musical-fidelity volumio[1038]: ,establishing connection Oct 25 15:05:13 musical-fidelity volumio[1038]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 15:05:13 musical-fidelity volumio[1038]: TypeError: Cannot read property 'charAt' of undefined Oct 25 15:05:13 musical-fidelity volumio[1038]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Oct 25 15:05:13 musical-fidelity volumio[1038]: at ontimeout (timers.js:482:11) Oct 25 15:05:13 musical-fidelity volumio[1038]: at tryOnTimeout (timers.js:317:5) Oct 25 15:05:13 musical-fidelity volumio[1038]: at Timer.listOnTimeout (timers.js:277:5) Oct 25 15:05:13 musical-fidelity sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Oct 25 15:05:13 musical-fidelity volumio[1038]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 15:05:13 musical-fidelity sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 15:05:13 musical-fidelity sudo[1358]: pam_unix(sudo:session): session closed for user root Oct 25 15:05:14 musical-fidelity sudo[1375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-25 15:04 Oct 25 15:05:14 musical-fidelity sudo[1375]: 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="mf" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 13 16:55:24 CEST 2022" VOLUMIO_VERSION="1.079" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Musical Fidelity" VOLUMIO_MODEL="MX-Stream" VOLUMIO_VENDOR_MODEL="Musical Fidelity MX-Stream" VOLUMIO_HASH="feef4003d1cbcf074f611a1d9cb9a86e"