-- Logs begin at Thu 2019-02-14 11:12:07 CET, end at Wed 2026-08-26 20:00:27 CEST. -- Aug 26 19:59:05 volumio-portabel volumio[827]: info: Loading plugin "network"... Aug 26 19:59:05 volumio-portabel volumio[827]: info: Refreshing Cached IP Addresses Aug 26 19:59:06 volumio-portabel sudo[1010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 19:59:06 volumio-portabel sudo[1010]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 19:59:06 volumio-portabel sudo[1010]: pam_unix(sudo:session): session closed for user root Aug 26 19:59:06 volumio-portabel sudo[1012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 19:59:06 volumio-portabel volumio[827]: info: Loading plugin "services"... Aug 26 19:59:06 volumio-portabel sudo[1012]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 19:59:06 volumio-portabel volumio[827]: info: Loading plugin "volumio5onboarding"... Aug 26 19:59:06 volumio-portabel sudo[1012]: pam_unix(sudo:session): session closed for user root Aug 26 19:59:06 volumio-portabel sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 26 19:59:06 volumio-portabel sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 19:59:06 volumio-portabel volumio[827]: info: Loading plugin "alsa_controller"... Aug 26 19:59:07 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 19:59:07 volumio-portabel volumio[827]: info: Loading plugin "wizard"... Aug 26 19:59:07 volumio-portabel volumio[827]: info: Loading plugin "networkfs"... Aug 26 19:59:07 volumio-portabel volumio[827]: info: Starting Udev Watcher for removable devices Aug 26 19:59:08 volumio-portabel volumio[827]: info: Ignoring mount for partition: boot Aug 26 19:59:08 volumio-portabel volumio[827]: info: Ignoring mount for partition: volumio Aug 26 19:59:08 volumio-portabel volumio[827]: info: Ignoring mount for partition: volumio_data Aug 26 19:59:08 volumio-portabel volumio[827]: info: Mounting Device A5F4-184E Aug 26 19:59:08 volumio-portabel sudo[1041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/A5F4-184E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 26 19:59:08 volumio-portabel sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 19:59:08 volumio-portabel kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Aug 26 19:59:08 volumio-portabel sudo[1041]: pam_unix(sudo:session): session closed for user root Aug 26 19:59:08 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 19:59:08 volumio-portabel volumio[827]: info: Loading plugin "volumio_command_line_client"... Aug 26 19:59:08 volumio-portabel volumio-remote-updater[440]: [2026-08-26 19:59:08] [connect] Successful connection Aug 26 19:59:08 volumio-portabel volumio[827]: info: Loading plugin "upnp"... Aug 26 19:59:08 volumio-portabel volumio[827]: info: [1787767148918] Starting Upmpd Daemon Aug 26 19:59:08 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 19:59:08 volumio-portabel volumio[827]: info: Loading plugin "my_music"... Aug 26 19:59:09 volumio-portabel volumio[827]: info: Loading plugin "mpd"... Aug 26 19:59:11 volumio-portabel sudo[1020]: pam_unix(sudo:session): session closed for user root Aug 26 19:59:12 volumio-portabel volumio[827]: info: Loading plugin "upnp_browser"... Aug 26 19:59:14 volumio-portabel volumio[827]: info: Starting UPNP Browser Aug 26 19:59:14 volumio-portabel volumio[827]: info: Loading plugin "alarm-clock"... Aug 26 19:59:16 volumio-portabel volumio[827]: info: Loading plugin "airplay_emulation"... Aug 26 19:59:16 volumio-portabel volumio[827]: info: Starting Shairport Sync Aug 26 19:59:16 volumio-portabel volumio[827]: info: Loading plugin "last_100"... Aug 26 19:59:16 volumio-portabel volumio[827]: info: Loading plugin "webradio"... Aug 26 19:59:17 volumio-portabel volumio[827]: info: Loading plugin "i2s_dacs"... Aug 26 19:59:17 volumio-portabel volumio[827]: info: I2S DAC not set, start Auto-detection Aug 26 19:59:17 volumio-portabel volumio[827]: info: Loading plugin "volumiodiscovery"... Aug 26 19:59:18 volumio-portabel volumio[827]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 26 19:59:18 volumio-portabel volumio[827]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 19:59:18 volumio-portabel volumio[827]: *** WARNING *** For more information see Aug 26 19:59:18 volumio-portabel volumio[827]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 26 19:59:18 volumio-portabel volumio[827]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 19:59:18 volumio-portabel volumio[827]: *** WARNING *** For more information see Aug 26 19:59:18 volumio-portabel node[827]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 26 19:59:18 volumio-portabel node[827]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 19:59:18 volumio-portabel node[827]: *** WARNING *** For more information see Aug 26 19:59:18 volumio-portabel node[827]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 26 19:59:18 volumio-portabel node[827]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 19:59:18 volumio-portabel node[827]: *** WARNING *** For more information see Aug 26 19:59:18 volumio-portabel volumio[827]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 26 19:59:18 volumio-portabel volumio[827]: info: Discovery: Started advertising with name: Volumio-portabel Aug 26 19:59:18 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 19:59:18 volumio-portabel volumio[827]: info: Loading plugin "autostart"... Aug 26 19:59:20 volumio-portabel volumio[827]: info: Applying required configuration parameters for plugin autostart Aug 26 19:59:20 volumio-portabel volumio[827]: info: AutoStart - onVolumioStart - read config.json Aug 26 19:59:20 volumio-portabel volumio[827]: info: Loading plugin "outputs"... Aug 26 19:59:21 volumio-portabel volumio[827]: info: Loading plugin "albumart"... Aug 26 19:59:21 volumio-portabel volumio[827]: info: Plugin example_plugin is not enabled Aug 26 19:59:21 volumio-portabel volumio[827]: info: Loading plugin "inputs"... Aug 26 19:59:21 volumio-portabel volumio[827]: info: Loading plugin "updater_comm"... Aug 26 19:59:23 volumio-portabel volumio[827]: info: Plugin mpdemulation is not enabled Aug 26 19:59:23 volumio-portabel volumio-remote-updater[440]: [2026-08-26 19:59:23] [connect] Successful connection Aug 26 19:59:23 volumio-portabel volumio[827]: info: Loading plugin "rest_api"... Aug 26 19:59:24 volumio-portabel volumio[827]: info: Loading plugin "websocket"... Aug 26 19:59:24 volumio-portabel volumio[827]: info: Starting Socket.io Server version 2.3.0 Aug 26 19:59:24 volumio-portabel volumio[827]: info: Plugin squeezelite is not enabled Aug 26 19:59:24 volumio-portabel volumio[827]: info: Loading plugin "randomizer"... Aug 26 19:59:25 volumio-portabel volumio[827]: Forking 1 albumart workers Aug 26 19:59:29 volumio-portabel volumio[827]: info: Applying required configuration parameters for plugin randomizer Aug 26 19:59:31 volumio-portabel volumio[827]: info: Loading i18n strings for locale de Aug 26 19:59:31 volumio-portabel volumio[827]: Updating browse sources language Aug 26 19:59:31 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 19:59:34 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 19:59:34 volumio-portabel volumio[827]: info: CoreCommandRouter::initPlayerControls Aug 26 19:59:34 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 19:59:34 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 19:59:34 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 19:59:34 volumio-portabel volumio[827]: Express server listening on port 3000 Aug 26 19:59:34 volumio-portabel volumio[827]: [Metrics] WebUI: 56s 295.30ms Aug 26 19:59:34 volumio-portabel volumio[827]: info: CoreStateMachine::resetVolumioState Aug 26 19:59:34 volumio-portabel volumio[827]: info: CoreStateMachine::getcurrentVolume Aug 26 19:59:34 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 19:59:34 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 19:59:35 volumio-portabel sudo[1094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 19:59:35 volumio-portabel sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 19:59:35 volumio-portabel sudo[1096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 19:59:35 volumio-portabel sudo[1096]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 19:59:35 volumio-portabel sudo[1094]: pam_unix(sudo:session): session closed for user root Aug 26 19:59:35 volumio-portabel sudo[1096]: pam_unix(sudo:session): session closed for user root Aug 26 19:59:36 volumio-portabel volumio[827]: info: Volumio Network Manager: Network status updated: 2 Aug 26 19:59:36 volumio-portabel volumio[827]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Headphone',0 Aug 26 19:59:37 volumio-portabel volumio[827]: Starting albumart workers Aug 26 19:59:38 volumio-portabel volumio-remote-updater[440]: [2026-08-26 19:59:38] [connect] Successful connection Aug 26 19:59:39 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 19:59:39 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 19:59:39 volumio-portabel volumio[827]: info: Discovery: Getting this device information Aug 26 19:59:39 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioGetState Aug 26 19:59:39 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 19:59:39 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 19:59:39 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 19:59:39 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 19:59:39 volumio-portabel volumio[827]: info: Discovery: Getting this device information Aug 26 19:59:39 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioGetState Aug 26 19:59:39 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 19:59:39 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 19:59:40 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 19:59:40 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 19:59:40 volumio-portabel volumio[827]: info: Discovery: Getting this device information Aug 26 19:59:40 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioGetState Aug 26 19:59:40 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 19:59:40 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 19:59:40 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 19:59:40 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 19:59:40 volumio-portabel volumio[827]: info: Discovery: Getting this device information Aug 26 19:59:40 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioGetState Aug 26 19:59:40 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 19:59:40 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 19:59:40 volumio-portabel volumio[827]: info: VolumeController:: Volume=undefined Mute =false Aug 26 19:59:40 volumio-portabel volumio[827]: info: CoreStateMachine::pushState Aug 26 19:59:40 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 19:59:40 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:59:40 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioPushState Aug 26 19:59:40 volumio-portabel volumio[827]: info: CoreStateMachine::updateTrackBlock Aug 26 19:59:40 volumio-portabel volumio[827]: info: CorePlayQueue::getTrackBlock Aug 26 19:59:40 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 19:59:41 volumio-portabel volumio[827]: info: Reloading queue from file Aug 26 19:59:41 volumio-portabel volumio-remote-updater[440]: [2026-08-26 19:59:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1787767178 101 Aug 26 19:59:41 volumio-portabel volumio[827]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Aug 26 19:59:41 volumio-portabel volumio[827]: info: CoreStateMachine::setRepeat true single undefined Aug 26 19:59:41 volumio-portabel volumio[827]: info: CoreStateMachine::pushState Aug 26 19:59:41 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 19:59:41 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:59:41 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioPushState Aug 26 19:59:41 volumio-portabel volumio[827]: info: CoreStateMachine::setRandom false Aug 26 19:59:41 volumio-portabel volumio[827]: info: CoreStateMachine::pushState Aug 26 19:59:41 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 19:59:41 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:59:41 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioPushState Aug 26 19:59:41 volumio-portabel volumio[827]: info: Setting Device type: Raspberry PI Aug 26 19:59:41 volumio-portabel volumio[827]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Headphone',0 Aug 26 19:59:42 volumio-portabel volumio[827]: info: Completed loading Core Plugins Aug 26 19:59:42 volumio-portabel volumio[827]: info: Preparing to generate the ALSA configuration file Aug 26 19:59:42 volumio-portabel sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 26 19:59:42 volumio-portabel sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 19:59:42 volumio-portabel systemd[1]: Started UPnP Renderer front-end to MPD. Aug 26 19:59:43 volumio-portabel sudo[1157]: pam_unix(sudo:session): session closed for user root Aug 26 19:59:43 volumio-portabel volumio[827]: info: Discovery: adding 0b300ec3-e3e8-4e0b-be72-1ea14ace72a8 Aug 26 19:59:43 volumio-portabel volumio[827]: info: Discovery: Found device Volumio-portabel Aug 26 19:59:43 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioGetState Aug 26 19:59:43 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 19:59:43 volumio-portabel volumio[827]: info: Upmpdcli Daemon Started Aug 26 19:59:43 volumio-portabel volumio[827]: info: VolumeController:: Volume=undefined Mute =false Aug 26 19:59:43 volumio-portabel volumio[827]: info: CoreStateMachine::pushState Aug 26 19:59:43 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 19:59:43 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioPushState Aug 26 19:59:43 volumio-portabel volumio[827]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 26 19:59:43 volumio-portabel volumio[827]: info: Asound.conf file unchanged, so no further update is needed Aug 26 19:59:43 volumio-portabel volumio[827]: info: Output device has changed, restarting MPD Aug 26 19:59:44 volumio-portabel volumio[827]: info: Output device has changed, restarting Shairport Sync Aug 26 19:59:44 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 19:59:44 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 19:59:44 volumio-portabel sudo[1161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 19:59:44 volumio-portabel sudo[1161]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 19:59:44 volumio-portabel sudo[1161]: pam_unix(sudo:session): session closed for user root Aug 26 19:59:44 volumio-portabel sudo[1163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 19:59:44 volumio-portabel sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 19:59:44 volumio-portabel systemd[1]: Listening on mpd.socket. Aug 26 19:59:44 volumio-portabel systemd[1]: Starting Music Player Daemon... Aug 26 19:59:45 volumio-portabel volumio[827]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 19:59:45 volumio-portabel volumio[827]: info: ___________ START PLUGINS ___________ Aug 26 19:59:45 volumio-portabel volumio[827]: info: ControllerMpd::onStart: Initializing MPD Aug 26 19:59:45 volumio-portabel volumio[827]: info: Creating MPD Configuration file Aug 26 19:59:45 volumio-portabel sudo[1170]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 19:59:45 volumio-portabel sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 19:59:45 volumio-portabel sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 26 19:59:45 volumio-portabel sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 19:59:46 volumio-portabel sudo[1170]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 26 19:59:46 volumio-portabel sudo[1170]: pam_unix(sudo:session): session closed for user root Aug 26 19:59:46 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 19:59:46 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 19:59:46 volumio-portabel volumio[827]: info: [1787767186308] CoreMusicLibrary::Adding element Medienserver Aug 26 19:59:46 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 19:59:46 volumio-portabel sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 19:59:46 volumio-portabel sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 19:59:46 volumio-portabel systemd[1]: Started Volumio5 Onboarding Server. Aug 26 19:59:46 volumio-portabel sudo[1172]: pam_unix(sudo:session): session closed for user root Aug 26 19:59:46 volumio-portabel sudo[1174]: pam_unix(sudo:session): session closed for user root Aug 26 19:59:47 volumio-portabel sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 19:59:47 volumio-portabel sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 19:59:47 volumio-portabel volumio[827]: info: UPNP Browser: Client initialized successfully Aug 26 19:59:47 volumio-portabel systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 26 19:59:47 volumio-portabel systemd[1]: mpd.service: Succeeded. Aug 26 19:59:47 volumio-portabel systemd[1]: Stopped Music Player Daemon. Aug 26 19:59:48 volumio-portabel systemd[1]: Starting Music Player Daemon... Aug 26 19:59:48 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 19:59:48 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 19:59:49 volumio-portabel sudo[1192]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 19:59:49 volumio-portabel sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 19:59:49 volumio-portabel sudo[1192]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 26 19:59:49 volumio-portabel sudo[1192]: pam_unix(sudo:session): session closed for user root Aug 26 19:59:50 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T19:59:50.584+02:00 level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Aug 26 19:59:50 volumio-portabel volumio[827]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 19:59:50 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 19:59:50 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 19:59:51 volumio-portabel volumio[827]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 19:59:51 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 19:59:51 volumio-portabel volumio[827]: info: [1787767191203] CoreMusicLibrary::Adding element Last_100 Aug 26 19:59:51 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 19:59:51 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 19:59:51 volumio-portabel volumio[827]: info: [1787767191238] CoreMusicLibrary::Adding element Webradio Aug 26 19:59:51 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 19:59:51 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 19:59:51 volumio-portabel volumio[827]: info: Initializing BBC Radios Aug 26 19:59:51 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 19:59:51 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 19:59:51 volumio-portabel volumio[827]: info: AutoStart - onStart Aug 26 19:59:52 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 19:59:52 volumio-portabel volumio[827]: info: [1787767192489] CoreMusicLibrary::Adding element Randomizer Aug 26 19:59:52 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 19:59:52 volumio-portabel volumio[827]: Cannot find translation for source Randomizer Aug 26 19:59:52 volumio-portabel volumio[827]: info: Volumio Calling Home Aug 26 19:59:56 volumio-portabel volumio[827]: info: MPD Permissions set Aug 26 19:59:56 volumio-portabel volumio[827]: info: MPD Permissions set Aug 26 19:59:57 volumio-portabel volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Aug 26 19:59:58 volumio-portabel dbus-daemon[448]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.12' (uid=0 pid=1184 comm="/usr/bin/volumio5-onboarding ") Aug 26 19:59:58 volumio-portabel volumio[827]: info: Volumio called home Aug 26 19:59:58 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 19:59:58 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 19:59:58 volumio-portabel volumio[827]: info: Discovery: Getting this device information Aug 26 19:59:58 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioGetState Aug 26 19:59:58 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 19:59:58 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 19:59:58 volumio-portabel systemd[1]: Starting Bluetooth service... Aug 26 19:59:59 volumio-portabel volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 26 19:59:59 volumio-portabel volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 26 19:59:59 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 19:59:59 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 19:59:59 volumio-portabel kernel: Bluetooth: Core ver 2.22 Aug 26 19:59:59 volumio-portabel kernel: NET: Registered PF_BLUETOOTH protocol family Aug 26 19:59:59 volumio-portabel kernel: Bluetooth: HCI device and connection manager initialized Aug 26 19:59:59 volumio-portabel kernel: Bluetooth: HCI socket layer initialized Aug 26 19:59:59 volumio-portabel kernel: Bluetooth: L2CAP socket layer initialized Aug 26 19:59:59 volumio-portabel kernel: Bluetooth: SCO socket layer initialized Aug 26 19:59:59 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 19:59:59 volumio-portabel bluetoothd[1234]: Bluetooth daemon 5.83 Aug 26 20:00:00 volumio-portabel bluetoothd[1234]: src/main.c:check_options() Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Aug 26 20:00:00 volumio-portabel dbus-daemon[448]: [system] Successfully activated service 'org.bluez' Aug 26 20:00:00 volumio-portabel systemd[1]: Started Bluetooth service. Aug 26 20:00:00 volumio-portabel bluetoothd[1234]: Starting SDP server Aug 26 20:00:00 volumio-portabel bluetoothd[1234]: Excluding (cli) hostname Aug 26 20:00:00 volumio-portabel bluetoothd[1234]: Excluding (cli) scanparam Aug 26 20:00:00 volumio-portabel bluetoothd[1234]: Excluding (cli) battery Aug 26 20:00:00 volumio-portabel kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 26 20:00:00 volumio-portabel kernel: Bluetooth: BNEP filters: protocol multicast Aug 26 20:00:00 volumio-portabel kernel: Bluetooth: BNEP socket layer initialized Aug 26 20:00:00 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:00.390+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" Aug 26 20:00:00 volumio-portabel bluetoothd[1234]: Bluetooth management interface 1.22 initialized Aug 26 20:00:00 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:00:00 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:00.421+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" Aug 26 20:00:00 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:00.480+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" Aug 26 20:00:00 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:00:00 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:00.527+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" Aug 26 20:00:00 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:00:00 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:00.578+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" Aug 26 20:00:00 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:00.580+02:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" Aug 26 20:00:00 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:00:00 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:00:01 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:00:01 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:00:03 volumio-portabel volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Aug 26 20:00:03 volumio-portabel volumio[827]: info: Received Get System Info Aug 26 20:00:03 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 20:00:03 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 20:00:03 volumio-portabel volumio[827]: info: Discovery: Getting this device information Aug 26 20:00:03 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioGetState Aug 26 20:00:04 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 20:00:04 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 20:00:04 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:04.029+02:00 level=INFO msg="system info for c3f4446f55cbde3f7a6d016e0f90ab3a" deviceName=Volumio-portabel deviceVariant=volumio deviceModel= softwareVersion=3.912 Aug 26 20:00:04 volumio-portabel volumio[827]: verbose: New Socket.io Connection to 192.168.2.102:3000 from 192.168.2.52 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Aug 26 20:00:04 volumio-portabel volumio[827]: info: Received Get System Info Aug 26 20:00:04 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 20:00:04 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 20:00:04 volumio-portabel volumio[827]: info: Discovery: Getting this device information Aug 26 20:00:04 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioGetState Aug 26 20:00:04 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 20:00:04 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 20:00:04 volumio-portabel volumio[827]: info: Starting Shairport Sync Aug 26 20:00:04 volumio-portabel volumio[827]: info: Starting Shairport Sync Aug 26 20:00:04 volumio-portabel volumio[827]: info: Starting Shairport Sync Aug 26 20:00:05 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 20:00:05 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 20:00:05 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:05.185+02:00 level=INFO msg="enabling local network discovery" Aug 26 20:00:05 volumio-portabel sudo[1264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 20:00:05 volumio-portabel sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 20:00:05 volumio-portabel sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:00:05 volumio-portabel sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:00:05 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:05.544+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 26 20:00:05 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioGetState Aug 26 20:00:05 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 20:00:05 volumio-portabel sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 20:00:05 volumio-portabel sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:00:06 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:06.217+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.52:56148 Aug 26 20:00:06 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 20:00:06 volumio-portabel systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 26 20:00:06 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 20:00:06 volumio-portabel volumio[827]: info: Discovery: Getting this device information Aug 26 20:00:06 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioGetState Aug 26 20:00:06 volumio-portabel systemd[1]: shairport-sync.service: Succeeded. Aug 26 20:00:06 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 20:00:06 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 20:00:06 volumio-portabel systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 26 20:00:06 volumio-portabel volumio[827]: info: Received Get System Info Aug 26 20:00:06 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 20:00:06 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 20:00:06 volumio-portabel volumio[827]: info: Discovery: Getting this device information Aug 26 20:00:06 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioGetState Aug 26 20:00:06 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 20:00:06 volumio-portabel systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 26 20:00:06 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 20:00:06 volumio-portabel sudo[1266]: pam_unix(sudo:session): session closed for user root Aug 26 20:00:06 volumio-portabel sudo[1268]: pam_unix(sudo:session): session closed for user root Aug 26 20:00:06 volumio-portabel sudo[1264]: pam_unix(sudo:session): session closed for user root Aug 26 20:00:07 volumio-portabel volumio-remote-updater[440]: No test mode Aug 26 20:00:08 volumio-portabel volumio-remote-updater[440]: No alpha test mode Aug 26 20:00:08 volumio-portabel volumio[827]: info: Shairport-Sync Started Aug 26 20:00:08 volumio-portabel volumio[827]: Error adding Membership: Error: addMembership EINVAL Aug 26 20:00:08 volumio-portabel volumio[827]: info: Shairport-Sync Started Aug 26 20:00:08 volumio-portabel volumio[827]: info: Shairport-Sync Started Aug 26 20:00:08 volumio-portabel volumio[827]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 26 20:00:08 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 26 20:00:08 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 20:00:08 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 20:00:08 volumio-portabel volumio[827]: info: Discovery: Getting this device information Aug 26 20:00:08 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioGetState Aug 26 20:00:08 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 20:00:08 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 20:00:08 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 20:00:08 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:08.761+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Aug 26 20:00:08 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:08.789+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Aug 26 20:00:08 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:08.815+02:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Aug 26 20:00:08 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 20:00:09 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 20:00:09 volumio-portabel volumio[827]: verbose: New Socket.io Connection to 192.168.2.102:3000 from 192.168.2.52 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Aug 26 20:00:09 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 20:00:09 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 20:00:09 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 20:00:09 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 20:00:09 volumio-portabel volumio[827]: info: Discovery: Getting this device information Aug 26 20:00:09 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioGetState Aug 26 20:00:09 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 20:00:09 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 20:00:09 volumio-portabel volumio[827]: verbose: New Socket.io Connection to 192.168.2.102:3000 from 192.168.2.52 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Aug 26 20:00:09 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 20:00:09 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 20:00:10 volumio-portabel volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 26 20:00:10 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:10.122+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 26 20:00:11 volumio-portabel volumio[827]: info: AutoStart - Plugin is starting Aug 26 20:00:12 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioGetQueue Aug 26 20:00:12 volumio-portabel volumio[827]: info: CoreStateMachine::getQueue Aug 26 20:00:12 volumio-portabel volumio[827]: info: CorePlayQueue::getQueue Aug 26 20:00:12 volumio-portabel volumio[827]: info: AutoStart - start playing Aug 26 20:00:12 volumio-portabel volumio[827]: info: AutoStart - start playing with no specific position Aug 26 20:00:12 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioPlay Aug 26 20:00:12 volumio-portabel volumio[827]: info: CoreStateMachine::play index 0 Aug 26 20:00:12 volumio-portabel volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 20:00:12 volumio-portabel volumio[827]: info: CoreStateMachine::stop Aug 26 20:00:12 volumio-portabel volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 20:00:12 volumio-portabel volumio[827]: info: CoreStateMachine::play index undefined Aug 26 20:00:12 volumio-portabel volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 20:00:12 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 20:00:12 volumio-portabel volumio[827]: info: CoreStateMachine::startPlaybackTimer Aug 26 20:00:12 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 20:00:12 volumio-portabel volumio[827]: verbose: ControllerMpd::clearAddPlayTracks USB/A5F4-184E/Musik/Kitschkrieg/KITSCHKRIEG ZWEI (2026)/Kitschkrieg - KITSCHKRIEG ZWEI - 01 - Intro.mp3 Aug 26 20:00:12 volumio-portabel volumio[827]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 20:00:13 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:13.471+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.2.52:56148 @ 0x2a7d200" latency=-122.266612ms timeout=20s Aug 26 20:00:13 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:13.487+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.2.52:56148 @ 0x2a7d200" Aug 26 20:00:13 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:13.506+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.2.52:56148 @ 0x2a7d200" latency=-87.644195ms platform=PLATFORM_ANDROID version=6.260807.0 Aug 26 20:00:13 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 20:00:13 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 20:00:13 volumio-portabel volumio[827]: info: Received Get System Info Aug 26 20:00:13 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 20:00:13 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 20:00:13 volumio-portabel volumio[827]: info: Discovery: Getting this device information Aug 26 20:00:13 volumio-portabel volumio[827]: info: CoreCommandRouter::volumioGetState Aug 26 20:00:13 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 20:00:13 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 20:00:13 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:13.633+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.2.52:56148 @ 0x2a7d200" name=Volumio-portabel Aug 26 20:00:13 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:13.696+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.2.52:56148 @ 0x2a7d200" language=de Aug 26 20:00:13 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 26 20:00:13 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:13.757+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.2.52:56148 @ 0x2a7d200" timezone=Europe/Berlin Aug 26 20:00:13 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:13.775+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.2.52:56148 @ 0x2a7d200" available=true connected=false macAddress= ip4Address= ip6Address= Aug 26 20:00:13 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:13.802+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.2.52:56148 @ 0x2a7d200" available=true connected=true macAddress=64:70:02:1a:a4:a2 ip4Address=192.168.2.102/24 ip6Address= ssid=EasyBox-DE9F43 Aug 26 20:00:13 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:13.817+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.2.52:56148 @ 0x2a7d200" setupComplete=true Aug 26 20:00:13 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 26 20:00:14 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 26 20:00:14 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:00:14 volumio-portabel volumio[827]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 20:00:14 volumio-portabel volumio[827]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 26 20:00:14 volumio-portabel volumio[827]: Card hw:1 'Headphones'/'bcm2835 Headphones' Aug 26 20:00:14 volumio-portabel volumio[827]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 26 20:00:20 volumio-portabel volumio[827]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 26 20:00:21 volumio-portabel volumio[827]: Card hw:1 'Headphones'/'bcm2835 Headphones' Aug 26 20:00:21 volumio-portabel volumio5-onboarding[1184]: time=2026-08-26T20:00:21.258+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.2.52:56148 @ 0x2a7d200" selectedOutputId=1 Aug 26 20:00:21 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 0 Aug 26 20:00:21 volumio-portabel volumio[827]: info: CorePlayQueue::getTrack 1 Aug 26 20:00:21 volumio-portabel volumio[827]: info: Prefetching next song Aug 26 20:00:21 volumio-portabel volumio[827]: info: DOING PREFETCH IN MPD Aug 26 20:00:21 volumio-portabel volumio[827]: verbose: ControllerMpd::sendMpdCommand add "USB/A5F4-184E/Musik/Kitschkrieg/KITSCHKRIEG ZWEI (2026)/Kitschkrieg - KITSCHKRIEG ZWEI - 02 - Sommerregen.mp3" Aug 26 20:00:21 volumio-portabel volumio[827]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 20:00:21 volumio-portabel volumio[827]: TypeError: Cannot read property 'then' of undefined Aug 26 20:00:21 volumio-portabel volumio[827]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Aug 26 20:00:21 volumio-portabel volumio[827]: at ControllerMpd.prefetch (/volumio/app/plugins/music_service/mpd/index.js:3751:15) Aug 26 20:00:21 volumio-portabel volumio[827]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:464:18) Aug 26 20:00:21 volumio-portabel volumio[827]: at listOnTimeout (internal/timers.js:554:17) Aug 26 20:00:21 volumio-portabel volumio[827]: at processTimers (internal/timers.js:497:7) Aug 26 20:00:21 volumio-portabel volumio[827]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 20:00:24 volumio-portabel mpd[1207]: Aug 26 20:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 20:00:26 volumio-portabel systemd[1]: Started Music Player Daemon. Aug 26 20:00:26 volumio-portabel sudo[1163]: pam_unix(sudo:session): session closed for user root Aug 26 20:00:26 volumio-portabel sudo[1183]: pam_unix(sudo:session): session closed for user root Aug 26 20:00:27 volumio-portabel sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-26 19:59 Aug 26 20:00:27 volumio-portabel sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"