Dec 26 08:40:00 volumio volumio[1258]: info: Loading plugin "network"... Dec 26 08:40:00 volumio volumio[1258]: info: Refreshing Cached IP Addresses Dec 26 08:40:00 volumio volumio[1258]: info: Loading plugin "services"... Dec 26 08:40:00 volumio volumio[1258]: info: Loading plugin "alsa_controller"... Dec 26 08:40:00 volumio sudo[1301]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 08:40:00 volumio sudo[1302]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 08:40:00 volumio sudo[1302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:00 volumio sudo[1301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:00 volumio sudo[1302]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:00 volumio sudo[1301]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:00 volumio sudo[1309]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 26 08:40:00 volumio sudo[1309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:00 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 08:40:00 volumio volumio[1258]: info: Loading plugin "wizard"... Dec 26 08:40:00 volumio volumio[1258]: info: Loading plugin "networkfs"... Dec 26 08:40:00 volumio volumio[1258]: info: Starting Udev Watcher for removable devices Dec 26 08:40:00 volumio volumio[1258]: info: Ignoring mount for partition: boot Dec 26 08:40:00 volumio volumio[1258]: info: Ignoring mount for partition: volumio Dec 26 08:40:00 volumio volumio[1258]: info: Ignoring mount for partition: volumio_data Dec 26 08:40:00 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 08:40:00 volumio volumio[1258]: info: Loading plugin "volumio_command_line_client"... Dec 26 08:40:00 volumio volumio[1258]: info: Loading plugin "upnp"... Dec 26 08:40:00 volumio volumio[1258]: info: [1766734800665] Starting Upmpd Daemon Dec 26 08:40:00 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 08:40:00 volumio volumio[1258]: info: Loading plugin "my_music"... Dec 26 08:40:00 volumio volumio[1258]: info: Loading plugin "mpd"... Dec 26 08:40:01 volumio sudo[1309]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:01 volumio volumio[1258]: info: Loading plugin "upnp_browser"... Dec 26 08:40:03 volumio volumio[1258]: info: Starting UPNP Browser Dec 26 08:40:03 volumio volumio[1258]: info: Loading plugin "alarm-clock"... Dec 26 08:40:04 volumio volumio-remote-updater[711]: [2025-12-26 08:40:04] [connect] Successful connection Dec 26 08:40:04 volumio volumio[1258]: info: Loading plugin "airplay_emulation"... Dec 26 08:40:04 volumio volumio[1258]: info: Starting Shairport Sync Dec 26 08:40:04 volumio volumio[1258]: info: Loading plugin "last_100"... Dec 26 08:40:04 volumio volumio[1258]: info: Loading plugin "webradio"... Dec 26 08:40:04 volumio volumio[1258]: info: Loading plugin "i2s_dacs"... Dec 26 08:40:04 volumio volumio[1258]: info: Loading plugin "volumiodiscovery"... Dec 26 08:40:04 volumio volumio[1258]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 26 08:40:04 volumio volumio[1258]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 08:40:04 volumio volumio[1258]: *** WARNING *** For more information see Dec 26 08:40:04 volumio volumio[1258]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 26 08:40:04 volumio volumio[1258]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 08:40:04 volumio volumio[1258]: *** WARNING *** For more information see Dec 26 08:40:04 volumio node[1258]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 26 08:40:04 volumio node[1258]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 08:40:04 volumio node[1258]: *** WARNING *** For more information see Dec 26 08:40:04 volumio node[1258]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 26 08:40:04 volumio node[1258]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 08:40:04 volumio node[1258]: *** WARNING *** For more information see Dec 26 08:40:04 volumio volumio[1258]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 26 08:40:04 volumio volumio[1258]: info: Discovery: Started advertising with name: volumio Dec 26 08:40:04 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 08:40:04 volumio volumio[1258]: info: Loading plugin "outputs"... Dec 26 08:40:04 volumio volumio[1258]: info: Loading plugin "albumart"... Dec 26 08:40:04 volumio volumio[1258]: info: Plugin example_plugin is not enabled Dec 26 08:40:04 volumio volumio[1258]: info: Loading plugin "inputs"... Dec 26 08:40:04 volumio volumio[1258]: info: Loading plugin "updater_comm"... Dec 26 08:40:05 volumio volumio[1258]: info: Plugin mpdemulation is not enabled Dec 26 08:40:05 volumio volumio[1258]: info: Loading plugin "rest_api"... Dec 26 08:40:05 volumio volumio[1258]: info: Loading plugin "websocket"... Dec 26 08:40:05 volumio volumio[1258]: info: Starting Socket.io Server version 1.7.4 Dec 26 08:40:05 volumio volumio[1258]: info: Loading plugin "touch_display"... Dec 26 08:40:06 volumio volumio[1337]: Forking 3 albumart workers Dec 26 08:40:06 volumio volumio[1258]: info: Applying required configuration parameters for plugin touch_display Dec 26 08:40:07 volumio volumio[1258]: info: Loading i18n strings for locale de Dec 26 08:40:07 volumio volumio[1258]: Updating browse sources language Dec 26 08:40:07 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 08:40:08 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 26 08:40:09 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 08:40:09 volumio volumio[1258]: info: CoreCommandRouter::initPlayerControls Dec 26 08:40:09 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:09 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:09 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:09 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:09 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:09 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:09 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:09 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:09 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 08:40:09 volumio volumio[1258]: Express server listening on port 3000 Dec 26 08:40:09 volumio volumio[1258]: [Metrics] WebUI: 15s 463.84ms Dec 26 08:40:10 volumio volumio[1258]: info: CoreStateMachine::resetVolumioState Dec 26 08:40:10 volumio volumio[1258]: info: CoreStateMachine::getcurrentVolume Dec 26 08:40:10 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 08:40:10 volumio volumio[1258]: info: Volumio Network Manager: Network status updated: 1 Dec 26 08:40:11 volumio sudo[1397]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 08:40:11 volumio sudo[1397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:11 volumio sudo[1397]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:11 volumio sudo[1400]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 08:40:11 volumio sudo[1400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:11 volumio sudo[1400]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:12 volumio volumio[1258]: info: VolumeController:: Volume=20 Mute =false Dec 26 08:40:12 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:40:12 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:40:12 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 08:40:12 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:40:12 volumio volumio[1258]: info: CoreStateMachine::updateTrackBlock Dec 26 08:40:12 volumio volumio[1258]: info: CorePlayQueue::getTrackBlock Dec 26 08:40:12 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 08:40:12 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 26 08:40:12 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 08:40:12 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 08:40:12 volumio upmpdcli[1406]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.254.6 port 49152 IPV6 port 49152 Dec 26 08:40:12 volumio upmpdcli[1406]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Dec 26 08:40:12 volumio upmpdcli[1406]: :2:../src/main.cxx:728::MPD connection failed Dec 26 08:40:12 volumio volumio[1258]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 26 08:40:13 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:40:13 volumio volumio[1258]: info: Reloading queue from file Dec 26 08:40:13 volumio volumio[1258]: info: CoreStateMachine::setRepeat null single undefined Dec 26 08:40:13 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:40:13 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:40:13 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 08:40:13 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:40:13 volumio volumio[1258]: info: CoreStateMachine::setRandom null Dec 26 08:40:13 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:40:13 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:40:13 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:40:13 volumio volumio[1258]: info: Setting Device type: Raspberry PI Dec 26 08:40:13 volumio volumio[1258]: info: Completed loading Core Plugins Dec 26 08:40:13 volumio volumio[1258]: info: Preparing to generate the ALSA configuration file Dec 26 08:40:13 volumio volumio[1258]: info: VolumeController:: Volume=20 Mute =false Dec 26 08:40:13 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:40:13 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:40:13 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:40:13 volumio volumio[1258]: info: Discovery: adding 62579d53-fd40-42c3-9d46-4306d55cf885 Dec 26 08:40:13 volumio volumio[1258]: info: Discovery: Found device volumio Dec 26 08:40:13 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Dec 26 08:40:13 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:40:14 volumio sudo[1425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 26 08:40:14 volumio sudo[1425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:14 volumio volumio[1258]: info: Discovery: this is already registered, 62579d53-fd40-42c3-9d46-4306d55cf885 Dec 26 08:40:14 volumio volumio[1258]: info: Discovery: Found device volumio Dec 26 08:40:14 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Dec 26 08:40:14 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:40:14 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 26 08:40:14 volumio sudo[1425]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:14 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 26 08:40:14 volumio upmpdcli[1406]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Dec 26 08:40:14 volumio upmpdcli[1406]: :2:../src/main.cxx:728::MPD connection failed Dec 26 08:40:14 volumio volumio[1258]: info: Asound.conf file unchanged, so no further update is needed Dec 26 08:40:14 volumio volumio[1258]: info: Output device has changed, restarting MPD Dec 26 08:40:14 volumio volumio[1258]: info: Output device has changed, restarting Shairport Sync Dec 26 08:40:14 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:14 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:40:15 volumio sudo[1444]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 08:40:15 volumio sudo[1444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:15 volumio sudo[1444]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:15 volumio sudo[1447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 08:40:15 volumio sudo[1447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:15 volumio volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 08:40:15 volumio volumio[1258]: info: ___________ START PLUGINS ___________ Dec 26 08:40:15 volumio volumio[1258]: info: ControllerMpd::onStart: Initializing MPD Dec 26 08:40:15 volumio volumio[1258]: info: Creating MPD Configuration file Dec 26 08:40:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 08:40:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 08:40:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 08:40:15 volumio volumio[1258]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 08:40:15 volumio volumio[1258]: info: [1766734815504] CoreMusicLibrary::Adding element Medienserver Dec 26 08:40:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 08:40:15 volumio sudo[1458]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 08:40:15 volumio sudo[1458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:15 volumio sudo[1459]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 08:40:15 volumio sudo[1459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 08:40:15 volumio sudo[1468]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 26 08:40:15 volumio sudo[1459]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:15 volumio sudo[1461]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 08:40:15 volumio sudo[1461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:15 volumio volumio[1258]: info: UPNP Browser: Client initialized successfully Dec 26 08:40:15 volumio sudo[1458]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:40:16 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 08:40:16 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 08:40:16 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 08:40:16 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 08:40:16 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 08:40:16 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 08:40:16 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 08:40:16 volumio volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 08:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:40:16 volumio sudo[1476]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 08:40:16 volumio sudo[1476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 08:40:16 volumio sudo[1481]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 26 08:40:16 volumio sudo[1476]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:16 volumio volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 08:40:16 volumio volumio[1258]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 08:40:16 volumio volumio[1258]: info: [1766734816619] CoreMusicLibrary::Adding element Last_100 Dec 26 08:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 08:40:16 volumio volumio[1258]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 08:40:16 volumio volumio[1258]: info: [1766734816718] CoreMusicLibrary::Adding element Webradio Dec 26 08:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 08:40:16 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 26 08:40:16 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 26 08:40:16 volumio systemd[1]: setdatetime-helper.service: Consumed 1.123s CPU time. Dec 26 08:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 08:40:16 volumio volumio[1258]: info: Initializing BBC Radios Dec 26 08:40:17 volumio volumio[1348]: Starting albumart workers Dec 26 08:40:17 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 08:40:17 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:40:17 volumio volumio[1258]: info: Loading i18n strings for locale de Dec 26 08:40:17 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 08:40:17 volumio volumio[1258]: info: Volumio Calling Home Dec 26 08:40:17 volumio sudo[1502]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Dec 26 08:40:17 volumio sudo[1502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:17 volumio sudo[1502]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:17 volumio volumio[1347]: Starting albumart workers Dec 26 08:40:17 volumio volumio[1349]: Starting albumart workers Dec 26 08:40:18 volumio sudo[1508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 26 08:40:18 volumio sudo[1508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:18 volumio sudo[1511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 26 08:40:18 volumio sudo[1511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:18 volumio sudo[1508]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:19 volumio sudo[1514]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 26 08:40:19 volumio sudo[1514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:19 volumio systemd[1]: Reloading. Dec 26 08:40:19 volumio volumio-remote-updater[711]: [2025-12-26 08:40:19] [connect] Successful connection Dec 26 08:40:20 volumio upmpdcli[1406]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Dec 26 08:40:20 volumio upmpdcli[1406]: :2:../src/main.cxx:728::MPD connection failed Dec 26 08:40:21 volumio systemd[1]: Reloading. Dec 26 08:40:21 volumio sudo[1511]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:23 volumio sudo[1514]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:24 volumio mpd[1482]: 2025-12-26T08:40:24 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 08:40:25 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 26 08:40:25 volumio sudo[1461]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:25 volumio sudo[1447]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:25 volumio volumio[1258]: info: touch_display: Backlight interface detected. Dec 26 08:40:25 volumio volumio[1258]: info: Upmpdcli Daemon Started Dec 26 08:40:25 volumio volumio[1258]: info: touch_display: systemctl daemon-reload succeeded. Dec 26 08:40:25 volumio volumio[1258]: info: touch_display: systemctl disable getty@tty1.service succeeded. Dec 26 08:40:25 volumio volumio[1258]: info: touch_display: systemctl stop getty@tty1.service succeeded. Dec 26 08:40:25 volumio volumio[1258]: info: MPD Permissions set Dec 26 08:40:25 volumio sudo[1586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Dec 26 08:40:25 volumio sudo[1586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:25 volumio volumio[1258]: info: MPD Permissions set Dec 26 08:40:25 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Dec 26 08:40:25 volumio sudo[1586]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:26 volumio volumio[1258]: info: Volumio called home Dec 26 08:40:26 volumio startx[1617]: X.Org X Server 1.21.1.7 Dec 26 08:40:26 volumio startx[1617]: X Protocol Version 11, Revision 0 Dec 26 08:40:26 volumio startx[1617]: Current Operating System: Linux volumio 6.12.47-v7+ #1904 SMP Mon Sep 15 13:05:52 BST 2025 armv7l Dec 26 08:40:26 volumio startx[1617]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=2fcd4aa9-19da-4188-a556-3009b8a5e64a imgfile=/volumio_current.sqsh bootpart=UUID=DFF5-D140 datapart=UUID=d70587d3-92de-4c97-a226-12cdc3254005 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Dec 26 08:40:26 volumio startx[1617]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Dec 26 08:40:26 volumio startx[1617]: Current version of pixman: 0.44.0 Dec 26 08:40:26 volumio startx[1617]: Before reporting problems, check http://wiki.x.org Dec 26 08:40:26 volumio startx[1617]: to make sure that you have the latest version. Dec 26 08:40:26 volumio startx[1617]: Markers: (--) probed, (**) from config file, (==) default setting, Dec 26 08:40:26 volumio startx[1617]: (++) from command line, (!!) notice, (II) informational, Dec 26 08:40:26 volumio startx[1617]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Dec 26 08:40:26 volumio startx[1617]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Dec 26 08:40:26 2025 Dec 26 08:40:26 volumio startx[1617]: (==) Using config directory: "/etc/X11/xorg.conf.d" Dec 26 08:40:26 volumio startx[1617]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Dec 26 08:40:26 volumio sudo[1589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Dec 26 08:40:26 volumio sudo[1589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:26 volumio sudo[1589]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:26 volumio startx[1617]: (EE) Dec 26 08:40:26 volumio startx[1617]: Fatal server error: Dec 26 08:40:26 volumio startx[1617]: (EE) no screens found(EE) Dec 26 08:40:26 volumio startx[1617]: (EE) Dec 26 08:40:26 volumio startx[1617]: Please consult the The X.Org Foundation support Dec 26 08:40:26 volumio startx[1617]: at http://wiki.x.org Dec 26 08:40:26 volumio startx[1617]: for help. Dec 26 08:40:26 volumio startx[1617]: (EE) Please also check the log file at "/var/log/Xorg.0.log" for additional information. Dec 26 08:40:26 volumio startx[1617]: (EE) Dec 26 08:40:26 volumio startx[1617]: (EE) Server terminated with error (1). Closing log file. Dec 26 08:40:27 volumio volumio[1258]: error: MPD error: The expression evaluated to a falsy value: Dec 26 08:40:27 volumio volumio[1258]: assert.ok(self.idling) Dec 26 08:40:27 volumio volumio[1258]: error: The expression evaluated to a falsy value: Dec 26 08:40:27 volumio volumio[1258]: assert.ok(self.idling) Dec 26 08:40:27 volumio volumio[1258]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Dec 26 08:40:27 volumio volumio[1258]: info: touch_display: Volumio Kiosk started. Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Dec 26 08:40:27 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:40:27 volumio volumio[1258]: info: Completed starting Core Plugins Dec 26 08:40:27 volumio volumio[1258]: info: ------------------------------------------- Dec 26 08:40:27 volumio volumio[1258]: info: ----- MyVolumio plugins startup ---- Dec 26 08:40:27 volumio volumio[1258]: info: ------------------------------------------- Dec 26 08:40:27 volumio volumio[1258]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 26 08:40:27 volumio volumio[1258]: info: MPD running with PID1482 Dec 26 08:40:27 volumio volumio[1258]: ,establishing connection Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:40:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:40:28 volumio volumio[1258]: error: updateQueue error: null Dec 26 08:40:28 volumio volumio[1258]: info: touch_display: IframeKeyboardBridge extension installed successfully Dec 26 08:40:28 volumio sudo[1634]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Dec 26 08:40:28 volumio sudo[1634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:28 volumio sudo[1634]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:28 volumio volumio[1258]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 26 08:40:28 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:28 volumio volumio[1258]: info: touch_display: Raspberry Pi Foundation touch screen detected. Dec 26 08:40:28 volumio sudo[1641]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Dec 26 08:40:28 volumio sudo[1641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:28 volumio volumio[1258]: error: updateQueue error: null Dec 26 08:40:28 volumio sudo[1641]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:28 volumio upmpdcli[1643]: writing RSA key Dec 26 08:40:28 volumio volumio[1258]: info: touch_display: File permissions for backlight brightness control set. Dec 26 08:40:28 volumio volumio[1258]: info: Starting Shairport Sync Dec 26 08:40:29 volumio volumio[1258]: info: Starting Shairport Sync Dec 26 08:40:29 volumio volumio[1258]: info: Starting Shairport Sync Dec 26 08:40:29 volumio sudo[1649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 08:40:29 volumio sudo[1652]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 08:40:29 volumio sudo[1652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:29 volumio volumio[1258]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Dec 26 08:40:29 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:29 volumio sudo[1649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:29 volumio sudo[1654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 08:40:29 volumio sudo[1654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:29 volumio sudo[1660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 08:40:29 volumio sudo[1660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:29 volumio sudo[1659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 08:40:29 volumio sudo[1659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:29 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 08:40:29 volumio mpd_monitor.sh[1672]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 08:40:29 volumio sudo[1652]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:29 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 26 08:40:29 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 08:40:29 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 08:40:29 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 08:40:29 volumio systemd[1]: shairport-sync.service: Consumed 1.931s CPU time. Dec 26 08:40:29 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 26 08:40:29 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 26 08:40:29 volumio volumio[1258]: info: Successfully started MPD Monitor Dec 26 08:40:29 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:29 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 08:40:29 volumio mpd_monitor.sh[1678]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 08:40:29 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 08:40:29 volumio sudo[1660]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:29 volumio sudo[1649]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:29 volumio sudo[1654]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:29 volumio sudo[1659]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:29 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Dec 26 08:40:29 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:40:29 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:29 volumio volumio[1258]: info: Shairport-Sync Started Dec 26 08:40:29 volumio volumio[1258]: Error adding Membership: Error: addMembership EINVAL Dec 26 08:40:29 volumio volumio[1258]: info: Successfully started MPD Monitor Dec 26 08:40:29 volumio volumio[1258]: info: Shairport-Sync Started Dec 26 08:40:29 volumio volumio[1258]: info: Shairport-Sync Started Dec 26 08:40:30 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:30 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:30 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:30 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:30 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:30 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:31 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:31 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:31 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:31 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:31 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:31 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:31 volumio volumio[1258]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 26 08:40:32 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:32 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:32 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:32 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:32 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:32 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:32 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:33 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:33 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:33 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:33 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:33 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:33 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:33 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:34 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:34 volumio volumio-remote-updater[711]: [2025-12-26 08:40:34] [connect] Successful connection Dec 26 08:40:34 volumio volumio-remote-updater[711]: [2025-12-26 08:40:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766734834 101 Dec 26 08:40:34 volumio volumio[1258]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Dec 26 08:40:34 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:34 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:34 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:34 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:34 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:34 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:35 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:35 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:35 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:35 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:35 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:36 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:36 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 26 08:40:36 volumio volumio[1258]: info: Adding plugin bluetooth to MyMusic Plugins Dec 26 08:40:36 volumio volumio[1258]: info: Adding plugin multiroom to MyMusic Plugins Dec 26 08:40:36 volumio volumio[1258]: info: Adding plugin metavolumio to MyMusic Plugins Dec 26 08:40:36 volumio volumio[1258]: info: Adding plugin cd_controller to MyMusic Plugins Dec 26 08:40:36 volumio volumio[1258]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 26 08:40:36 volumio volumio[1258]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 26 08:40:36 volumio volumio[1258]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 26 08:40:36 volumio volumio[1258]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 26 08:40:41 volumio startx[1616]: xinit: giving up Dec 26 08:40:41 volumio startx[1616]: xinit: unable to connect to X server: Connection refused Dec 26 08:40:41 volumio startx[1616]: xinit: server error Dec 26 08:40:41 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Dec 26 08:40:41 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Dec 26 08:40:43 volumio volumio[1258]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 26 08:40:43 volumio volumio[1258]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 26 08:40:43 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:40:43 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:40:43 volumio volumio[1258]: info: Starting MyVolumio Remote Streaming Endpoints Dec 26 08:40:43 volumio volumio[1258]: info: MyVolumio login type: Token Dec 26 08:40:43 volumio volumio[1258]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 26 08:40:43 volumio volumio[1258]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 26 08:40:50 volumio volumio[1258]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 26 08:40:50 volumio volumio[1258]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 26 08:40:50 volumio volumio[1258]: info: Streaming services startup Dec 26 08:40:50 volumio volumio[1258]: info: Starting Streaming Daemon Dec 26 08:40:50 volumio volumio[1258]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 26 08:40:50 volumio sudo[1815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 26 08:40:50 volumio sudo[1815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:50 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:50 volumio sudo[1815]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:50 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 08:40:50 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:40:50 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 08:40:50 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:50 volumio volumio[1258]: error: Cannot start Volumio Streaming Daemon Dec 26 08:40:50 volumio volumio[1258]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 26 08:40:50 volumio volumio[1258]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 26 08:40:50 volumio volumio[1258]: info: touch_display: X display number found: 0 Dec 26 08:40:51 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:51 volumio volumio[1258]: error: MyVolumio Custom Token format not valid, refreshing it Dec 26 08:40:51 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:52 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:52 volumio volumio-remote-updater[711]: Test mode disabled Dec 26 08:40:52 volumio volumio-remote-updater[711]: Alpha mode disabled Dec 26 08:40:52 volumio volumio-remote-updater[711]: Alpha legacy test mode disabled Dec 26 08:40:52 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 26 08:40:52 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:52 volumio volumio[1258]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 26 08:40:52 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 26 08:40:52 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:53 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:53 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:53 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:53 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:53 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:53 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:54 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:54 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:54 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:54 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:54 volumio volumio[1258]: info: MyVolumio login type: Token Dec 26 08:40:54 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:55 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:55 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:55 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:55 volumio volumio[1258]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 26 08:40:56 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:56 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:56 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:56 volumio volumio[1258]: info: MyVolumio token set successfully Dec 26 08:40:56 volumio volumio[1258]: info: MYVOLUMIO: Adding device Dec 26 08:40:56 volumio volumio[1258]: info: MYVOLUMIO: Evaluating Server Dec 26 08:40:57 volumio volumio[1258]: info: MyVolumio status changed Dec 26 08:40:57 volumio volumio[1258]: info: Streaming services startup Dec 26 08:40:57 volumio volumio[1258]: info: Starting Streaming Daemon Dec 26 08:40:57 volumio volumio[1258]: info: Removing browser output: myVolumio user plan is not superstar Dec 26 08:40:57 volumio volumio[1258]: info: Removing audio output: Dec 26 08:40:57 volumio volumio[1258]: info: Stoppping Tunnel 1 Dec 26 08:40:57 volumio sudo[1910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 26 08:40:57 volumio sudo[1910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:57 volumio sudo[1910]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:57 volumio sudo[1913]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 26 08:40:57 volumio sudo[1913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:40:57 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:57 volumio 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. Dec 26 08:40:57 volumio 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. Dec 26 08:40:57 volumio volumio[1258]: info: Setting Geolocation for MyVolumio to eu6 Dec 26 08:40:57 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:40:57 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:40:57 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:40:57 volumio 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. Dec 26 08:40:57 volumio 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. Dec 26 08:40:58 volumio 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. Dec 26 08:40:58 volumio 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. Dec 26 08:40:58 volumio sudo[1913]: pam_unix(sudo:session): session closed for user root Dec 26 08:40:58 volumio volumio[1258]: error: Cannot start Volumio Streaming Daemon Dec 26 08:40:58 volumio volumio[1258]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 26 08:40:58 volumio volumio[1258]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 26 08:40:58 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:58 volumio volumio[1258]: info: Remote SSH Stopped Dec 26 08:40:58 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:58 volumio volumio[1258]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 26 08:40:58 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 08:40:58 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:58 volumio volumio[1258]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 26 08:40:58 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:58 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:59 volumio volumio[1258]: info: Updating MyVolumio device info Dec 26 08:40:59 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:40:59 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:40:59 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:40:59 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:59 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Dec 26 08:40:59 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:40:59 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:59 volumio volumio[1258]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 26 08:40:59 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:59 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:59 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:40:59 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:00 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:00 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:00 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:00 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:00 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:00 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:01 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:01 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:01 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:01 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:01 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:01 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:02 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:02 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:02 volumio volumio[1258]: info: MYVOLUMIO: Adding device Dec 26 08:41:02 volumio volumio[1258]: info: MYVOLUMIO: Evaluating Server Dec 26 08:41:02 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:03 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:03 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:41:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:41:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 26 08:41:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 26 08:41:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 26 08:41:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 26 08:41:03 volumio volumio[1258]: info: CoreCommandRouter::volumioGetBrowseSources Dec 26 08:41:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 08:41:03 volumio volumio[1258]: info: Setting Geolocation for MyVolumio to eu6 Dec 26 08:41:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:41:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:41:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:41:03 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:03 volumio volumio[1258]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 26 08:41:03 volumio volumio[1258]: error: touch_display: Connecting to the Xserver failed. Dec 26 08:41:04 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:04 volumio volumio[1258]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 26 08:41:04 volumio volumio[1258]: info: Updating MyVolumio device info Dec 26 08:41:04 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:41:04 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:41:04 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:41:04 volumio volumio[1258]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 26 08:41:10 volumio volumio[1258]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 26 08:41:10 volumio volumio[1258]: info: Completed starting MyVolumio Plugin Dec 26 08:41:10 volumio volumio[1258]: [Metrics] CommandRouter: 74s 651.13ms Dec 26 08:41:10 volumio volumio[1258]: info: CoreCommandRouter::volumiosetStartupVolume Dec 26 08:41:10 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 08:41:10 volumio volumio[1258]: info: VolumeController:: Setting startup Volume 20 Dec 26 08:41:10 volumio volumio[1258]: info: VolumeController::SetAlsaVolume20 Dec 26 08:41:10 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 08:41:10 volumio volumio[1258]: info: CoreCommandRouter::Close All Modals sent Dec 26 08:41:10 volumio volumio[1258]: info: CoreCommandRouter::Close All Modals sent Dec 26 08:41:10 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:10 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:10 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:11 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 26 08:41:11 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 08:41:11 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 26 08:41:12 volumio volumio[1258]: verbose: New Socket.io Connection to 192.168.254.6 from 192.168.254.60 UA: Mozilla/5.0 (Android 16; Mobile; rv:146.0) Gecko/146.0 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 5 Dec 26 08:41:12 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 08:41:12 volumio volumio[1258]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 08:41:12 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 08:41:12 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Dec 26 08:41:12 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:12 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 26 08:41:12 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 26 08:41:12 volumio volumio[1258]: info: Received Get System Info Dec 26 08:41:12 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 08:41:12 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 08:41:12 volumio volumio[1258]: info: Discovery: Getting this device information Dec 26 08:41:12 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Dec 26 08:41:12 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:12 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 08:41:12 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Dec 26 08:41:12 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:12 volumio volumio[1258]: info: Listing playlists Dec 26 08:41:12 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 26 08:41:12 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 08:41:13 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 26 08:41:14 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 08:41:14 volumio volumio[1258]: info: Received Get System Info Dec 26 08:41:14 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 08:41:14 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 08:41:14 volumio volumio[1258]: info: Discovery: Getting this device information Dec 26 08:41:14 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Dec 26 08:41:14 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:14 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 08:41:14 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 26 08:41:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 08:41:15 volumio volumio[1258]: info: Received Get System Info Dec 26 08:41:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 08:41:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 08:41:15 volumio volumio[1258]: info: Discovery: Getting this device information Dec 26 08:41:15 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Dec 26 08:41:15 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 08:41:17 volumio volumio[1258]: info: BOOT COMPLETED Dec 26 08:41:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 26 08:41:19 volumio volumio[1258]: info: CoreCommandRouter::volumioPlay Dec 26 08:41:19 volumio volumio[1258]: info: CoreStateMachine::play index undefined Dec 26 08:41:19 volumio volumio[1258]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 08:41:19 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:19 volumio volumio[1258]: info: CoreStateMachine::startPlaybackTimer Dec 26 08:41:19 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:19 volumio volumio[1258]: info: [1766734879957] ControllerWebradio::clearAddPlayTrack Dec 26 08:41:19 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 08:41:20 volumio volumio[1258]: info: sendMpdCommand stop took 46 milliseconds Dec 26 08:41:20 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 08:41:20 volumio volumio[1258]: info: Dec 26 08:41:20 volumio volumio[1258]: ---------------------------- MPD announces system playlist update Dec 26 08:41:20 volumio volumio[1258]: info: Ignoring MPD Status Update Dec 26 08:41:20 volumio volumio[1258]: info: sendMpdCommand clear took 46 milliseconds Dec 26 08:41:20 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein" Dec 26 08:41:20 volumio volumio[1258]: info: Dec 26 08:41:20 volumio volumio[1258]: ---------------------------- MPD announces system playlist update Dec 26 08:41:20 volumio volumio[1258]: info: Ignoring MPD Status Update Dec 26 08:41:20 volumio volumio[1258]: info: Dec 26 08:41:20 volumio volumio[1258]: ---------------------------- MPD announces system playlist update Dec 26 08:41:20 volumio volumio[1258]: info: Ignoring MPD Status Update Dec 26 08:41:20 volumio volumio[1258]: error: updateQueue error: null Dec 26 08:41:20 volumio volumio[1258]: info: ------------------------------ 77ms Dec 26 08:41:21 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 26 08:41:22 volumio volumio[1258]: error: updateQueue error: null Dec 26 08:41:22 volumio volumio[1258]: error: updateQueue error: null Dec 26 08:41:22 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein" Dec 26 08:41:22 volumio volumio[1258]: info: ------------------------------ 2379ms Dec 26 08:41:22 volumio volumio[1258]: info: ------------------------------ 2353ms Dec 26 08:41:22 volumio volumio[1258]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein" took 2 milliseconds Dec 26 08:41:22 volumio volumio[1258]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 08:41:22 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand play Dec 26 08:41:22 volumio volumio[1258]: info: Dec 26 08:41:22 volumio volumio[1258]: ---------------------------- MPD announces system playlist update Dec 26 08:41:22 volumio volumio[1258]: info: Ignoring MPD Status Update Dec 26 08:41:22 volumio volumio[1258]: info: Dec 26 08:41:22 volumio volumio[1258]: ---------------------------- MPD announces system playlist update Dec 26 08:41:22 volumio volumio[1258]: info: Ignoring MPD Status Update Dec 26 08:41:22 volumio volumio[1258]: info: Dec 26 08:41:22 volumio volumio[1258]: ---------------------------- MPD announces system playlist update Dec 26 08:41:22 volumio volumio[1258]: info: Ignoring MPD Status Update Dec 26 08:41:22 volumio volumio[1258]: info: sendMpdCommand play took 99 milliseconds Dec 26 08:41:22 volumio volumio[1258]: info: ------------------------------ 98ms Dec 26 08:41:22 volumio volumio[1258]: info: ------------------------------ 40ms Dec 26 08:41:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 26 08:41:22 volumio volumio[1258]: info: ------------------------------ 75ms Dec 26 08:41:23 volumio volumio[1258]: info: Dec 26 08:41:23 volumio volumio[1258]: ---------------------------- MPD announces state update: player Dec 26 08:41:23 volumio volumio[1258]: info: ControllerMpd::getState Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand status Dec 26 08:41:23 volumio volumio[1258]: info: Dec 26 08:41:23 volumio volumio[1258]: ---------------------------- MPD announces state update: player Dec 26 08:41:23 volumio volumio[1258]: info: ControllerMpd::getState Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand status Dec 26 08:41:23 volumio volumio[1258]: info: Dec 26 08:41:23 volumio volumio[1258]: ---------------------------- MPD announces state update: player Dec 26 08:41:23 volumio volumio[1258]: info: ControllerMpd::getState Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand status Dec 26 08:41:23 volumio volumio[1258]: info: Dec 26 08:41:23 volumio volumio[1258]: ---------------------------- MPD announces state update: player Dec 26 08:41:23 volumio volumio[1258]: info: ControllerMpd::getState Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand status Dec 26 08:41:23 volumio volumio[1258]: info: Dec 26 08:41:23 volumio volumio[1258]: ---------------------------- MPD announces state update: player Dec 26 08:41:23 volumio volumio[1258]: info: sendMpdCommand status took 102 milliseconds Dec 26 08:41:23 volumio volumio[1258]: info: ControllerMpd::getState Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand status Dec 26 08:41:23 volumio volumio[1258]: info: sendMpdCommand status took 102 milliseconds Dec 26 08:41:23 volumio volumio[1258]: info: sendMpdCommand status took 100 milliseconds Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::parseState Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::parseState Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::parseState Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 08:41:23 volumio volumio[1258]: info: Dec 26 08:41:23 volumio volumio[1258]: ---------------------------- MPD announces state update: player Dec 26 08:41:23 volumio volumio[1258]: info: ControllerMpd::getState Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand status Dec 26 08:41:23 volumio volumio[1258]: info: sendMpdCommand status took 92 milliseconds Dec 26 08:41:23 volumio volumio[1258]: info: sendMpdCommand status took 56 milliseconds Dec 26 08:41:23 volumio volumio[1258]: info: sendMpdCommand playlistinfo took 52 milliseconds Dec 26 08:41:23 volumio volumio[1258]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 26 08:41:23 volumio volumio[1258]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 26 08:41:23 volumio volumio[1258]: info: sendMpdCommand status took 4 milliseconds Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::parseState Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::parseState Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::parseTrackInfo Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::parseTrackInfo Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::parseTrackInfo Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::parseState Dec 26 08:41:23 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 08:41:23 volumio volumio[1258]: info: ControllerMpd::pushState Dec 26 08:41:23 volumio volumio[1258]: info: CoreCommandRouter::servicePushState Dec 26 08:41:23 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:23 volumio volumio[1258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Dec 26 08:41:23 volumio volumio[1258]: verbose: CURRENT POSITION 0 Dec 26 08:41:23 volumio volumio[1258]: info: CoreStateMachine::syncState stateService play Dec 26 08:41:23 volumio volumio[1258]: info: CoreStateMachine::syncState currentStatus stop Dec 26 08:41:23 volumio volumio[1258]: info: ControllerMpd::pushState Dec 26 08:41:23 volumio volumio[1258]: info: CoreCommandRouter::servicePushState Dec 26 08:41:23 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:23 volumio volumio[1258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Dec 26 08:41:23 volumio volumio[1258]: verbose: CURRENT POSITION 0 Dec 26 08:41:23 volumio volumio[1258]: info: CoreStateMachine::syncState stateService play Dec 26 08:41:23 volumio volumio[1258]: info: CoreStateMachine::syncState currentStatus play Dec 26 08:41:23 volumio volumio[1258]: info: Received an update from plugin. extracting info from payload Dec 26 08:41:23 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:23 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:23 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 08:41:23 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:23 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:23 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:23 volumio volumio[1258]: info: ControllerMpd::pushState Dec 26 08:41:23 volumio volumio[1258]: info: CoreCommandRouter::servicePushState Dec 26 08:41:23 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:23 volumio volumio[1258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Dec 26 08:41:23 volumio volumio[1258]: verbose: CURRENT POSITION 0 Dec 26 08:41:23 volumio volumio[1258]: info: CoreStateMachine::syncState stateService play Dec 26 08:41:23 volumio volumio[1258]: info: CoreStateMachine::syncState currentStatus play Dec 26 08:41:23 volumio volumio[1258]: info: Received an update from plugin. extracting info from payload Dec 26 08:41:23 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:23 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:23 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:23 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:23 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:23 volumio volumio[1258]: info: ------------------------------ 414ms Dec 26 08:41:23 volumio volumio[1258]: info: ------------------------------ 505ms Dec 26 08:41:23 volumio volumio[1258]: info: ------------------------------ 504ms Dec 26 08:41:23 volumio volumio[1258]: info: Dec 26 08:41:23 volumio volumio[1258]: ---------------------------- MPD announces system playlist update Dec 26 08:41:23 volumio volumio[1258]: info: Ignoring MPD Status Update Dec 26 08:41:23 volumio volumio[1258]: info: Dec 26 08:41:23 volumio volumio[1258]: ---------------------------- MPD announces state update: player Dec 26 08:41:24 volumio volumio[1258]: info: Dec 26 08:41:24 volumio volumio[1258]: ---------------------------- MPD announces system playlist update Dec 26 08:41:24 volumio volumio[1258]: info: Ignoring MPD Status Update Dec 26 08:41:24 volumio volumio[1258]: info: Dec 26 08:41:24 volumio volumio[1258]: ---------------------------- MPD announces state update: player Dec 26 08:41:24 volumio volumio[1258]: info: sendMpdCommand playlistinfo took 420 milliseconds Dec 26 08:41:24 volumio volumio[1258]: info: sendMpdCommand playlistinfo took 419 milliseconds Dec 26 08:41:24 volumio volumio[1258]: info: ControllerMpd::getState Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand status Dec 26 08:41:24 volumio volumio[1258]: info: sendMpdCommand playlistinfo took 427 milliseconds Dec 26 08:41:24 volumio volumio[1258]: info: ControllerMpd::getState Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand status Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::parseTrackInfo Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::parseTrackInfo Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::parseTrackInfo Dec 26 08:41:24 volumio volumio[1258]: info: ControllerMpd::pushState Dec 26 08:41:24 volumio volumio[1258]: info: CoreCommandRouter::servicePushState Dec 26 08:41:24 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:24 volumio volumio[1258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Dec 26 08:41:24 volumio volumio[1258]: verbose: CURRENT POSITION 0 Dec 26 08:41:24 volumio volumio[1258]: info: CoreStateMachine::syncState stateService play Dec 26 08:41:24 volumio volumio[1258]: info: CoreStateMachine::syncState currentStatus play Dec 26 08:41:24 volumio volumio[1258]: info: Received an update from plugin. extracting info from payload Dec 26 08:41:24 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:24 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:24 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 08:41:24 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:24 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:24 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:24 volumio volumio[1258]: info: ControllerMpd::pushState Dec 26 08:41:24 volumio volumio[1258]: info: CoreCommandRouter::servicePushState Dec 26 08:41:24 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:24 volumio volumio[1258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Dec 26 08:41:24 volumio volumio[1258]: verbose: CURRENT POSITION 0 Dec 26 08:41:24 volumio volumio[1258]: info: CoreStateMachine::syncState stateService play Dec 26 08:41:24 volumio volumio[1258]: info: CoreStateMachine::syncState currentStatus play Dec 26 08:41:24 volumio volumio[1258]: info: Received an update from plugin. extracting info from payload Dec 26 08:41:24 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:24 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:24 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:24 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:24 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:24 volumio volumio[1258]: info: ControllerMpd::pushState Dec 26 08:41:24 volumio volumio[1258]: info: CoreCommandRouter::servicePushState Dec 26 08:41:24 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:24 volumio volumio[1258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tears dry tonight von CYRIL & James Blunt ","artist":"hr3 Rhein-Main","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Dec 26 08:41:24 volumio volumio[1258]: verbose: CURRENT POSITION 0 Dec 26 08:41:24 volumio volumio[1258]: info: CoreStateMachine::syncState stateService play Dec 26 08:41:24 volumio volumio[1258]: info: CoreStateMachine::syncState currentStatus play Dec 26 08:41:24 volumio volumio[1258]: info: Received an update from plugin. extracting info from payload Dec 26 08:41:24 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:24 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 08:41:24 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:24 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:24 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:24 volumio volumio[1258]: info: ------------------------------ 940ms Dec 26 08:41:24 volumio volumio[1258]: info: ------------------------------ 939ms Dec 26 08:41:24 volumio volumio[1258]: info: ------------------------------ 867ms Dec 26 08:41:24 volumio volumio[1258]: info: Dec 26 08:41:24 volumio volumio[1258]: ---------------------------- MPD announces system playlist update Dec 26 08:41:24 volumio volumio[1258]: info: Ignoring MPD Status Update Dec 26 08:41:24 volumio volumio[1258]: info: Dec 26 08:41:24 volumio volumio[1258]: ---------------------------- MPD announces state update: player Dec 26 08:41:24 volumio volumio[1258]: info: ControllerMpd::getState Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand status Dec 26 08:41:24 volumio volumio[1258]: info: Dec 26 08:41:24 volumio volumio[1258]: ---------------------------- MPD announces system playlist update Dec 26 08:41:24 volumio volumio[1258]: info: Ignoring MPD Status Update Dec 26 08:41:24 volumio volumio[1258]: info: Dec 26 08:41:24 volumio volumio[1258]: ---------------------------- MPD announces state update: player Dec 26 08:41:24 volumio volumio[1258]: info: ControllerMpd::getState Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand status Dec 26 08:41:24 volumio volumio[1258]: info: ------------------------------ 789ms Dec 26 08:41:24 volumio volumio[1258]: info: sendMpdCommand status took 750 milliseconds Dec 26 08:41:24 volumio volumio[1258]: info: ------------------------------ 768ms Dec 26 08:41:24 volumio volumio[1258]: info: sendMpdCommand status took 750 milliseconds Dec 26 08:41:24 volumio volumio[1258]: info: ------------------------------ 195ms Dec 26 08:41:24 volumio volumio[1258]: info: sendMpdCommand status took 180 milliseconds Dec 26 08:41:24 volumio volumio[1258]: info: ------------------------------ 176ms Dec 26 08:41:24 volumio volumio[1258]: info: sendMpdCommand status took 161 milliseconds Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::parseState Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::parseState Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::parseState Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::parseState Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 08:41:24 volumio volumio[1258]: info: Dec 26 08:41:24 volumio volumio[1258]: ---------------------------- MPD announces system playlist update Dec 26 08:41:24 volumio volumio[1258]: info: Ignoring MPD Status Update Dec 26 08:41:24 volumio volumio[1258]: info: Dec 26 08:41:24 volumio volumio[1258]: ---------------------------- MPD announces state update: player Dec 26 08:41:24 volumio volumio[1258]: info: ControllerMpd::getState Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand status Dec 26 08:41:24 volumio volumio[1258]: info: Dec 26 08:41:24 volumio volumio[1258]: ---------------------------- MPD announces system playlist update Dec 26 08:41:24 volumio volumio[1258]: info: Ignoring MPD Status Update Dec 26 08:41:24 volumio volumio[1258]: info: Dec 26 08:41:24 volumio volumio[1258]: ---------------------------- MPD announces state update: player Dec 26 08:41:24 volumio volumio[1258]: info: ControllerMpd::getState Dec 26 08:41:24 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand status Dec 26 08:41:25 volumio volumio[1258]: info: sendMpdCommand playlistinfo took 210 milliseconds Dec 26 08:41:25 volumio volumio[1258]: info: sendMpdCommand playlistinfo took 155 milliseconds Dec 26 08:41:25 volumio volumio[1258]: info: sendMpdCommand playlistinfo took 155 milliseconds Dec 26 08:41:25 volumio volumio[1258]: info: sendMpdCommand playlistinfo took 154 milliseconds Dec 26 08:41:25 volumio volumio[1258]: info: ------------------------------ 153ms Dec 26 08:41:25 volumio volumio[1258]: info: sendMpdCommand status took 152 milliseconds Dec 26 08:41:25 volumio volumio[1258]: info: ------------------------------ 141ms Dec 26 08:41:25 volumio volumio[1258]: info: sendMpdCommand status took 140 milliseconds Dec 26 08:41:25 volumio volumio[1258]: verbose: ControllerMpd::parseTrackInfo Dec 26 08:41:25 volumio volumio[1258]: verbose: ControllerMpd::parseTrackInfo Dec 26 08:41:25 volumio volumio[1258]: verbose: ControllerMpd::parseTrackInfo Dec 26 08:41:25 volumio volumio[1258]: verbose: ControllerMpd::parseTrackInfo Dec 26 08:41:25 volumio volumio[1258]: verbose: ControllerMpd::parseState Dec 26 08:41:25 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 08:41:25 volumio volumio[1258]: verbose: ControllerMpd::parseState Dec 26 08:41:25 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 08:41:25 volumio volumio[1258]: info: ControllerMpd::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::servicePushState Dec 26 08:41:25 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:25 volumio volumio[1258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1012,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tears dry tonight von CYRIL & James Blunt ","artist":"hr3 Rhein-Main","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Dec 26 08:41:25 volumio volumio[1258]: verbose: CURRENT POSITION 0 Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::syncState stateService play Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::syncState currentStatus play Dec 26 08:41:25 volumio volumio[1258]: info: Received an update from plugin. extracting info from payload Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:25 volumio volumio[1258]: info: ControllerMpd::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::servicePushState Dec 26 08:41:25 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:25 volumio volumio[1258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1233,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tears dry tonight von CYRIL & James Blunt ","artist":"hr3 Rhein-Main","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Dec 26 08:41:25 volumio volumio[1258]: verbose: CURRENT POSITION 0 Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::syncState stateService play Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::syncState currentStatus play Dec 26 08:41:25 volumio volumio[1258]: info: Received an update from plugin. extracting info from payload Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:25 volumio volumio[1258]: info: ControllerMpd::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::servicePushState Dec 26 08:41:25 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:25 volumio volumio[1258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1738,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tears dry tonight von CYRIL & James Blunt ","artist":"hr3 Rhein-Main","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Dec 26 08:41:25 volumio volumio[1258]: verbose: CURRENT POSITION 0 Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::syncState stateService play Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::syncState currentStatus play Dec 26 08:41:25 volumio volumio[1258]: info: Received an update from plugin. extracting info from payload Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:25 volumio volumio[1258]: info: ControllerMpd::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::servicePushState Dec 26 08:41:25 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:25 volumio volumio[1258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1854,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tears dry tonight von CYRIL & James Blunt ","artist":"hr3 Rhein-Main","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Dec 26 08:41:25 volumio volumio[1258]: verbose: CURRENT POSITION 0 Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::syncState stateService play Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::syncState currentStatus play Dec 26 08:41:25 volumio volumio[1258]: info: Received an update from plugin. extracting info from payload Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:25 volumio volumio[1258]: info: ------------------------------ 1490ms Dec 26 08:41:25 volumio volumio[1258]: info: ------------------------------ 1468ms Dec 26 08:41:25 volumio volumio[1258]: info: ------------------------------ 895ms Dec 26 08:41:25 volumio volumio[1258]: info: ------------------------------ 876ms Dec 26 08:41:25 volumio volumio[1258]: info: sendMpdCommand playlistinfo took 498 milliseconds Dec 26 08:41:25 volumio volumio[1258]: info: sendMpdCommand playlistinfo took 498 milliseconds Dec 26 08:41:25 volumio volumio[1258]: verbose: ControllerMpd::parseTrackInfo Dec 26 08:41:25 volumio volumio[1258]: verbose: ControllerMpd::parseTrackInfo Dec 26 08:41:25 volumio volumio[1258]: info: ControllerMpd::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::servicePushState Dec 26 08:41:25 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:25 volumio volumio[1258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tears dry tonight von CYRIL & James Blunt ","artist":"hr3 Rhein-Main","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Dec 26 08:41:25 volumio volumio[1258]: verbose: CURRENT POSITION 0 Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::syncState stateService play Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::syncState currentStatus play Dec 26 08:41:25 volumio volumio[1258]: info: Received an update from plugin. extracting info from payload Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:25 volumio volumio[1258]: info: ControllerMpd::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::servicePushState Dec 26 08:41:25 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:25 volumio volumio[1258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2106,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tears dry tonight von CYRIL & James Blunt ","artist":"hr3 Rhein-Main","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Dec 26 08:41:25 volumio volumio[1258]: verbose: CURRENT POSITION 0 Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::syncState stateService play Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::syncState currentStatus play Dec 26 08:41:25 volumio volumio[1258]: info: Received an update from plugin. extracting info from payload Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:25 volumio volumio[1258]: info: ------------------------------ 906ms Dec 26 08:41:25 volumio volumio[1258]: info: ------------------------------ 870ms Dec 26 08:41:25 volumio volumio[1258]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 08:41:26 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 26 08:41:26 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 26 08:41:26 volumio sudo[2053]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 26 08:41:26 volumio sudo[2053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:41:26 volumio sudo[2057]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 26 08:41:26 volumio sudo[2057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:41:26 volumio sudo[2068]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 26 08:41:26 volumio sudo[2068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:41:26 volumio sudo[2057]: pam_unix(sudo:session): session closed for user root Dec 26 08:41:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 26 08:41:27 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 26 08:41:27 volumio sudo[2053]: pam_unix(sudo:session): session closed for user root Dec 26 08:41:27 volumio sudo[2065]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 26 08:41:27 volumio sudo[2065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:41:27 volumio sudo[2065]: pam_unix(sudo:session): session closed for user root Dec 26 08:41:27 volumio sudo[2068]: pam_unix(sudo:session): session closed for user root Dec 26 08:41:27 volumio sudo[2075]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 08:41:27 volumio sudo[2075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:41:27 volumio sudo[2077]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 08:41:27 volumio sudo[2077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:41:27 volumio sudo[2075]: pam_unix(sudo:session): session closed for user root Dec 26 08:41:27 volumio sudo[2077]: pam_unix(sudo:session): session closed for user root Dec 26 08:41:27 volumio sudo[2085]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 26 08:41:27 volumio sudo[2085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:41:28 volumio sudo[2085]: pam_unix(sudo:session): session closed for user root Dec 26 08:41:31 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 08:41:31 volumio volumio[1258]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 26 08:41:31 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 26 08:41:31 volumio volumio[1258]: info: Received Get System Version Dec 26 08:41:31 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 08:41:31 volumio volumio[1258]: info: Received Get System Info Dec 26 08:41:31 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 08:41:31 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 08:41:31 volumio volumio[1258]: info: Discovery: Getting this device information Dec 26 08:41:31 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Dec 26 08:41:31 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 08:41:33 volumio volumio[1258]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Dec 26 08:41:33 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Dec 26 08:41:33 volumio sudo[2105]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Dec 26 08:41:33 volumio sudo[2105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:41:33 volumio sudo[2105]: pam_unix(sudo:session): session closed for user root Dec 26 08:41:33 volumio sudo[2107]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Dec 26 08:41:33 volumio sudo[2107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:41:33 volumio sudo[2107]: pam_unix(sudo:session): session closed for user root Dec 26 08:41:33 volumio volumio[1258]: info: Permissions for /etc/network/interfaces set Dec 26 08:41:33 volumio volumio[1258]: info: Restarting Wireless Network Dec 26 08:41:33 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 26 08:41:33 volumio volumio[1258]: info: Discovery: Restarting Advertising due to device name change Dec 26 08:41:33 volumio sudo[2110]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 26 08:41:33 volumio sudo[2110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 08:41:34 volumio systemd[1]: Stopping wireless.service - Wireless Services... Dec 26 08:41:34 volumio systemd[1]: wireless.service: Deactivated successfully. Dec 26 08:41:34 volumio systemd[1]: Stopped wireless.service - Wireless Services. Dec 26 08:41:34 volumio systemd[1]: wireless.service: Consumed 3min 23.534s CPU time. Dec 26 08:41:34 volumio systemd[1]: Starting wireless.service - Wireless Services... Dec 26 08:41:34 volumio volumio[1258]: info: Dec 26 08:41:34 volumio volumio[1258]: ---------------------------- MPD announces system playlist update Dec 26 08:41:34 volumio volumio[1258]: info: Ignoring MPD Status Update Dec 26 08:41:34 volumio volumio[1258]: info: Dec 26 08:41:34 volumio volumio[1258]: ---------------------------- MPD announces state update: player Dec 26 08:41:34 volumio volumio[1258]: info: ControllerMpd::getState Dec 26 08:41:34 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand status Dec 26 08:41:34 volumio volumio[1258]: info: Dec 26 08:41:34 volumio volumio[1258]: ---------------------------- MPD announces system playlist update Dec 26 08:41:34 volumio volumio[1258]: info: Ignoring MPD Status Update Dec 26 08:41:34 volumio volumio[1258]: info: Dec 26 08:41:34 volumio volumio[1258]: ---------------------------- MPD announces state update: player Dec 26 08:41:34 volumio volumio[1258]: info: ControllerMpd::getState Dec 26 08:41:34 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand status Dec 26 08:41:34 volumio volumio[1258]: info: Dec 26 08:41:34 volumio volumio[1258]: ---------------------------- MPD announces system playlist update Dec 26 08:41:34 volumio volumio[1258]: info: Ignoring MPD Status Update Dec 26 08:41:34 volumio volumio[1258]: info: Dec 26 08:41:34 volumio volumio[1258]: ---------------------------- MPD announces state update: player Dec 26 08:41:34 volumio volumio[1258]: info: ControllerMpd::getState Dec 26 08:41:34 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand status Dec 26 08:41:34 volumio volumio[1258]: info: ------------------------------ 54ms Dec 26 08:41:34 volumio volumio[1258]: info: sendMpdCommand status took 49 milliseconds Dec 26 08:41:34 volumio volumio[1258]: info: ------------------------------ 31ms Dec 26 08:41:34 volumio volumio[1258]: verbose: ControllerMpd::parseState Dec 26 08:41:34 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 08:41:34 volumio volumio[1258]: info: sendMpdCommand status took 31 milliseconds Dec 26 08:41:34 volumio volumio[1258]: info: ------------------------------ 50ms Dec 26 08:41:34 volumio volumio[1258]: verbose: ControllerMpd::parseState Dec 26 08:41:34 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 08:41:34 volumio volumio[1258]: info: sendMpdCommand status took 25 milliseconds Dec 26 08:41:34 volumio volumio[1258]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 26 08:41:34 volumio volumio[1258]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 26 08:41:34 volumio volumio[1258]: verbose: ControllerMpd::parseState Dec 26 08:41:34 volumio volumio[1258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 08:41:34 volumio volumio[1258]: verbose: ControllerMpd::parseTrackInfo Dec 26 08:41:34 volumio volumio[1258]: verbose: ControllerMpd::parseTrackInfo Dec 26 08:41:34 volumio volumio[1258]: info: ControllerMpd::pushState Dec 26 08:41:34 volumio volumio[1258]: info: CoreCommandRouter::servicePushState Dec 26 08:41:34 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:34 volumio volumio[1258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11733,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"hr3 an Weihnachten ","artist":"hr3 Rhein-Main","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Dec 26 08:41:34 volumio volumio[1258]: verbose: CURRENT POSITION 0 Dec 26 08:41:34 volumio volumio[1258]: info: CoreStateMachine::syncState stateService play Dec 26 08:41:34 volumio volumio[1258]: info: CoreStateMachine::syncState currentStatus play Dec 26 08:41:34 volumio volumio[1258]: info: Received an update from plugin. extracting info from payload Dec 26 08:41:34 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:34 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 08:41:34 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:34 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:34 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:34 volumio volumio[1258]: info: ControllerMpd::pushState Dec 26 08:41:34 volumio volumio[1258]: info: CoreCommandRouter::servicePushState Dec 26 08:41:34 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:34 volumio volumio[1258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11733,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"hr3 an Weihnachten ","artist":"hr3 Rhein-Main","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Dec 26 08:41:34 volumio volumio[1258]: verbose: CURRENT POSITION 0 Dec 26 08:41:34 volumio volumio[1258]: info: CoreStateMachine::syncState stateService play Dec 26 08:41:34 volumio volumio[1258]: info: CoreStateMachine::syncState currentStatus play Dec 26 08:41:34 volumio volumio[1258]: info: Received an update from plugin. extracting info from payload Dec 26 08:41:34 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:34 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:34 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:34 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:34 volumio volumio[1258]: info: ------------------------------ 339ms Dec 26 08:41:34 volumio volumio[1258]: info: ------------------------------ 320ms Dec 26 08:41:34 volumio volumio[1258]: info: sendMpdCommand playlistinfo took 251 milliseconds Dec 26 08:41:34 volumio volumio[1258]: verbose: ControllerMpd::parseTrackInfo Dec 26 08:41:34 volumio volumio[1258]: info: ControllerMpd::pushState Dec 26 08:41:34 volumio volumio[1258]: info: CoreCommandRouter::servicePushState Dec 26 08:41:34 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 26 08:41:34 volumio volumio[1258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11733,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"hr3 an Weihnachten ","artist":"hr3 Rhein-Main","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Dec 26 08:41:34 volumio volumio[1258]: verbose: CURRENT POSITION 0 Dec 26 08:41:34 volumio volumio[1258]: info: CoreStateMachine::syncState stateService play Dec 26 08:41:34 volumio volumio[1258]: info: CoreStateMachine::syncState currentStatus play Dec 26 08:41:34 volumio volumio[1258]: info: Received an update from plugin. extracting info from payload Dec 26 08:41:34 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:34 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 08:41:34 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:34 volumio volumio[1258]: info: CoreStateMachine::pushState Dec 26 08:41:34 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 26 08:41:34 volumio volumio[1258]: info: ------------------------------ 472ms Dec 26 08:41:35 volumio volumio[1258]: info: Discovery: A device disappeared from network Dec 26 08:41:35 volumio volumio[1258]: info: Discovery: Device volumio disappeared from network Dec 26 08:41:35 volumio volumio[1258]: info: Discovery: A device disappeared from network Dec 26 08:41:35 volumio wireless.js[2114]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 26 08:41:35 volumio wireless.js[2114]: WIRELESS.JS: Wired network status changed to: ---connected--- Dec 26 08:41:35 volumio wireless.js[2114]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 26 08:41:35 volumio wireless.js[2114]: WIRELESS.JS: Cleaning previous... Dec 26 08:41:35 volumio sudo[2133]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 26 08:41:35 volumio sudo[2133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 08:41:35 volumio sudo[2133]: pam_unix(sudo:session): session closed for user root Dec 26 08:41:36 volumio sudo[2135]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 26 08:41:36 volumio sudo[2135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 08:41:36 volumio sudo[2135]: pam_unix(sudo:session): session closed for user root Dec 26 08:41:36 volumio wireless.js[2114]: WIRELESS.JS: Stopped aP Dec 26 08:41:36 volumio wireless.js[2114]: WIRELESS.JS: Wireless Networking DISABLED, not starting wireless flow Dec 26 08:41:37 volumio wireless.js[2114]: WIRELESS.JS: Notified systemd about wireless ready Dec 26 08:41:37 volumio systemd[1]: Started wireless.service - Wireless Services. Dec 26 08:41:37 volumio sudo[2110]: pam_unix(sudo:session): session closed for user root Dec 26 08:41:38 volumio volumio[1258]: info: Discovery: Started advertising with name: volumio Dec 26 08:41:39 volumio volumio[1258]: compat.c: read() failed: Resource temporarily unavailable Dec 26 08:41:39 volumio volumio[1258]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 08:41:40 volumio volumio[1258]: Error: dns service error: unknown Dec 26 08:41:40 volumio volumio[1258]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 26 08:41:40 volumio volumio[1258]: errorCode: -65537 Dec 26 08:41:40 volumio volumio[1258]: } Dec 26 08:41:40 volumio volumio[1258]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 08:41:46 volumio sudo[2174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-26 08:40' Dec 26 08:41:46 volumio sudo[2174]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"