-- Logs begin at Thu 2019-02-14 08:11:58 -02, end at Sun 2026-01-11 11:04:27 -03. --
Jan 11 11:03:00 volumio sudo[1411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service
Jan 11 11:03:00 volumio sudo[1411]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:00 volumio sudo[1413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service
Jan 11 11:03:00 volumio sudo[1413]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:00 volumio sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
Jan 11 11:03:00 volumio sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:00 volumio 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 11 11:03:00 volumio volumio[1160]: info: [now-playing] App is listening on port 4004.
Jan 11 11:03:00 volumio volumio[1160]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds
Jan 11 11:03:00 volumio volumio[1160]: info: Discovery: adding 9d8c9c38-265a-4433-8cf9-7720e4bd706e
Jan 11 11:03:00 volumio volumio[1160]: info: Discovery: Found device Volumio
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState
Jan 11 11:03:00 volumio volumio[1160]: info: CorePlayQueue::getTrack 0
Jan 11 11:03:00 volumio volumio[1160]: info: touch_display: Backlight interface detected.
Jan 11 11:03:00 volumio volumio[1160]: info: MPD Permissions set
Jan 11 11:03:00 volumio volumio[1160]: info: MPD Permissions set
Jan 11 11:03:00 volumio volumio[1160]: info: Spotify config file written
Jan 11 11:03:00 volumio volumio[1160]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Jan 11 11:03:00 volumio volumio[1160]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Jan 11 11:03:00 volumio sudo[1431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Jan 11 11:03:00 volumio sudo[1431]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:00 volumio volumio[1160]: info: No need to fix Spotify hosts
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:00 volumio volumio[1160]: info: [CDPlayer]: [web-socket] connected
Jan 11 11:03:00 volumio volumio[1160]: info: Volumio called home
Jan 11 11:03:00 volumio sudo[1457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness
Jan 11 11:03:00 volumio sudo[1457]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:00 volumio systemd[1]: Started Samba SMB Daemon.
Jan 11 11:03:00 volumio systemd[1]: man-db.service: Succeeded.
Jan 11 11:03:00 volumio sudo[1457]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:00 volumio volumio[1160]: info: touch_display: No Raspberry Pi Foundation touch screen detected.
Jan 11 11:03:00 volumio systemd[1]: Started Daily man-db regeneration.
Jan 11 11:03:00 volumio systemd[1]: apt-daily.service: Succeeded.
Jan 11 11:03:00 volumio systemd[1]: Started Daily apt download activities.
Jan 11 11:03:00 volumio sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf
Jan 11 11:03:00 volumio sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:00 volumio sudo[1462]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:00 volumio volumio[1160]: info: Starting Shairport Sync
Jan 11 11:03:00 volumio mpd[1373]: Jan 11 11:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jan 11 11:03:00 volumio systemd[1]: Started Music Player Daemon.
Jan 11 11:03:00 volumio sudo[1357]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:00 volumio sudo[1466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf
Jan 11 11:03:00 volumio volumio[1160]: info: Starting Shairport Sync
Jan 11 11:03:00 volumio sudo[1466]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:00 volumio sudo[1347]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:00 volumio sudo[1466]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:00 volumio sudo[1470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jan 11 11:03:00 volumio sudo[1470]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:00 volumio volumio[1160]: info: Starting Shairport Sync
Jan 11 11:03:00 volumio systemd[1]: Reloading.
Jan 11 11:03:00 volumio sudo[1474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jan 11 11:03:00 volumio sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:00 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState
Jan 11 11:03:00 volumio volumio[1160]: info: CorePlayQueue::getTrack 0
Jan 11 11:03:00 volumio volumio[1160]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set.
Jan 11 11:03:00 volumio sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jan 11 11:03:00 volumio sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:00 volumio volumio[1160]: info: touch_display: File permissions for backlight brightness control set.
Jan 11 11:03:00 volumio volumio[1160]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set.
Jan 11 11:03:00 volumio volumio[1160]: error: touch_display: Error setting display brightness: Error: EINVAL: invalid argument, write
Jan 11 11:03:00 volumio 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 11 11:03:00 volumio volumio[1160]: error: MPD error: The expression evaluated to a falsy value:
Jan 11 11:03:00 volumio volumio[1160]: assert.ok(self.idling)
Jan 11 11:03:00 volumio volumio[1160]: error: The expression evaluated to a falsy value:
Jan 11 11:03:00 volumio volumio[1160]: assert.ok(self.idling)
Jan 11 11:03:00 volumio volumio[1160]: error: updateQueue error: null
Jan 11 11:03:00 volumio volumio[1160]: info: MPD running with PID1373
Jan 11 11:03:00 volumio volumio[1160]: ,establishing connection
Jan 11 11:03:00 volumio volumio[1160]: error: updateQueue error: null
Jan 11 11:03:01 volumio systemd[1]: Reloading.
Jan 11 11:03:01 volumio sudo[1416]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:01 volumio volumio[1160]: info: touch_display: systemctl daemon-reload succeeded.
Jan 11 11:03:01 volumio sudo[1497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service
Jan 11 11:03:01 volumio sudo[1497]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:01 volumio 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 11 11:03:01 volumio sudo[1413]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:01 volumio volumio[1160]: info: touch_display: systemctl disable getty@tty1.service succeeded.
Jan 11 11:03:01 volumio systemd[1]: Started Volumio Kiosk.
Jan 11 11:03:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jan 11 11:03:01 volumio systemd[1]: Started go-librespot Daemon.
Jan 11 11:03:01 volumio sudo[1431]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:01 volumio systemd[1]: Starting Daily apt upgrade and clean activities...
Jan 11 11:03:01 volumio go-librespot[1513]: go-librespot daemon starting...
Jan 11 11:03:01 volumio sudo[1384]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:01 volumio volumio[1160]: info: [CDPlayer]: Daemon service started
Jan 11 11:03:01 volumio systemd[1]: shairport-sync.service: Succeeded.
Jan 11 11:03:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jan 11 11:03:01 volumio sudo[1497]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:01 volumio volumio[1160]: info: touch_display: systemctl start volumio-kiosk.service succeeded.
Jan 11 11:03:01 volumio volumio[1160]: info: touch_display: Volumio Kiosk started.
Jan 11 11:03:01 volumio sudo[1411]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jan 11 11:03:01 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState
Jan 11 11:03:01 volumio volumio[1160]: info: CorePlayQueue::getTrack 0
Jan 11 11:03:01 volumio systemd[1]: Reached target Multi-User System.
Jan 11 11:03:01 volumio sudo[1477]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:01 volumio systemd[1]: Reached target Graphical Interface.
Jan 11 11:03:01 volumio volumio[1160]: info: Completed starting Core Plugins
Jan 11 11:03:01 volumio sudo[1470]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:01 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
Jan 11 11:03:01 volumio volumio[1160]: info: -------------------------------------------
Jan 11 11:03:01 volumio volumio[1160]: info: ----- MyVolumio plugins startup ----
Jan 11 11:03:01 volumio volumio[1160]: info: -------------------------------------------
Jan 11 11:03:01 volumio volumio[1160]: info: [MyVolumio PluginManager] Fetching plans data....
Jan 11 11:03:01 volumio sudo[1474]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:01 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState
Jan 11 11:03:01 volumio volumio[1160]: info: CorePlayQueue::getTrack 0
Jan 11 11:03:01 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Jan 11 11:03:01 volumio volumio[1160]: info: Shairport-Sync Started
Jan 11 11:03:01 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
Jan 11 11:03:01 volumio volumio[1160]: Error adding Membership: Error: addMembership EINVAL
Jan 11 11:03:01 volumio volumio[1160]: info: Shairport-Sync Started
Jan 11 11:03:01 volumio volumio[1160]: info: Shairport-Sync Started
Jan 11 11:03:01 volumio volumio[1160]: info: touch_display: systemctl stop getty@tty1.service succeeded.
Jan 11 11:03:01 volumio volumio[1160]: info: touch_display: X display number found:
Jan 11 11:03:01 volumio startx[1512]: X.Org X Server 1.20.4
Jan 11 11:03:01 volumio startx[1512]: X Protocol Version 11, Revision 0
Jan 11 11:03:01 volumio startx[1512]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian
Jan 11 11:03:01 volumio startx[1512]: Current Operating System: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l
Jan 11 11:03:01 volumio startx[1512]: 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 snd_bcm2835.enable_hdmi=0 video=HDMI-A-1:640x480M@60D numa=fake=2 system_heap.max_order=0 smsc95xx.macaddr=DC:A6:32:E2:AE:24 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=ea4305a4-d90d-47fc-afb2-715854f091ba imgfile=/volumio_current.sqsh bootpart=UUID=ABA1-7067 datapart=UUID=f7b616b7-de12-485f-aa0b-647e37a9b4ff 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 11 11:03:01 volumio startx[1512]: Build Date: 04 April 2023 07:50:56AM
Jan 11 11:03:01 volumio startx[1512]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support)
Jan 11 11:03:01 volumio startx[1512]: Current version of pixman: 0.36.0
Jan 11 11:03:01 volumio startx[1512]: Before reporting problems, check http://wiki.x.org
Jan 11 11:03:01 volumio startx[1512]: to make sure that you have the latest version.
Jan 11 11:03:01 volumio startx[1512]: Markers: (--) probed, (**) from config file, (==) default setting,
Jan 11 11:03:01 volumio startx[1512]: (++) from command line, (!!) notice, (II) informational,
Jan 11 11:03:01 volumio startx[1512]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown.
Jan 11 11:03:01 volumio startx[1512]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Jan 11 11:03:01 2026
Jan 11 11:03:02 volumio startx[1512]: (==) Using config directory: "/etc/X11/xorg.conf.d"
Jan 11 11:03:02 volumio startx[1512]: (==) Using system config directory "/usr/share/X11/xorg.conf.d"
Jan 11 11:03:02 volumio volumio[1160]: info: touch_display: X display number found: 0
Jan 11 11:03:02 volumio volumio[1160]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
Jan 11 11:03:02 volumio volumio[1160]: info: touch_display: X display number found: 0
Jan 11 11:03:02 volumio go-librespot[1513]: time="2026-01-11T11:03:02-03:00" level=info msg="running go-librespot 0.4.0"
Jan 11 11:03:02 volumio go-librespot[1513]: time="2026-01-11T11:03:02-03:00" level=debug msg="app state loaded"
Jan 11 11:03:02 volumio go-librespot[1513]: time="2026-01-11T11:03:02-03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 11:03:02 volumio go-librespot[1513]: time="2026-01-11T11:03:02-03:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 11:03:02 volumio go-librespot[1513]: time="2026-01-11T11:03:02-03:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Jan 11 11:03:02 volumio go-librespot[1513]: time="2026-01-11T11:03:02-03:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Jan 11 11:03:02 volumio go-librespot[1513]: time="2026-01-11T11:03:02-03:00" level=info msg="zeroconf server listening on port 34907"
Jan 11 11:03:02 volumio volumio[1160]: info: Volumio Network Manager: Network status updated: 2
Jan 11 11:03:02 volumio sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jan 11 11:03:02 volumio sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:02 volumio sudo[1598]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:02 volumio sudo[1601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jan 11 11:03:02 volumio sudo[1601]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:02 volumio sudo[1601]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:02 volumio sudo[1608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Jan 11 11:03:02 volumio sudo[1608]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:02 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Jan 11 11:03:02 volumio sudo[1608]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:02 volumio volumio[1160]: info: Upmpdcli Daemon Started
Jan 11 11:03:03 volumio systemd[1]: apt-daily-upgrade.service: Succeeded.
Jan 11 11:03:03 volumio systemd[1]: Started Daily apt upgrade and clean activities.
Jan 11 11:03:03 volumio systemd[1]: Startup finished in 12.075s (kernel) + 44.837s (userspace) = 56.913s.
Jan 11 11:03:03 volumio volumio[1614]: Generating RSA private key, 4096 bit long modulus (2 primes)
Jan 11 11:03:03 volumio kernel: broken atomic modeset userspace detected, disabling atomic
Jan 11 11:03:03 volumio startx[1512]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc.
Jan 11 11:03:03 volumio sudo[1644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Jan 11 11:03:03 volumio sudo[1644]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:03 volumio sudo[1646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Jan 11 11:03:03 volumio sudo[1646]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:03 volumio systemd[1]: Started MPD Monitor Service.
Jan 11 11:03:03 volumio mpd_monitor.sh[1649]: MPD Monitor Service: Starting MPD Monitor Service
Jan 11 11:03:03 volumio sudo[1644]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:03 volumio volumio[1160]: info: Successfully started MPD Monitor
Jan 11 11:03:03 volumio systemd[1]: Stopping MPD Monitor Service...
Jan 11 11:03:03 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM
Jan 11 11:03:03 volumio systemd[1]: mpd_monitor.service: Succeeded.
Jan 11 11:03:03 volumio systemd[1]: Stopped MPD Monitor Service.
Jan 11 11:03:03 volumio systemd[1]: Started MPD Monitor Service.
Jan 11 11:03:03 volumio sudo[1646]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:03 volumio volumio[1160]: info: Successfully started MPD Monitor
Jan 11 11:03:03 volumio mpd_monitor.sh[1652]: MPD Monitor Service: Starting MPD Monitor Service
Jan 11 11:03:04 volumio volumio[1160]: info: touch_display: Setting screensaver timeout to 120 seconds.
Jan 11 11:03:04 volumio volumio[1160]: info: go-librespot daemon successfully initialized
Jan 11 11:03:05 volumio volumio[1160]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Jan 11 11:03:06 volumio volumio[1614]: ................................++++
Jan 11 11:03:07 volumio volumio[1160]: info: Initializing connection to go-librespot Websocket
Jan 11 11:03:07 volumio go-librespot[1513]: time="2026-01-11T11:03:07-03:00" level=debug msg="new websocket client"
Jan 11 11:03:07 volumio volumio[1160]: info: Connection to go-librespot Websocket established
Jan 11 11:03:10 volumio volumio[1614]: ...............................................++++
Jan 11 11:03:10 volumio volumio[1614]: e is 65537 (0x010001)
Jan 11 11:03:10 volumio volumio[1614]: writing RSA key
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Jan 11 11:03:10 volumio volumio[1160]: info: Adding plugin bluetooth to MyMusic Plugins
Jan 11 11:03:10 volumio volumio[1160]: info: Adding plugin multiroom to MyMusic Plugins
Jan 11 11:03:10 volumio volumio[1160]: info: Adding plugin metavolumio to MyMusic Plugins
Jan 11 11:03:10 volumio volumio[1160]: info: Adding plugin cd_controller to MyMusic Plugins
Jan 11 11:03:10 volumio volumio[1160]: info: Adding plugin qobuzconnect to MyMusic Plugins
Jan 11 11:03:10 volumio volumio[1160]: info: Adding plugin smart_inputs to MyMusic Plugins
Jan 11 11:03:10 volumio volumio[1160]: info: Adding plugin tidalconnect to MyMusic Plugins
Jan 11 11:03:10 volumio volumio[1160]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Jan 11 11:03:11 volumio volumio[1160]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Jan 11 11:03:11 volumio volumio[1160]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Jan 11 11:03:11 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 11:03:11 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 11:03:11 volumio volumio[1160]: info: Starting MyVolumio Remote Streaming Endpoints
Jan 11 11:03:11 volumio volumio[1160]: info: MyVolumio login type: Token
Jan 11 11:03:11 volumio volumio[1160]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Jan 11 11:03:11 volumio volumio[1160]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Jan 11 11:03:12 volumio volumio[1160]: info: Starting Streaming Service Transparent Proxy
Jan 11 11:03:12 volumio volumio[1160]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Jan 11 11:03:12 volumio volumio[1160]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Jan 11 11:03:12 volumio volumio[1160]: info: Streaming services startup
Jan 11 11:03:12 volumio volumio[1160]: info: Starting Streaming Daemon
Jan 11 11:03:12 volumio sudo[1881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jan 11 11:03:12 volumio sudo[1881]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:12 volumio volumio[1160]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Jan 11 11:03:12 volumio sudo[1881]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:12 volumio volumio[1160]: info: Getting Spotify volume
Jan 11 11:03:12 volumio volumio[1160]: error: Cannot start Volumio Streaming Daemon
Jan 11 11:03:12 volumio volumio[1160]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jan 11 11:03:12 volumio volumio[1160]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jan 11 11:03:12 volumio volumio[1160]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Jan 11 11:03:12 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState
Jan 11 11:03:12 volumio volumio[1160]: info: CorePlayQueue::getTrack 0
Jan 11 11:03:12 volumio volumio[1160]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75
Jan 11 11:03:12 volumio volumio[1160]: SPOTIFY: SPOTIFY VOLUME undefined
Jan 11 11:03:12 volumio volumio[1160]: SPOTIFY: VOLUMIO VOLUME 75
Jan 11 11:03:12 volumio volumio[1160]: info: Aligning Spotify Volume to Volumio Volume
Jan 11 11:03:12 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState
Jan 11 11:03:12 volumio volumio[1160]: info: CorePlayQueue::getTrack 0
Jan 11 11:03:12 volumio volumio[1160]: info: Setting Spotify Volume from Volumio: 75
Jan 11 11:03:13 volumio volumio[1160]: STREAMING PROXY: Starting server on port 3245
Jan 11 11:03:13 volumio volumio[1160]: Node JS runtime: 14
Jan 11 11:03:13 volumio volumio[1160]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Jan 11 11:03:14 volumio volumio[1160]: info: MyVolumio token set successfully
Jan 11 11:03:14 volumio volumio[1160]: info: MYVOLUMIO: Adding device
Jan 11 11:03:14 volumio volumio[1160]: info: MYVOLUMIO: Evaluating Server
Jan 11 11:03:14 volumio volumio[1160]: SPOTIFY: SETTING SPOTIFY VOLUME 75
Jan 11 11:03:14 volumio volumio[1160]: info: Sending Spotify command with payload to local API: /player/volume
Jan 11 11:03:14 volumio volumio[1160]: info: MyVolumio status changed
Jan 11 11:03:14 volumio volumio[1160]: info: Streaming services startup
Jan 11 11:03:14 volumio volumio[1160]: info: Starting Streaming Daemon
Jan 11 11:03:14 volumio volumio[1160]: info: Removing browser output: myVolumio user plan is not superstar
Jan 11 11:03:14 volumio volumio[1160]: info: Removing audio output:
Jan 11 11:03:14 volumio volumio[1160]: info: Stoppping Tunnel 1
Jan 11 11:03:14 volumio sudo[1910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jan 11 11:03:14 volumio sudo[1910]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:14 volumio sudo[1913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Jan 11 11:03:14 volumio sudo[1913]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 11 11:03:14 volumio sudo[1910]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:14 volumio sudo[1913]: pam_unix(sudo:session): session closed for user root
Jan 11 11:03:14 volumio volumio[1160]: info: Remote SSH Stopped
Jan 11 11:03:14 volumio volumio[1160]: error: Cannot start Volumio Streaming Daemon
Jan 11 11:03:14 volumio volumio[1160]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jan 11 11:03:14 volumio volumio[1160]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jan 11 11:03:15 volumio volumio[1160]: 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: 6
Jan 11 11:03:15 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 11 11:03:15 volumio volumio[1160]: info: Setting Geolocation for MyVolumio to us1
Jan 11 11:03:15 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 11:03:15 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 11:03:16 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jan 11 11:03:16 volumio volumio[1160]: 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: 7
Jan 11 11:03:16 volumio volumio[1160]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jan 11 11:03:16 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 11 11:03:16 volumio volumio[1160]: info: Updating MyVolumio device info
Jan 11 11:03:16 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 11:03:16 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 11:03:16 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 11:03:16 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jan 11 11:03:17 volumio volumio[1160]: info: CoreCommandRouter::volumioGetVisibleSources
Jan 11 11:03:17 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 11 11:03:17 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState
Jan 11 11:03:17 volumio volumio[1160]: info: CorePlayQueue::getTrack 0
Jan 11 11:03:17 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jan 11 11:03:17 volumio volumio[1160]: info: CoreCommandRouter::volumioGetQueue
Jan 11 11:03:17 volumio volumio[1160]: info: CoreStateMachine::getQueue
Jan 11 11:03:17 volumio volumio[1160]: info: CorePlayQueue::getQueue
Jan 11 11:03:17 volumio volumio[1160]: info: Listing playlists
Jan 11 11:03:17 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jan 11 11:03:17 volumio volumio[1160]: info: Received Get System Info
Jan 11 11:03:17 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 11 11:03:17 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 11 11:03:17 volumio volumio[1160]: info: Discovery: Getting this device information
Jan 11 11:03:17 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState
Jan 11 11:03:17 volumio volumio[1160]: info: CorePlayQueue::getTrack 0
Jan 11 11:03:17 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 11 11:03:17 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState
Jan 11 11:03:17 volumio volumio[1160]: info: CorePlayQueue::getTrack 0
Jan 11 11:03:17 volumio volumio[1160]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jan 11 11:03:18 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jan 11 11:03:18 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 11 11:03:18 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jan 11 11:03:18 volumio volumio[1160]: info: Received Get System Info
Jan 11 11:03:18 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 11 11:03:18 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 11 11:03:18 volumio volumio[1160]: info: Discovery: Getting this device information
Jan 11 11:03:18 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState
Jan 11 11:03:18 volumio volumio[1160]: info: CorePlayQueue::getTrack 0
Jan 11 11:03:18 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 11 11:03:18 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jan 11 11:03:19 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Jan 11 11:03:20 volumio volumio[1160]: info: MYVOLUMIO: Adding device
Jan 11 11:03:20 volumio volumio[1160]: info: MYVOLUMIO: Evaluating Server
Jan 11 11:03:20 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jan 11 11:03:20 volumio volumio[1160]: info: Received Get System Info
Jan 11 11:03:20 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 11 11:03:20 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 11 11:03:20 volumio volumio[1160]: info: Discovery: Getting this device information
Jan 11 11:03:20 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState
Jan 11 11:03:20 volumio volumio[1160]: info: CorePlayQueue::getTrack 0
Jan 11 11:03:20 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 11 11:03:21 volumio volumio[1160]: info: Setting Geolocation for MyVolumio to us1
Jan 11 11:03:21 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 11:03:21 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 11:03:21 volumio volumio[1160]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jan 11 11:03:21 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jan 11 11:03:21 volumio volumio[1160]: info: Received Get System Info
Jan 11 11:03:21 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 11 11:03:21 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 11 11:03:21 volumio volumio[1160]: info: Discovery: Getting this device information
Jan 11 11:03:21 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState
Jan 11 11:03:21 volumio volumio[1160]: info: CorePlayQueue::getTrack 0
Jan 11 11:03:21 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 11 11:03:22 volumio volumio[1160]: info: Updating MyVolumio device info
Jan 11 11:03:22 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 11:03:22 volumio volumio[1160]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jan 11 11:03:26 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Jan 11 11:03:26 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 11 11:03:26 volumio volumio[1160]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Jan 11 11:03:26 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState
Jan 11 11:03:26 volumio volumio[1160]: info: CorePlayQueue::getTrack 0
Jan 11 11:03:28 volumio systemd[1]: systemd-timedated.service: Succeeded.
Jan 11 11:03:29 volumio ntpd[909]: Soliciting pool server 200.192.232.8
Jan 11 11:03:29 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 11 11:03:29 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 11:03:29 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 11 11:03:29 volumio ntpd[909]: Soliciting pool server 216.238.102.201
Jan 11 11:03:30 volumio ntpd[909]: Soliciting pool server 200.160.7.197
Jan 11 11:03:30 volumio volumio-remote-updater[745]: No test mode
Jan 11 11:03:30 volumio volumio-remote-updater[745]: No alpha test mode
Jan 11 11:03:30 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jan 11 11:03:30 volumio volumio[1160]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Fix TIDAL low quality (AAC) playback
- Fix for DLNA Server discovery
- Fix for Plugins installation
NEW ADDITIONS
- BLE-based onboarding setup
- Update TIDAL SDK
","title":"Update v3.886","updateavailable":true}
Jan 11 11:03:30 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jan 11 11:03:30 volumio ntpd[909]: Soliciting pool server 143.107.229.210
Jan 11 11:03:30 volumio ntpd[909]: Soliciting pool server 216.238.112.29
Jan 11 11:03:31 volumio ntpd[909]: Soliciting pool server 192.36.143.130
Jan 11 11:03:31 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:31 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:31 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jan 11 11:03:31 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jan 11 11:03:31 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jan 11 11:03:31 volumio volumio[1160]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 11 11:03:31 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 11 11:03:31 volumio ntpd[909]: Soliciting pool server 143.107.229.211
Jan 11 11:03:31 volumio ntpd[909]: Soliciting pool server 146.164.48.66
Jan 11 11:03:31 volumio ntpd[909]: Soliciting pool server 200.160.7.196
Jan 11 11:03:32 volumio ntpd[909]: Soliciting pool server 200.160.7.186
Jan 11 11:03:32 volumio volumio[1160]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Jan 11 11:03:32 volumio volumio[1160]: info: Completed starting MyVolumio Plugin
Jan 11 11:03:32 volumio volumio[1160]: [Metrics] CommandRouter: 41s 963.45ms
Jan 11 11:03:32 volumio volumio[1160]: info: CoreCommandRouter::volumiosetStartupVolume
Jan 11 11:03:32 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 11:03:32 volumio volumio[1160]: info: VolumeController:: Setting startup Volume 100
Jan 11 11:03:32 volumio volumio[1160]: info: VolumeController::SetAlsaVolume100
Jan 11 11:03:32 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 11:03:32 volumio volumio[1160]: info: CoreCommandRouter::Close All Modals sent
Jan 11 11:03:32 volumio volumio[1160]: info: CoreCommandRouter::Close All Modals sent
Jan 11 11:03:32 volumio volumio[1160]: info: CoreStateMachine::pushState
Jan 11 11:03:32 volumio volumio[1160]: info: CorePlayQueue::getTrack 0
Jan 11 11:03:32 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 11 11:03:32 volumio volumio[1160]: info: CoreCommandRouter::volumioPushState
Jan 11 11:03:32 volumio volumio[1160]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jan 11 11:03:32 volumio volumio[1160]: SPOTIFY: SPOTIFY VOLUME 75
Jan 11 11:03:32 volumio volumio[1160]: SPOTIFY: VOLUMIO VOLUME 100
Jan 11 11:03:32 volumio volumio[1160]: SPOTIFY: DELTA VOLUME ENOUGH: true
Jan 11 11:03:32 volumio volumio[1160]: info: Setting Spotify Volume from Volumio: 100
Jan 11 11:03:32 volumio ntpd[909]: Soliciting pool server 200.160.0.8
Jan 11 11:03:32 volumio ntpd[909]: Soliciting pool server 168.181.126.108
Jan 11 11:03:32 volumio ntpd[909]: Soliciting pool server 200.189.40.8
Jan 11 11:03:32 volumio ntpd[909]: Soliciting pool server 194.58.38.95
Jan 11 11:03:33 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jan 11 11:03:33 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jan 11 11:03:33 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jan 11 11:03:33 volumio ntpd[909]: Soliciting pool server 2001:19f0:b800:1c73:5400:5ff:fe04:48c3
Jan 11 11:03:33 volumio ntpd[909]: Soliciting pool server 162.159.200.1
Jan 11 11:03:34 volumio volumio[1160]: SPOTIFY: SETTING SPOTIFY VOLUME 100
Jan 11 11:03:34 volumio volumio[1160]: info: Sending Spotify command with payload to local API: /player/volume
Jan 11 11:03:34 volumio ntpd[909]: Soliciting pool server 172.233.29.160
Jan 11 11:03:35 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 11 11:03:35 volumio volumio[1160]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Jan 11 11:03:35 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Jan 11 11:03:35 volumio volumio[1160]: info: Received Get System Version
Jan 11 11:03:35 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 11 11:03:35 volumio volumio[1160]: info: Received Get System Info
Jan 11 11:03:35 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 11 11:03:35 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 11 11:03:35 volumio volumio[1160]: info: Discovery: Getting this device information
Jan 11 11:03:35 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState
Jan 11 11:03:35 volumio volumio[1160]: info: CorePlayQueue::getTrack 0
Jan 11 11:03:35 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 11 11:03:38 volumio ntpd[909]: receive: Unexpected origin timestamp 0xed0e2c39.df1f4d3d does not match aorg 0000000000.00000000 from server@200.192.232.8 xmt 0xed0e2c3a.cdcb77a4
Jan 11 11:03:38 volumio ntpd[909]: receive: Unexpected origin timestamp 0xed0e2c39.df1ccaca does not match aorg 0000000000.00000000 from server@200.160.7.186 xmt 0xed0e2c3a.d28683ef
Jan 11 11:03:38 volumio ntpd[909]: receive: Unexpected origin timestamp 0xed0e2c39.df1bb739 does not match aorg 0000000000.00000000 from server@200.160.0.8 xmt 0xed0e2c3a.d28b9b50
Jan 11 11:03:38 volumio ntpd[909]: receive: Unexpected origin timestamp 0xed0e2c39.df1db310 does not match aorg 0000000000.00000000 from server@192.36.143.130 xmt 0xed0e2c3a.de256d8e
Jan 11 11:03:38 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 3
Jan 11 11:03:38 volumio kernel: xhci_hcd 0000:01:00.0: @000000040d50cfe0 00000000 00000000 0e000000 02048001
Jan 11 11:03:40 volumio volumio[1160]: info: BOOT COMPLETED
Jan 11 11:03:59 volumio kernel: usb 1-1.2: new high-speed USB device number 4 using xhci_hcd
Jan 11 11:03:59 volumio kernel: usb 1-1.2: New USB device found, idVendor=413c, idProduct=9016, bcdDevice= 0.00
Jan 11 11:03:59 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 11 11:03:59 volumio kernel: usb 1-1.2: Product: Dell DVD+/-RW Drive DW316
Jan 11 11:03:59 volumio kernel: usb 1-1.2: Manufacturer: Dell Inc.
Jan 11 11:03:59 volumio kernel: usb 1-1.2: SerialNumber: K51J9RI3504
Jan 11 11:03:59 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected
Jan 11 11:03:59 volumio kernel: scsi host0: usb-storage 1-1.2:1.0
Jan 11 11:04:00 volumio kernel: scsi 0:0:0:0: CD-ROM Dell DVD+/-RW DW316 A1C1 PQ: 0 ANSI: 0
Jan 11 11:04:00 volumio kernel: scsi 0:0:0:0: Attached scsi generic sg0 type 5
Jan 11 11:04:00 volumio kernel: sr 0:0:0:0: Power-on or device reset occurred
Jan 11 11:04:00 volumio kernel: sr 0:0:0:0: [sr0] scsi3-mmc drive: 24x/24x writer dvd-ram cd/rw xa/form2 cdda tray
Jan 11 11:04:00 volumio kernel: cdrom: Uniform CD-ROM driver Revision: 3.20
Jan 11 11:04:00 volumio kernel: sr 0:0:0:0: Attached scsi CD-ROM sr0
Jan 11 11:04:00 volumio volumio[1160]: info: [CDPlayer]: [tray-watcher] handling add on /dev/sr0
Jan 11 11:04:00 volumio volumio[1160]: info: [CDPlayer]: [tray-watcher] handling change on /dev/sr0
Jan 11 11:04:00 volumio volumio[1160]: info: [CDPlayer]: [tray-watcher] handling change on /dev/sr0
Jan 11 11:04:00 volumio volumio[1160]: info: [CDPlayer]: [tray-watcher] decision for /dev/sr0: action=change media= ready= ejectReq=0 mediaPresent=null pendingEject=false noDisc=false
Jan 11 11:04:00 volumio volumio[1160]: info: [CDPlayer]: [tray-watcher] decision for /dev/sr0: action=add media= ready= ejectReq=0 mediaPresent=null pendingEject=false noDisc=false
Jan 11 11:04:00 volumio volumio[1160]: info: [CDPlayer]: [tray-watcher] decision for /dev/sr0: action=change media= ready= ejectReq=0 mediaPresent=null pendingEject=false noDisc=false
Jan 11 11:04:00 volumio volumio[1160]: info: [CDPlayer]: [tray-watcher] handling add on /dev/sr0
Jan 11 11:04:00 volumio volumio[1160]: info: [CDPlayer]: [tray-watcher] decision for /dev/sr0: action=add media= ready= ejectReq=0 mediaPresent=null pendingEject=false noDisc=false
Jan 11 11:04:00 volumio volumio[1160]: info: [CDPlayer]: [tray-watcher] handling change on /dev/sr0
Jan 11 11:04:00 volumio volumio[1160]: info: [CDPlayer]: [tray-watcher] decision for /dev/sr0: action=change media= ready=0 ejectReq=0 mediaPresent=null pendingEject=false noDisc=false
Jan 11 11:04:00 volumio volumio[1160]: info: [CDPlayer]: [tray-watcher] handling change on /dev/sr0
Jan 11 11:04:00 volumio volumio[1160]: info: [CDPlayer]: [tray-watcher] decision for /dev/sr0: action=change media= ready= ejectReq=0 mediaPresent=null pendingEject=false noDisc=false
Jan 11 11:04:09 volumio volumio[1160]: info: [CDPlayer]: [tray-watcher] handling change on /dev/sr0
Jan 11 11:04:09 volumio volumio[1160]: info: [CDPlayer]: [tray-watcher] decision for /dev/sr0: action=change media= ready= ejectReq=0 mediaPresent=null pendingEject=false noDisc=false
Jan 11 11:04:09 volumio volumio[1160]: info: [CDPlayer]: [tray-watcher] handling change on /dev/sr0
Jan 11 11:04:09 volumio volumio[1160]: info: [CDPlayer]: [tray-watcher] decision for /dev/sr0: action=change media=1 ready= ejectReq=0 mediaPresent=true pendingEject=false noDisc=false
Jan 11 11:04:27 volumio volumio[1160]: info: Preload queue cleared
Jan 11 11:04:27 volumio volumio[1160]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jan 11 11:04:27 volumio volumio[1160]: info: CoreStateMachine::ClearQueue
Jan 11 11:04:27 volumio volumio[1160]: info: CoreStateMachine::stop
Jan 11 11:04:27 volumio volumio[1160]: info: CoreStateMachine::setConsumeUpdateService undefined
Jan 11 11:04:27 volumio volumio[1160]: info: CorePlayQueue::clearPlayQueue
Jan 11 11:04:27 volumio volumio[1160]: info: CorePlayQueue::saveQueue
Jan 11 11:04:27 volumio volumio[1160]: info: CoreCommandRouter::volumioPushQueue
Jan 11 11:04:27 volumio volumio[1160]: info: CoreStateMachine::addQueueItems
Jan 11 11:04:27 volumio volumio[1160]: info: CorePlayQueue::addQueueItems
Jan 11 11:04:27 volumio volumio[1160]: info: Preload queue cleared
Jan 11 11:04:27 volumio volumio[1160]: info: Adding Item to queue: cdplayer/1?disc=1768060120704
Jan 11 11:04:27 volumio volumio[1160]: info: Exploding uri cdplayer/1?disc=1768060120704 in service cdplayer
Jan 11 11:04:27 volumio volumio[1160]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 11 11:04:27 volumio volumio[1160]: TypeError: Cannot read property '0' of null
Jan 11 11:04:27 volumio volumio[1160]: at cdplayer.explodeUri (/data/plugins/music_service/cdplayer/index.js:355:21)
Jan 11 11:04:27 volumio volumio[1160]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16)
Jan 11 11:04:27 volumio volumio[1160]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24)
Jan 11 11:04:27 volumio volumio[1160]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19)
Jan 11 11:04:27 volumio volumio[1160]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30)
Jan 11 11:04:27 volumio volumio[1160]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:251:25)
Jan 11 11:04:27 volumio volumio[1160]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:511:25)
Jan 11 11:04:27 volumio volumio[1160]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17)
Jan 11 11:04:27 volumio volumio[1160]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33)
Jan 11 11:04:27 volumio volumio[1160]: at Socket.emit (events.js:315:20)
Jan 11 11:04:27 volumio volumio[1160]: at /volumio/node_modules/socket.io/lib/socket.js:528:12
Jan 11 11:04:27 volumio volumio[1160]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
Jan 11 11:04:27 volumio volumio[1160]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 11 11:04:27 volumio sudo[2378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-11 11:03
Jan 11 11:04:27 volumio sudo[2378]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET"
VOLUMIO_VERSION="3.874"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"