-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Thu 2025-05-22 20:08:28 UTC. -- May 22 20:07:11 den systemd-timedated[1118]: Changed local time to Thu May 22 20:07:11 2025 May 22 20:07:11 den sudo[1114]: pam_unix(sudo:session): session closed for user root May 22 20:07:11 den volumio-time-update[567]: volumio-time-update-util: System time updated successfully. May 22 20:07:11 den volumio[947]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 20:07:11 den systemd[1]: Started Volumio Time Update Utility. May 22 20:07:11 den volumio[947]: info: ___________ START PLUGINS ___________ May 22 20:07:11 den volumio[947]: info: ControllerMpd::onStart: Initializing MPD May 22 20:07:11 den volumio[947]: info: Creating MPD Configuration file May 22 20:07:11 den systemd[1]: Starting Daily apt download activities... May 22 20:07:11 den systemd[1]: Starting Daily man-db regeneration... May 22 20:07:11 den systemd[1]: Stopping Music Player Daemon... May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 20:07:11 den volumio[947]: info: [1747944431047] CoreMusicLibrary::Adding element Media Servers May 22 20:07:11 den sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 20:07:11 den sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:11 den sudo[1134]: pam_unix(sudo:session): session closed for user root May 22 20:07:11 den sudo[1136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 20:07:11 den sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:11 den volumio[947]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:11 den volumio[947]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 20:07:11 den volumio[947]: info: [1747944431151] CoreMusicLibrary::Adding element Last_100 May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 20:07:11 den volumio[947]: info: [1747944431153] CoreMusicLibrary::Adding element Webradio May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 20:07:11 den volumio[947]: info: Initializing BBC Radios May 22 20:07:11 den systemd[1]: mpd.service: Succeeded. May 22 20:07:11 den systemd[1]: Stopped Music Player Daemon. May 22 20:07:11 den systemd[1]: Starting Music Player Daemon... May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:11 den volumio[947]: info: Checking for old spotify connect plugin installed May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 20:07:11 den volumio[947]: info: [1747944431233] CoreMusicLibrary::Adding element Pandora Radio May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 20:07:11 den volumio[947]: Cannot find translation for source Pandora Radio May 22 20:07:11 den volumio[947]: info: [1747944431236] ControllerPandora::initializeMQTT May 22 20:07:11 den volumio[947]: info: [1747944431237] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration May 22 20:07:11 den volumio[947]: info: Loading i18n strings for locale en May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 20:07:11 den volumio[947]: info: Volumio Calling Home May 22 20:07:11 den sudo[1148]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 20:07:11 den sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:11 den sudo[1148]: pam_unix(sudo:session): session closed for user root May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 20:07:11 den volumio[947]: [SpotifyConnect] Creating VLS config file May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: [SpotifyConnect] Starting metadata listener May 22 20:07:11 den volumio[947]: info: [1747944431352] ControllerPandora::initialSetup May 22 20:07:11 den volumio[947]: info: [1747944431357] ControllerPandora::PandoraHandler::init May 22 20:07:11 den volumio[947]: info: [1747944431357] ControllerPandora::PandoraHandler::loadErrorCodes May 22 20:07:11 den sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service May 22 20:07:11 den sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:11 den nmbd[696]: [2025/05/22 20:07:11.376360, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 22 20:07:11 den nmbd[696]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 22 20:07:11 den volumio[947]: info: [1747944431387] [Pandora] PandoraHandler::setMaxStationTracks: 16 May 22 20:07:11 den volumio[947]: info: [1747944431389] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty May 22 20:07:11 den systemd[1]: Started Samba NMB Daemon. May 22 20:07:11 den volumio[947]: info: [1747944431396] ControllerPandora::flushPandora May 22 20:07:11 den volumio[947]: info: CorePlayQueue::getQueue May 22 20:07:11 den volumio[947]: info: CorePlayQueue::clearAddPlayQueue May 22 20:07:11 den volumio[947]: info: CorePlayQueue::saveQueue May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::serviceClearAddPlayTracks May 22 20:07:11 den volumio[947]: error: WARNING: No clearAddPlayTracks method for service webradio May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::volumioPushQueue May 22 20:07:11 den volumio[947]: info: [1747944431406] ControllerPandora::validateAndSetAccountOptions May 22 20:07:11 den volumio[947]: info: [1747944431407] ControllerPandora::PandoraHandler::setAccountOptions May 22 20:07:11 den sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 22 20:07:11 den volumio[947]: info: [1747944431409] ControllerPandora::flushPandora May 22 20:07:11 den volumio[947]: info: CorePlayQueue::getQueue May 22 20:07:11 den volumio[947]: info: CorePlayQueue::clearAddPlayQueue May 22 20:07:11 den volumio[947]: info: CorePlayQueue::saveQueue May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::serviceClearAddPlayTracks May 22 20:07:11 den volumio[947]: error: WARNING: No clearAddPlayTracks method for service webradio May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::volumioPushQueue May 22 20:07:11 den volumio[947]: info: [1747944431423] [Pandora] PreventAuthTimeout::init: Timer loaded May 22 20:07:11 den volumio[947]: info: [1747944431424] [Pandora] PreventAuthTimeout::init: Interval set to 10800000 ms May 22 20:07:11 den volumio[947]: info: [1747944431424] [Pandora] PreventAuthTimeout::init: Starting delayed timer after next interval May 22 20:07:11 den volumio[947]: info: [1747944431425] ControllerPandora::PandoraHandler::pandoraLoginAndGetStations May 22 20:07:11 den systemd[1]: Starting Samba Winbind Daemon... May 22 20:07:11 den sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:11 den systemd[1]: man-db.service: Succeeded. May 22 20:07:11 den systemd[1]: Started Daily man-db regeneration. May 22 20:07:11 den systemd[1]: Reloading. May 22 20:07:11 den volumio[947]: info: Discovery: adding 8e3a9a33-d673-4e20-ad8c-07624b5cf6d6 May 22 20:07:11 den volumio[947]: info: Discovery: Found device Den May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:11 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:11 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:11 den volumio[947]: info: MPD Permissions set May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 22 20:07:11 den volumio[947]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 22 20:07:11 den volumio[947]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:11 den volumio[947]: info: Discovery: adding 2597a6ae-8bec-4c12-9e57-7c793133a029 May 22 20:07:11 den volumio[947]: info: Discovery: Found device porch May 22 20:07:11 den volumio[947]: info: Discovery: Connecting to remote: 192.168.1.125 May 22 20:07:11 den volumio[947]: info: touch_display: No Raspberry Pi Foundation touch screen detected. May 22 20:07:11 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:11 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:11 den volumio[947]: info: Starting Shairport Sync May 22 20:07:11 den volumio[947]: info: Starting Shairport Sync May 22 20:07:11 den winbindd[1180]: [2025/05/22 20:07:11.842702, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 22 20:07:11 den winbindd[1180]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 22 20:07:11 den volumio[947]: info: Starting Shairport Sync May 22 20:07:11 den sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 20:07:11 den sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:11 den sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 20:07:11 den sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:11 den sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 20:07:11 den sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:11 den volumio[947]: info: Discovery: Connected to remote: 192.168.1.125 May 22 20:07:11 den winbindd[1180]: [2025/05/22 20:07:11.911023, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 22 20:07:11 den winbindd[1180]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 22 20:07:11 den 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. May 22 20:07:11 den volumio[947]: info: Discovery: A device disappeared from network May 22 20:07:11 den volumio[947]: info: Discovery: Device porch disappeared from network May 22 20:07:11 den volumio[947]: info: Discovery: Disconnected from remote: 192.168.1.125 May 22 20:07:11 den volumio[947]: info: Volumio called home May 22 20:07:11 den volumio[947]: info: Discovery: A device disappeared from network May 22 20:07:11 den volumio[947]: info: Discovery: A device disappeared from network May 22 20:07:11 den volumio[947]: info: Discovery: Device den disappeared from network May 22 20:07:12 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:12 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:12 den systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. May 22 20:07:12 den volumio[947]: info: Discovery: adding 8e3a9a33-d673-4e20-ad8c-07624b5cf6d6 May 22 20:07:12 den volumio[947]: info: Discovery: Found device Den May 22 20:07:12 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:12 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:12 den systemd[1]: Started Samba Winbind Daemon. May 22 20:07:12 den sudo[1179]: pam_unix(sudo:session): session closed for user root May 22 20:07:12 den volumio[947]: info: touch_display: systemctl daemon-reload succeeded. May 22 20:07:12 den sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 22 20:07:12 den sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:12 den systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 20:07:12 den systemd[1]: Started Volspotconnect2 Daemon. May 22 20:07:12 den sudo[1172]: pam_unix(sudo:session): session closed for user root May 22 20:07:12 den systemd[1]: Starting Samba SMB Daemon... May 22 20:07:12 den mpd[1167]: May 22 20:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 20:07:12 den systemd[1]: shairport-sync.service: Succeeded. May 22 20:07:12 den volumio[947]: [SpotifyConnect] Vollibrespot Daemon service started! May 22 20:07:12 den volumio[947]: [Metrics] SpotifyConnect: 1s 11.23ms May 22 20:07:12 den systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 20:07:12 den systemd[1]: Started Music Player Daemon. May 22 20:07:12 den sudo[1125]: pam_unix(sudo:session): session closed for user root May 22 20:07:12 den sudo[1136]: pam_unix(sudo:session): session closed for user root May 22 20:07:12 den systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 20:07:12 den sudo[1213]: pam_unix(sudo:session): session closed for user root May 22 20:07:12 den sudo[1218]: pam_unix(sudo:session): session closed for user root May 22 20:07:12 den sudo[1215]: pam_unix(sudo:session): session closed for user root May 22 20:07:12 den systemd[1]: Started Volumio Kiosk. May 22 20:07:12 den sudo[1226]: pam_unix(sudo:session): session closed for user root May 22 20:07:12 den volumio[947]: error: MPD error: The expression evaluated to a falsy value: May 22 20:07:12 den volumio[947]: assert.ok(self.idling) May 22 20:07:12 den volumio[947]: error: The expression evaluated to a falsy value: May 22 20:07:12 den volumio[947]: assert.ok(self.idling) May 22 20:07:12 den volumio[947]: info: Shairport-Sync Started May 22 20:07:12 den volumio[947]: Error adding Membership: Error: addMembership EINVAL May 22 20:07:12 den volumio[947]: info: MPD running with PID1167 May 22 20:07:12 den volumio[947]: ,establishing connection May 22 20:07:12 den volumio[947]: info: Shairport-Sync Started May 22 20:07:12 den volumio[947]: info: Shairport-Sync Started May 22 20:07:12 den volumio[947]: error: updateQueue error: null May 22 20:07:12 den volumio[947]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 22 20:07:12 den volumio[947]: info: touch_display: Volumio Kiosk started May 22 20:07:12 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:12 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:12 den volumio[947]: info: [1747944432573] [Pandora] PandoraHandler::pandoraLoginAndGetStations::pandoraLogin: [<=- Logged in to Pandora Servers -=>] May 22 20:07:12 den volumio[1228]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 22 20:07:12 den volumio[1228]: Reading Config from "/tmp/volspotify.toml" May 22 20:07:12 den volumio[947]: info: [1747944432605] [Pandora] validateAndSetAccountOptions: ***DEBUG*** self.loggedIn: true May 22 20:07:12 den volumio[947]: info: [1747944432607] [Pandora] ExpireOldTracks::init: Timer loaded May 22 20:07:12 den volumio[947]: info: [1747944432607] [Pandora] ExpireOldTracks::init: Interval set to 300000 ms May 22 20:07:12 den volumio[947]: info: [1747944432607] [Pandora] ExpireOldTracks::init: Starting delayed timer after next interval May 22 20:07:12 den volumio[947]: info: [1747944432608] ControllerPandora::PandoraHandler::fillStationData May 22 20:07:12 den volumio[1228]: [Vollibrespot] : Using Alsa backend with device: volumio May 22 20:07:12 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:12 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:12 den volumio[947]: error: updateQueue error: null May 22 20:07:12 den volumio[947]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X May 22 20:07:12 den volumio[1228]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } May 22 20:07:12 den volumio[947]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 22 20:07:12 den startx[1239]: X.Org X Server 1.20.4 May 22 20:07:12 den startx[1239]: X Protocol Version 11, Revision 0 May 22 20:07:12 den startx[1239]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian May 22 20:07:12 den startx[1239]: Current Operating System: Linux den 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l May 22 20:07:12 den startx[1239]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1824 bcm2708_fb.fbheight=984 bcm2708_fb.fbswap=1 smsc95xx.macaddr=E4:5F:01:AA:6B:DF 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=aad048ad-7eb3-4549-8020-f5742baed86d imgfile=/volumio_current.sqsh bootpart=UUID=5883-B9C7 datapart=UUID=cfbe554b-d5ac-4dfd-b89b-effce104ffff 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 May 22 20:07:12 den startx[1239]: Build Date: 07 February 2023 08:17:48AM May 22 20:07:12 den startx[1239]: xorg-server 2:1.20.4-1+rpt4+deb10u8 (https://www.debian.org/support) May 22 20:07:12 den startx[1239]: Current version of pixman: 0.36.0 May 22 20:07:12 den startx[1239]: Before reporting problems, check http://wiki.x.org May 22 20:07:12 den startx[1239]: to make sure that you have the latest version. May 22 20:07:12 den startx[1239]: Markers: (--) probed, (**) from config file, (==) default setting, May 22 20:07:12 den startx[1239]: (++) from command line, (!!) notice, (II) informational, May 22 20:07:12 den startx[1239]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 22 20:07:12 den startx[1239]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu May 22 20:07:12 2025 May 22 20:07:12 den volumio[1228]: [Vollibrespot] : Connecting to AP "ap-gue1.spotify.com:443" May 22 20:07:12 den startx[1239]: (==) Using config directory: "/etc/X11/xorg.conf.d" May 22 20:07:12 den startx[1239]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 22 20:07:12 den volumio[947]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 22 20:07:12 den smbd[1234]: [2025/05/22 20:07:12.956675, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 22 20:07:12 den systemd[1]: Started Samba SMB Daemon. May 22 20:07:12 den smbd[1234]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 22 20:07:12 den systemd[1]: Reached target Multi-User System. May 22 20:07:12 den systemd[1]: Reached target Graphical Interface. May 22 20:07:13 den systemd[1]: Starting Update UTMP about System Runlevel Changes... May 22 20:07:13 den systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 22 20:07:13 den systemd[1]: Started Update UTMP about System Runlevel Changes. May 22 20:07:13 den volumio[947]: info: Completed starting Core Plugins May 22 20:07:13 den volumio[947]: info: ------------------------------------------- May 22 20:07:13 den volumio[947]: info: ----- MyVolumio plugins startup ---- May 22 20:07:13 den volumio[947]: info: ------------------------------------------- May 22 20:07:13 den volumio[947]: info: [MyVolumio PluginManager] Fetching plans data.... May 22 20:07:13 den volumio[947]: info: Discovery: adding c669c196-b6c5-44a7-ae0e-016ccf2b3730 May 22 20:07:13 den volumio[947]: info: Discovery: Found device Studio May 22 20:07:13 den volumio[947]: info: Discovery: Connecting to remote: 192.168.1.90 May 22 20:07:13 den systemd[1]: apt-daily.service: Succeeded. May 22 20:07:13 den systemd[1]: Started Daily apt download activities. May 22 20:07:13 den volumio[947]: info: Discovery: Connected to remote: 192.168.1.90 May 22 20:07:13 den volumio[947]: info: Discovery: adding 2597a6ae-8bec-4c12-9e57-7c793133a029 May 22 20:07:13 den volumio[947]: info: Discovery: Found device porch May 22 20:07:13 den volumio[947]: info: Discovery: Connecting to remote: 192.168.1.125 May 22 20:07:13 den volumio[947]: info: Discovery: Connected to remote: 192.168.1.125 May 22 20:07:13 den volumio[947]: info: Discovery: this is already registered, c669c196-b6c5-44a7-ae0e-016ccf2b3730 May 22 20:07:13 den volumio[947]: info: Discovery: Found device Studio May 22 20:07:13 den systemd[1]: Starting Daily apt upgrade and clean activities... May 22 20:07:13 den volumio[947]: info: touch_display: Setting screensaver timeout to 120 seconds. May 22 20:07:14 den systemd[1]: apt-daily-upgrade.service: Succeeded. May 22 20:07:14 den systemd[1]: Started Daily apt upgrade and clean activities. May 22 20:07:14 den systemd[1]: Startup finished in 11.775s (kernel) + 43.361s (userspace) = 55.137s. May 22 20:07:16 den volumio[947]: info: Volumio Network Manager: Network status updated: 2 May 22 20:07:16 den volumio[947]: info: Checking Spotify Web API May 22 20:07:16 den sudo[1463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 20:07:16 den sudo[1463]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:16 den sudo[1463]: pam_unix(sudo:session): session closed for user root May 22 20:07:16 den sudo[1465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 20:07:16 den sudo[1465]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:16 den sudo[1465]: pam_unix(sudo:session): session closed for user root May 22 20:07:16 den sudo[1469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 22 20:07:16 den sudo[1469]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:16 den systemd[1]: Started UPnP Renderer front-end to MPD. May 22 20:07:16 den sudo[1469]: pam_unix(sudo:session): session closed for user root May 22 20:07:16 den volumio[947]: info: Upmpdcli Daemon Started May 22 20:07:16 den volumio[1471]: Generating RSA private key, 4096 bit long modulus (2 primes) May 22 20:07:17 den volumio[947]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 22 20:07:19 den volumio[947]: info: Discovery: adding 5e36a771-eb01-42e9-8549-fc399262f1a9 May 22 20:07:19 den volumio[947]: info: Discovery: Found device Bedroom May 22 20:07:19 den volumio[947]: info: Discovery: Connecting to remote: 192.168.1.95 May 22 20:07:20 den volumio[1471]: ...........................................++++ May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 22 20:07:21 den volumio[947]: info: Adding plugin bluetooth to MyMusic Plugins May 22 20:07:21 den volumio[947]: info: Adding plugin multiroom to MyMusic Plugins May 22 20:07:21 den volumio[947]: info: Adding plugin metavolumio to MyMusic Plugins May 22 20:07:21 den volumio[947]: info: Adding plugin cd_controller to MyMusic Plugins May 22 20:07:21 den volumio[947]: info: Adding plugin qobuzconnect to MyMusic Plugins May 22 20:07:21 den volumio[947]: info: Adding plugin smart_inputs to MyMusic Plugins May 22 20:07:21 den volumio[947]: info: Adding plugin tidalconnect to MyMusic Plugins May 22 20:07:21 den volumio[947]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 22 20:07:22 den volumio[1471]: .................++++ May 22 20:07:22 den volumio[1471]: e is 65537 (0x010001) May 22 20:07:22 den volumio[1471]: writing RSA key May 22 20:07:22 den volumio[947]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 22 20:07:22 den volumio[947]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 22 20:07:22 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:22 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:22 den volumio[947]: info: Starting MyVolumio Remote Streaming Endpoints May 22 20:07:22 den volumio[947]: info: MyVolumio login type: Token May 22 20:07:22 den volumio[947]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 22 20:07:22 den volumio[947]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 22 20:07:23 den volumio[947]: info: Starting Streaming Service Transparent Proxy May 22 20:07:23 den volumio[947]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 22 20:07:23 den volumio[947]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 22 20:07:23 den volumio[947]: info: Streaming services startup May 22 20:07:23 den volumio[947]: info: Starting Streaming Daemon May 22 20:07:23 den volumio[947]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 22 20:07:23 den sudo[1611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 22 20:07:23 den sudo[1611]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:23 den volumio[947]: info: Discovery: Connected to remote: 192.168.1.95 May 22 20:07:23 den sudo[1611]: pam_unix(sudo:session): session closed for user root May 22 20:07:23 den volumio[947]: error: Cannot start Volumio Streaming Daemon May 22 20:07:23 den volumio[947]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 22 20:07:23 den volumio[947]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 22 20:07:24 den volumio[947]: error: MyVolumio Custom Token format not valid, refreshing it May 22 20:07:24 den volumio[947]: verbose: New Socket.io Connection to 192.168.1.33:3000 from 192.168.1.95 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 22 20:07:24 den volumio[947]: 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: 9 May 22 20:07:24 den volumio[947]: STREAMING PROXY: Starting server on port 3245 May 22 20:07:24 den volumio[947]: Node JS runtime: 14 May 22 20:07:24 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 22 20:07:24 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:24 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:24 den volumio[947]: info: MyVolumio login type: Token May 22 20:07:25 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 20:07:25 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 22 20:07:25 den volumio[947]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 22 20:07:25 den volumio[947]: 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: 10 May 22 20:07:26 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 20:07:26 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 22 20:07:26 den volumio[947]: info: MyVolumio token set successfully May 22 20:07:26 den volumio[947]: info: MYVOLUMIO: Adding device May 22 20:07:26 den volumio[947]: info: MYVOLUMIO: Evaluating Server May 22 20:07:27 den volumio[947]: info: MyVolumio Plan changed: premium May 22 20:07:27 den volumio[947]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 22 20:07:27 den volumio[947]: info: Removing browser output: myVolumio user plan is not superstar May 22 20:07:27 den volumio[947]: info: Removing audio output: May 22 20:07:27 den volumio[947]: info: MYVOLUMIO: Adding device May 22 20:07:27 den volumio[947]: info: MYVOLUMIO: Evaluating Server May 22 20:07:27 den volumio[947]: info: Remote config written successfully May 22 20:07:27 den volumio[947]: info: Starting Tunnel 1 May 22 20:07:27 den volumio[947]: info: Starting Tunnel Connection Checker May 22 20:07:27 den volumio[947]: info: Completed starting MyVolumio Plugin May 22 20:07:27 den volumio[947]: info: MYVolumio Device enabled May 22 20:07:27 den volumio[947]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 22 20:07:27 den volumio[947]: info: MyVolumio status changed May 22 20:07:27 den volumio[947]: info: Streaming services startup May 22 20:07:27 den volumio[947]: info: Starting Streaming Daemon May 22 20:07:27 den sudo[1665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 22 20:07:27 den sudo[1665]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:27 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 22 20:07:27 den volumio[947]: error: [MyVolumio PluginManager] Cache data is invalid! May 22 20:07:27 den sudo[1665]: pam_unix(sudo:session): session closed for user root May 22 20:07:27 den volumio[947]: error: Cannot start Volumio Streaming Daemon May 22 20:07:27 den volumio[947]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 22 20:07:27 den volumio[947]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 22 20:07:27 den volumio[947]: info: CoreCommandRouter::volumioGetVisibleSources May 22 20:07:27 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 20:07:27 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:27 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:27 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 22 20:07:27 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 20:07:27 den volumio[947]: info: Received Get System Info May 22 20:07:27 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 20:07:27 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 20:07:27 den volumio[947]: info: Discovery: Getting this device information May 22 20:07:27 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:27 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:27 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 20:07:27 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:27 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:27 den volumio[947]: info: Listing playlists May 22 20:07:27 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 22 20:07:27 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 20:07:27 den volumio[947]: info: Setting Geolocation for MyVolumio to us1 May 22 20:07:27 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:27 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:27 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:28 den volumio[947]: info: Setting Geolocation for MyVolumio to us1 May 22 20:07:28 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:28 den volumio[947]: info: Successfully Added MyVolumio device May 22 20:07:28 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:28 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:28 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 20:07:28 den volumio[947]: info: Received Get System Info May 22 20:07:28 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 20:07:28 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 20:07:28 den volumio[947]: info: Discovery: Getting this device information May 22 20:07:28 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:28 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:28 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 20:07:28 den volumio[947]: info: Successfully Added MyVolumio device May 22 20:07:28 den volumio[947]: info: Updating MyVolumio device info May 22 20:07:28 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:28 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:28 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:29 den volumio[947]: info: Updating MyVolumio device info May 22 20:07:29 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:29 den volumio[947]: info: Successfully Updated MyVolumio device May 22 20:07:29 den volumio[947]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 22 20:07:29 den volumio[947]: info: [1747944449376] Starting BluetoothController May 22 20:07:29 den volumio[947]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 22 20:07:30 den volumio[947]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom May 22 20:07:30 den sudo[1668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 22 20:07:30 den sudo[1668]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:30 den sudo[1668]: pam_unix(sudo:session): session closed for user root May 22 20:07:30 den volumio[947]: info: MRS: MultiRoom plugin initialized May 22 20:07:30 den volumio[947]: info: MRS: STOPPING SNAPCLIENT May 22 20:07:30 den volumio[947]: info: MRS: Snap server stop May 22 20:07:30 den sudo[1684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 22 20:07:30 den sudo[1684]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:30 den volumio[947]: info: MRS: STOPPING volumioStreaming May 22 20:07:30 den sudo[1687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 22 20:07:30 den sudo[1687]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:30 den sudo[1689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 22 20:07:30 den sudo[1689]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:30 den volumio[947]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 22 20:07:30 den sudo[1687]: pam_unix(sudo:session): session closed for user root May 22 20:07:30 den sudo[1684]: pam_unix(sudo:session): session closed for user root May 22 20:07:30 den sudo[1693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 22 20:07:30 den sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:30 den sudo[1693]: pam_unix(sudo:session): session closed for user root May 22 20:07:30 den sudo[1689]: pam_unix(sudo:session): session closed for user root May 22 20:07:30 den volumio[947]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 22 20:07:30 den volumio[947]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 22 20:07:30 den volumio[947]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 22 20:07:30 den volumio[947]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 22 20:07:30 den volumio[947]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio May 22 20:07:30 den volumio[947]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 22 20:07:30 den volumio[947]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 22 20:07:30 den volumio[947]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 22 20:07:30 den volumio[947]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... May 22 20:07:30 den volumio[947]: info: Preparing to generate the ALSA configuration file May 22 20:07:30 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 22 20:07:30 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 22 20:07:30 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 20:07:30 den volumio[947]: info: Received Get System Info May 22 20:07:30 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 20:07:30 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 20:07:30 den volumio[947]: info: Discovery: Getting this device information May 22 20:07:30 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:30 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:30 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 20:07:31 den volumio[947]: info: MRS: Removed streaming files May 22 20:07:31 den volumio[947]: info: MRS: volumioStreaming STOPPED May 22 20:07:31 den volumio[947]: info: MRS: SNAPSERVER STOPPED May 22 20:07:31 den volumio[947]: info: MRS: SNAPCLIENT STOPPED May 22 20:07:31 den volumio[947]: info: Asound.conf file unchanged, so no further update is needed May 22 20:07:31 den volumio[947]: info: Output device has changed, restarting MPD May 22 20:07:31 den sudo[1696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 20:07:31 den sudo[1696]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:31 den volumio[947]: info: Output device has changed, restarting Shairport Sync May 22 20:07:31 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:31 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:31 den sudo[1696]: pam_unix(sudo:session): session closed for user root May 22 20:07:31 den sudo[1699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 20:07:31 den sudo[1699]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:31 den systemd[1]: Stopping Music Player Daemon... May 22 20:07:31 den systemd[1]: mpd.service: Succeeded. May 22 20:07:31 den systemd[1]: Stopped Music Player Daemon. May 22 20:07:31 den systemd[1]: Starting Music Player Daemon... May 22 20:07:31 den volumio[947]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 20:07:31 den volumio[947]: [SpotifyConnect] Creating VLS config file May 22 20:07:31 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:31 den volumio[947]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 22 20:07:31 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:31 den volumio[947]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 22 20:07:31 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 20:07:31 den sudo[1705]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 20:07:31 den sudo[1705]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:31 den volumio[947]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 22 20:07:31 den volumio[947]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting May 22 20:07:31 den volumio[947]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 22 20:07:31 den volumio[947]: info: Adding METAVOLUMIO REST API Endpoints May 22 20:07:31 den sudo[1705]: pam_unix(sudo:session): session closed for user root May 22 20:07:31 den volumio[947]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 22 20:07:31 den volumio[947]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 22 20:07:31 den volumio[947]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 22 20:07:31 den volumio[947]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 22 20:07:31 den volumio[947]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 22 20:07:31 den sudo[1714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Den May 22 20:07:31 den sudo[1714]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:31 den volumio[947]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 22 20:07:31 den volumio[947]: info: Preparing CD Folders May 22 20:07:31 den volumio[947]: info: Adding CD REST API Endpoints May 22 20:07:31 den volumio[947]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 22 20:07:31 den volumio[947]: info: Starting UDEV Watcher for CD May 22 20:07:31 den volumio[947]: info: Detecting CD presence with UDEV May 22 20:07:31 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 22 20:07:31 den volumio[947]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 22 20:07:31 den volumio[947]: info: Adding inputs REST Endpoints May 22 20:07:31 den volumio[947]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 22 20:07:31 den volumio[947]: info: Scanning Audio Inputs May 22 20:07:31 den sudo[1714]: pam_unix(sudo:session): session closed for user root May 22 20:07:31 den volumio[947]: info: Checking against Known Cards name May 22 20:07:31 den volumio[947]: info: Adding Server instance for streaming May 22 20:07:31 den volumio[947]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 22 20:07:31 den volumio[947]: error: Hi Res Audio Failed Login: Missing Login Data May 22 20:07:31 den volumio[947]: info: Adding HIGHRESAUDIO REST API Endpoints May 22 20:07:31 den volumio[947]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 22 20:07:31 den volumio[947]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 22 20:07:31 den volumio[947]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 22 20:07:31 den volumio[947]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 22 20:07:31 den volumio[947]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect May 22 20:07:31 den volumio[947]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect May 22 20:07:31 den sudo[1743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 22 20:07:31 den sudo[1743]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:31 den volumio[947]: info: QobuzConnect: Starting Qobuz Connect socket and service May 22 20:07:31 den sudo[1743]: pam_unix(sudo:session): session closed for user root May 22 20:07:31 den volumio[947]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections May 22 20:07:31 den volumio[947]: info: Stopping AccessToken refresher cron May 22 20:07:31 den sudo[1776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 22 20:07:31 den sudo[1776]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:31 den sudo[1776]: pam_unix(sudo:session): session closed for user root May 22 20:07:31 den volumio[947]: info: AccessToken refresher cron started May 22 20:07:31 den volumio[947]: info: Stopping AccessToken refresher cron for QOBUZ May 22 20:07:31 den volumio[947]: info: AccessToken refresher cron started for QOBUZ May 22 20:07:31 den volumio[947]: info: Adding TIDAL REST API Endpoints May 22 20:07:31 den volumio[947]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 22 20:07:31 den volumio[947]: info: Adding QOBUZ REST API Endpoints May 22 20:07:31 den volumio[947]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 22 20:07:31 den volumio[947]: [SpotifyConnect] Restarting Vollibrespot Daemon May 22 20:07:31 den sudo[1786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 22 20:07:31 den sudo[1786]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:32 den volumio[947]: info: Bluetooth name changed to Den May 22 20:07:32 den sudo[1790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service May 22 20:07:32 den volumio[947]: info: MPD Permissions set May 22 20:07:32 den sudo[1790]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:32 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:32 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:32 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:32 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:32 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:32 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:32 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:32 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:32 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:32 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:32 den systemd[1]: Stopping Volspotconnect2 Daemon... May 22 20:07:32 den volumio[947]: info: Starting Shairport Sync May 22 20:07:32 den systemd[1]: Started Volumio Qobuz Connect Service. May 22 20:07:32 den sudo[1786]: pam_unix(sudo:session): session closed for user root May 22 20:07:32 den systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM May 22 20:07:32 den systemd[1]: volspotconnect.service: Succeeded. May 22 20:07:32 den systemd[1]: Stopped Volspotconnect2 Daemon. May 22 20:07:32 den systemd[1]: Started Volspotconnect2 Daemon. May 22 20:07:32 den sudo[1790]: pam_unix(sudo:session): session closed for user root May 22 20:07:32 den sudo[1860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 20:07:32 den sudo[1860]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:32 den systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 20:07:32 den systemd[1]: shairport-sync.service: Succeeded. May 22 20:07:32 den systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 20:07:32 den systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 20:07:32 den volumio[1868]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 22 20:07:32 den volumio[1868]: Reading Config from "/tmp/volspotify.toml" May 22 20:07:32 den volumio[1868]: [Vollibrespot] : Using Alsa backend with device: volumio May 22 20:07:32 den sudo[1860]: pam_unix(sudo:session): session closed for user root May 22 20:07:32 den volumio[947]: info: Successfully Updated MyVolumio device May 22 20:07:32 den volumio[1868]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } May 22 20:07:32 den volumio[947]: [SpotifyConnect] Vollibrespot Daemon service restarted! May 22 20:07:32 den volumio[947]: info: Shairport-Sync Started May 22 20:07:34 den volumio[947]: info: TidalConnect service stoped! May 22 20:07:35 den volumio[947]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 22 20:07:35 den volumio[947]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 22 20:07:35 den volumio[1868]: [Vollibrespot] : Connecting to AP "ap-gue1.spotify.com:443" May 22 20:07:35 den sudo[1939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 22 20:07:35 den sudo[1922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 22 20:07:35 den sudo[1932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 22 20:07:35 den sudo[1922]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:35 den sudo[1939]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:35 den sudo[1932]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:35 den volumio[947]: info: Executing endpoint qc_getconfig May 22 20:07:35 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 22 20:07:35 den qobuz-connect[1850]: 20250522 20:07:35.515 [1850.1850] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 22 20:07:35 den volumio[947]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 22 20:07:35 den volumio[947]: info: QobuzConnect: QOBUZ Connect daemon connected May 22 20:07:35 den systemd[1]: Started Volumio Tidal Connect Service. May 22 20:07:35 den systemd[1]: Started Volumio Bluetooth Module. May 22 20:07:35 den sudo[1932]: pam_unix(sudo:session): session closed for user root May 22 20:07:35 den sudo[1922]: pam_unix(sudo:session): session closed for user root May 22 20:07:35 den volumio[947]: info: Volumio BT Module successfully started May 22 20:07:35 den systemd[1]: Started MyVolumio SSH Tunnel. May 22 20:07:35 den sudo[1939]: pam_unix(sudo:session): session closed for user root May 22 20:07:35 den volumio[947]: info: Remote SSH Started May 22 20:07:35 den sudo[1993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* May 22 20:07:35 den sudo[1993]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:35 den volumiobt[1987]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory May 22 20:07:35 den sudo[1993]: pam_unix(sudo:session): session closed for user root May 22 20:07:35 den autossh[1991]: port set to 0, monitoring disabled May 22 20:07:35 den autossh[1991]: starting ssh (count 1) May 22 20:07:35 den autossh[1991]: ssh child pid is 1997 May 22 20:07:35 den volumio[947]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 22 20:07:35 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:35 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:35 den qobuz-connect[1850]: 20250522 20:07:35.822 [1850.1850] INFO VolumeManager: [0x187e2d8]: Setting new playback volume: 75 May 22 20:07:35 den qobuz-connect[1850]: 20250522 20:07:35.822 [1850.1850] INFO VolumeManager: [0x187e2d8]: Setting new mute state: 0 May 22 20:07:35 den qobuz-connect[1850]: 20250522 20:07:35.822 [1850.1850] INFO QobuzConnect: [0x187eca8]: Client initialized! May 22 20:07:35 den qobuz-connect[1850]: 20250522 20:07:35.822 [1850.1850] INFO SampleApp: Starting Avahi advertising, name: Den, service name: _qobuz-connect._tcp May 22 20:07:35 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 22 20:07:35 den qobuz-connect[1850]: 20250522 20:07:35.867 [1850.1850] INFO LocalConfigManager: [0x187dbb8]: Starting Local Configuration server May 22 20:07:35 den qobuz-connect[1850]: 20250522 20:07:35.867 [1850.1850] INFO SampleApp: Starting Local configuration server May 22 20:07:35 den qobuz-connect[1850]: 20250522 20:07:35.867 [1850.1850] INFO SampleApp: Playback volume changed: 75 May 22 20:07:35 den qobuz-connect[1850]: 20250522 20:07:35.868 [1850.1850] INFO SampleApp: Connected to UNIX socket client 0x1873f60 May 22 20:07:35 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:35 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:36 den volumio[947]: info: Executing endpoint tc_getconfig May 22 20:07:36 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 22 20:07:36 den vtcs[1985]: STARTING TidalConnect services, version: 1.4.0.34 May 22 20:07:36 den vtcs[1985]: STARTED TidalConnect services. May 22 20:07:36 den volumio[947]: info: Executing endpoint tc_connect May 22 20:07:36 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 22 20:07:36 den volumio[947]: info: Connecting to TidalConnect May 22 20:07:36 den volumio[947]: info: CoreCommandRouter::servicePushState May 22 20:07:36 den volumio[947]: info: CoreStateMachine::pushState May 22 20:07:36 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:36 den volumio[947]: info: CoreCommandRouter::volumioPushState May 22 20:07:36 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:36 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:36 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:36 den volumio[947]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect May 22 20:07:36 den volumio[947]: info: CoreCommandRouter::servicePushState May 22 20:07:36 den volumio[947]: info: CoreStateMachine::pushState May 22 20:07:36 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:36 den volumio[947]: info: CoreCommandRouter::volumioPushState May 22 20:07:36 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:36 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:36 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:36 den volumio[947]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect May 22 20:07:36 den sudo[2029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio May 22 20:07:36 den sudo[2029]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 20:07:36 den volumiobt[1987]: pulseaudio: no process found May 22 20:07:36 den sudo[2029]: pam_unix(sudo:session): session closed for user root May 22 20:07:36 den volumiossh-tunnel[1990]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. May 22 20:07:36 den dbus-daemon[556]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.33' (uid=1000 pid=2033 comm="/usr/bin/pulseaudio --high-priority=true ") May 22 20:07:36 den systemd[1]: Starting RealtimeKit Scheduling Policy Service... May 22 20:07:36 den volumio[947]: ------------------------------------ BT MESSAGE: BT STATUS: running May 22 20:07:36 den dbus-daemon[556]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' May 22 20:07:36 den systemd[1]: Started RealtimeKit Scheduling Policy Service. May 22 20:07:36 den rtkit-daemon[2034]: Successfully called chroot. May 22 20:07:36 den rtkit-daemon[2034]: Successfully dropped privileges. May 22 20:07:36 den rtkit-daemon[2034]: Successfully limited resources. May 22 20:07:36 den rtkit-daemon[2034]: Running. May 22 20:07:36 den rtkit-daemon[2034]: Canary thread running. May 22 20:07:36 den rtkit-daemon[2034]: Watchdog thread running. May 22 20:07:36 den dbus-daemon[556]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.34' (uid=0 pid=2034 comm="/usr/lib/rtkit/rtkit-daemon ") May 22 20:07:36 den systemd[1]: Starting Authorization Manager... May 22 20:07:36 den volumio[947]: ------------------------------------ BT MESSAGE: BT STATUS: running May 22 20:07:36 den mpd[1717]: May 22 20:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 20:07:37 den polkitd[2038]: started daemon version 0.105 using authority implementation `local' version `0.105' May 22 20:07:37 den dbus-daemon[556]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 22 20:07:37 den systemd[1]: Started Authorization Manager. May 22 20:07:37 den systemd[1]: Started Music Player Daemon. May 22 20:07:37 den sudo[1699]: pam_unix(sudo:session): session closed for user root May 22 20:07:37 den volumio[947]: error: updateQueue error: null May 22 20:07:37 den pulseaudio[2033]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 22 20:07:37 den pulseaudio[2033]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 22 20:07:37 den volumiobt[1987]: Applying permissions May 22 20:07:37 den volumiobt[1987]: Setting BT discoverable and pairable May 22 20:07:37 den pulseaudio[2033]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files May 22 20:07:37 den bluetoothd[700]: Endpoint registered: sender=:1.54 path=/MediaEndpoint/A2DPSource May 22 20:07:37 den bluetoothd[700]: Endpoint registered: sender=:1.54 path=/MediaEndpoint/A2DPSink May 22 20:07:37 den kernel: Bluetooth: RFCOMM TTY layer initialized May 22 20:07:37 den kernel: Bluetooth: RFCOMM socket layer initialized May 22 20:07:37 den kernel: Bluetooth: RFCOMM ver 1.11 May 22 20:07:37 den volumiobt[1987]: [CHG] Controller E4:5F:01:AA:6B:E1 Class: 0x0008041c May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [55B blob data] May 22 20:07:37 den volumiobt[1987]: [91B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [117B blob data] May 22 20:07:37 den volumiobt[1987]: [bluetooth]# -e power on May 22 20:07:37 den volumiobt[1987]: Invalid command in menu main: -e May 22 20:07:37 den volumiobt[1987]: May 22 20:07:37 den volumiobt[1987]: Use "help" for a list of available commands in a menu. May 22 20:07:37 den volumiobt[1987]: Use "menu " if you want to enter any submenu. May 22 20:07:37 den volumiobt[1987]: Use "back" if you want to return to menu main. May 22 20:07:37 den volumiobt[1987]: [bluetooth]# agent on May 22 20:07:37 den volumiobt[1987]: Agent is already registered May 22 20:07:37 den volumiobt[1987]: [bluetooth]# discoverable on May 22 20:07:37 den volumiobt[1987]: [bluetooth]# pairable on May 22 20:07:37 den volumiobt[1987]: [bluetooth]# agent NoInputNoOutput May 22 20:07:37 den volumiobt[1987]: Agent is already registered May 22 20:07:37 den volumiobt[1987]: [bluetooth]# default-agent May 22 20:07:37 den volumiobt[1987]: [bluetooth]# quit May 22 20:07:37 den volumiobt[1987]: [59B blob data] May 22 20:07:37 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 20:07:37 den volumio[947]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 May 22 20:07:37 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:37 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:38 den volumio[947]: info: Executing endpoint metavolumio May 22 20:07:38 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 22 20:07:38 den volumio[947]: info: Checking Spotify Web API May 22 20:07:38 den volumio[947]: info: TidalConnect service started! May 22 20:07:38 den volumio[947]: [Metrics] CommandRouter: 34s 168.74ms May 22 20:07:38 den volumio[947]: info: CoreCommandRouter::volumiosetStartupVolume May 22 20:07:38 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:38 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:38 den volumio[947]: info: CoreCommandRouter::Close All Modals sent May 22 20:07:38 den volumio[947]: info: CoreCommandRouter::Close All Modals sent May 22 20:07:39 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 22 20:07:39 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 22 20:07:39 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 22 20:07:41 den systemd[1]: systemd-timedated.service: Succeeded. May 22 20:07:41 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 20:07:41 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 20:07:41 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 20:07:41 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 22 20:07:41 den volumio-remote-updater[560]: No test mode May 22 20:07:41 den volumio-remote-updater[560]: No alpha test mode May 22 20:07:41 den ntpd[719]: Soliciting pool server 192.155.94.72 May 22 20:07:41 den ntpd[719]: Soliciting pool server 138.68.201.49 May 22 20:07:42 den volumio[947]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 22 20:07:42 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 22 20:07:42 den ntpd[719]: Soliciting pool server 162.159.200.1 May 22 20:07:42 den ntpd[719]: Soliciting pool server 23.155.40.38 May 22 20:07:42 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:42 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 20:07:42 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 22 20:07:42 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 22 20:07:42 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 22 20:07:42 den volumio[947]: info: CoreCommandRouter::volumioGetBrowseSources May 22 20:07:42 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 20:07:43 den ntpd[719]: Soliciting pool server 50.205.57.38 May 22 20:07:43 den ntpd[719]: Soliciting pool server 50.203.248.23 May 22 20:07:44 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 20:07:44 den volumio[947]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 22 20:07:44 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 22 20:07:44 den volumio[947]: info: Received Get System Version May 22 20:07:44 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 20:07:44 den volumio[947]: info: Received Get System Info May 22 20:07:44 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 20:07:44 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 20:07:44 den volumio[947]: info: Discovery: Getting this device information May 22 20:07:44 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:07:44 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:07:44 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 20:07:44 den ntpd[719]: Soliciting pool server 23.150.40.242 May 22 20:07:44 den ntpd[719]: Soliciting pool server 64.44.115.65 May 22 20:07:45 den volumio[947]: info: BOOT COMPLETED May 22 20:07:45 den ntpd[719]: Soliciting pool server 198.60.22.240 May 22 20:07:46 den ntpd[719]: Soliciting pool server 208.113.130.146 May 22 20:07:46 den ntpd[719]: Soliciting pool server 141.11.228.173 May 22 20:07:47 den ntpd[719]: Soliciting pool server 5.78.62.36 May 22 20:07:47 den ntpd[719]: Soliciting pool server 23.131.160.7 May 22 20:07:48 den ntpd[719]: Soliciting pool server 23.186.168.123 May 22 20:07:48 den ntpd[719]: Soliciting pool server 45.83.234.123 May 22 20:07:49 den ntpd[719]: Soliciting pool server 54.236.224.171 May 22 20:07:49 den ntpd[719]: Soliciting pool server 2604:a880:800:a1::ec9:5001 May 22 20:08:20 den volumio[947]: verbose: New Socket.io Connection to 192.168.1.33:3000 from 192.168.1.95 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 22 20:08:20 den volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 22 20:08:20 den volumio[947]: info: CoreCommandRouter::volumioGetState May 22 20:08:20 den volumio[947]: info: CorePlayQueue::getTrack 0 May 22 20:08:27 den volumio[947]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 20:08:27 den volumio[947]: TypeError: Cannot read property 'id' of null May 22 20:08:27 den volumio[947]: at multiroom.disableMultiroomSyncOutput (/myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:17182) May 22 20:08:27 den volumio[947]: at CoreCommandRouter.disableMultiroomSyncOutput (/volumio/app/index.js:1298:23) May 22 20:08:27 den volumio[947]: at Socket. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:285:28) May 22 20:08:27 den volumio[947]: at Socket.Emitter.emit (/volumio/node_modules/socket.io-client/node_modules/component-emitter/index.js:133:20) May 22 20:08:27 den volumio[947]: at Socket.onevent (/volumio/node_modules/socket.io-client/lib/socket.js:278:10) May 22 20:08:27 den volumio[947]: at Socket.onpacket (/volumio/node_modules/socket.io-client/lib/socket.js:236:12) May 22 20:08:27 den volumio[947]: at Manager. (/volumio/node_modules/component-bind/index.js:21:15) May 22 20:08:27 den volumio[947]: at Manager.Emitter.emit (/volumio/node_modules/socket.io-client/node_modules/component-emitter/index.js:133:20) May 22 20:08:27 den volumio[947]: at Manager.ondecoded (/volumio/node_modules/socket.io-client/lib/manager.js:345:8) May 22 20:08:27 den volumio[947]: at Decoder. (/volumio/node_modules/component-bind/index.js:21:15) May 22 20:08:27 den volumio[947]: at Decoder.Emitter.emit (/volumio/node_modules/socket.io-client/node_modules/socket.io-parser/node_modules/component-emitter/index.js:145:20) May 22 20:08:27 den volumio[947]: at Decoder.add (/volumio/node_modules/socket.io-client/node_modules/socket.io-parser/index.js:254:12) May 22 20:08:27 den volumio[947]: at Manager.ondata (/volumio/node_modules/socket.io-client/lib/manager.js:335:16) May 22 20:08:27 den volumio[947]: at Socket. (/volumio/node_modules/component-bind/index.js:21:15) May 22 20:08:27 den volumio[947]: at Socket.Emitter.emit (/volumio/node_modules/engine.io-client/node_modules/component-emitter/index.js:145:20) May 22 20:08:27 den volumio[947]: at Socket.onPacket (/volumio/node_modules/engine.io-client/lib/socket.js:461:14) May 22 20:08:27 den volumio[947]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 20:08:28 den sudo[2152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 20:07 May 22 20:08:28 den sudo[2152]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"