Dec 17 22:32:00 retrostream systemd[1]: Reloading. Dec 17 22:32:00 retrostream sudo[1577]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:00 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:00 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:32:00 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:32:00 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:32:00 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:32:00 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:32:00 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:32:00 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:32:00 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:32:00 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:32:00 retrostream sudo[1617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Dec 17 22:32:00 retrostream sudo[1617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:00 retrostream sudo[1617]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:00 retrostream mpd[1536]: 2025-12-17T22:32:00 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 17 22:32:00 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:00 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:32:00 retrostream volumio[1314]: info: No need to fix Spotify hosts Dec 17 22:32:00 retrostream volumio[1314]: info: Discovery: this is already registered, 8ce8aa29-c44f-48b5-9e62-ae96b60b2516 Dec 17 22:32:00 retrostream volumio[1314]: info: Discovery: Found device RetroStream Dec 17 22:32:00 retrostream volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 17 22:32:00 retrostream volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 17 22:32:00 retrostream volumio[1314]: info: touch_display: IframeKeyboardBridge extension installed successfully Dec 17 22:32:00 retrostream volumio[1314]: info: touch_display: File permissions for backlight brightness control set. Dec 17 22:32:00 retrostream volumio[1314]: info: touch_display: systemctl disable getty@tty1.service succeeded. Dec 17 22:32:00 retrostream volumio[1314]: info: Starting Shairport Sync Dec 17 22:32:01 retrostream volumio[1314]: info: Starting Shairport Sync Dec 17 22:32:01 retrostream volumio[1314]: info: Starting Shairport Sync Dec 17 22:32:01 retrostream systemd[1]: Started mpd.service - Music Player Daemon. Dec 17 22:32:01 retrostream volumio[1314]: info: touch_display: Raspberry Pi Foundation touch screen detected. Dec 17 22:32:01 retrostream sudo[1655]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 17 22:32:01 retrostream sudo[1655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:01 retrostream sudo[1580]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:01 retrostream sudo[1512]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:01 retrostream systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 17 22:32:01 retrostream systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 17 22:32:01 retrostream sudo[1659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 17 22:32:01 retrostream sudo[1659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:01 retrostream sudo[1523]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:01 retrostream sudo[1666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 17 22:32:01 retrostream sudo[1666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:01 retrostream sudo[1666]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:01 retrostream systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 17 22:32:01 retrostream volumio[1314]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 17 22:32:01 retrostream volumio[1314]: SPOTIFY: BQDse8L19R9UK1_fiECmfrd5GfAQB_Ldl18VYTUCp_jbRFL3Ttva9pCeZrBmE5Ayxsx7Psf-tRp5bwbqwaIvUpWr-MtckNKMrKPKAvjmGpYoKMAVaux_NUCBvKElMzVxdZUTafgikETh4h8caa0exSwTAI5XxyVKkx7zEqaW1avM8q5s1L_AdkIdqtJEM-sP8imqWQfBj908-rkJOuE8pjLsZ1ILSx4w1FWidq-N9bM47no-r7tCZ8fhZ7VPU1NkXZW-HV4_DwOJPlkC-KPTShm_AYjoYQ_0-xJQGjM Dec 17 22:32:01 retrostream volumio[1314]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 17 22:32:01 retrostream volumio[1314]: info: New Spotify access token = BQDse8L19R9UK1_fiECmfrd5GfAQB_Ldl18VYTUCp_jbRFL3Ttva9pCeZrBmE5Ayxsx7Psf-tRp5bwbqwaIvUpWr-MtckNKMrKPKAvjmGpYoKMAVaux_NUCBvKElMzVxdZUTafgikETh4h8caa0exSwTAI5XxyVKkx7zEqaW1avM8q5s1L_AdkIdqtJEM-sP8imqWQfBj908-rkJOuE8pjLsZ1ILSx4w1FWidq-N9bM47no-r7tCZ8fhZ7VPU1NkXZW-HV4_DwOJPlkC-KPTShm_AYjoYQ_0-xJQGjM Dec 17 22:32:01 retrostream sudo[1661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Dec 17 22:32:01 retrostream sudo[1661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:01 retrostream sudo[1593]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:01 retrostream systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 17 22:32:01 retrostream go-librespot[1665]: go-librespot daemon starting... Dec 17 22:32:01 retrostream systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 17 22:32:01 retrostream sudo[1661]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:01 retrostream systemd[1]: shairport-sync.service: Deactivated successfully. Dec 17 22:32:01 retrostream volumio[1314]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 17 22:32:01 retrostream systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 22:32:01 retrostream systemd[1]: shairport-sync.service: Consumed 1.137s CPU time. Dec 17 22:32:01 retrostream sudo[1657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 17 22:32:01 retrostream systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 22:32:01 retrostream sudo[1655]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:01 retrostream sudo[1657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:01 retrostream sudo[1659]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:01 retrostream sudo[1575]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:01 retrostream systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 17 22:32:01 retrostream systemd[1]: shairport-sync.service: Deactivated successfully. Dec 17 22:32:01 retrostream systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 22:32:01 retrostream systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 22:32:01 retrostream sudo[1657]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:01 retrostream go-librespot[1670]: time="2025-12-17T22:32:01Z" level=info msg="running go-librespot 0.4.0" Dec 17 22:32:01 retrostream go-librespot[1670]: time="2025-12-17T22:32:01Z" level=debug msg="app state loaded" Dec 17 22:32:01 retrostream go-librespot[1670]: time="2025-12-17T22:32:01Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 17 22:32:01 retrostream volumio[1314]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 17 22:32:01 retrostream volumio[1314]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Dec 17 22:32:01 retrostream volumio[1314]: info: Shairport-Sync Started Dec 17 22:32:01 retrostream volumio[1314]: Error adding Membership: Error: addMembership EINVAL Dec 17 22:32:01 retrostream volumio[1314]: info: Shairport-Sync Started Dec 17 22:32:01 retrostream volumio[1314]: info: Shairport-Sync Started Dec 17 22:32:01 retrostream volumio[1314]: info: touch_display: systemctl stop getty@tty1.service succeeded. Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 17 22:32:02 retrostream volumio[1314]: info: touch_display: systemctl daemon-reload succeeded. Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=info msg="zeroconf server listening on port 41609" Dec 17 22:32:02 retrostream systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 17 22:32:02 retrostream systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 17 22:32:02 retrostream sudo[1731]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Dec 17 22:32:02 retrostream sudo[1731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=debug msg="obtained new client token: AAAusbRvQ5A4qMiItEb3qNOQgJJxuLj5m4plcRZNij66nkmvms0Qkkl6Pyd/o9Yyhk+mXQzc6UBKlMEga8Ffjd40XiMFUigOS/wdLjzzOB/AB4xTBwOr6Xmo5vgaQwld1prpwOIWOXhO4s8oGpDBDinZ9aBKWv34JxWqgzyioj5af4X2YtFsjkLqe7SKseyj56ykq6DI/h0BaFTJD1F464cySBI9SqjIEF8hvHD3njYPvsx/tWiAurem" Dec 17 22:32:02 retrostream volumio[1314]: info: MPD running with PID1536 Dec 17 22:32:02 retrostream volumio[1314]: ,establishing connection Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=debug msg="completed keyexchange" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=debug msg="completed challenge" Dec 17 22:32:02 retrostream systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Dec 17 22:32:02 retrostream sudo[1731]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=info msg="authenticated AP" username="vi********ar" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=info msg="authenticated Login5" username="vi********ar" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=debug msg="initializing zeroconf session" username="vi********ar" Dec 17 22:32:02 retrostream startx[1758]: X.Org X Server 1.21.1.7 Dec 17 22:32:02 retrostream startx[1758]: X Protocol Version 11, Revision 0 Dec 17 22:32:02 retrostream startx[1758]: Current Operating System: Linux retrostream 6.12.47-v7l+ #1904 SMP Mon Sep 15 13:08:10 BST 2025 armv7l Dec 17 22:32:02 retrostream startx[1758]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 numa=fake=2 system_heap.max_order=0 smsc95xx.macaddr=DC:A6:32:31:EC:8C 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=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 imgfile=/volumio_current.sqsh bootpart=UUID=3B89-0B23 datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 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 17 22:32:02 retrostream startx[1758]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Dec 17 22:32:02 retrostream startx[1758]: Current version of pixman: 0.44.0 Dec 17 22:32:02 retrostream startx[1758]: Before reporting problems, check http://wiki.x.org Dec 17 22:32:02 retrostream startx[1758]: to make sure that you have the latest version. Dec 17 22:32:02 retrostream startx[1758]: Markers: (--) probed, (**) from config file, (==) default setting, Dec 17 22:32:02 retrostream startx[1758]: (++) from command line, (!!) notice, (II) informational, Dec 17 22:32:02 retrostream startx[1758]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Dec 17 22:32:02 retrostream startx[1758]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Dec 17 22:32:02 2025 Dec 17 22:32:02 retrostream startx[1758]: (==) Using config directory: "/etc/X11/xorg.conf.d" Dec 17 22:32:02 retrostream startx[1758]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=debug msg="dealer connection opened" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=trace msg="starting accesspoint recv loop" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=trace msg="starting dealer recv loop" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=trace msg="received accesspoint ping" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=debug msg="received connection id: MzgyNTkxZDAtN2M3...Mjc1MERFMTY1MA==" Dec 17 22:32:02 retrostream go-librespot[1670]: time="2025-12-17T22:32:02Z" level=trace msg="received accesspoint pong ack" Dec 17 22:32:03 retrostream go-librespot[1670]: time="2025-12-17T22:32:03Z" level=debug msg="put connect state because NEW_DEVICE" Dec 17 22:32:03 retrostream volumio[1314]: error: MPD error: The expression evaluated to a falsy value: Dec 17 22:32:03 retrostream volumio[1314]: assert.ok(self.idling) Dec 17 22:32:03 retrostream volumio[1314]: error: The expression evaluated to a falsy value: Dec 17 22:32:03 retrostream volumio[1314]: assert.ok(self.idling) Dec 17 22:32:03 retrostream volumio[1314]: error: MPD error: The expression evaluated to a falsy value: Dec 17 22:32:03 retrostream volumio[1314]: assert.ok(self.idling) Dec 17 22:32:03 retrostream volumio[1314]: error: The expression evaluated to a falsy value: Dec 17 22:32:03 retrostream volumio[1314]: assert.ok(self.idling) Dec 17 22:32:03 retrostream volumio[1314]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Dec 17 22:32:03 retrostream volumio[1314]: info: touch_display: Volumio Kiosk started. Dec 17 22:32:03 retrostream volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 17 22:32:03 retrostream volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 17 22:32:03 retrostream volumio[1314]: info: Completed starting Core Plugins Dec 17 22:32:03 retrostream volumio[1314]: info: ------------------------------------------- Dec 17 22:32:03 retrostream volumio[1314]: info: ----- MyVolumio plugins startup ---- Dec 17 22:32:03 retrostream volumio[1314]: info: ------------------------------------------- Dec 17 22:32:03 retrostream volumio[1314]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 17 22:32:03 retrostream volumio[1314]: error: updateQueue error: null Dec 17 22:32:03 retrostream volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 17 22:32:03 retrostream volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 17 22:32:03 retrostream volumio[1314]: info: touch_display: X display number found: 0 Dec 17 22:32:03 retrostream volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 17 22:32:03 retrostream volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 17 22:32:03 retrostream volumio[1314]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 17 22:32:03 retrostream volumio[1314]: SPOTIFY: User informations: {"country":"IS","display_name":"viddispottar","email":"viddi69@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/viddispottar"},"followers":{"href":null,"total":2},"href":"https://api.spotify.com/v1/users/viddispottar","id":"viddispottar","images":[],"product":"premium","type":"user","uri":"spotify:user:viddispottar"} Dec 17 22:32:03 retrostream volumio[1314]: info: Spotify Successfully logged in Dec 17 22:32:03 retrostream volumio[1314]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 22:32:03 retrostream volumio[1314]: info: [1766010723950] CoreMusicLibrary::Adding element Spotify Dec 17 22:32:03 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 22:32:03 retrostream volumio[1314]: Cannot find translation for source Spotify Dec 17 22:32:04 retrostream volumio[1314]: info: touch_display: X display number found: 0 Dec 17 22:32:04 retrostream volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 17 22:32:04 retrostream volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 17 22:32:04 retrostream systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 17 22:32:04 retrostream systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 17 22:32:04 retrostream volumio[1314]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 17 22:32:04 retrostream systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 17 22:32:04 retrostream sudo[1583]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:04 retrostream volumio[1314]: info: Upmpdcli Daemon Started Dec 17 22:32:04 retrostream volumio[1314]: info: go-librespot daemon successfully initialized Dec 17 22:32:05 retrostream sudo[1865]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 17 22:32:05 retrostream sudo[1865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:05 retrostream sudo[1869]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 17 22:32:05 retrostream sudo[1869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:05 retrostream systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 17 22:32:05 retrostream sudo[1865]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:05 retrostream mpd_monitor.sh[1867]: MPD Monitor Service: Starting MPD Monitor Service Dec 17 22:32:05 retrostream volumio[1314]: info: Successfully started MPD Monitor Dec 17 22:32:05 retrostream systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 17 22:32:05 retrostream systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 17 22:32:05 retrostream systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 17 22:32:05 retrostream systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 17 22:32:05 retrostream mpd_monitor.sh[1873]: MPD Monitor Service: Starting MPD Monitor Service Dec 17 22:32:05 retrostream sudo[1869]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:05 retrostream volumio[1314]: info: Successfully started MPD Monitor Dec 17 22:32:07 retrostream volumio[1314]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 17 22:32:07 retrostream volumio[1314]: info: Initializing connection to go-librespot Websocket Dec 17 22:32:08 retrostream go-librespot[1670]: time="2025-12-17T22:32:08Z" level=debug msg="new websocket client" Dec 17 22:32:08 retrostream volumio[1314]: info: Connection to go-librespot Websocket established Dec 17 22:32:11 retrostream volumio[1314]: info: Getting Spotify volume Dec 17 22:32:11 retrostream volumio[1314]: info: Spotify volume: 100 Dec 17 22:32:11 retrostream volumio[1314]: 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 17 22:32:11 retrostream volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 17 22:32:11 retrostream volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 17 22:32:12 retrostream volumio[1314]: info: Adding plugin bluetooth to MyMusic Plugins Dec 17 22:32:12 retrostream volumio[1314]: info: Adding plugin multiroom to MyMusic Plugins Dec 17 22:32:12 retrostream volumio[1314]: info: Adding plugin metavolumio to MyMusic Plugins Dec 17 22:32:12 retrostream volumio[1314]: info: Adding plugin cd_controller to MyMusic Plugins Dec 17 22:32:12 retrostream volumio[1314]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 17 22:32:12 retrostream volumio[1314]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 17 22:32:12 retrostream volumio[1314]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 17 22:32:12 retrostream volumio[1314]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 17 22:32:15 retrostream volumio[1314]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 17 22:32:15 retrostream volumio[1314]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 17 22:32:15 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:15 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:15 retrostream volumio[1314]: info: Starting MyVolumio Remote Streaming Endpoints Dec 17 22:32:16 retrostream volumio[1314]: info: MyVolumio login type: Token Dec 17 22:32:16 retrostream volumio[1314]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 17 22:32:16 retrostream volumio[1314]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 17 22:32:17 retrostream upmpdcli[2010]: writing RSA key Dec 17 22:32:21 retrostream volumio[1314]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 17 22:32:21 retrostream volumio[1314]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 17 22:32:21 retrostream volumio[1314]: info: Streaming services startup Dec 17 22:32:21 retrostream volumio[1314]: info: Starting Streaming Daemon Dec 17 22:32:21 retrostream sudo[2015]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 17 22:32:21 retrostream sudo[2015]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:21 retrostream volumio[1314]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 17 22:32:21 retrostream sudo[2015]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:21 retrostream volumio[1314]: error: Cannot start Volumio Streaming Daemon Dec 17 22:32:21 retrostream volumio[1314]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 17 22:32:21 retrostream volumio[1314]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 17 22:32:22 retrostream volumio[1314]: error: MyVolumio Custom Token format not valid, refreshing it Dec 17 22:32:23 retrostream volumio[1314]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 17 22:32:24 retrostream volumio[1314]: info: MyVolumio login type: Token Dec 17 22:32:25 retrostream volumio[1314]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 17 22:32:25 retrostream volumio[1314]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 17 22:32:26 retrostream volumio[1314]: info: MyVolumio token set successfully Dec 17 22:32:26 retrostream volumio[1314]: info: MYVOLUMIO: Adding device Dec 17 22:32:26 retrostream volumio[1314]: info: MYVOLUMIO: Evaluating Server Dec 17 22:32:27 retrostream volumio[1314]: info: MyVolumio status changed Dec 17 22:32:27 retrostream volumio[1314]: info: Streaming services startup Dec 17 22:32:27 retrostream volumio[1314]: info: Starting Streaming Daemon Dec 17 22:32:27 retrostream volumio[1314]: info: Removing browser output: myVolumio user plan is not superstar Dec 17 22:32:27 retrostream volumio[1314]: info: Removing audio output: Dec 17 22:32:27 retrostream volumio[1314]: info: Stoppping Tunnel 1 Dec 17 22:32:27 retrostream sudo[2067]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 17 22:32:27 retrostream sudo[2067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:27 retrostream sudo[2065]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 17 22:32:27 retrostream sudo[2065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:27 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 17 22:32:27 retrostream 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 17 22:32:27 retrostream 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 17 22:32:27 retrostream 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 17 22:32:27 retrostream 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 17 22:32:27 retrostream 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 17 22:32:27 retrostream 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 17 22:32:27 retrostream 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 17 22:32:27 retrostream 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 17 22:32:27 retrostream sudo[2067]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:27 retrostream sudo[2065]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 17 22:32:28 retrostream volumio-remote-updater[818]: Test mode disabled Dec 17 22:32:28 retrostream volumio-remote-updater[818]: Alpha mode disabled Dec 17 22:32:28 retrostream volumio-remote-updater[818]: Alpha legacy test mode disabled Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::volumioGetVisibleSources Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 17 22:32:28 retrostream volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 17 22:32:28 retrostream volumio[1314]: info: Received Get System Info Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 22:32:28 retrostream volumio[1314]: info: Discovery: Getting this device information Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 17 22:32:28 retrostream volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 17 22:32:28 retrostream volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 17 22:32:28 retrostream volumio[1314]: info: Listing playlists Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 17 22:32:28 retrostream volumio[1314]: info: Received Get System Info Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 22:32:28 retrostream volumio[1314]: info: Discovery: Getting this device information Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 17 22:32:28 retrostream volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 22:32:28 retrostream volumio[1314]: info: Setting Geolocation for MyVolumio to eu2 Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:28 retrostream volumio[1314]: error: Cannot start Volumio Streaming Daemon Dec 17 22:32:28 retrostream volumio[1314]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 17 22:32:28 retrostream volumio[1314]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 17 22:32:28 retrostream volumio[1314]: info: Remote SSH Stopped Dec 17 22:32:28 retrostream volumio[1314]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 17 22:32:28 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 17 22:32:28 retrostream volumio[1314]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 17 22:32:29 retrostream volumio[1314]: info: Updating MyVolumio device info Dec 17 22:32:29 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:29 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:29 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:29 retrostream volumio[1314]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 17 22:32:29 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 17 22:32:29 retrostream volumio[1314]: info: Received Get System Info Dec 17 22:32:29 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 22:32:29 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 22:32:29 retrostream volumio[1314]: info: Discovery: Getting this device information Dec 17 22:32:29 retrostream volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 17 22:32:29 retrostream volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 17 22:32:29 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 22:32:29 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 17 22:32:31 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 17 22:32:31 retrostream volumio[1314]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 17 22:32:31 retrostream volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 17 22:32:31 retrostream volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 17 22:32:32 retrostream volumio[1314]: info: MYVOLUMIO: Adding device Dec 17 22:32:32 retrostream volumio[1314]: info: MYVOLUMIO: Evaluating Server Dec 17 22:32:32 retrostream go-librespot[1670]: time="2025-12-17T22:32:32Z" level=trace msg="sent dealer ping" Dec 17 22:32:32 retrostream go-librespot[1670]: time="2025-12-17T22:32:32Z" level=trace msg="received dealer pong" Dec 17 22:32:33 retrostream volumio[1314]: info: Setting Geolocation for MyVolumio to eu2 Dec 17 22:32:33 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:33 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:33 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:33 retrostream volumio[1314]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 17 22:32:34 retrostream volumio[1314]: info: Updating MyVolumio device info Dec 17 22:32:34 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:34 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:34 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:34 retrostream volumio[1314]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 17 22:32:35 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 17 22:32:35 retrostream volumio[1314]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 22:32:35 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 17 22:32:36 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:32:36 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:32:36 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 17 22:32:36 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 17 22:32:36 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 17 22:32:36 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 17 22:32:36 retrostream volumio[1314]: info: CoreCommandRouter::volumioGetBrowseSources Dec 17 22:32:36 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 17 22:32:36 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 17 22:32:36 retrostream sudo[2114]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 17 22:32:36 retrostream sudo[2118]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 17 22:32:36 retrostream sudo[2125]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 17 22:32:36 retrostream sudo[2114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:36 retrostream sudo[2118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:36 retrostream sudo[2125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:36 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 17 22:32:36 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 17 22:32:36 retrostream sudo[2139]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 22:32:36 retrostream sudo[2139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:36 retrostream sudo[2137]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 22:32:36 retrostream sudo[2137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:36 retrostream sudo[2139]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:36 retrostream sudo[2118]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:36 retrostream sudo[2137]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:36 retrostream sudo[2114]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:36 retrostream sudo[2142]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 17 22:32:36 retrostream sudo[2142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:36 retrostream sudo[2125]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:36 retrostream sudo[2129]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 17 22:32:36 retrostream sudo[2129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:32:37 retrostream sudo[2129]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:40 retrostream sudo[2142]: pam_unix(sudo:session): session closed for user root Dec 17 22:32:41 retrostream volumio[1314]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 17 22:32:41 retrostream volumio[1314]: info: Completed starting MyVolumio Plugin Dec 17 22:32:41 retrostream volumio[1314]: [Metrics] CommandRouter: 60s 792.61ms Dec 17 22:32:41 retrostream volumio[1314]: info: CoreCommandRouter::volumiosetStartupVolume Dec 17 22:32:41 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:32:41 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:32:41 retrostream volumio[1314]: info: CoreCommandRouter::Close All Modals sent Dec 17 22:32:41 retrostream volumio[1314]: info: CoreCommandRouter::Close All Modals sent Dec 17 22:32:41 retrostream volumio[1314]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Dec 17 22:32:41 retrostream volumio[1314]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for IQaudIODAC Dec 17 22:32:41 retrostream volumio[1314]: aplay: main:831: audio open error: No such device Dec 17 22:32:42 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 17 22:32:42 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 22:32:42 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 17 22:32:43 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 17 22:32:43 retrostream volumio[1314]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 17 22:32:43 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 17 22:32:43 retrostream volumio[1314]: info: Received Get System Version Dec 17 22:32:43 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 17 22:32:43 retrostream volumio[1314]: info: Received Get System Info Dec 17 22:32:43 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 22:32:43 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 22:32:43 retrostream volumio[1314]: info: Discovery: Getting this device information Dec 17 22:32:43 retrostream volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 17 22:32:43 retrostream volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 17 22:32:43 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 22:32:48 retrostream volumio[1314]: info: BOOT COMPLETED Dec 17 22:32:55 retrostream volumio[1314]: info: CoreCommandRouter::volumioSeek Dec 17 22:32:55 retrostream volumio[1314]: info: CoreStateMachine::seek Dec 17 22:32:55 retrostream volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 17 22:32:59 retrostream volumio[1314]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Dec 17 22:32:59 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Dec 17 22:33:00 retrostream sudo[2183]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Dec 17 22:33:00 retrostream sudo[2183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:00 retrostream sudo[2183]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:00 retrostream volumio[1314]: info: Permissions for /etc/hostapd/hostapd.conf Dec 17 22:33:00 retrostream volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 17 22:33:00 retrostream volumio[1314]: info: Discovery: Restarting Advertising due to device name change Dec 17 22:33:00 retrostream sudo[2186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 17 22:33:00 retrostream sudo[2186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:01 retrostream systemd[1]: Stopping wireless.service - Wireless Services... Dec 17 22:33:01 retrostream systemd[1]: wireless.service: Killing process 1224 (wpa_supplicant) with signal SIGKILL. Dec 17 22:33:01 retrostream systemd[1]: wireless.service: Deactivated successfully. Dec 17 22:33:01 retrostream systemd[1]: Stopped wireless.service - Wireless Services. Dec 17 22:33:01 retrostream systemd[1]: wireless.service: Consumed 3min 2.353s CPU time. Dec 17 22:33:01 retrostream systemd[1]: Starting wireless.service - Wireless Services... Dec 17 22:33:01 retrostream volumio[1314]: info: Discovery: A device disappeared from network Dec 17 22:33:01 retrostream volumio[1314]: info: Discovery: Device retrostream disappeared from network Dec 17 22:33:01 retrostream volumio[1314]: info: Discovery: A device disappeared from network Dec 17 22:33:02 retrostream wireless.js[2190]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 17 22:33:02 retrostream wireless.js[2190]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 17 22:33:02 retrostream wireless.js[2190]: WIRELESS.JS: Cleaning previous... Dec 17 22:33:02 retrostream sudo[2208]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 17 22:33:02 retrostream sudo[2208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 17 22:33:02 retrostream avahi-daemon[1313]: Withdrawing address record for 192.168.1.98 on wlan0. Dec 17 22:33:02 retrostream avahi-daemon[1313]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.98. Dec 17 22:33:02 retrostream dhcpcd[882]: wlan0: pid 2209 deleted IP address 192.168.1.98/24 Dec 17 22:33:02 retrostream dhcpcd[882]: wlan0: deleting route to 192.168.1.0/24 Dec 17 22:33:02 retrostream dhcpcd[882]: wlan0: deleting default route via 192.168.1.1 Dec 17 22:33:02 retrostream avahi-daemon[1313]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 17 22:33:02 retrostream sudo[2208]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:02 retrostream dhcpcd[882]: wlan0: rebinding lease of 192.168.1.98 Dec 17 22:33:02 retrostream systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 17 22:33:02 retrostream systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 17 22:33:02 retrostream systemd[1]: welcome.service: Deactivated successfully. Dec 17 22:33:02 retrostream systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 17 22:33:02 retrostream systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 17 22:33:02 retrostream sudo[2213]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 17 22:33:02 retrostream sudo[2213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 17 22:33:02 retrostream systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 17 22:33:02 retrostream go-librespot[1670]: time="2025-12-17T22:33:02Z" level=trace msg="sent dealer ping" Dec 17 22:33:02 retrostream dhcpcd[882]: wlan0: carrier lost Dec 17 22:33:02 retrostream welcome[2223]: Resolved ip:[0] Dec 17 22:33:02 retrostream sudo[2213]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:02 retrostream systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 17 22:33:02 retrostream systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 17 22:33:03 retrostream wireless.js[2190]: WIRELESS.JS: Stopped aP Dec 17 22:33:03 retrostream sudo[2249]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 17 22:33:03 retrostream sudo[2249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:03 retrostream kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 17 22:33:03 retrostream sudo[2249]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:03 retrostream sudo[2251]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 17 22:33:03 retrostream sudo[2251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:03 retrostream sudo[2251]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:03 retrostream sudo[2259]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 17 22:33:03 retrostream sudo[2259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:03 retrostream sudo[2259]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:03 retrostream sudo[2261]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 17 22:33:03 retrostream sudo[2261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:04 retrostream volumio[1314]: info: Volumio Network Manager: Network status updated: 0 Dec 17 22:33:05 retrostream ntpd[1061]: IO: Deleting interface #3 wlan0, 192.168.1.98#123, interface stats: received=54, sent=54, dropped=0, active_time=88 secs Dec 17 22:33:05 retrostream ntpd[1061]: PROTO: 185.93.156.160 unlink local addr 192.168.1.98 -> Dec 17 22:33:05 retrostream ntpd[1061]: PROTO: 195.246.230.126 unlink local addr 192.168.1.98 -> Dec 17 22:33:05 retrostream ntpd[1061]: PROTO: 89.17.158.36 unlink local addr 192.168.1.98 -> Dec 17 22:33:05 retrostream ntpd[1061]: PROTO: 93.95.229.195 unlink local addr 192.168.1.98 -> Dec 17 22:33:05 retrostream ntpd[1061]: PROTO: 162.159.200.1 unlink local addr 192.168.1.98 -> Dec 17 22:33:05 retrostream ntpd[1061]: PROTO: 162.159.200.123 unlink local addr 192.168.1.98 -> Dec 17 22:33:05 retrostream volumio[1314]: info: Discovery: Started advertising with name: RetroStream Dec 17 22:33:06 retrostream sudo[2261]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:06 retrostream wireless.js[2190]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: IS Dec 17 22:33:06 retrostream sudo[2285]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 17 22:33:06 retrostream sudo[2285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:06 retrostream sudo[2285]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:06 retrostream sudo[2287]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set IS Dec 17 22:33:06 retrostream sudo[2287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:06 retrostream sudo[2287]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:06 retrostream wireless.js[2190]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: IS Dec 17 22:33:06 retrostream wireless.js[2190]: WIRELESS.JS: Start wireless flow Dec 17 22:33:06 retrostream wireless.js[2190]: WIRELESS.JS: Stopped hotspot (if there).. Dec 17 22:33:06 retrostream sudo[2295]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 17 22:33:06 retrostream sudo[2295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 17 22:33:06 retrostream sudo[2295]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:06 retrostream sudo[2297]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 17 22:33:06 retrostream sudo[2297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 17 22:33:06 retrostream volumio[1314]: info: Discovery: adding 8ce8aa29-c44f-48b5-9e62-ae96b60b2516 Dec 17 22:33:06 retrostream volumio[1314]: info: Discovery: Found device RetroStream Dec 17 22:33:06 retrostream volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 17 22:33:06 retrostream volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 17 22:33:06 retrostream sudo[2297]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:07 retrostream wireless.js[2190]: WIRELESS.JS: DHCP IP fallback Dec 17 22:33:07 retrostream wireless.js[2190]: WIRELESS.JS: Start ap Dec 17 22:33:07 retrostream wpa_supplicant[2303]: Successfully initialized wpa_supplicant Dec 17 22:33:07 retrostream kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 17 22:33:07 retrostream sudo[2304]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Dec 17 22:33:07 retrostream sudo[2304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 17 22:33:07 retrostream dhcpcd[882]: ps_ctl_dispatch: cannot handle another client Dec 17 22:33:07 retrostream dhcpcd[882]: control_free: No such file or directory Dec 17 22:33:07 retrostream sudo[2304]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:07 retrostream wpa_supplicant[2303]: nl80211: kernel reports: Registration to specific type not supported Dec 17 22:33:08 retrostream wireless.js[2190]: WIRELESS.JS: trying... Dec 17 22:33:08 retrostream sudo[2311]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 17 22:33:08 retrostream sudo[2311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:08 retrostream sudo[2311]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:09 retrostream wireless.js[2190]: WIRELESS.JS: trying... Dec 17 22:33:09 retrostream sudo[2315]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 17 22:33:09 retrostream sudo[2315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:09 retrostream sudo[2315]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:09 retrostream wpa_supplicant[2308]: wlan0: Trying to associate with c4:69:f0:dc:00:40 (SSID='Vodafone_YGKG2' freq=5180 MHz) Dec 17 22:33:09 retrostream wpa_supplicant[2308]: wlan0: Associated with c4:69:f0:dc:00:40 Dec 17 22:33:09 retrostream wpa_supplicant[2308]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 17 22:33:09 retrostream wpa_supplicant[2308]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=IS Dec 17 22:33:09 retrostream wpa_supplicant[2308]: wlan0: WPA: Key negotiation completed with c4:69:f0:dc:00:40 [PTK=CCMP GTK=CCMP] Dec 17 22:33:09 retrostream wpa_supplicant[2308]: wlan0: CTRL-EVENT-CONNECTED - Connection to c4:69:f0:dc:00:40 completed [id=0 id_str=] Dec 17 22:33:09 retrostream dhcpcd[882]: wlan0: carrier acquired Dec 17 22:33:09 retrostream dhcpcd[882]: wlan0: IAID 32:31:ec:8d Dec 17 22:33:10 retrostream wireless.js[2190]: WIRELESS.JS: trying... Dec 17 22:33:10 retrostream sudo[2320]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 17 22:33:10 retrostream sudo[2320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:10 retrostream dhcpcd[882]: wlan0: soliciting an IPv6 router Dec 17 22:33:10 retrostream sudo[2320]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:10 retrostream dhcpcd[882]: wlan0: rebinding lease of 192.168.1.98 Dec 17 22:33:10 retrostream dhcpcd[882]: wlan0: probing address 192.168.1.98/24 Dec 17 22:33:11 retrostream wireless.js[2190]: WIRELESS.JS: trying... Dec 17 22:33:11 retrostream sudo[2326]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 17 22:33:11 retrostream sudo[2326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:11 retrostream sudo[2326]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:12 retrostream wireless.js[2190]: WIRELESS.JS: trying... Dec 17 22:33:12 retrostream sudo[2330]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 17 22:33:12 retrostream sudo[2330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:12 retrostream sudo[2330]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:13 retrostream wireless.js[2190]: WIRELESS.JS: trying... Dec 17 22:33:13 retrostream sudo[2334]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 17 22:33:13 retrostream sudo[2334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:13 retrostream sudo[2334]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:14 retrostream wireless.js[2190]: WIRELESS.JS: trying... Dec 17 22:33:14 retrostream sudo[2338]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 17 22:33:14 retrostream sudo[2338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:14 retrostream sudo[2338]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:15 retrostream wireless.js[2190]: WIRELESS.JS: trying... Dec 17 22:33:15 retrostream sudo[2356]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 17 22:33:15 retrostream sudo[2356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:15 retrostream sudo[2356]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:16 retrostream wireless.js[2190]: WIRELESS.JS: trying... Dec 17 22:33:16 retrostream sudo[2360]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 17 22:33:16 retrostream sudo[2360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:16 retrostream sudo[2360]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:16 retrostream dhcpcd[882]: wlan0: leased 192.168.1.98 for 86400 seconds Dec 17 22:33:16 retrostream avahi-daemon[1313]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.98. Dec 17 22:33:16 retrostream avahi-daemon[1313]: New relevant interface wlan0.IPv4 for mDNS. Dec 17 22:33:16 retrostream avahi-daemon[1313]: Registering new address record for 192.168.1.98 on wlan0.IPv4. Dec 17 22:33:16 retrostream dhcpcd[882]: wlan0: adding route to 192.168.1.0/24 Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1356" Dec 17 22:33:16 retrostream systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 17 22:33:16 retrostream systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 17 22:33:16 retrostream systemd[1]: welcome.service: Deactivated successfully. Dec 17 22:33:16 retrostream systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 17 22:33:16 retrostream systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.1.98:39502->104.199.65.9:4070: read: connection reset by peer" Dec 17 22:33:16 retrostream dhcpcd[882]: wlan0: adding default route via 192.168.1.1 Dec 17 22:33:16 retrostream systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=debug msg="handling transfer player command from 85620f0c4bdc3191512c82cfe895453834cca8d0" Dec 17 22:33:16 retrostream welcome[2375]: Resolved ip:[1] 192.168.1.98 Dec 17 22:33:16 retrostream volumio[1314]: compat.c: read() failed: Resource temporarily unavailable Dec 17 22:33:16 retrostream volumio[1314]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 22:33:16 retrostream systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 17 22:33:16 retrostream systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=debug msg="connected to ap-gew1.spotify.com:443" Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=debug msg="resolved context of track" uri="spotify:artist:3vRSdvFnDzuye8bIFWQGFs" Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=trace msg="fetched new page 0 with 10 items (list: 10)" uri="spotify:artist:3vRSdvFnDzuye8bIFWQGFs" Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=trace msg="fetched new page 1 with 23 items (list: 33)" uri="spotify:artist:3vRSdvFnDzuye8bIFWQGFs" Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=debug msg="loading track (paused: false, position: 3967605ms)" uri="spotify:track:6cPx1JzASudNLAaZBmRn0k" Dec 17 22:33:16 retrostream volumio[1314]: Error: dns service error: unknown Dec 17 22:33:16 retrostream volumio[1314]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 17 22:33:16 retrostream volumio[1314]: errorCode: -65537 Dec 17 22:33:16 retrostream volumio[1314]: } Dec 17 22:33:16 retrostream volumio[1314]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=debug msg="completed keyexchange" Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=debug msg="completed challenge" Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=info msg="authenticated AP" username="vi********ar" Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=debug msg="re-established accesspoint connection" Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=trace msg="received accesspoint ping" Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=trace msg="emitting websocket event: will_play" Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=debug msg="selected format OGG_VORBIS_320 (4b1250754bbc5de24119145b79c4e502a6dcce7b)" uri="spotify:track:6cPx1JzASudNLAaZBmRn0k" Dec 17 22:33:16 retrostream go-librespot[1670]: time="2025-12-17T22:33:16Z" level=debug msg="requested aes key for file 4b1250754bbc5de24119145b79c4e502a6dcce7b, gid: 6cPx1JzASudNLAaZBmRn0k" Dec 17 22:33:17 retrostream wireless.js[2190]: WIRELESS.JS: trying... Dec 17 22:33:17 retrostream sudo[2403]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 17 22:33:17 retrostream sudo[2403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:33:17 retrostream sudo[2403]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:17 retrostream wireless.js[2190]: WIRELESS.JS: Connected to: ----Vodafone_YGKG2---- Dec 17 22:33:17 retrostream sudo[2406]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 22:33:17 retrostream sudo[2406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 17 22:33:17 retrostream sudo[2406]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:17 retrostream wireless.js[2190]: WIRELESS.JS: ... joined AP, wlan0 IPv4 is 192.168.1.98, ipV6 is undefined Dec 17 22:33:17 retrostream wireless.js[2190]: WIRELESS.JS: It's done! AP Dec 17 22:33:17 retrostream wireless.js[2190]: WIRELESS.JS: Restarting avahi-daemon... Dec 17 22:33:17 retrostream systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 17 22:33:17 retrostream systemd[1]: shairport-sync.service: Deactivated successfully. Dec 17 22:33:17 retrostream systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 22:33:17 retrostream systemd[1]: shairport-sync.service: Consumed 1.599s CPU time. Dec 17 22:33:17 retrostream avahi-daemon[1313]: Got SIGTERM, quitting. Dec 17 22:33:17 retrostream systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Dec 17 22:33:17 retrostream avahi-daemon[1313]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.98. Dec 17 22:33:17 retrostream avahi-daemon[1313]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Dec 17 22:33:17 retrostream avahi-daemon[1313]: avahi-daemon 0.8 exiting. Dec 17 22:33:17 retrostream systemd[1]: avahi-daemon.service: Deactivated successfully. Dec 17 22:33:17 retrostream systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Dec 17 22:33:17 retrostream dbus-daemon[786]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.27' (uid=0 pid=1350 comm="/usr/sbin/smbd --foreground --no-process-group") Dec 17 22:33:17 retrostream systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Dec 17 22:33:17 retrostream avahi-daemon[2412]: Process 1313 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Dec 17 22:33:17 retrostream avahi-daemon[2412]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Dec 17 22:33:17 retrostream avahi-daemon[2412]: Successfully dropped root privileges. Dec 17 22:33:17 retrostream avahi-daemon[2412]: avahi-daemon 0.8 starting up. Dec 17 22:33:17 retrostream wireless.js[2190]: WIRELESS.JS: Notified systemd about wireless ready Dec 17 22:33:17 retrostream dbus-daemon[786]: [system] Successfully activated service 'org.freedesktop.Avahi' Dec 17 22:33:17 retrostream avahi-daemon[2412]: Successfully called chroot(). Dec 17 22:33:17 retrostream avahi-daemon[2412]: Successfully dropped remaining capabilities. Dec 17 22:33:17 retrostream avahi-daemon[2412]: Loading service file /services/volumio.service. Dec 17 22:33:17 retrostream avahi-daemon[2412]: *** WARNING: Detected another IPv4 mDNS stack running on this host. This makes mDNS unreliable and is thus not recommended. *** Dec 17 22:33:17 retrostream systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Dec 17 22:33:17 retrostream avahi-daemon[2412]: *** WARNING: Detected another IPv6 mDNS stack running on this host. This makes mDNS unreliable and is thus not recommended. *** Dec 17 22:33:17 retrostream kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 17 22:33:17 retrostream systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 22:33:17 retrostream systemd[1]: Started wireless.service - Wireless Services. Dec 17 22:33:17 retrostream avahi-daemon[2412]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.98. Dec 17 22:33:17 retrostream avahi-daemon[2412]: New relevant interface wlan0.IPv4 for mDNS. Dec 17 22:33:17 retrostream avahi-daemon[2412]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Dec 17 22:33:17 retrostream avahi-daemon[2412]: New relevant interface lo.IPv4 for mDNS. Dec 17 22:33:17 retrostream avahi-daemon[2412]: Network interface enumeration completed. Dec 17 22:33:17 retrostream avahi-daemon[2412]: Registering new address record for 192.168.1.98 on wlan0.IPv4. Dec 17 22:33:17 retrostream avahi-daemon[2412]: Registering new address record for 127.0.0.1 on lo.IPv4. Dec 17 22:33:17 retrostream sudo[2186]: pam_unix(sudo:session): session closed for user root Dec 17 22:33:18 retrostream ntpd[1061]: IO: Listen normally on 4 wlan0 192.168.1.98:123 Dec 17 22:33:18 retrostream ntpd[1061]: IO: new interface(s) found: waking up resolver Dec 17 22:33:18 retrostream avahi-daemon[2412]: Server startup complete. Host name is retrostream.local. Local service cookie is 1972244348. Dec 17 22:33:19 retrostream avahi-daemon[2412]: Service "RetroStream" (/services/volumio.service) successfully established. Dec 17 22:33:20 retrostream sudo[2433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-17 22:32' Dec 17 22:33:20 retrostream sudo[2433]: 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"