Oct 13 18:49:00 musikkasten sudo[2539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 18:49:00 musikkasten sudo[2539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 18:49:00 musikkasten sudo[2537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 18:49:00 musikkasten sudo[2537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 18:49:00 musikkasten sudo[2537]: pam_unix(sudo:session): session closed for user root Oct 13 18:49:01 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:01.222+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" latency=10.906421ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 13 18:49:01 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:01.238+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" latency=-345.879µs timeout=10s Oct 13 18:49:01 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:01.251+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" latency=-345.879µs timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=12.434271ms Oct 13 18:49:01 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:01.258+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" latency=-345.879µs timeout=10s endpoint=http://pushupdates.volumio.org duration=18.919139ms Oct 13 18:49:01 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:01.263+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" latency=-345.879µs timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=24.344376ms Oct 13 18:49:01 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:01.265+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" latency=-345.879µs timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=25.607288ms Oct 13 18:49:01 musikkasten systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 13 18:49:01 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:01.352+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" latency=-345.879µs timeout=10s endpoint=https://www.googleapis.com duration=113.184001ms Oct 13 18:49:01 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:01.369+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" latency=-345.879µs timeout=10s endpoint=https://securetoken.googleapis.com duration=129.904607ms Oct 13 18:49:01 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:01.370+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" latency=-345.879µs timeout=10s endpoint=https://myvolumio.firebaseio.com duration=130.717416ms Oct 13 18:49:01 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:01.410+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" latency=-345.879µs timeout=10s endpoint=https://database.volumio.cloud duration=170.049281ms Oct 13 18:49:01 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:01.429+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" latency=-345.879µs timeout=10s endpoint=https://functions.volumio.cloud duration=189.579407ms Oct 13 18:49:01 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:01.581+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" latency=-345.879µs timeout=10s endpoint=https://functions.volumio.cloud duration=340.907725ms Oct 13 18:49:01 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:01.622+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" latency=-345.879µs timeout=10s endpoint=https://google.com duration=381.016827ms Oct 13 18:49:01 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:01.630+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" latency=-345.879µs timeout=10s endpoint=http://cddb.volumio.org duration=390.938295ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 13 18:49:01 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:01.832+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" latency=-345.879µs timeout=10s endpoint=http://plugins.volumio.org duration=591.872081ms Oct 13 18:49:02 musikkasten volumio[1362]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 18:49:02 musikkasten volumio[1362]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 13 18:49:02 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 18:49:02 musikkasten volumio[1362]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 13 18:49:02 musikkasten volumio[1362]: ------------------------------------ BT MESSAGE: [FUNC] onStart Oct 13 18:49:02 musikkasten volumio[1362]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Oct 13 18:49:03 musikkasten volumio[1362]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Oct 13 18:49:03 musikkasten volumio[1362]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Oct 13 18:49:03 musikkasten volumio[1362]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Oct 13 18:49:03 musikkasten volumio[1362]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 13 18:49:03 musikkasten volumio[1362]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Oct 13 18:49:03 musikkasten volumio[1362]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 13 18:49:03 musikkasten volumio[1362]: info: Adding METAVOLUMIO REST API Endpoints Oct 13 18:49:03 musikkasten volumio[1362]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 13 18:49:03 musikkasten volumio[1362]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 13 18:49:03 musikkasten volumio[1362]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 13 18:49:03 musikkasten volumio[1362]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 13 18:49:03 musikkasten volumio[1362]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 13 18:49:03 musikkasten volumio[1362]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 13 18:49:03 musikkasten volumio[1362]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Oct 13 18:49:03 musikkasten volumio[1362]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 13 18:49:03 musikkasten volumio[1362]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Oct 13 18:49:03 musikkasten volumio[1362]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 13 18:49:03 musikkasten volumio[1362]: error: Hi Res Audio Failed Login: Missing Login Data Oct 13 18:49:03 musikkasten volumio[1362]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 13 18:49:04 musikkasten volumio[1362]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 13 18:49:04 musikkasten volumio[1362]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 13 18:49:04 musikkasten volumio[1362]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 13 18:49:04 musikkasten volumio[1362]: info: Refreshing QOBUZ token Oct 13 18:49:04 musikkasten volumio[1362]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 13 18:49:04 musikkasten volumio[1362]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Oct 13 18:49:04 musikkasten volumio[1362]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Oct 13 18:49:04 musikkasten volumio[1362]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Oct 13 18:49:05 musikkasten volumio[1362]: info: QobuzConnect: Starting Qobuz Connect socket and service Oct 13 18:49:05 musikkasten bluetoothd[919]: Path / reserved for Adv Monitor app :1.26 Oct 13 18:49:05 musikkasten volumio[1362]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Oct 13 18:49:05 musikkasten volumio[1362]: info: Adding TIDAL REST API Endpoints Oct 13 18:49:05 musikkasten bluetoothd[919]: Adv Monitor app :1.26 disconnected from D-Bus Oct 13 18:49:06 musikkasten systemd[1]: mpd.service: Deactivated successfully. Oct 13 18:49:06 musikkasten systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 18:49:06 musikkasten systemd[1]: mpd.service: Consumed 7.301s CPU time. Oct 13 18:49:06 musikkasten systemd[1]: mpd.socket: Deactivated successfully. Oct 13 18:49:06 musikkasten systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 18:49:06 musikkasten systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 18:49:06 musikkasten systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 18:49:06 musikkasten systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 18:49:06 musikkasten sudo[2602]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 18:49:06 musikkasten sudo[2602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 18:49:06 musikkasten sudo[2596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 18:49:06 musikkasten sudo[2602]: pam_unix(sudo:session): session closed for user root Oct 13 18:49:06 musikkasten sudo[2596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 18:49:07 musikkasten volumio[1362]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Oct 13 18:49:07 musikkasten sudo[2596]: pam_unix(sudo:session): session closed for user root Oct 13 18:49:07 musikkasten volumio[1362]: info: MPD Permissions set Oct 13 18:49:07 musikkasten sudo[2608]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 18:49:07 musikkasten sudo[2608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 18:49:07 musikkasten sudo[2607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 13 18:49:07 musikkasten sudo[2607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 18:49:07 musikkasten systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 18:49:07 musikkasten sudo[2608]: pam_unix(sudo:session): session closed for user root Oct 13 18:49:07 musikkasten sudo[2611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 13 18:49:07 musikkasten sudo[2611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 18:49:08 musikkasten systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 13 18:49:08 musikkasten sudo[2607]: pam_unix(sudo:session): session closed for user root Oct 13 18:49:08 musikkasten systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 18:49:08 musikkasten systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 18:49:08 musikkasten volumiobt[2620]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 13 18:49:08 musikkasten systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Oct 13 18:49:08 musikkasten sudo[2611]: pam_unix(sudo:session): session closed for user root Oct 13 18:49:08 musikkasten sudo[2622]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 13 18:49:08 musikkasten sudo[2622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 18:49:08 musikkasten autossh[2621]: port set to 0, monitoring disabled Oct 13 18:49:08 musikkasten autossh[2621]: starting ssh (count 1) Oct 13 18:49:08 musikkasten autossh[2621]: ssh child pid is 2626 Oct 13 18:49:08 musikkasten sudo[2622]: pam_unix(sudo:session): session closed for user root Oct 13 18:49:08 musikkasten sudo[2627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 13 18:49:08 musikkasten sudo[2627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 18:49:08 musikkasten sudo[2627]: pam_unix(sudo:session): session closed for user root Oct 13 18:49:08 musikkasten volumiobt[2629]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 13 18:49:08 musikkasten bluetoothd[919]: Path / reserved for Adv Monitor app :1.27 Oct 13 18:49:08 musikkasten volumiossh-tunnel[2626]: Warning: Permanently added '[eu10.myvolumio.org]:2222' (RSA) to the list of known hosts. Oct 13 18:49:08 musikkasten volumio[1362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 13 18:49:08 musikkasten bluetoothd[919]: Adv Monitor app :1.27 disconnected from D-Bus Oct 13 18:49:08 musikkasten volumiobt[2632]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 13 18:49:08 musikkasten volumiobt[2633]: [198B blob data] Oct 13 18:49:08 musikkasten volumiobt[2633]: [162B blob data] Oct 13 18:49:08 musikkasten volumiobt[2633]: [162B blob data] Oct 13 18:49:08 musikkasten volumiobt[2633]: [162B blob data] Oct 13 18:49:08 musikkasten volumiobt[2633]: [118B blob data] Oct 13 18:49:09 musikkasten volumiobt[2633]: [5D-2A-F6-D0-00-A9]> discoverable on Oct 13 18:49:09 musikkasten volumiobt[2633]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Oct 13 18:49:09 musikkasten volumiobt[2633]: [5D-2A-F6-D0-00-A9]> pairable on Oct 13 18:49:09 musikkasten bluetoothd[919]: Path / reserved for Adv Monitor app :1.28 Oct 13 18:49:09 musikkasten volumio[1362]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 13 18:49:09 musikkasten bluetoothd[919]: Adv Monitor app :1.28 disconnected from D-Bus Oct 13 18:49:09 musikkasten volumiobt[2633]: [5D-2A-F6-D0-00-A9]> Oct 13 18:49:09 musikkasten volumiobt[2636]: INFO [BTSTART] Registering Bluetooth agent... Oct 13 18:49:09 musikkasten volumiobt[2637]: [NEW] Media /org/bluez/hci0 Oct 13 18:49:09 musikkasten volumiobt[2637]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 13 18:49:09 musikkasten volumiobt[2637]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 13 18:49:09 musikkasten volumiobt[2637]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 13 18:49:09 musikkasten bluetoothd[919]: Path / reserved for Adv Monitor app :1.29 Oct 13 18:49:09 musikkasten bluetoothd[919]: Adv Monitor app :1.29 disconnected from D-Bus Oct 13 18:49:09 musikkasten volumio[1362]: info: Remote SSH Started Oct 13 18:49:09 musikkasten volumiobt[2638]: No agent is registered Oct 13 18:49:09 musikkasten volumiobt[2638]: [NEW] Media /org/bluez/hci0 Oct 13 18:49:09 musikkasten volumiobt[2638]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 13 18:49:09 musikkasten volumiobt[2638]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 13 18:49:09 musikkasten volumiobt[2638]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 13 18:49:09 musikkasten bluetoothd[919]: Path / reserved for Adv Monitor app :1.30 Oct 13 18:49:09 musikkasten bluetoothd[919]: Adv Monitor app :1.30 disconnected from D-Bus Oct 13 18:49:09 musikkasten volumiobt[2639]: INFO [BTSTART] Agent registered successfully. Oct 13 18:49:09 musikkasten volumiobt[2640]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 13 18:49:11 musikkasten volumiobt[2641]: 2026-10-13 18:49:11 a2dp-agent [INFO] Connecting to system D-Bus Oct 13 18:49:11 musikkasten volumiobt[2641]: 2026-10-13 18:49:11 a2dp-agent [INFO] Connected to system D-Bus Oct 13 18:49:11 musikkasten volumiobt[2641]: 2026-10-13 18:49:11 bluezutils [INFO] Found adapter at: /org/bluez/hci0 Oct 13 18:49:11 musikkasten volumiobt[2641]: 2026-10-13 18:49:11 a2dp-agent [INFO] Found Bluetooth adapter: /org/bluez/hci0 Oct 13 18:49:11 musikkasten volumiobt[2641]: 2026-10-13 18:49:11 a2dp-agent [INFO] Set DiscoverableTimeout to infinite Oct 13 18:49:11 musikkasten volumiobt[2641]: 2026-10-13 18:49:11 a2dp-agent [INFO] Enabled Discoverable mode Oct 13 18:49:11 musikkasten volumiobt[2641]: 2026-10-13 18:49:11 a2dp-agent [INFO] Agent registered at /local/a2dpagent Oct 13 18:49:11 musikkasten volumiobt[2641]: 2026-10-13 18:49:11 a2dp-agent [INFO] Agent set as default Oct 13 18:49:11 musikkasten volumiobt[2641]: 2026-10-13 18:49:11 a2dp-agent [INFO] A2DP agent running, waiting for connections... Oct 13 18:49:13 musikkasten volumio[1362]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Oct 13 18:49:13 musikkasten volumio[1362]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Oct 13 18:49:14 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 18:49:14 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 18:49:14 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 18:49:14 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 18:49:14 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 18:49:14 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 18:49:14 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 18:49:14 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 18:49:14 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 18:49:14 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 18:49:15 musikkasten mpd[2604]: 2026-10-13T18:49:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 13 18:49:16 musikkasten systemd[1]: Started mpd.service - Music Player Daemon. Oct 13 18:49:16 musikkasten sudo[2539]: pam_unix(sudo:session): session closed for user root Oct 13 18:49:17 musikkasten volumio[1362]: info: Access Token successfully retrieved Oct 13 18:49:17 musikkasten volumio[1362]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 18:49:17 musikkasten volumio[1362]: info: [1791910157230] CoreMusicLibrary::Adding element QOBUZ Oct 13 18:49:17 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 18:49:17 musikkasten volumio[1362]: Cannot find translation for source Podcast Oct 13 18:49:17 musikkasten volumio[1362]: Cannot find translation for source QOBUZ Oct 13 18:49:17 musikkasten volumio[1362]: info: Stopping AccessToken refresher cron for QOBUZ Oct 13 18:49:17 musikkasten volumio[1362]: info: AccessToken refresher cron started for QOBUZ Oct 13 18:49:17 musikkasten volumio[1362]: info: Adding QOBUZ REST API Endpoints Oct 13 18:49:18 musikkasten sudo[2669]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 18:49:18 musikkasten sudo[2669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 18:49:18 musikkasten sudo[2671]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 18:49:18 musikkasten sudo[2671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 18:49:18 musikkasten volumio[1362]: info: Starting Shairport Sync Oct 13 18:49:18 musikkasten sudo[2669]: pam_unix(sudo:session): session closed for user root Oct 13 18:49:18 musikkasten sudo[2671]: pam_unix(sudo:session): session closed for user root Oct 13 18:49:18 musikkasten sudo[2676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 18:49:18 musikkasten sudo[2676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 18:49:18 musikkasten volumio[1362]: error: updateQueue error: null Oct 13 18:49:18 musikkasten volumio[1362]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Oct 13 18:49:18 musikkasten systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 18:49:18 musikkasten systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 18:49:18 musikkasten systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 18:49:18 musikkasten systemd[1]: shairport-sync.service: Consumed 2.432s CPU time. Oct 13 18:49:18 musikkasten systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 18:49:19 musikkasten sudo[2676]: pam_unix(sudo:session): session closed for user root Oct 13 18:49:19 musikkasten volumio[1362]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 13 18:49:19 musikkasten volumio[1362]: info: Shairport-Sync Started Oct 13 18:49:19 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:19 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:19 musikkasten volumio[1362]: info: Executing endpoint qc_getconfig Oct 13 18:49:19 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 13 18:49:20 musikkasten qobuz-connect[2616]: 20261013 18:49:20.300 [2616.2616] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 13 18:49:20 musikkasten qobuz-connect[2616]: 20261013 18:49:20.468 [2616.2616] INFO VolumeManager: [0x960f68]: Setting new playback volume: 75 Oct 13 18:49:20 musikkasten qobuz-connect[2616]: 20261013 18:49:20.468 [2616.2616] INFO VolumeManager: [0x960f68]: Setting new mute state: 0 Oct 13 18:49:20 musikkasten qobuz-connect[2616]: 20261013 18:49:20.468 [2616.2616] INFO AudioStreamManager: [0x960cc0]: Setting new audio download buffer size: 1048576 Oct 13 18:49:20 musikkasten qobuz-connect[2616]: 20261013 18:49:20.468 [2616.2616] INFO QobuzConnect: [0x961830]: Client initialized! Oct 13 18:49:20 musikkasten qobuz-connect[2616]: 20261013 18:49:20.468 [2616.2616] INFO SampleApp: Starting Avahi advertising, name: Musikkasten, service name: _qobuz-connect._tcp Oct 13 18:49:20 musikkasten qobuz-connect[2616]: 20261013 18:49:20.519 [2616.2616] INFO LocalConfigManager: [0x9609e8]: Starting Local Configuration server Oct 13 18:49:20 musikkasten qobuz-connect[2616]: 20261013 18:49:20.519 [2616.2616] INFO SampleApp: Starting Local configuration server Oct 13 18:49:20 musikkasten qobuz-connect[2616]: 20261013 18:49:20.520 [2616.2616] INFO SampleApp: Connected to UNIX socket client 0x94b818 Oct 13 18:49:20 musikkasten qobuz-connect[2616]: 20261013 18:49:20.520 [2616.2616] INFO SampleApp: Playback volume changed: 75 Oct 13 18:49:20 musikkasten volumio[1362]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 13 18:49:20 musikkasten volumio[1362]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 13 18:49:20 musikkasten volumio[1362]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Oct 13 18:49:20 musikkasten volumio[1362]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Oct 13 18:49:21 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:21 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:21 musikkasten volumio[1362]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Oct 13 18:49:21 musikkasten volumio[1362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Oct 13 18:49:21 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 18:49:21 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 18:49:21 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 18:49:22 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:22 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:22 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 13 18:49:22 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 13 18:49:22 musikkasten volumio[1362]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 13 18:49:22 musikkasten volumio[1362]: info: Received Get System Info Oct 13 18:49:22 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 18:49:22 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 18:49:22 musikkasten volumio[1362]: info: Discovery: Getting this device information Oct 13 18:49:22 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:22 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:22 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 18:49:22 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:22 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:23 musikkasten volumio[1362]: info: Listing playlists Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 18:49:23 musikkasten volumio[1362]: info: Received Get System Info Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 18:49:23 musikkasten volumio[1362]: info: Discovery: Getting this device information Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:23 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 18:49:23 musikkasten volumio[1362]: info: Received Get System Info Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 18:49:23 musikkasten volumio[1362]: info: Discovery: Getting this device information Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:23 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 18:49:23 musikkasten volumio[1362]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 13 18:49:23 musikkasten volumio[1362]: info: Received Get System Version Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 18:49:23 musikkasten volumio[1362]: info: Received Get System Info Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 18:49:23 musikkasten volumio[1362]: info: Discovery: Getting this device information Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:23 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:23 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 13 18:49:23 musikkasten volumio[1362]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 13 18:49:23 musikkasten volumio[1362]: info: Received Get System Info Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 18:49:23 musikkasten volumio[1362]: info: Discovery: Getting this device information Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:23 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:23 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:23 musikkasten volumio[1362]: info: Listing playlists Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 13 18:49:23 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 13 18:49:25 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:25 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:26 musikkasten sudo[2741]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 18:49:26 musikkasten sudo[2743]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 18:49:26 musikkasten sudo[2741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 18:49:26 musikkasten sudo[2743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 18:49:26 musikkasten sudo[2743]: pam_unix(sudo:session): session closed for user root Oct 13 18:49:26 musikkasten sudo[2741]: pam_unix(sudo:session): session closed for user root Oct 13 18:49:26 musikkasten volumio[1362]: verbose: New Socket.io Connection to 192.168.179.78 from 192.168.179.51 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 16 Oct 13 18:49:26 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 13 18:49:27 musikkasten sudo[2786]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 18:49:27 musikkasten sudo[2786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 18:49:27 musikkasten sudo[2789]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 18:49:27 musikkasten sudo[2789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 18:49:27 musikkasten sudo[2789]: pam_unix(sudo:session): session closed for user root Oct 13 18:49:27 musikkasten sudo[2786]: pam_unix(sudo:session): session closed for user root Oct 13 18:49:27 musikkasten volumio[1362]: verbose: New Socket.io Connection to 192.168.179.78 from 192.168.179.51 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 17 Oct 13 18:49:28 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 18:49:28 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 18:49:28 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 18:49:28 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:28 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:28 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 13 18:49:28 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 13 18:49:28 musikkasten volumio[1362]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 13 18:49:28 musikkasten volumio[1362]: info: Received Get System Info Oct 13 18:49:28 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 18:49:28 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 18:49:28 musikkasten volumio[1362]: info: Discovery: Getting this device information Oct 13 18:49:28 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:28 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:28 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 18:49:28 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:28 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:28 musikkasten volumio[1362]: info: Listing playlists Oct 13 18:49:28 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 13 18:49:28 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 18:49:28 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 13 18:49:29 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 18:49:29 musikkasten volumio[1362]: info: Received Get System Info Oct 13 18:49:29 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 18:49:29 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 18:49:29 musikkasten volumio[1362]: info: Discovery: Getting this device information Oct 13 18:49:29 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:29 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:29 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 18:49:29 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 18:49:29 musikkasten volumio[1362]: info: Received Get System Info Oct 13 18:49:29 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 18:49:29 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 18:49:29 musikkasten volumio[1362]: info: Discovery: Getting this device information Oct 13 18:49:29 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:29 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:29 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 18:49:29 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 13 18:49:37 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 13 18:49:41 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetQueue Oct 13 18:49:41 musikkasten volumio[1362]: info: CoreStateMachine::getQueue Oct 13 18:49:41 musikkasten volumio[1362]: info: CorePlayQueue::getQueue Oct 13 18:49:45 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 18:49:45 musikkasten volumio[1362]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 13 18:49:45 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 13 18:49:45 musikkasten volumio[1362]: info: Received Get System Version Oct 13 18:49:45 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 18:49:45 musikkasten volumio[1362]: info: Received Get System Info Oct 13 18:49:45 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 18:49:45 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 18:49:45 musikkasten volumio[1362]: info: Discovery: Getting this device information Oct 13 18:49:45 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:45 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 0 Oct 13 18:49:45 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 18:49:55 musikkasten volumio[1362]: info: Preload queue cleared Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreStateMachine::ClearQueue Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreStateMachine::stop Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 18:49:55 musikkasten volumio[1362]: info: CorePlayQueue::clearPlayQueue Oct 13 18:49:55 musikkasten volumio[1362]: info: CorePlayQueue::saveQueue Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreCommandRouter::volumioPushQueue Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreStateMachine::addQueueItems Oct 13 18:49:55 musikkasten volumio[1362]: info: CorePlayQueue::addQueueItems Oct 13 18:49:55 musikkasten volumio[1362]: info: Preload queue cleared Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/01 - Willkommen.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/01 - Willkommen.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/02 - Herzschlag.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/02 - Herzschlag.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/03 - Denn Wer Liebt.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/03 - Denn Wer Liebt.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/04 - Sehnsucht.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/04 - Sehnsucht.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/05 - Wehmut.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/05 - Wehmut.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/06 - Black.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/06 - Black.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/07 - Mitternacht.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/07 - Mitternacht.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/08 - Let Me Love You.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/08 - Let Me Love You.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/09 - Nacht.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/09 - Nacht.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/10 - Fate.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/10 - Fate.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/11 - Porque te vas.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/11 - Porque te vas.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/12 - In der Weite.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/12 - In der Weite.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/13 - Sommernacht.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/13 - Sommernacht.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/14 - Everything.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/14 - Everything.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/15 - Lichter.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/15 - Lichter.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F01%20-%20Willkommen.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/01 - Willkommen.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F02%20-%20Herzschlag.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/02 - Herzschlag.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Anna%20Maria%20M%C3%BChe/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F03%20-%20Denn%20Wer%20Liebt.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/03 - Denn Wer Liebt.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Xavier%20Naidoo/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F04%20-%20Sehnsucht.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/04 - Sehnsucht.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F05%20-%20Wehmut.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/05 - Wehmut.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Jette%20von%20Roth/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F06%20-%20Black.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/06 - Black.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F07%20-%20Mitternacht.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/07 - Mitternacht.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Kim%20Sanders/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F08%20-%20Let%20Me%20Love%20You.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/08 - Let Me Love You.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Ben%20Becker/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F09%20-%20Nacht.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/09 - Nacht.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Isis%20Gee/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F10%20-%20Fate.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/10 - Fate.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Ana%20Torroja/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F11%20-%20Porque%20te%20vas.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/11 - Porque te vas.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Anna%20Maria%20M%C3%BChe/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F12%20-%20In%20der%20Weite.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/12 - In der Weite.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F13%20-%20Sommernacht.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/13 - Sommernacht.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Helen%20Boulding/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F14%20-%20Everything.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/14 - Everything.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F15%20-%20Lichter.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/15 - Lichter.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreCommandRouter::volumioPushQueue Oct 13 18:49:55 musikkasten volumio[1362]: info: CorePlayQueue::saveQueue Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreStateMachine::updateTrackBlock Oct 13 18:49:55 musikkasten volumio[1362]: info: CorePlayQueue::getTrackBlock Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreCommandRouter::volumioPlay Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreStateMachine::play index 14 Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreStateMachine::addQueueItems Oct 13 18:49:55 musikkasten volumio[1362]: info: CorePlayQueue::addQueueItems Oct 13 18:49:55 musikkasten volumio[1362]: info: Preload queue cleared Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/16 - Ile Aye.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/16 - Ile Aye.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/17 - Once Upon a Time.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/17 - Once Upon a Time.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/18 - Der Kuss.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/18 - Der Kuss.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/19 - Wunschtraum.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/19 - Wunschtraum.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/20 - Tired.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/20 - Tired.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/21 - Lichtung.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/21 - Lichtung.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/22 - Breathe.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/22 - Breathe.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/23 - Zenit (Ausschnitt).flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/23 - Zenit (Ausschnitt).flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/24 - In the Dark.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/24 - In the Dark.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/25 - Tagtraum.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/25 - Tagtraum.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/26 - Destiny.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/26 - Destiny.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/27 - White.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/27 - White.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/28 - Lonely.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/28 - Lonely.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/29 - Sehnsucht (reprise).flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/29 - Sehnsucht (reprise).flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/30 - Vor der Zeit.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/30 - Vor der Zeit.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: Adding Item to queue: music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/31 - Forever.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: Exploding uri music-library/USB/Musikarchiv/Schiller/2008 Sehnsucht/31 - Forever.flac in service mpd Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreStateMachine::stop Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreStateMachine::play index undefined Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 18:49:55 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 14 Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreStateMachine::startPlaybackTimer Oct 13 18:49:55 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 14 Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 18:49:55 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 13 18:49:55 musikkasten volumio[1362]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Oct 13 18:49:55 musikkasten volumio[1362]: verbose: ControllerMpd::clearAddPlayTracks USB/Musikarchiv/Schiller/2008 Sehnsucht/15 - Lichter.flac Oct 13 18:49:55 musikkasten volumio[1362]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Stephenie%20Coker/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F16%20-%20Ile%20Aye.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/16 - Ile Aye.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F17%20-%20Once%20Upon%20a%20Time.flac&metadata=false Oct 13 18:49:55 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/17 - Once Upon a Time.flac Oct 13 18:49:55 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Anna%20Maria%20M%C3%BChe/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F18%20-%20Der%20Kuss.flac&metadata=false Oct 13 18:49:56 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/18 - Der Kuss.flac Oct 13 18:49:56 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F19%20-%20Wunschtraum.flac&metadata=false Oct 13 18:49:56 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/19 - Wunschtraum.flac Oct 13 18:49:56 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Ja%C3%ABl/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F20%20-%20Tired.flac&metadata=false Oct 13 18:49:56 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/20 - Tired.flac Oct 13 18:49:56 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F21%20-%20Lichtung.flac&metadata=false Oct 13 18:49:56 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/21 - Lichtung.flac Oct 13 18:49:56 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20September/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F22%20-%20Breathe.flac&metadata=false Oct 13 18:49:56 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/22 - Breathe.flac Oct 13 18:49:56 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Klaus%20Schulze/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F23%20-%20Zenit%20(Ausschnitt).flac&metadata=false Oct 13 18:49:56 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/23 - Zenit (Ausschnitt).flac Oct 13 18:49:56 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Jette%20von%20Roth/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F24%20-%20In%20the%20Dark.flac&metadata=false Oct 13 18:49:56 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/24 - In the Dark.flac Oct 13 18:49:56 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F25%20-%20Tagtraum.flac&metadata=false Oct 13 18:49:56 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/25 - Tagtraum.flac Oct 13 18:49:56 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Despina%20Vandi%20(%CE%94%CE%AD%CF%83%CF%80%CE%BF%CE%B9%CE%BD%CE%B1%20%CE%92%CE%B1%CE%BD%CE%B4%CE%AE)/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F26%20-%20Destiny.flac&metadata=false Oct 13 18:49:56 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/26 - Destiny.flac Oct 13 18:49:56 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F27%20-%20White.flac&metadata=false Oct 13 18:49:56 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/27 - White.flac Oct 13 18:49:56 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Damae/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F28%20-%20Lonely.flac&metadata=false Oct 13 18:49:56 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/28 - Lonely.flac Oct 13 18:49:56 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F29%20-%20Sehnsucht%20(reprise).flac&metadata=false Oct 13 18:49:56 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/29 - Sehnsucht (reprise).flac Oct 13 18:49:56 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Ben%20Becker/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F30%20-%20Vor%20der%20Zeit.flac&metadata=false Oct 13 18:49:56 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/30 - Vor der Zeit.flac Oct 13 18:49:56 musikkasten volumio[1362]: info: ALBUMART /albumart?cacheid=417&web=Schiller%2C%20Kim%20Sanders/Sehnsucht/large&path=%2Fmnt%2FUSB%2FMusikarchiv%2FSchiller%2F2008%20Sehnsucht%2F31%20-%20Forever.flac&metadata=false Oct 13 18:49:56 musikkasten volumio[1362]: info: URI /mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/31 - Forever.flac Oct 13 18:49:56 musikkasten volumio[1362]: info: sendMpdCommand stop took 202 milliseconds Oct 13 18:49:56 musikkasten volumio[1362]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 18:49:56 musikkasten volumio[1362]: info: CoreCommandRouter::volumioPushQueue Oct 13 18:49:56 musikkasten volumio[1362]: info: CorePlayQueue::saveQueue Oct 13 18:49:56 musikkasten volumio[1362]: info: CoreStateMachine::updateTrackBlock Oct 13 18:49:56 musikkasten volumio[1362]: info: CorePlayQueue::getTrackBlock Oct 13 18:49:56 musikkasten volumio[1362]: info: Oct 13 18:49:56 musikkasten volumio[1362]: ---------------------------- MPD announces system playlist update Oct 13 18:49:56 musikkasten volumio[1362]: info: Ignoring MPD Status Update Oct 13 18:49:56 musikkasten volumio[1362]: info: sendMpdCommand clear took 187 milliseconds Oct 13 18:49:56 musikkasten volumio[1362]: verbose: ControllerMpd::sendMpdCommand add "USB/Musikarchiv/Schiller/2008 Sehnsucht/15 - Lichter.flac" Oct 13 18:49:56 musikkasten volumio[1362]: error: updateQueue error: null Oct 13 18:49:56 musikkasten volumio[1362]: info: Oct 13 18:49:56 musikkasten volumio[1362]: ---------------------------- MPD announces system playlist update Oct 13 18:49:56 musikkasten volumio[1362]: info: Ignoring MPD Status Update Oct 13 18:49:56 musikkasten volumio[1362]: info: ------------------------------ 58ms Oct 13 18:49:56 musikkasten volumio[1362]: info: sendMpdCommand add "USB/Musikarchiv/Schiller/2008 Sehnsucht/15 - Lichter.flac" took 41 milliseconds Oct 13 18:49:56 musikkasten volumio[1362]: verbose: ControllerMpd::sendMpdCommand play Oct 13 18:49:56 musikkasten kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Oct 13 18:49:56 musikkasten volumio[1362]: info: Oct 13 18:49:56 musikkasten volumio[1362]: ---------------------------- MPD announces state update: player Oct 13 18:49:56 musikkasten volumio[1362]: info: ------------------------------ 288ms Oct 13 18:49:56 musikkasten volumio[1362]: info: sendMpdCommand play took 275 milliseconds Oct 13 18:49:56 musikkasten volumio[1362]: info: ControllerMpd::getState Oct 13 18:49:56 musikkasten volumio[1362]: verbose: ControllerMpd::sendMpdCommand status Oct 13 18:49:56 musikkasten volumio[1362]: info: Oct 13 18:49:56 musikkasten volumio[1362]: ---------------------------- MPD announces state update: player Oct 13 18:49:56 musikkasten volumio[1362]: info: ControllerMpd::getState Oct 13 18:49:56 musikkasten volumio[1362]: verbose: ControllerMpd::sendMpdCommand status Oct 13 18:49:56 musikkasten volumio[1362]: info: sendMpdCommand status took 26 milliseconds Oct 13 18:49:56 musikkasten volumio[1362]: verbose: ControllerMpd::parseState Oct 13 18:49:56 musikkasten volumio[1362]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 18:49:56 musikkasten volumio[1362]: info: sendMpdCommand status took 42 milliseconds Oct 13 18:49:56 musikkasten volumio[1362]: info: sendMpdCommand playlistinfo took 31 milliseconds Oct 13 18:49:56 musikkasten volumio[1362]: verbose: ControllerMpd::parseState Oct 13 18:49:56 musikkasten volumio[1362]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 18:49:56 musikkasten volumio[1362]: verbose: ControllerMpd::parseTrackInfo Oct 13 18:49:56 musikkasten volumio[1362]: info: ControllerMpd::pushState Oct 13 18:49:56 musikkasten volumio[1362]: info: CoreCommandRouter::servicePushState Oct 13 18:49:56 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 14 Oct 13 18:49:56 musikkasten volumio[1362]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"636 Kbps","isStreaming":false,"title":"Lichter","artist":"Schiller","album":"Sehnsucht","uri":"USB/Musikarchiv/Schiller/2008 Sehnsucht/15 - Lichter.flac","trackType":"flac"} Oct 13 18:49:56 musikkasten volumio[1362]: verbose: CURRENT POSITION 14 Oct 13 18:49:56 musikkasten volumio[1362]: info: CoreStateMachine::syncState stateService play Oct 13 18:49:56 musikkasten volumio[1362]: info: CoreStateMachine::syncState currentStatus stop Oct 13 18:49:56 musikkasten volumio[1362]: info: ------------------------------ 174ms Oct 13 18:49:56 musikkasten volumio[1362]: info: sendMpdCommand playlistinfo took 57 milliseconds Oct 13 18:49:56 musikkasten volumio[1362]: verbose: ControllerMpd::parseTrackInfo Oct 13 18:49:56 musikkasten volumio[1362]: info: ControllerMpd::pushState Oct 13 18:49:56 musikkasten volumio[1362]: info: CoreCommandRouter::servicePushState Oct 13 18:49:56 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 14 Oct 13 18:49:56 musikkasten volumio[1362]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"636 Kbps","isStreaming":false,"title":"Lichter","artist":"Schiller","album":"Sehnsucht","uri":"USB/Musikarchiv/Schiller/2008 Sehnsucht/15 - Lichter.flac","trackType":"flac"} Oct 13 18:49:56 musikkasten volumio[1362]: verbose: CURRENT POSITION 14 Oct 13 18:49:56 musikkasten volumio[1362]: info: CoreStateMachine::syncState stateService play Oct 13 18:49:56 musikkasten volumio[1362]: info: CoreStateMachine::syncState currentStatus play Oct 13 18:49:56 musikkasten volumio[1362]: info: Received an update from plugin. extracting info from payload Oct 13 18:49:56 musikkasten volumio[1362]: info: CoreStateMachine::pushState Oct 13 18:49:56 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 14 Oct 13 18:49:56 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:49:56 musikkasten volumio[1362]: info: CoreCommandRouter::volumioPushState Oct 13 18:49:56 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:56 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 14 Oct 13 18:49:56 musikkasten volumio[1362]: info: CoreStateMachine::pushState Oct 13 18:49:56 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 14 Oct 13 18:49:56 musikkasten volumio[1362]: info: CoreCommandRouter::volumioPushState Oct 13 18:49:56 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetState Oct 13 18:49:56 musikkasten volumio[1362]: info: CorePlayQueue::getTrack 14 Oct 13 18:49:57 musikkasten volumio[1362]: info: ------------------------------ 408ms Oct 13 18:49:57 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:57.797+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" state=STATUS_PLAYING positionMs=962 volume= Oct 13 18:49:57 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:57.785+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" state=STATUS_PLAYING positionMs=962 volume= Oct 13 18:49:57 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:57.976+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" id="mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/15 - Lichter.flac" title="15 - Lichter" Oct 13 18:49:58 musikkasten volumio5-onboarding[2192]: time=2026-10-13T18:49:58.108+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.179.51:49756,00:00:00:00:00:00%01 @ 0x1fb4510" id="mnt/USB/Musikarchiv/Schiller/2008 Sehnsucht/15 - Lichter.flac" title="15 - Lichter" Oct 13 18:49:59 musikkasten volumio[1362]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 13 18:49:59 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_music , getSuperSearchEnabledSourcesList Oct 13 18:49:59 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 18:49:59 musikkasten volumio[1362]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: All cached search sources collected Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 18:50:00 musikkasten volumio[1362]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 13 18:50:03 musikkasten volumio[1362]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 18:50:03 musikkasten volumio[1362]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Oct 13 18:50:03 musikkasten volumio[1362]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Oct 13 18:50:03 musikkasten volumio[1362]: at new NodeError (node:internal/errors:405:5) Oct 13 18:50:03 musikkasten volumio[1362]: at assert (node:internal/assert:14:11) Oct 13 18:50:03 musikkasten volumio[1362]: at internalConnectMultiple (node:net:1118:3) Oct 13 18:50:03 musikkasten volumio[1362]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Oct 13 18:50:03 musikkasten volumio[1362]: at listOnTimeout (node:internal/timers:575:11) Oct 13 18:50:03 musikkasten volumio[1362]: at process.processTimers (node:internal/timers:514:7) { Oct 13 18:50:03 musikkasten volumio[1362]: code: 'ERR_INTERNAL_ASSERTION' Oct 13 18:50:03 musikkasten volumio[1362]: } Oct 13 18:50:03 musikkasten volumio[1362]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 18:50:15 musikkasten systemd[1]: mpd.service: Watchdog timeout (limit 20s)! Oct 13 18:50:15 musikkasten systemd[1]: mpd.service: Killing process 2604 (mpd) with signal SIGABRT. Oct 13 18:50:15 musikkasten systemd[1]: mpd.service: Main process exited, code=killed, status=6/ABRT Oct 13 18:50:15 musikkasten systemd[1]: mpd.service: Failed with result 'watchdog'. Oct 13 18:50:16 musikkasten systemd[1]: mpd.service: Consumed 18.758s CPU time. Oct 13 18:50:16 musikkasten systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 18:50:16 musikkasten sudo[2926]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 18:50:16 musikkasten sudo[2926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 18:50:16 musikkasten sudo[2926]: pam_unix(sudo:session): session closed for user root Oct 13 18:50:25 musikkasten mpd[2928]: 2026-10-13T18:50:25 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 13 18:50:26 musikkasten systemd[1]: Started mpd.service - Music Player Daemon. Oct 13 18:50:27 musikkasten sudo[2948]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 18:49' Oct 13 18:50:27 musikkasten sudo[2948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"