-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-01-18 23:41:09 CET. -- Jan 18 23:40:00 volumio4 sudo[1824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jan 18 23:40:00 volumio4 sudo[1824]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 23:40:00 volumio4 volumiobt[1644]: pulseaudio: no process found Jan 18 23:40:00 volumio4 sudo[1824]: pam_unix(sudo:session): session closed for user root Jan 18 23:40:00 volumio4 dbus-daemon[619]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.25' (uid=1000 pid=1828 comm="/usr/bin/pulseaudio --high-priority=true ") Jan 18 23:40:00 volumio4 systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jan 18 23:40:00 volumio4 volumio[977]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 18 23:40:00 volumio4 dbus-daemon[619]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jan 18 23:40:00 volumio4 systemd[1]: Started RealtimeKit Scheduling Policy Service. Jan 18 23:40:00 volumio4 rtkit-daemon[1829]: Successfully called chroot. Jan 18 23:40:00 volumio4 rtkit-daemon[1829]: Successfully dropped privileges. Jan 18 23:40:00 volumio4 rtkit-daemon[1829]: Successfully limited resources. Jan 18 23:40:00 volumio4 rtkit-daemon[1829]: Running. Jan 18 23:40:00 volumio4 rtkit-daemon[1829]: Canary thread running. Jan 18 23:40:00 volumio4 rtkit-daemon[1829]: Watchdog thread running. Jan 18 23:40:00 volumio4 dbus-daemon[619]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.26' (uid=0 pid=1829 comm="/usr/lib/rtkit/rtkit-daemon ") Jan 18 23:40:00 volumio4 systemd[1]: Starting Authorization Manager... Jan 18 23:40:00 volumio4 volumio[977]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 18 23:40:00 volumio4 polkitd[1833]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 18 23:40:00 volumio4 dbus-daemon[619]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 18 23:40:00 volumio4 systemd[1]: Started Authorization Manager. Jan 18 23:40:00 volumio4 pulseaudio[1828]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 18 23:40:00 volumio4 pulseaudio[1828]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 18 23:40:00 volumio4 volumiobt[1644]: Applying permissions Jan 18 23:40:00 volumio4 volumiobt[1644]: Setting BT discoverable and pairable Jan 18 23:40:00 volumio4 bluetoothd[740]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSource Jan 18 23:40:00 volumio4 bluetoothd[740]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSink Jan 18 23:40:00 volumio4 pulseaudio[1828]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jan 18 23:40:00 volumio4 kernel: Bluetooth: RFCOMM TTY layer initialized Jan 18 23:40:00 volumio4 kernel: Bluetooth: RFCOMM socket layer initialized Jan 18 23:40:00 volumio4 kernel: Bluetooth: RFCOMM ver 1.11 Jan 18 23:40:00 volumio4 volumiobt[1644]: [CHG] Controller 2C:CF:67:21:66:B8 UUIDs: 00001112-0000-1000-8000-00805f9b34fb Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [55B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [117B blob data] Jan 18 23:40:00 volumio4 volumiobt[1644]: [bluetooth]# -e power on Jan 18 23:40:00 volumio4 volumiobt[1644]: Invalid command in menu main: -e Jan 18 23:40:00 volumio4 volumiobt[1644]: Jan 18 23:40:00 volumio4 volumiobt[1644]: Use "help" for a list of available commands in a menu. Jan 18 23:40:00 volumio4 volumiobt[1644]: Use "menu " if you want to enter any submenu. Jan 18 23:40:00 volumio4 volumiobt[1644]: Use "back" if you want to return to menu main. Jan 18 23:40:00 volumio4 volumiobt[1644]: [bluetooth]# agent on Jan 18 23:40:00 volumio4 volumiobt[1644]: Agent is already registered Jan 18 23:40:00 volumio4 volumiobt[1644]: [bluetooth]# discoverable on Jan 18 23:40:00 volumio4 volumiobt[1644]: [bluetooth]# pairable on Jan 18 23:40:00 volumio4 volumiobt[1644]: [bluetooth]# agent NoInputNoOutput Jan 18 23:40:00 volumio4 volumiobt[1644]: Agent is already registered Jan 18 23:40:00 volumio4 volumiobt[1644]: [bluetooth]# default-agent Jan 18 23:40:00 volumio4 volumiobt[1644]: [bluetooth]# quit Jan 18 23:40:00 volumio4 volumiobt[1644]: [59B blob data] Jan 18 23:40:01 volumio4 systemd[1]: systemd-timedated.service: Succeeded. Jan 18 23:40:01 volumio4 volumio[977]: info: MRS: Getting audio outputs on start Jan 18 23:40:01 volumio4 volumio[977]: info: MRS: Requesting all other devices output Jan 18 23:40:01 volumio4 kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Jan 18 23:40:01 volumio4 volumio[977]: info: camilladsp spawned new process with pid 1845, instance 1, run: true Jan 18 23:40:03 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 18 23:40:03 volumio4 volumio[977]: info: BOOT COMPLETED Jan 18 23:40:05 volumio4 volumio[977]: info: CALLMETHOD: user_interface touch_display savePointerConf [object Object] Jan 18 23:40:05 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: touch_display , savePointerConf Jan 18 23:40:05 volumio4 sudo[1871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /ExecStart=\\/usr\\/bin\\/startx \\/etc\\/X11\\/Xsession \\/opt\\/volumiokiosk.sh/c\\ExecStart=\\/usr\\/bin\\/startx \\/etc\\/X11\\/Xsession \\/opt\\/volumiokiosk.sh /lib/systemd/system/volumio-kiosk.service Jan 18 23:40:05 volumio4 sudo[1871]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 23:40:05 volumio4 sudo[1871]: pam_unix(sudo:session): session closed for user root Jan 18 23:40:05 volumio4 sudo[1874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 18 23:40:05 volumio4 sudo[1874]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 23:40:05 volumio4 systemd[1]: Reloading. Jan 18 23:40:05 volumio4 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 18 23:40:05 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 18 23:40:05 volumio4 volumio[977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jan 18 23:40:05 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetState Jan 18 23:40:05 volumio4 volumio[977]: info: CorePlayQueue::getTrack 0 Jan 18 23:40:05 volumio4 systemd[1]: volumio-kiosk.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jan 18 23:40:05 volumio4 sudo[1874]: pam_unix(sudo:session): session closed for user root Jan 18 23:40:05 volumio4 volumio[977]: info: touch_display: systemctl daemon-reload succeeded. Jan 18 23:40:05 volumio4 sudo[1890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Jan 18 23:40:05 volumio4 sudo[1890]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 23:40:05 volumio4 startx[1228]: xinit: connection to X server lost Jan 18 23:40:05 volumio4 startx[1228]: [1B blob data] Jan 18 23:40:05 volumio4 systemd[1]: Stopping Volumio Kiosk... Jan 18 23:40:05 volumio4 volumio[977]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 18 23:40:05 volumio4 startx[1228]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Jan 18 23:40:06 volumio4 startx[1228]: xinit: unexpected signal 15 Jan 18 23:40:06 volumio4 systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Jan 18 23:40:06 volumio4 systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Jan 18 23:40:06 volumio4 systemd[1]: Stopped Volumio Kiosk. Jan 18 23:40:06 volumio4 volumio[977]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 18 23:40:06 volumio4 systemd[1]: Started Volumio Kiosk. Jan 18 23:40:06 volumio4 sudo[1890]: pam_unix(sudo:session): session closed for user root Jan 18 23:40:06 volumio4 volumio[977]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Jan 18 23:40:06 volumio4 volumio[977]: info: touch_display: Volumio Kiosk restarted. Jan 18 23:40:06 volumio4 volumio[977]: info: touch_display: X display number found: Jan 18 23:40:07 volumio4 startx[1896]: X.Org X Server 1.20.4 Jan 18 23:40:07 volumio4 startx[1896]: X Protocol Version 11, Revision 0 Jan 18 23:40:07 volumio4 startx[1896]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jan 18 23:40:07 volumio4 startx[1896]: Current Operating System: Linux volumio4 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Jan 18 23:40:07 volumio4 startx[1896]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=2C:CF:67:21:66:B6 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=fb80cb44-b4d3-4b36-be35-613cba0a09fb imgfile=/volumio_current.sqsh bootpart=UUID=51B1-040D datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a 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 Jan 18 23:40:07 volumio4 startx[1896]: Build Date: 04 April 2023 07:50:56AM Jan 18 23:40:07 volumio4 startx[1896]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jan 18 23:40:07 volumio4 startx[1896]: Current version of pixman: 0.36.0 Jan 18 23:40:07 volumio4 startx[1896]: Before reporting problems, check http://wiki.x.org Jan 18 23:40:07 volumio4 startx[1896]: to make sure that you have the latest version. Jan 18 23:40:07 volumio4 startx[1896]: Markers: (--) probed, (**) from config file, (==) default setting, Jan 18 23:40:07 volumio4 startx[1896]: (++) from command line, (!!) notice, (II) informational, Jan 18 23:40:07 volumio4 startx[1896]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jan 18 23:40:07 volumio4 startx[1896]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Jan 18 23:40:07 2025 Jan 18 23:40:07 volumio4 startx[1896]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jan 18 23:40:07 volumio4 startx[1896]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jan 18 23:40:07 volumio4 volumio[977]: info: touch_display: X display number found: 0 Jan 18 23:40:07 volumio4 volumio[977]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 18 23:40:07 volumio4 volumio[977]: info: touch_display: X display number found: 0 Jan 18 23:40:07 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetState Jan 18 23:40:07 volumio4 volumio[977]: info: CorePlayQueue::getTrack 0 Jan 18 23:40:07 volumio4 volumio[977]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 18 23:40:09 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 18 23:40:09 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 23:40:09 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 18 23:40:09 volumio4 volumio-remote-updater[612]: No test mode Jan 18 23:40:09 volumio4 volumio-remote-updater[612]: No alpha test mode Jan 18 23:40:09 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 18 23:40:10 volumio4 volumio[977]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 18 23:40:10 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 18 23:40:10 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 23:40:10 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 23:40:10 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 18 23:40:10 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 18 23:40:10 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 18 23:40:10 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetBrowseSources Jan 18 23:40:10 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 18 23:40:10 volumio4 volumio[977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jan 18 23:40:11 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 18 23:40:11 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 18 23:40:11 volumio4 volumio[977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jan 18 23:40:11 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetVisibleSources Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetState Jan 18 23:40:12 volumio4 volumio[977]: info: CorePlayQueue::getTrack 0 Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 18 23:40:12 volumio4 volumio[977]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 18 23:40:12 volumio4 volumio[977]: info: Received Get System Info Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 23:40:12 volumio4 volumio[977]: info: Discovery: Getting this device information Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetState Jan 18 23:40:12 volumio4 volumio[977]: info: CorePlayQueue::getTrack 0 Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetState Jan 18 23:40:12 volumio4 volumio[977]: info: CorePlayQueue::getTrack 0 Jan 18 23:40:12 volumio4 volumio[977]: info: Listing playlists Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 18 23:40:12 volumio4 volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 23:40:13 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 18 23:40:13 volumio4 volumio[977]: info: Received Get System Info Jan 18 23:40:13 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 23:40:13 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 23:40:13 volumio4 volumio[977]: info: Discovery: Getting this device information Jan 18 23:40:13 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetState Jan 18 23:40:13 volumio4 volumio[977]: info: CorePlayQueue::getTrack 0 Jan 18 23:40:13 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 23:40:13 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 18 23:40:14 volumio4 wpa_supplicant[872]: RRM: Ignoring radio measurement request: Not RRM network Jan 18 23:40:14 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 18 23:40:16 volumio4 wpa_supplicant[872]: RRM: Ignoring radio measurement request: Not RRM network Jan 18 23:40:16 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 18 23:40:16 volumio4 volumio[977]: info: Received Get System Info Jan 18 23:40:16 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 23:40:16 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 23:40:16 volumio4 volumio[977]: info: Discovery: Getting this device information Jan 18 23:40:16 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetState Jan 18 23:40:16 volumio4 volumio[977]: info: CorePlayQueue::getTrack 0 Jan 18 23:40:16 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 23:40:17 volumio4 ntpd[780]: Soliciting pool server 5.75.152.43 Jan 18 23:40:17 volumio4 ntpd[780]: Soliciting pool server 88.99.101.252 Jan 18 23:40:17 volumio4 ntpd[780]: Soliciting pool server 144.76.76.107 Jan 18 23:40:18 volumio4 wpa_supplicant[872]: RRM: Ignoring radio measurement request: Not RRM network Jan 18 23:40:18 volumio4 ntpd[780]: Soliciting pool server 90.187.112.137 Jan 18 23:40:18 volumio4 ntpd[780]: Soliciting pool server 185.228.138.224 Jan 18 23:40:19 volumio4 ntpd[780]: Soliciting pool server 85.215.166.214 Jan 18 23:40:19 volumio4 ntpd[780]: Soliciting pool server 185.228.139.165 Jan 18 23:40:19 volumio4 ntpd[780]: Soliciting pool server 129.70.132.35 Jan 18 23:40:20 volumio4 wpa_supplicant[872]: RRM: Ignoring radio measurement request: Not RRM network Jan 18 23:40:20 volumio4 ntpd[780]: Soliciting pool server 168.119.211.223 Jan 18 23:40:20 volumio4 ntpd[780]: Soliciting pool server 217.79.189.239 Jan 18 23:40:21 volumio4 ntpd[780]: Soliciting pool server 217.144.138.234 Jan 18 23:40:21 volumio4 ntpd[780]: Soliciting pool server 46.4.54.78 Jan 18 23:40:21 volumio4 volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 23:40:21 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 23:40:21 volumio4 volumio[977]: info: Discovery: Getting this device information Jan 18 23:40:21 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetState Jan 18 23:40:21 volumio4 volumio[977]: info: CorePlayQueue::getTrack 0 Jan 18 23:40:21 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 23:40:21 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 18 23:40:22 volumio4 ntpd[780]: Soliciting pool server 157.90.247.99 Jan 18 23:40:22 volumio4 ntpd[780]: Soliciting pool server 49.12.199.148 Jan 18 23:40:23 volumio4 ntpd[780]: Soliciting pool server 85.215.229.230 Jan 18 23:40:23 volumio4 ntpd[780]: Soliciting pool server 2a02:c207:2127:4427::1 Jan 18 23:40:24 volumio4 ntpd[780]: receive: Unexpected origin timestamp 0xeb36ac58.79aecca6 does not match aorg 0000000000.00000000 from server@185.228.139.165 xmt 0xeb36ac58.d52eddac Jan 18 23:40:24 volumio4 ntpd[780]: receive: Unexpected origin timestamp 0xeb36ac58.79a4721a does not match aorg 0000000000.00000000 from server@217.144.138.234 xmt 0xeb36ac58.d5ae3b8e Jan 18 23:40:24 volumio4 ntpd[780]: receive: Unexpected origin timestamp 0xeb36ac58.79b26450 does not match aorg 0000000000.00000000 from server@144.76.76.107 xmt 0xeb36ac58.d598e8aa Jan 18 23:40:24 volumio4 ntpd[780]: receive: Unexpected origin timestamp 0xeb36ac58.79abcabe does not match aorg 0000000000.00000000 from server@85.215.166.214 xmt 0xeb36ac58.d64eb566 Jan 18 23:40:29 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 23:40:29 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 23:40:29 volumio4 volumio[977]: info: Discovery: Getting this device information Jan 18 23:40:29 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetState Jan 18 23:40:29 volumio4 volumio[977]: info: CorePlayQueue::getTrack 0 Jan 18 23:40:29 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 23:40:30 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 18 23:40:30 volumio4 volumio[977]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 18 23:40:30 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 18 23:40:30 volumio4 volumio[977]: info: Received Get System Version Jan 18 23:40:30 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 18 23:40:30 volumio4 volumio[977]: info: Received Get System Info Jan 18 23:40:30 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 23:40:30 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 23:40:30 volumio4 volumio[977]: info: Discovery: Getting this device information Jan 18 23:40:30 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetState Jan 18 23:40:30 volumio4 volumio[977]: info: CorePlayQueue::getTrack 0 Jan 18 23:40:30 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 23:40:59 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 23:40:59 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 23:40:59 volumio4 volumio[977]: info: Discovery: Getting this device information Jan 18 23:40:59 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetState Jan 18 23:40:59 volumio4 volumio[977]: info: CorePlayQueue::getTrack 0 Jan 18 23:40:59 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 23:41:00 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetQueue Jan 18 23:41:00 volumio4 volumio[977]: info: CoreStateMachine::getQueue Jan 18 23:41:00 volumio4 volumio[977]: info: CorePlayQueue::getQueue Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 23:41:04 volumio4 volumio[977]: info: Retrieving Cloud Streaming UI Jan 18 23:41:04 volumio4 volumio[977]: info: Getting Tidal Cloud Configuration Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 23:41:04 volumio4 volumio[977]: info: Getting Qobuz Cloud Configuration Jan 18 23:41:04 volumio4 volumio[977]: info: Asking plugin for UI Config Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 23:41:04 volumio4 volumio[977]: info: Getting Spotify Cloud Configuration Jan 18 23:41:04 volumio4 volumio[977]: info: Asking plugin for UI Config Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 23:41:04 volumio4 volumio[977]: info: Saving Spotify Acccount Jan 18 23:41:04 volumio4 volumio[977]: info: Got it Jan 18 23:41:04 volumio4 volumio[977]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 18 23:41:04 volumio4 volumio[977]: info: Got Tidal Cloud Configuration Jan 18 23:41:04 volumio4 volumio[977]: info: Got it Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetBrowseSources Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetBrowseSources Jan 18 23:41:04 volumio4 volumio[977]: info: CoreCommandRouter::volumioGetBrowseSources Jan 18 23:41:05 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 23:41:05 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 18 23:41:09 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jan 18 23:41:09 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jan 18 23:41:09 volumio4 volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 23:41:09 volumio4 volumio[977]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 18 23:41:09 volumio4 volumio[977]: Error: dns service error: unknown Jan 18 23:41:09 volumio4 volumio[977]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jan 18 23:41:09 volumio4 volumio[977]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jan 18 23:41:09 volumio4 volumio[977]: errorCode: -65537 Jan 18 23:41:09 volumio4 volumio[977]: } Jan 18 23:41:09 volumio4 volumio[977]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 18 23:41:09 volumio4 sudo[2466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-18 23:40 Jan 18 23:41:09 volumio4 sudo[2466]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"