-- Logs begin at Tue 2025-01-21 18:38:08 CET, end at Tue 2025-01-21 18:40:15 CET. -- Jan 21 18:39:00 volumio-soundbox volumio[983]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jan 21 18:39:00 volumio-soundbox volumio[983]: info: Starting Shairport Sync Jan 21 18:39:00 volumio-soundbox sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Jan 21 18:39:00 volumio-soundbox sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:00 volumio-soundbox volumio[983]: info: Starting Shairport Sync Jan 21 18:39:00 volumio-soundbox systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jan 21 18:39:00 volumio-soundbox sudo[1230]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:00 volumio-soundbox volumio[983]: info: Starting Shairport Sync Jan 21 18:39:00 volumio-soundbox sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 18:39:00 volumio-soundbox sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:00 volumio-soundbox sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 18:39:00 volumio-soundbox sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:00 volumio-soundbox sudo[1238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 18:39:00 volumio-soundbox sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 18:39:00 volumio-soundbox volumio[983]: info: Discovery: Getting this device information Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 18:39:00 volumio-soundbox volumio[983]: verbose: New Socket.io Connection to 192.168.178.31 from 192.168.178.132 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_1_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 13 Jan 21 18:39:00 volumio-soundbox systemd[1]: systemd-fsckd.service: Succeeded. Jan 21 18:39:00 volumio-soundbox volumio[983]: info: touch_display: File permissions for backlight brightness control set. Jan 21 18:39:00 volumio-soundbox sudo[1186]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 18:39:00 volumio-soundbox systemd[1]: Reloading. Jan 21 18:39:00 volumio-soundbox volumio[983]: info: touch_display: systemctl daemon-reload succeeded. Jan 21 18:39:00 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] addOverlay for Button: 13 true up Jan 21 18:39:00 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] addOverlay: executing command: /usr/bin/sudo /usr/bin/dtoverlay gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 & Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetVisibleSources Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 21 18:39:00 volumio-soundbox volumio[983]: info: Received Get System Info Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 18:39:00 volumio-soundbox volumio[983]: info: Discovery: Getting this device information Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:00 volumio-soundbox sudo[1245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 Jan 21 18:39:00 volumio-soundbox sudo[1245]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:00 volumio-soundbox sudo[1243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 21 18:39:00 volumio-soundbox sudo[1243]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:00 volumio-soundbox volumio[983]: info: Listing playlists Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetQueue Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreStateMachine::getQueue Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CorePlayQueue::getQueue Jan 21 18:39:00 volumio-soundbox sudo[1245]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:00 volumio-soundbox kernel: input: button@d as /devices/platform/button@d/input/input2 Jan 21 18:39:00 volumio-soundbox volumio[983]: verbose: New Socket.io Connection to 192.168.178.31:3000 from 192.168.178.132 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 14 Jan 21 18:39:00 volumio-soundbox volumio[983]: info: /dev/input: Jan 21 18:39:00 volumio-soundbox volumio[983]: by-path Jan 21 18:39:00 volumio-soundbox volumio[983]: event0 Jan 21 18:39:00 volumio-soundbox volumio[983]: event1 Jan 21 18:39:00 volumio-soundbox volumio[983]: event2 Jan 21 18:39:00 volumio-soundbox volumio[983]: mice Jan 21 18:39:00 volumio-soundbox volumio[983]: mouse0 Jan 21 18:39:00 volumio-soundbox volumio[983]: /dev/input/by-path: Jan 21 18:39:00 volumio-soundbox volumio[983]: platform-button@d-event Jan 21 18:39:00 volumio-soundbox volumio[983]: platform-rotary@5-event Jan 21 18:39:00 volumio-soundbox volumio[983]: platform-soc:firmware:touchscreen-event Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 21 18:39:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 21 18:39:00 volumio-soundbox systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 21 18:39:00 volumio-soundbox systemd-udevd[1194]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 21 18:39:01 volumio-soundbox mpd[1173]: Jan 21 18:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 21 18:39:01 volumio-soundbox systemd[1]: Started Music Player Daemon. Jan 21 18:39:01 volumio-soundbox sudo[1152]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:01 volumio-soundbox sudo[1143]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:01 volumio-soundbox sudo[1183]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:01 volumio-soundbox volumio[983]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jan 21 18:39:01 volumio-soundbox volumio[983]: error: MPD error: The expression evaluated to a falsy value: Jan 21 18:39:01 volumio-soundbox volumio[983]: assert.ok(self.idling) Jan 21 18:39:01 volumio-soundbox volumio[983]: error: The expression evaluated to a falsy value: Jan 21 18:39:01 volumio-soundbox volumio[983]: assert.ok(self.idling) Jan 21 18:39:01 volumio-soundbox systemd[1]: Started Volumio Kiosk. Jan 21 18:39:01 volumio-soundbox volumio[983]: error: updateQueue error: null Jan 21 18:39:01 volumio-soundbox systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 21 18:39:01 volumio-soundbox volumio[983]: info: MPD running with PID1173 Jan 21 18:39:01 volumio-soundbox volumio[983]: ,establishing connection Jan 21 18:39:01 volumio-soundbox volumio[983]: error: updateQueue error: null Jan 21 18:39:01 volumio-soundbox systemd[1]: shairport-sync.service: Succeeded. Jan 21 18:39:01 volumio-soundbox systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 21 18:39:01 volumio-soundbox sudo[1181]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:01 volumio-soundbox volumio[983]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 21 18:39:01 volumio-soundbox sudo[1243]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:01 volumio-soundbox systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 21 18:39:01 volumio-soundbox sudo[1232]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:01 volumio-soundbox volumio[983]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jan 21 18:39:01 volumio-soundbox volumio[983]: info: touch_display: Volumio Kiosk started. Jan 21 18:39:01 volumio-soundbox sudo[1238]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:01 volumio-soundbox sudo[1235]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:01 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:01 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:01 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:01 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:01 volumio-soundbox volumio[983]: info: Shairport-Sync Started Jan 21 18:39:01 volumio-soundbox volumio[983]: Error adding Membership: Error: addMembership EINVAL Jan 21 18:39:01 volumio-soundbox volumio[983]: info: Shairport-Sync Started Jan 21 18:39:01 volumio-soundbox volumio[983]: info: Shairport-Sync Started Jan 21 18:39:01 volumio-soundbox volumio[983]: info: touch_display: X display number found: 0 Jan 21 18:39:01 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] addOverlay for Rotary: 23 25 2 Jan 21 18:39:01 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] addOverlay: executing command: /usr/bin/sudo /usr/bin/dtoverlay rotary-encoder pin_a=23 pin_b=25 relative_axis=true steps-per-period=2 & Jan 21 18:39:01 volumio-soundbox startx[1277]: X.Org X Server 1.20.4 Jan 21 18:39:01 volumio-soundbox startx[1277]: X Protocol Version 11, Revision 0 Jan 21 18:39:01 volumio-soundbox startx[1277]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jan 21 18:39:01 volumio-soundbox startx[1277]: Current Operating System: Linux volumio-soundbox 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l Jan 21 18:39:01 volumio-soundbox startx[1277]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=fb80cb44-b4d3-4b36-be35-613cba0a09fb imgfile=/volumio_current.sqsh bootpart=UUID=51B1-040D datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jan 21 18:39:01 volumio-soundbox startx[1277]: Build Date: 04 April 2023 07:50:56AM Jan 21 18:39:01 volumio-soundbox startx[1277]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jan 21 18:39:01 volumio-soundbox startx[1277]: Current version of pixman: 0.36.0 Jan 21 18:39:01 volumio-soundbox startx[1277]: Before reporting problems, check http://wiki.x.org Jan 21 18:39:01 volumio-soundbox startx[1277]: to make sure that you have the latest version. Jan 21 18:39:01 volumio-soundbox startx[1277]: Markers: (--) probed, (**) from config file, (==) default setting, Jan 21 18:39:01 volumio-soundbox startx[1277]: (++) from command line, (!!) notice, (II) informational, Jan 21 18:39:01 volumio-soundbox startx[1277]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jan 21 18:39:01 volumio-soundbox startx[1277]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue Jan 21 18:39:01 2025 Jan 21 18:39:01 volumio-soundbox startx[1277]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jan 21 18:39:01 volumio-soundbox startx[1277]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jan 21 18:39:01 volumio-soundbox volumio[983]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 21 18:39:01 volumio-soundbox sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=23 pin_b=25 relative_axis=true steps-per-period=2 Jan 21 18:39:01 volumio-soundbox sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:01 volumio-soundbox kernel: rotary-encoder rotary@17: gray Jan 21 18:39:01 volumio-soundbox kernel: input: rotary@17 as /devices/platform/rotary@17/input/input3 Jan 21 18:39:01 volumio-soundbox volumio[983]: info: touch_display: X display number found: 0 Jan 21 18:39:01 volumio-soundbox sudo[1306]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:01 volumio-soundbox volumio[983]: info: /dev/input: Jan 21 18:39:01 volumio-soundbox volumio[983]: by-path Jan 21 18:39:01 volumio-soundbox volumio[983]: event0 Jan 21 18:39:01 volumio-soundbox volumio[983]: event1 Jan 21 18:39:01 volumio-soundbox volumio[983]: event2 Jan 21 18:39:01 volumio-soundbox volumio[983]: event3 Jan 21 18:39:01 volumio-soundbox volumio[983]: mice Jan 21 18:39:01 volumio-soundbox volumio[983]: mouse0 Jan 21 18:39:01 volumio-soundbox volumio[983]: /dev/input/by-path: Jan 21 18:39:01 volumio-soundbox volumio[983]: platform-button@d-event Jan 21 18:39:01 volumio-soundbox volumio[983]: platform-rotary@5-event Jan 21 18:39:01 volumio-soundbox volumio[983]: platform-soc:firmware:touchscreen-event Jan 21 18:39:01 volumio-soundbox sh[522]: timed out Jan 21 18:39:01 volumio-soundbox dhcpcd[567]: timed out Jan 21 18:39:01 volumio-soundbox sh[522]: dhcpcd exited Jan 21 18:39:01 volumio-soundbox dhcpcd[567]: dhcpcd exited Jan 21 18:39:01 volumio-soundbox sh[522]: ifup: failed to bring up eth0 Jan 21 18:39:01 volumio-soundbox systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jan 21 18:39:01 volumio-soundbox systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jan 21 18:39:02 volumio-soundbox systemd-udevd[1194]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 21 18:39:02 volumio-soundbox sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 18:39:02 volumio-soundbox sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:02 volumio-soundbox sudo[1340]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:02 volumio-soundbox sudo[1342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 18:39:02 volumio-soundbox sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:02 volumio-soundbox sudo[1342]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:02 volumio-soundbox sudo[1346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 21 18:39:02 volumio-soundbox sudo[1346]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:02 volumio-soundbox systemd[1]: Started UPnP Renderer front-end to MPD. Jan 21 18:39:02 volumio-soundbox sudo[1346]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:02 volumio-soundbox volumio[983]: info: Upmpdcli Daemon Started Jan 21 18:39:02 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] addOverlay for Button: 22 false up Jan 21 18:39:02 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] addOverlay: executing command: /usr/bin/sudo /usr/bin/dtoverlay gpio-key gpio=22 active_low=0 gpio_pull=up keycode=20 & Jan 21 18:39:02 volumio-soundbox volumio[1348]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 21 18:39:02 volumio-soundbox sudo[1365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=22 active_low=0 gpio_pull=up keycode=20 Jan 21 18:39:02 volumio-soundbox sudo[1365]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:02 volumio-soundbox kernel: input: button@16 as /devices/platform/button@16/input/input4 Jan 21 18:39:02 volumio-soundbox sudo[1365]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:03 volumio-soundbox volumio[983]: info: /dev/input: Jan 21 18:39:03 volumio-soundbox volumio[983]: by-path Jan 21 18:39:03 volumio-soundbox volumio[983]: event0 Jan 21 18:39:03 volumio-soundbox volumio[983]: event1 Jan 21 18:39:03 volumio-soundbox volumio[983]: event2 Jan 21 18:39:03 volumio-soundbox volumio[983]: event3 Jan 21 18:39:03 volumio-soundbox volumio[983]: event4 Jan 21 18:39:03 volumio-soundbox volumio[983]: mice Jan 21 18:39:03 volumio-soundbox volumio[983]: mouse0 Jan 21 18:39:03 volumio-soundbox volumio[983]: /dev/input/by-path: Jan 21 18:39:03 volumio-soundbox volumio[983]: platform-button@d-event Jan 21 18:39:03 volumio-soundbox volumio[983]: platform-rotary@17-event Jan 21 18:39:03 volumio-soundbox volumio[983]: platform-rotary@5-event Jan 21 18:39:03 volumio-soundbox volumio[983]: platform-soc:firmware:touchscreen-event Jan 21 18:39:03 volumio-soundbox systemd-udevd[1194]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] attachAllListeners: 1,2,3 Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] attachAllListeners: 1,2 Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] attachAllListeners: 1 Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] attachAllListeners: Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] attachAllListeners: end of recursion. Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 1 type: rotary Jan 21 18:39:04 volumio-soundbox volumio[983]: info: touch_display: Setting screensaver timeout to 120 seconds. Jan 21 18:39:04 volumio-soundbox sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jan 21 18:39:04 volumio-soundbox sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:04 volumio-soundbox sudo[1399]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=5pinB=6 in Overlays (in load order): Jan 21 18:39:04 volumio-soundbox volumio[983]: 0: rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=1 Jan 21 18:39:04 volumio-soundbox volumio[983]: 1: gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 Jan 21 18:39:04 volumio-soundbox volumio[983]: 2: rotary-encoder pin_a=23 pin_b=25 relative_axis=true steps-per-period=2 Jan 21 18:39:04 volumio-soundbox volumio[983]: 3: gpio-key gpio=22 active_low=0 gpio_pull=up keycode=20 Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] checkOverlayExists: rotary 1 uses overlay 0 Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] attachAllListeners: attach rotary 1 Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] attachListener: path: /dev/input/by-path/platform-rotary@5-event, type: rotary Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 1 type: button Jan 21 18:39:04 volumio-soundbox sudo[1412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jan 21 18:39:04 volumio-soundbox sudo[1412]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:04 volumio-soundbox sudo[1412]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=13pinB=undefined in Overlays (in load order): Jan 21 18:39:04 volumio-soundbox volumio[983]: 0: rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=1 Jan 21 18:39:04 volumio-soundbox volumio[983]: 1: gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 Jan 21 18:39:04 volumio-soundbox volumio[983]: 2: rotary-encoder pin_a=23 pin_b=25 relative_axis=true steps-per-period=2 Jan 21 18:39:04 volumio-soundbox volumio[983]: 3: gpio-key gpio=22 active_low=0 gpio_pull=up keycode=20 Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] checkOverlayExists: button 1 uses overlay 1 Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] attachAllListeners: attach button 1 Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] attachListener: path: /dev/input/by-path/platform-button@d-event, type: button Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 2 type: rotary Jan 21 18:39:04 volumio-soundbox sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jan 21 18:39:04 volumio-soundbox sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:04 volumio-soundbox sudo[1416]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=23pinB=25 in Overlays (in load order): Jan 21 18:39:04 volumio-soundbox volumio[983]: 0: rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=1 Jan 21 18:39:04 volumio-soundbox volumio[983]: 1: gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 Jan 21 18:39:04 volumio-soundbox volumio[983]: 2: rotary-encoder pin_a=23 pin_b=25 relative_axis=true steps-per-period=2 Jan 21 18:39:04 volumio-soundbox volumio[983]: 3: gpio-key gpio=22 active_low=0 gpio_pull=up keycode=20 Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] checkOverlayExists: rotary 2 uses overlay 2 Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] attachAllListeners: attach rotary 2 Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] attachListener: path: /dev/input/by-path/platform-rotary@17-event, type: rotary Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 2 type: button Jan 21 18:39:04 volumio-soundbox sudo[1421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jan 21 18:39:04 volumio-soundbox sudo[1421]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:04 volumio-soundbox sudo[1421]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=22pinB=undefined in Overlays (in load order): Jan 21 18:39:04 volumio-soundbox volumio[983]: 0: rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=1 Jan 21 18:39:04 volumio-soundbox volumio[983]: 1: gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 Jan 21 18:39:04 volumio-soundbox volumio[983]: 2: rotary-encoder pin_a=23 pin_b=25 relative_axis=true steps-per-period=2 Jan 21 18:39:04 volumio-soundbox volumio[983]: 3: gpio-key gpio=22 active_low=0 gpio_pull=up keycode=20 Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] checkOverlayExists: button 2 uses overlay 3 Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] attachAllListeners: attach button 2 Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] attachListener: path: /dev/input/by-path/platform-button@16-event, type: button Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] attachAllListeners rotaries: Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] attachAllListeners buttons: Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] onStart: Attach Event-handles now. Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] Now addAllEventHandles for rotaries. Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] addEventHandle for rotary: 1 type: rotary Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] addEventHandle for rotary: 2 type: rotary Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] Now addAllEventHandles for buttons. Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] addEventHandle for rotary: 1 type: button Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] addEventHandle: adding handle : Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] addEventHandle for rotary: 2 type: button Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] addEventHandle: adding handle : Jan 21 18:39:04 volumio-soundbox systemd[1]: systemd-hostnamed.service: Succeeded. Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [ROTARYENCODER2] onStart: Plugin successfully started. Jan 21 18:39:04 volumio-soundbox volumio[983]: info: Completed starting Core Plugins Jan 21 18:39:04 volumio-soundbox volumio[983]: info: ------------------------------------------- Jan 21 18:39:04 volumio-soundbox volumio[983]: info: ----- MyVolumio plugins startup ---- Jan 21 18:39:04 volumio-soundbox volumio[983]: info: ------------------------------------------- Jan 21 18:39:04 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 21 18:39:05 volumio-soundbox volumio[1348]: ..................++++ Jan 21 18:39:06 volumio-soundbox volumio[983]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 21 18:39:10 volumio-soundbox volumio[1348]: ...............................++++ Jan 21 18:39:10 volumio-soundbox volumio[1348]: e is 65537 (0x010001) Jan 21 18:39:10 volumio-soundbox volumio[1348]: writing RSA key Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 21 18:39:13 volumio-soundbox volumio[983]: info: Adding plugin bluetooth to MyMusic Plugins Jan 21 18:39:13 volumio-soundbox volumio[983]: info: Adding plugin multiroom to MyMusic Plugins Jan 21 18:39:13 volumio-soundbox volumio[983]: info: Adding plugin metavolumio to MyMusic Plugins Jan 21 18:39:13 volumio-soundbox volumio[983]: info: Adding plugin cd_controller to MyMusic Plugins Jan 21 18:39:13 volumio-soundbox volumio[983]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 21 18:39:13 volumio-soundbox volumio[983]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 21 18:39:13 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 21 18:39:15 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 21 18:39:15 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 21 18:39:15 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 18:39:15 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 18:39:15 volumio-soundbox volumio[983]: info: Starting MyVolumio Remote Streaming Endpoints Jan 21 18:39:15 volumio-soundbox volumio[983]: info: MyVolumio login type: Token Jan 21 18:39:15 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 21 18:39:15 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 21 18:39:17 volumio-soundbox volumio[983]: info: Starting Streaming Service Transparent Proxy Jan 21 18:39:17 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 21 18:39:17 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 21 18:39:17 volumio-soundbox volumio[983]: info: Streaming services startup Jan 21 18:39:17 volumio-soundbox volumio[983]: info: Starting Streaming Daemon Jan 21 18:39:17 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 21 18:39:17 volumio-soundbox sudo[1618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 21 18:39:17 volumio-soundbox sudo[1618]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:17 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 18:39:17 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 18:39:17 volumio-soundbox volumio[983]: info: Discovery: Getting this device information Jan 21 18:39:17 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:17 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:17 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 18:39:17 volumio-soundbox sudo[1618]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:17 volumio-soundbox volumio[983]: error: Cannot start Volumio Streaming Daemon Jan 21 18:39:17 volumio-soundbox volumio[983]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 21 18:39:17 volumio-soundbox volumio[983]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 21 18:39:17 volumio-soundbox volumio[983]: error: MyVolumio Custom Token format not valid, refreshing it Jan 21 18:39:18 volumio-soundbox volumio[983]: info: MyVolumio login type: Token Jan 21 18:39:18 volumio-soundbox volumio[983]: info: AutoStart - Plugin is starting Jan 21 18:39:18 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetQueue Jan 21 18:39:18 volumio-soundbox volumio[983]: info: CoreStateMachine::getQueue Jan 21 18:39:18 volumio-soundbox volumio[983]: info: CorePlayQueue::getQueue Jan 21 18:39:18 volumio-soundbox volumio[983]: info: AutoStart - start playing Jan 21 18:39:18 volumio-soundbox volumio[983]: info: AutoStart - start playing with no specific position Jan 21 18:39:18 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPlay Jan 21 18:39:18 volumio-soundbox volumio[983]: info: CoreStateMachine::play index 0 Jan 21 18:39:18 volumio-soundbox volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 18:39:18 volumio-soundbox volumio[983]: info: CoreStateMachine::stop Jan 21 18:39:18 volumio-soundbox volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 18:39:18 volumio-soundbox volumio[983]: info: CoreStateMachine::play index undefined Jan 21 18:39:18 volumio-soundbox volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 18:39:18 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:18 volumio-soundbox volumio[983]: info: CoreStateMachine::startPlaybackTimer Jan 21 18:39:18 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:18 volumio-soundbox volumio[983]: info: [1737481158875] ControllerWebradio::clearAddPlayTrack Jan 21 18:39:18 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand stop Jan 21 18:39:18 volumio-soundbox volumio[983]: info: sendMpdCommand stop took 4 milliseconds Jan 21 18:39:18 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand clear Jan 21 18:39:18 volumio-soundbox volumio[983]: info: Jan 21 18:39:18 volumio-soundbox volumio[983]: ---------------------------- MPD announces system playlist update Jan 21 18:39:18 volumio-soundbox volumio[983]: info: Ignoring MPD Status Update Jan 21 18:39:18 volumio-soundbox volumio[983]: info: sendMpdCommand clear took 6 milliseconds Jan 21 18:39:18 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand load "http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein" Jan 21 18:39:18 volumio-soundbox volumio[983]: info: Jan 21 18:39:18 volumio-soundbox volumio[983]: ---------------------------- MPD announces system playlist update Jan 21 18:39:18 volumio-soundbox volumio[983]: info: Ignoring MPD Status Update Jan 21 18:39:18 volumio-soundbox volumio[983]: info: Jan 21 18:39:18 volumio-soundbox volumio[983]: ---------------------------- MPD announces system playlist update Jan 21 18:39:18 volumio-soundbox volumio[983]: info: Ignoring MPD Status Update Jan 21 18:39:18 volumio-soundbox volumio[983]: error: updateQueue error: null Jan 21 18:39:18 volumio-soundbox volumio[983]: info: ------------------------------ 19ms Jan 21 18:39:19 volumio-soundbox volumio[983]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 21 18:39:19 volumio-soundbox systemd[1]: systemd-timedated.service: Succeeded. Jan 21 18:39:19 volumio-soundbox volumio[983]: STREAMING PROXY: Starting server on port 3245 Jan 21 18:39:19 volumio-soundbox volumio[983]: Node JS runtime: 14 Jan 21 18:39:20 volumio-soundbox volumio[983]: info: MyVolumio token set successfully Jan 21 18:39:20 volumio-soundbox volumio[983]: info: MYVOLUMIO: Adding device Jan 21 18:39:20 volumio-soundbox volumio[983]: info: MYVOLUMIO: Evaluating Server Jan 21 18:39:20 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand add "http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein" Jan 21 18:39:20 volumio-soundbox volumio[983]: error: updateQueue error: null Jan 21 18:39:20 volumio-soundbox volumio[983]: error: updateQueue error: null Jan 21 18:39:20 volumio-soundbox volumio[983]: info: Jan 21 18:39:20 volumio-soundbox volumio[983]: ---------------------------- MPD announces system playlist update Jan 21 18:39:20 volumio-soundbox volumio[983]: info: Ignoring MPD Status Update Jan 21 18:39:20 volumio-soundbox volumio[983]: info: ------------------------------ 1433ms Jan 21 18:39:20 volumio-soundbox volumio[983]: info: ------------------------------ 1431ms Jan 21 18:39:20 volumio-soundbox volumio[983]: info: sendMpdCommand add "http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein" took 5 milliseconds Jan 21 18:39:20 volumio-soundbox volumio[983]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 21 18:39:20 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand play Jan 21 18:39:20 volumio-soundbox volumio[983]: info: Jan 21 18:39:20 volumio-soundbox volumio[983]: ---------------------------- MPD announces system playlist update Jan 21 18:39:20 volumio-soundbox volumio[983]: info: Ignoring MPD Status Update Jan 21 18:39:20 volumio-soundbox volumio[983]: info: Jan 21 18:39:20 volumio-soundbox volumio[983]: ---------------------------- MPD announces system playlist update Jan 21 18:39:20 volumio-soundbox volumio[983]: info: Ignoring MPD Status Update Jan 21 18:39:20 volumio-soundbox volumio[983]: info: ------------------------------ 15ms Jan 21 18:39:20 volumio-soundbox volumio[983]: info: sendMpdCommand play took 10 milliseconds Jan 21 18:39:20 volumio-soundbox volumio[983]: info: ------------------------------ 9ms Jan 21 18:39:20 volumio-soundbox volumio[983]: info: ------------------------------ 7ms Jan 21 18:39:20 volumio-soundbox volumio[983]: info: MyVolumio Plan changed: premium Jan 21 18:39:20 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jan 21 18:39:20 volumio-soundbox volumio[983]: info: Removing browser output: myVolumio user plan is not superstar Jan 21 18:39:20 volumio-soundbox volumio[983]: info: Removing audio output: Jan 21 18:39:20 volumio-soundbox volumio[983]: info: MYVOLUMIO: Adding device Jan 21 18:39:20 volumio-soundbox volumio[983]: info: MYVOLUMIO: Evaluating Server Jan 21 18:39:20 volumio-soundbox volumio[983]: info: Remote config written successfully Jan 21 18:39:20 volumio-soundbox volumio[983]: info: Starting Tunnel 1 Jan 21 18:39:20 volumio-soundbox volumio[983]: info: Starting Tunnel Connection Checker Jan 21 18:39:20 volumio-soundbox volumio[983]: info: Completed starting MyVolumio Plugin Jan 21 18:39:21 volumio-soundbox volumio[983]: info: MYVolumio Device enabled Jan 21 18:39:21 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 21 18:39:21 volumio-soundbox volumio[983]: info: MyVolumio status changed Jan 21 18:39:21 volumio-soundbox volumio[983]: info: Streaming services startup Jan 21 18:39:21 volumio-soundbox volumio[983]: info: Starting Streaming Daemon Jan 21 18:39:21 volumio-soundbox volumio[983]: info: Setting Geolocation for MyVolumio to eu4 Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 18:39:21 volumio-soundbox sudo[1853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 21 18:39:21 volumio-soundbox sudo[1853]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:21 volumio-soundbox kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jan 21 18:39:21 volumio-soundbox volumio[983]: info: Jan 21 18:39:21 volumio-soundbox volumio[983]: ---------------------------- MPD announces state update: player Jan 21 18:39:21 volumio-soundbox volumio[983]: info: ControllerMpd::getState Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand status Jan 21 18:39:21 volumio-soundbox volumio[983]: info: Jan 21 18:39:21 volumio-soundbox volumio[983]: ---------------------------- MPD announces state update: player Jan 21 18:39:21 volumio-soundbox volumio[983]: info: ControllerMpd::getState Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand status Jan 21 18:39:21 volumio-soundbox volumio[983]: info: Jan 21 18:39:21 volumio-soundbox volumio[983]: ---------------------------- MPD announces state update: player Jan 21 18:39:21 volumio-soundbox volumio[983]: info: ControllerMpd::getState Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand status Jan 21 18:39:21 volumio-soundbox sudo[1853]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:21 volumio-soundbox volumio[983]: info: Jan 21 18:39:21 volumio-soundbox volumio[983]: ---------------------------- MPD announces state update: player Jan 21 18:39:21 volumio-soundbox volumio[983]: info: ControllerMpd::getState Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand status Jan 21 18:39:21 volumio-soundbox volumio[983]: info: sendMpdCommand status took 17 milliseconds Jan 21 18:39:21 volumio-soundbox volumio[983]: info: sendMpdCommand status took 15 milliseconds Jan 21 18:39:21 volumio-soundbox volumio[983]: info: sendMpdCommand status took 13 milliseconds Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseState Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseState Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseState Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 18:39:21 volumio-soundbox volumio[983]: info: Jan 21 18:39:21 volumio-soundbox volumio[983]: ---------------------------- MPD announces state update: player Jan 21 18:39:21 volumio-soundbox volumio[983]: info: ControllerMpd::getState Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand status Jan 21 18:39:21 volumio-soundbox volumio[983]: info: Jan 21 18:39:21 volumio-soundbox volumio[983]: ---------------------------- MPD announces state update: player Jan 21 18:39:21 volumio-soundbox volumio[983]: info: ControllerMpd::getState Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand status Jan 21 18:39:21 volumio-soundbox volumio[983]: info: sendMpdCommand status took 30 milliseconds Jan 21 18:39:21 volumio-soundbox volumio[983]: info: sendMpdCommand playlistinfo took 24 milliseconds Jan 21 18:39:21 volumio-soundbox volumio[983]: info: sendMpdCommand playlistinfo took 23 milliseconds Jan 21 18:39:21 volumio-soundbox volumio[983]: info: sendMpdCommand status took 21 milliseconds Jan 21 18:39:21 volumio-soundbox volumio[983]: info: sendMpdCommand status took 20 milliseconds Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseState Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseTrackInfo Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseTrackInfo Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseTrackInfo Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseState Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseState Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 18:39:21 volumio-soundbox volumio[983]: info: ControllerMpd::pushState Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mid?aggregator=tunein","artist":null,"album":null,"uri":"http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein","trackType":""} Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: CURRENT POSITION 0 Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState stateService play Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState currentStatus stop Jan 21 18:39:21 volumio-soundbox volumio[983]: info: ControllerMpd::pushState Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mid?aggregator=tunein","artist":null,"album":null,"uri":"http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein","trackType":""} Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: CURRENT POSITION 0 Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState stateService play Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState currentStatus play Jan 21 18:39:21 volumio-soundbox volumio[983]: info: Received an update from plugin. extracting info from payload Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:21 volumio-soundbox volumio[983]: info: ControllerMpd::pushState Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mid?aggregator=tunein","artist":null,"album":null,"uri":"http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein","trackType":""} Jan 21 18:39:21 volumio-soundbox volumio[983]: verbose: CURRENT POSITION 0 Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState stateService play Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState currentStatus play Jan 21 18:39:21 volumio-soundbox volumio[983]: info: Received an update from plugin. extracting info from payload Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:21 volumio-soundbox volumio[983]: info: ------------------------------ 119ms Jan 21 18:39:21 volumio-soundbox volumio[983]: info: ------------------------------ 164ms Jan 21 18:39:21 volumio-soundbox volumio[983]: info: ------------------------------ 164ms Jan 21 18:39:21 volumio-soundbox volumio[983]: error: Cannot start Volumio Streaming Daemon Jan 21 18:39:21 volumio-soundbox volumio[983]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 21 18:39:21 volumio-soundbox volumio[983]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 21 18:39:21 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 21 18:39:21 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 21 18:39:21 volumio-soundbox volumio[983]: info: [1737481161962] Starting BluetoothController Jan 21 18:39:22 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 21 18:39:24 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jan 21 18:39:24 volumio-soundbox sudo[1871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 21 18:39:24 volumio-soundbox sudo[1871]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:24 volumio-soundbox sudo[1871]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:24 volumio-soundbox volumio[983]: info: MRS: MultiRoom plugin initialized Jan 21 18:39:24 volumio-soundbox volumio[983]: info: MRS: STOPPING SNAPCLIENT Jan 21 18:39:24 volumio-soundbox volumio[983]: info: MRS: Snap server stop Jan 21 18:39:24 volumio-soundbox sudo[1887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 21 18:39:24 volumio-soundbox volumio[983]: info: MRS: STOPPING volumioStreaming Jan 21 18:39:24 volumio-soundbox sudo[1887]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:24 volumio-soundbox sudo[1889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 21 18:39:24 volumio-soundbox sudo[1889]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:24 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 21 18:39:24 volumio-soundbox sudo[1895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 21 18:39:24 volumio-soundbox sudo[1895]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:24 volumio-soundbox sudo[1892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 21 18:39:24 volumio-soundbox sudo[1895]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:24 volumio-soundbox sudo[1892]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:24 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 21 18:39:24 volumio-soundbox sudo[1887]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:24 volumio-soundbox sudo[1889]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:24 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 21 18:39:24 volumio-soundbox sudo[1892]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:24 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 21 18:39:24 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 21 18:39:24 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jan 21 18:39:24 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 21 18:39:24 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 21 18:39:25 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Preparing to generate the ALSA configuration file Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Jan 21 18:39:25 volumio-soundbox volumio[983]: ---------------------------- MPD announces system playlist update Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Ignoring MPD Status Update Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Jan 21 18:39:25 volumio-soundbox volumio[983]: ---------------------------- MPD announces state update: player Jan 21 18:39:25 volumio-soundbox volumio[983]: info: sendMpdCommand playlistinfo took 3695 milliseconds Jan 21 18:39:25 volumio-soundbox volumio[983]: info: sendMpdCommand playlistinfo took 3689 milliseconds Jan 21 18:39:25 volumio-soundbox volumio[983]: info: sendMpdCommand playlistinfo took 3686 milliseconds Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ControllerMpd::getState Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand status Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseTrackInfo Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseTrackInfo Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseTrackInfo Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ControllerMpd::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mid?aggregator=tunein","artist":null,"album":null,"uri":"http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein","trackType":""} Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: CURRENT POSITION 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState stateService play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState currentStatus play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Received an update from plugin. extracting info from payload Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ControllerMpd::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mid?aggregator=tunein","artist":null,"album":null,"uri":"http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein","trackType":""} Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: CURRENT POSITION 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState stateService play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState currentStatus play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Received an update from plugin. extracting info from payload Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ControllerMpd::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mid?aggregator=tunein","artist":null,"album":null,"uri":"http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein","trackType":""} Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: CURRENT POSITION 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState stateService play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState currentStatus play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Received an update from plugin. extracting info from payload Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ------------------------------ 3827ms Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ------------------------------ 3816ms Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ------------------------------ 3815ms Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Setting Geolocation for MyVolumio to eu4 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Updating MyVolumio device info Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 18:39:25 volumio-soundbox volumio[983]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Reading ALSA contributions from plugins. Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Jan 21 18:39:25 volumio-soundbox volumio[983]: ---------------------------- MPD announces system playlist update Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Ignoring MPD Status Update Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Jan 21 18:39:25 volumio-soundbox volumio[983]: ---------------------------- MPD announces state update: player Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ControllerMpd::getState Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand status Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ------------------------------ 282ms Jan 21 18:39:25 volumio-soundbox volumio[983]: info: sendMpdCommand status took 276 milliseconds Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseState Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Jan 21 18:39:25 volumio-soundbox volumio[983]: ---------------------------- MPD announces system playlist update Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Ignoring MPD Status Update Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Jan 21 18:39:25 volumio-soundbox volumio[983]: ---------------------------- MPD announces state update: player Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ControllerMpd::getState Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand status Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Jan 21 18:39:25 volumio-soundbox volumio[983]: ---------------------------- MPD announces system playlist update Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Ignoring MPD Status Update Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Jan 21 18:39:25 volumio-soundbox volumio[983]: ---------------------------- MPD announces state update: player Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ControllerMpd::getState Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand status Jan 21 18:39:25 volumio-soundbox volumio[983]: Upnp client error: Error: This socket has been ended by the other party Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jan 21 18:39:25 volumio-soundbox volumio[983]: 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: 14 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: MRS: Removed streaming files Jan 21 18:39:25 volumio-soundbox volumio[983]: info: MRS: volumioStreaming STOPPED Jan 21 18:39:25 volumio-soundbox volumio[983]: info: MRS: SNAPSERVER STOPPED Jan 21 18:39:25 volumio-soundbox volumio[983]: info: MRS: SNAPCLIENT STOPPED Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ------------------------------ 160ms Jan 21 18:39:25 volumio-soundbox volumio[983]: info: sendMpdCommand status took 156 milliseconds Jan 21 18:39:25 volumio-soundbox volumio[983]: info: sendMpdCommand playlistinfo took 152 milliseconds Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ------------------------------ 146ms Jan 21 18:39:25 volumio-soundbox volumio[983]: info: sendMpdCommand status took 142 milliseconds Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ------------------------------ 140ms Jan 21 18:39:25 volumio-soundbox volumio[983]: info: sendMpdCommand status took 137 milliseconds Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseState Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseTrackInfo Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseState Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseState Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ControllerMpd::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4361,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radioeins vom rbb","artist":"RadioEins","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein","trackType":""} Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: CURRENT POSITION 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState stateService play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState currentStatus play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Received an update from plugin. extracting info from payload Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ------------------------------ 563ms Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Jan 21 18:39:25 volumio-soundbox volumio[983]: ---------------------------- MPD announces system playlist update Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Ignoring MPD Status Update Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Jan 21 18:39:25 volumio-soundbox volumio[983]: ---------------------------- MPD announces state update: player Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ControllerMpd::getState Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand status Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Jan 21 18:39:25 volumio-soundbox volumio[983]: ---------------------------- MPD announces system playlist update Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Ignoring MPD Status Update Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Jan 21 18:39:25 volumio-soundbox volumio[983]: ---------------------------- MPD announces state update: player Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ControllerMpd::getState Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand status Jan 21 18:39:25 volumio-soundbox volumio[983]: info: sendMpdCommand playlistinfo took 198 milliseconds Jan 21 18:39:25 volumio-soundbox volumio[983]: info: sendMpdCommand playlistinfo took 192 milliseconds Jan 21 18:39:25 volumio-soundbox volumio[983]: info: sendMpdCommand playlistinfo took 192 milliseconds Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ------------------------------ 76ms Jan 21 18:39:25 volumio-soundbox volumio[983]: info: sendMpdCommand status took 74 milliseconds Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ------------------------------ 74ms Jan 21 18:39:25 volumio-soundbox volumio[983]: info: sendMpdCommand status took 73 milliseconds Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseTrackInfo Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseTrackInfo Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseTrackInfo Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseState Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseState Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ControllerMpd::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4729,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radioeins vom rbb","artist":"RadioEins","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein","trackType":""} Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: CURRENT POSITION 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState stateService play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState currentStatus play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Received an update from plugin. extracting info from payload Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ControllerMpd::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4729,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radioeins vom rbb","artist":"RadioEins","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein","trackType":""} Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: CURRENT POSITION 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState stateService play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState currentStatus play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Received an update from plugin. extracting info from payload Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ControllerMpd::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4729,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radioeins vom rbb","artist":"RadioEins","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein","trackType":""} Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: CURRENT POSITION 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState stateService play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState currentStatus play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Received an update from plugin. extracting info from payload Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ------------------------------ 473ms Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ------------------------------ 457ms Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ------------------------------ 453ms Jan 21 18:39:25 volumio-soundbox volumio[983]: info: sendMpdCommand playlistinfo took 164 milliseconds Jan 21 18:39:25 volumio-soundbox volumio[983]: info: sendMpdCommand playlistinfo took 163 milliseconds Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseTrackInfo Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseTrackInfo Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ControllerMpd::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4981,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radioeins vom rbb","artist":"RadioEins","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein","trackType":""} Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: CURRENT POSITION 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState stateService play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState currentStatus play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Received an update from plugin. extracting info from payload Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ControllerMpd::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4981,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radioeins vom rbb","artist":"RadioEins","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein","trackType":""} Jan 21 18:39:25 volumio-soundbox volumio[983]: verbose: CURRENT POSITION 0 Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState stateService play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState currentStatus play Jan 21 18:39:25 volumio-soundbox volumio[983]: info: Received an update from plugin. extracting info from payload Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ------------------------------ 305ms Jan 21 18:39:25 volumio-soundbox volumio[983]: info: ------------------------------ 302ms Jan 21 18:39:26 volumio-soundbox volumio[983]: info: Successfully Added MyVolumio device Jan 21 18:39:26 volumio-soundbox volumio[983]: info: Successfully Added MyVolumio device Jan 21 18:39:26 volumio-soundbox volumio[983]: info: Updating MyVolumio device info Jan 21 18:39:26 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 18:39:26 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 18:39:26 volumio-soundbox volumio[983]: info: Successfully Updated MyVolumio device Jan 21 18:39:26 volumio-soundbox volumio[983]: info: Asound.conf file written Jan 21 18:39:26 volumio-soundbox sudo[1903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 21 18:39:26 volumio-soundbox sudo[1903]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:26 volumio-soundbox sudo[1906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 21 18:39:26 volumio-soundbox sudo[1906]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:26 volumio-soundbox sudo[1906]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:26 volumio-soundbox systemd[1]: Started MyVolumio SSH Tunnel. Jan 21 18:39:26 volumio-soundbox sudo[1903]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:26 volumio-soundbox autossh[1911]: port set to 0, monitoring disabled Jan 21 18:39:26 volumio-soundbox autossh[1911]: starting ssh (count 1) Jan 21 18:39:26 volumio-soundbox autossh[1911]: ssh child pid is 1914 Jan 21 18:39:27 volumio-soundbox volumio[983]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Jan 21 18:39:27 volumio-soundbox volumio[983]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Jan 21 18:39:27 volumio-soundbox volumio[983]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Output device has changed, restarting MPD Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Output device has changed, restarting Shairport Sync Jan 21 18:39:27 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 18:39:27 volumio-soundbox sudo[1916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 21 18:39:27 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 18:39:27 volumio-soundbox sudo[1916]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:27 volumio-soundbox sudo[1916]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:27 volumio-soundbox sudo[1918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 21 18:39:27 volumio-soundbox sudo[1918]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:27 volumio-soundbox systemd[1]: Stopping Music Player Daemon... Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 18:39:27 volumio-soundbox volumio[983]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 21 18:39:27 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 18:39:27 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 21 18:39:27 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 18:39:27 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jan 21 18:39:27 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 21 18:39:27 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output for this device Jan 21 18:39:27 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding audio output: Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding audio output: Jan 21 18:39:27 volumio-soundbox sudo[1927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio-Soundbox Jan 21 18:39:27 volumio-soundbox sudo[1927]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:27 volumio-soundbox volumio[983]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 21 18:39:27 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 18:39:27 volumio-soundbox sudo[1927]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:27 volumio-soundbox kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jan 21 18:39:27 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding METAVOLUMIO REST API Endpoints Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 21 18:39:27 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 21 18:39:27 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Preparing CD Folders Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding CD REST API Endpoints Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Starting UDEV Watcher for CD Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Detecting CD presence with UDEV Jan 21 18:39:27 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jan 21 18:39:27 volumio-soundbox kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jan 21 18:39:27 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding inputs REST Endpoints Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Scanning Audio Inputs Jan 21 18:39:27 volumio-soundbox systemd[1]: mpd.service: Succeeded. Jan 21 18:39:27 volumio-soundbox systemd[1]: Stopped Music Player Daemon. Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Checking against Known Cards name Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding Server instance for streaming Jan 21 18:39:27 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 21 18:39:27 volumio-soundbox volumio[983]: error: Hi Res Audio Failed Login: Missing Login Data Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 21 18:39:27 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 21 18:39:27 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 21 18:39:27 volumio-soundbox volumio[983]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 21 18:39:27 volumio-soundbox systemd[1]: Starting Music Player Daemon... Jan 21 18:39:27 volumio-soundbox sudo[1936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 21 18:39:27 volumio-soundbox sudo[1936]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Stopping AccessToken refresher cron Jan 21 18:39:27 volumio-soundbox sudo[1936]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:27 volumio-soundbox volumio[983]: info: AccessToken refresher cron started Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Stopping AccessToken refresher cron for QOBUZ Jan 21 18:39:27 volumio-soundbox volumio[983]: info: AccessToken refresher cron started for QOBUZ Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding TIDAL REST API Endpoints Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding QOBUZ REST API Endpoints Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 21 18:39:27 volumio-soundbox sudo[1934]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 21 18:39:27 volumio-soundbox sudo[1934]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:27 volumio-soundbox sudo[1934]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Bluetooth name changed to Volumio-Soundbox Jan 21 18:39:27 volumio-soundbox volumio[983]: info: MPD Permissions set Jan 21 18:39:27 volumio-soundbox volumio[983]: info: Remote SSH Started Jan 21 18:39:28 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 18:39:28 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 18:39:28 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 18:39:28 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 18:39:28 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 18:39:28 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 18:39:28 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 18:39:28 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 18:39:28 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 18:39:28 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 18:39:28 volumio-soundbox volumio[983]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 21 18:39:28 volumio-soundbox volumio[983]: info: Starting Shairport Sync Jan 21 18:39:28 volumio-soundbox sudo[1948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 18:39:28 volumio-soundbox sudo[1948]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:28 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:28 volumio-soundbox systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 21 18:39:28 volumio-soundbox systemd[1]: shairport-sync.service: Succeeded. Jan 21 18:39:28 volumio-soundbox systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 21 18:39:28 volumio-soundbox systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 21 18:39:28 volumio-soundbox sudo[1948]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:28 volumio-soundbox volumio[983]: info: Shairport-Sync Started Jan 21 18:39:28 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 18:39:28 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 18:39:28 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 18:39:29 volumio-soundbox volumio[983]: 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: 8 Jan 21 18:39:29 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 21 18:39:29 volumio-soundbox volumio-remote-updater[638]: No test mode Jan 21 18:39:29 volumio-soundbox volumio-remote-updater[638]: No alpha test mode Jan 21 18:39:29 volumio-soundbox volumio[983]: info: Successfully Updated MyVolumio device Jan 21 18:39:29 volumio-soundbox volumio[983]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 21 18:39:29 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 21 18:39:29 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 18:39:30 volumio-soundbox systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 21 18:39:30 volumio-soundbox systemd[1]: mpd.service: Failed with result 'protocol'. Jan 21 18:39:30 volumio-soundbox systemd[1]: Failed to start Music Player Daemon. Jan 21 18:39:30 volumio-soundbox sudo[1918]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:30 volumio-soundbox volumio[983]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jan 21 18:39:30 volumio-soundbox volumio[983]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Jan 21 18:39:30 volumio-soundbox volumio[983]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetVisibleSources Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 21 18:39:30 volumio-soundbox volumio[983]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 21 18:39:30 volumio-soundbox volumio[983]: info: Received Get System Info Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 18:39:30 volumio-soundbox volumio[983]: info: Discovery: Getting this device information Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:30 volumio-soundbox systemd[1]: Starting Music Player Daemon... Jan 21 18:39:30 volumio-soundbox volumio[983]: info: Listing playlists Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 21 18:39:30 volumio-soundbox volumio[983]: info: Received Get System Info Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 18:39:30 volumio-soundbox volumio[983]: info: Discovery: Getting this device information Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 21 18:39:30 volumio-soundbox sudo[1958]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 21 18:39:30 volumio-soundbox sudo[1958]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:30 volumio-soundbox sudo[1958]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:30 volumio-soundbox sudo[1961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 21 18:39:30 volumio-soundbox sudo[1961]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:30 volumio-soundbox systemd[1]: Started Volumio Bluetooth Module. Jan 21 18:39:30 volumio-soundbox sudo[1961]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:30 volumio-soundbox volumio[983]: info: Volumio BT Module successfully started Jan 21 18:39:30 volumio-soundbox sudo[1965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jan 21 18:39:30 volumio-soundbox sudo[1965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:30 volumio-soundbox volumiobt[1964]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jan 21 18:39:30 volumio-soundbox sudo[1965]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 21 18:39:30 volumio-soundbox volumio[983]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 21 18:39:30 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:30 volumio-soundbox volumio[983]: info: TidalConnect service stoped! Jan 21 18:39:30 volumio-soundbox volumio[983]: error: Upnp client error: Error: This socket has been ended by the other party Jan 21 18:39:30 volumio-soundbox volumio[983]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 21 18:39:30 volumio-soundbox volumio[983]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 21 18:39:30 volumio-soundbox sudo[1977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 21 18:39:30 volumio-soundbox sudo[1977]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:30 volumio-soundbox systemd[1]: Started Volumio Tidal Connect Service. Jan 21 18:39:30 volumio-soundbox sudo[1977]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:31 volumio-soundbox volumio[983]: info: Executing endpoint tc_getconfig Jan 21 18:39:31 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 21 18:39:31 volumio-soundbox vtcs[1979]: STARTING TidalConnect services, version: 1.4.0.34 Jan 21 18:39:31 volumio-soundbox sudo[1983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jan 21 18:39:31 volumio-soundbox sudo[1983]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 18:39:31 volumio-soundbox vtcs[1979]: STARTED TidalConnect services. Jan 21 18:39:31 volumio-soundbox volumio[983]: info: Executing endpoint tc_connect Jan 21 18:39:31 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 21 18:39:31 volumio-soundbox volumiobt[1964]: pulseaudio: no process found Jan 21 18:39:31 volumio-soundbox volumio[983]: info: Connecting to TidalConnect Jan 21 18:39:31 volumio-soundbox sudo[1983]: pam_unix(sudo:session): session closed for user root Jan 21 18:39:31 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:39:31 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:31 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 18:39:31 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:31 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output update for this device Jan 21 18:39:31 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output Jan 21 18:39:31 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:31 volumio-soundbox volumio[983]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Jan 21 18:39:31 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:39:31 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:39:31 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:39:31 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output update for this device Jan 21 18:39:31 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output Jan 21 18:39:31 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:39:31 volumio-soundbox volumio[983]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Jan 21 18:39:31 volumio-soundbox dbus-daemon[629]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.26' (uid=1000 pid=2002 comm="/usr/bin/pulseaudio --high-priority=true ") Jan 21 18:39:31 volumio-soundbox systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jan 21 18:39:31 volumio-soundbox volumio[983]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 21 18:39:31 volumio-soundbox dbus-daemon[629]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jan 21 18:39:31 volumio-soundbox systemd[1]: Started RealtimeKit Scheduling Policy Service. Jan 21 18:39:31 volumio-soundbox rtkit-daemon[2003]: Successfully called chroot. Jan 21 18:39:31 volumio-soundbox rtkit-daemon[2003]: Successfully dropped privileges. Jan 21 18:39:31 volumio-soundbox rtkit-daemon[2003]: Successfully limited resources. Jan 21 18:39:31 volumio-soundbox rtkit-daemon[2003]: Canary thread running. Jan 21 18:39:31 volumio-soundbox rtkit-daemon[2003]: Running. Jan 21 18:39:31 volumio-soundbox rtkit-daemon[2003]: Watchdog thread running. Jan 21 18:39:31 volumio-soundbox dbus-daemon[629]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.27' (uid=0 pid=2003 comm="/usr/lib/rtkit/rtkit-daemon ") Jan 21 18:39:31 volumio-soundbox systemd[1]: Starting Authorization Manager... Jan 21 18:39:31 volumio-soundbox volumio[983]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 21 18:39:31 volumio-soundbox polkitd[2007]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 21 18:39:31 volumio-soundbox dbus-daemon[629]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 21 18:39:31 volumio-soundbox systemd[1]: Started Authorization Manager. Jan 21 18:39:32 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 21 18:39:32 volumio-soundbox volumio[983]: info: Received Get System Info Jan 21 18:39:32 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 18:39:32 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 18:39:32 volumio-soundbox volumio[983]: info: Discovery: Getting this device information Jan 21 18:39:32 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:32 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 18:39:32 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 21 18:39:32 volumio-soundbox mpd[1962]: Jan 21 18:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 21 18:39:32 volumio-soundbox systemd[1]: Started Music Player Daemon. Jan 21 18:39:32 volumio-soundbox volumio[983]: Upnp client error: Error: This socket has been ended by the other party Jan 21 18:39:32 volumio-soundbox volumio[983]: error: updateQueue error: null Jan 21 18:39:32 volumio-soundbox mpd_monitor.sh[620]: MPD restarted due to no mpc output. Jan 21 18:39:32 volumio-soundbox volumio[983]: info: MRS: Getting audio outputs on start Jan 21 18:39:32 volumio-soundbox volumio[983]: info: MRS: Requesting all other devices output Jan 21 18:39:32 volumio-soundbox pulseaudio[2002]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 21 18:39:32 volumio-soundbox pulseaudio[2002]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: Applying permissions Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: Setting BT discoverable and pairable Jan 21 18:39:32 volumio-soundbox pulseaudio[2002]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jan 21 18:39:32 volumio-soundbox bluetoothd[782]: Endpoint registered: sender=:1.47 path=/MediaEndpoint/A2DPSource Jan 21 18:39:32 volumio-soundbox bluetoothd[782]: Endpoint registered: sender=:1.47 path=/MediaEndpoint/A2DPSink Jan 21 18:39:32 volumio-soundbox kernel: Bluetooth: RFCOMM TTY layer initialized Jan 21 18:39:32 volumio-soundbox kernel: Bluetooth: RFCOMM socket layer initialized Jan 21 18:39:32 volumio-soundbox kernel: Bluetooth: RFCOMM ver 1.11 Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [CHG] Controller B8:27:EB:1A:D6:F3 UUIDs: 00001112-0000-1000-8000-00805f9b34fb Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [117B blob data] Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [117B blob data] Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [117B blob data] Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [117B blob data] Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [117B blob data] Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [117B blob data] Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [117B blob data] Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [117B blob data] Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [55B blob data] Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [bluetooth]# -e power on Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: Invalid command in menu main: -e Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: Use "help" for a list of available commands in a menu. Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: Use "menu " if you want to enter any submenu. Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: Use "back" if you want to return to menu main. Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [bluetooth]# agent on Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: Agent is already registered Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [bluetooth]# discoverable on Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [bluetooth]# pairable on Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [bluetooth]# agent NoInputNoOutput Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: Agent is already registered Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [bluetooth]# default-agent Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [bluetooth]# quit Jan 21 18:39:32 volumio-soundbox volumiobt[1964]: [59B blob data] Jan 21 18:39:33 volumio-soundbox volumio[983]: info: TidalConnect service started! Jan 21 18:39:33 volumio-soundbox volumio[983]: [Metrics] CommandRouter: 45s 323.89ms Jan 21 18:39:33 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumiosetStartupVolume Jan 21 18:39:33 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 18:39:33 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 18:39:33 volumio-soundbox volumio[983]: info: CoreCommandRouter::Close All Modals sent Jan 21 18:39:33 volumio-soundbox volumio[983]: info: CoreCommandRouter::Close All Modals sent Jan 21 18:39:34 volumio-soundbox kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jan 21 18:39:34 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 21 18:39:34 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 21 18:39:34 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 21 18:39:35 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 18:39:35 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 18:39:35 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 21 18:39:35 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 21 18:39:35 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 21 18:39:35 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 21 18:39:35 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 18:39:35 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 18:39:38 volumio-soundbox ntpd[798]: Soliciting pool server 185.21.224.3 Jan 21 18:39:39 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 21 18:39:39 volumio-soundbox ntpd[798]: Soliciting pool server 162.159.200.1 Jan 21 18:39:40 volumio-soundbox ntpd[798]: Soliciting pool server 129.250.35.250 Jan 21 18:39:40 volumio-soundbox ntpd[798]: Soliciting pool server 188.174.253.188 Jan 21 18:39:40 volumio-soundbox volumio[983]: info: BOOT COMPLETED Jan 21 18:39:41 volumio-soundbox ntpd[798]: Soliciting pool server 185.252.140.126 Jan 21 18:39:41 volumio-soundbox ntpd[798]: Soliciting pool server 178.63.52.50 Jan 21 18:39:42 volumio-soundbox ntpd[798]: Soliciting pool server 162.159.200.123 Jan 21 18:39:42 volumio-soundbox ntpd[798]: Soliciting pool server 136.243.7.20 Jan 21 18:39:43 volumio-soundbox ntpd[798]: Soliciting pool server 144.91.126.59 Jan 21 18:39:43 volumio-soundbox ntpd[798]: Soliciting pool server 116.202.14.29 Jan 21 18:39:44 volumio-soundbox ntpd[798]: Soliciting pool server 167.235.139.237 Jan 21 18:39:44 volumio-soundbox ntpd[798]: Soliciting pool server 185.248.188.98 Jan 21 18:39:45 volumio-soundbox ntpd[798]: Soliciting pool server 158.220.97.17 Jan 21 18:39:45 volumio-soundbox ntpd[798]: Soliciting pool server 88.198.7.62 Jan 21 18:39:46 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 21 18:39:46 volumio-soundbox volumio[983]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 21 18:39:46 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 21 18:39:46 volumio-soundbox volumio[983]: info: Received Get System Version Jan 21 18:39:46 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 18:39:46 volumio-soundbox volumio[983]: info: Received Get System Info Jan 21 18:39:46 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 18:39:46 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 18:39:46 volumio-soundbox volumio[983]: info: Discovery: Getting this device information Jan 21 18:39:46 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:46 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 18:39:46 volumio-soundbox ntpd[798]: Soliciting pool server 62.169.23.198 Jan 21 18:39:46 volumio-soundbox ntpd[798]: Soliciting pool server 2a01:239:25e:bd00::1 Jan 21 18:39:47 volumio-soundbox ntpd[798]: receive: Unexpected origin timestamp 0xeb3a5a62.784716ed does not match aorg 0000000000.00000000 from server@188.174.253.188 xmt 0xeb3a5a63.65f28104 Jan 21 18:39:47 volumio-soundbox ntpd[798]: receive: Unexpected origin timestamp 0xeb3a5a62.784955cd does not match aorg 0000000000.00000000 from server@185.21.224.3 xmt 0xeb3a5a63.66f89e23 Jan 21 18:39:47 volumio-soundbox ntpd[798]: receive: Unexpected origin timestamp 0xeb3a5a62.78407e49 does not match aorg 0000000000.00000000 from server@158.220.97.17 xmt 0xeb3a5a63.6731fe7f Jan 21 18:39:47 volumio-soundbox ntpd[798]: receive: Unexpected origin timestamp 0xeb3a5a62.7844ca7c does not match aorg 0000000000.00000000 from server@136.243.7.20 xmt 0xeb3a5a63.66ec18c8 Jan 21 18:39:47 volumio-soundbox ntpd[798]: receive: Unexpected origin timestamp 0xeb3a5a62.7838a926 does not match aorg 0000000000.00000000 from server@88.198.7.62 xmt 0xeb3a5a63.66f23f2e Jan 21 18:39:47 volumio-soundbox ntpd[798]: receive: Unexpected origin timestamp 0xeb3a5a62.7845fb1a does not match aorg 0000000000.00000000 from server@185.252.140.126 xmt 0xeb3a5a63.672fed78 Jan 21 18:39:47 volumio-soundbox ntpd[798]: receive: Unexpected origin timestamp 0xeb3a5a62.78436278 does not match aorg 0000000000.00000000 from server@116.202.14.29 xmt 0xeb3a5a63.67712ddb Jan 21 18:39:48 volumio-soundbox ntpd[798]: Soliciting pool server 94.130.35.4 Jan 21 18:39:54 volumio-soundbox volumiossh-tunnel[1908]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Jan 21 18:39:56 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 18:39:56 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 18:39:56 volumio-soundbox volumio[983]: info: Discovery: Getting this device information Jan 21 18:39:56 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:56 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 18:39:56 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 18:39:56 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 18:39:56 volumio-soundbox volumio[983]: info: Discovery: Getting this device information Jan 21 18:39:56 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:56 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 18:39:56 volumio-soundbox volumio[983]: verbose: New Socket.io Connection to 192.168.178.31:3000 from 192.168.178.132 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jan 21 18:39:58 volumio-soundbox volumio[983]: verbose: New Socket.io Connection to 192.168.178.31 from 192.168.178.132 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_1_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Jan 21 18:39:59 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 18:39:59 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetVisibleSources Jan 21 18:39:59 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 18:39:59 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:59 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 21 18:39:59 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 21 18:39:59 volumio-soundbox volumio[983]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 21 18:39:59 volumio-soundbox volumio[983]: info: Received Get System Info Jan 21 18:39:59 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 18:39:59 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 18:39:59 volumio-soundbox volumio[983]: info: Discovery: Getting this device information Jan 21 18:39:59 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:59 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 18:39:59 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetState Jan 21 18:39:59 volumio-soundbox volumio[983]: info: Listing playlists Jan 21 18:39:59 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetQueue Jan 21 18:39:59 volumio-soundbox volumio[983]: info: CoreStateMachine::getQueue Jan 21 18:39:59 volumio-soundbox volumio[983]: info: CorePlayQueue::getQueue Jan 21 18:39:59 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 21 18:40:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioStop Jan 21 18:40:00 volumio-soundbox volumio[983]: info: CoreStateMachine::stop Jan 21 18:40:00 volumio-soundbox volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 18:40:00 volumio-soundbox volumio[983]: info: CoreStateMachine::stPlaybackTimer Jan 21 18:40:00 volumio-soundbox volumio[983]: info: CoreStateMachine::updateTrackBlock Jan 21 18:40:00 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrackBlock Jan 21 18:40:00 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:40:00 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:40:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 18:40:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:40:00 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output update for this device Jan 21 18:40:00 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output Jan 21 18:40:00 volumio-soundbox volumio[983]: info: CoreStateMachine::serviceStop Jan 21 18:40:00 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:40:00 volumio-soundbox volumio[983]: info: CoreCommandRouter::serviceStop Jan 21 18:40:00 volumio-soundbox volumio[983]: info: [1737481200526] ControllerWebradio::stop Jan 21 18:40:00 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand stop Jan 21 18:40:00 volumio-soundbox volumio[983]: info: sendMpdCommand stop took 57 milliseconds Jan 21 18:40:00 volumio-soundbox volumio[983]: info: touch_display: Setting screensaver timeout to 120 seconds. Jan 21 18:40:01 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPlay Jan 21 18:40:01 volumio-soundbox volumio[983]: info: CoreStateMachine::play index undefined Jan 21 18:40:01 volumio-soundbox volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 18:40:01 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:40:01 volumio-soundbox volumio[983]: info: CoreStateMachine::startPlaybackTimer Jan 21 18:40:01 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:40:01 volumio-soundbox volumio[983]: info: [1737481201487] ControllerWebradio::clearAddPlayTrack Jan 21 18:40:01 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand stop Jan 21 18:40:01 volumio-soundbox volumio[983]: info: sendMpdCommand stop took 1 milliseconds Jan 21 18:40:01 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand clear Jan 21 18:40:01 volumio-soundbox volumio[983]: info: Jan 21 18:40:01 volumio-soundbox volumio[983]: ---------------------------- MPD announces system playlist update Jan 21 18:40:01 volumio-soundbox volumio[983]: info: Ignoring MPD Status Update Jan 21 18:40:01 volumio-soundbox volumio[983]: info: sendMpdCommand clear took 1 milliseconds Jan 21 18:40:01 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand load "http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein" Jan 21 18:40:01 volumio-soundbox volumio[983]: error: updateQueue error: null Jan 21 18:40:01 volumio-soundbox volumio[983]: info: ------------------------------ 3ms Jan 21 18:40:03 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand add "http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein" Jan 21 18:40:03 volumio-soundbox volumio[983]: info: Jan 21 18:40:03 volumio-soundbox volumio[983]: ---------------------------- MPD announces system playlist update Jan 21 18:40:03 volumio-soundbox volumio[983]: info: Ignoring MPD Status Update Jan 21 18:40:03 volumio-soundbox volumio[983]: info: sendMpdCommand add "http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein" took 3 milliseconds Jan 21 18:40:03 volumio-soundbox volumio[983]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 21 18:40:03 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand play Jan 21 18:40:03 volumio-soundbox volumio[983]: info: ------------------------------ 4ms Jan 21 18:40:03 volumio-soundbox volumio[983]: info: sendMpdCommand play took 2 milliseconds Jan 21 18:40:04 volumio-soundbox volumio[983]: info: Jan 21 18:40:04 volumio-soundbox volumio[983]: ---------------------------- MPD announces state update: player Jan 21 18:40:04 volumio-soundbox volumio[983]: info: ControllerMpd::getState Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand status Jan 21 18:40:04 volumio-soundbox kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jan 21 18:40:04 volumio-soundbox volumio[983]: info: Jan 21 18:40:04 volumio-soundbox volumio[983]: ---------------------------- MPD announces state update: player Jan 21 18:40:04 volumio-soundbox volumio[983]: info: sendMpdCommand status took 33 milliseconds Jan 21 18:40:04 volumio-soundbox volumio[983]: info: ControllerMpd::getState Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand status Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseState Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 18:40:04 volumio-soundbox volumio[983]: info: sendMpdCommand status took 2 milliseconds Jan 21 18:40:04 volumio-soundbox volumio[983]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseState Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseTrackInfo Jan 21 18:40:04 volumio-soundbox volumio[983]: info: ControllerMpd::pushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mid?aggregator=tunein","artist":null,"album":null,"uri":"http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein","trackType":""} Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: CURRENT POSITION 0 Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState stateService play Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState currentStatus stop Jan 21 18:40:04 volumio-soundbox volumio[983]: info: ------------------------------ 41ms Jan 21 18:40:04 volumio-soundbox volumio[983]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseTrackInfo Jan 21 18:40:04 volumio-soundbox volumio[983]: info: ControllerMpd::pushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mid?aggregator=tunein","artist":null,"album":null,"uri":"http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein","trackType":""} Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: CURRENT POSITION 0 Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState stateService play Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState currentStatus play Jan 21 18:40:04 volumio-soundbox volumio[983]: info: Received an update from plugin. extracting info from payload Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output update for this device Jan 21 18:40:04 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output update for this device Jan 21 18:40:04 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output Jan 21 18:40:04 volumio-soundbox volumio[983]: info: ------------------------------ 100ms Jan 21 18:40:04 volumio-soundbox volumio[983]: info: Jan 21 18:40:04 volumio-soundbox volumio[983]: ---------------------------- MPD announces system playlist update Jan 21 18:40:04 volumio-soundbox volumio[983]: info: Ignoring MPD Status Update Jan 21 18:40:04 volumio-soundbox volumio[983]: info: Jan 21 18:40:04 volumio-soundbox volumio[983]: ---------------------------- MPD announces state update: player Jan 21 18:40:04 volumio-soundbox volumio[983]: info: ControllerMpd::getState Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand status Jan 21 18:40:04 volumio-soundbox volumio[983]: info: Jan 21 18:40:04 volumio-soundbox volumio[983]: ---------------------------- MPD announces system playlist update Jan 21 18:40:04 volumio-soundbox volumio[983]: info: Ignoring MPD Status Update Jan 21 18:40:04 volumio-soundbox volumio[983]: info: Jan 21 18:40:04 volumio-soundbox volumio[983]: ---------------------------- MPD announces state update: player Jan 21 18:40:04 volumio-soundbox volumio[983]: info: ControllerMpd::getState Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand status Jan 21 18:40:04 volumio-soundbox volumio[983]: info: ------------------------------ 10ms Jan 21 18:40:04 volumio-soundbox volumio[983]: info: sendMpdCommand status took 6 milliseconds Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseState Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 18:40:04 volumio-soundbox volumio[983]: info: ------------------------------ 8ms Jan 21 18:40:04 volumio-soundbox volumio[983]: info: sendMpdCommand status took 4 milliseconds Jan 21 18:40:04 volumio-soundbox volumio[983]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseState Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseTrackInfo Jan 21 18:40:04 volumio-soundbox volumio[983]: info: ControllerMpd::pushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1138,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radioeins vom rbb","artist":"RadioEins","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein","trackType":""} Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: CURRENT POSITION 0 Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState stateService play Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState currentStatus play Jan 21 18:40:04 volumio-soundbox volumio[983]: info: Received an update from plugin. extracting info from payload Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output update for this device Jan 21 18:40:04 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output update for this device Jan 21 18:40:04 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output Jan 21 18:40:04 volumio-soundbox volumio[983]: info: ------------------------------ 86ms Jan 21 18:40:04 volumio-soundbox volumio[983]: info: sendMpdCommand playlistinfo took 75 milliseconds Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: ControllerMpd::parseTrackInfo Jan 21 18:40:04 volumio-soundbox volumio[983]: info: ControllerMpd::pushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreCommandRouter::servicePushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CorePlayQueue::getTrack 0 Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1211,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radioeins vom rbb","artist":"RadioEins","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/radioeins/live/mp3/mid?aggregator=tunein","trackType":""} Jan 21 18:40:04 volumio-soundbox volumio[983]: verbose: CURRENT POSITION 0 Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState stateService play Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreStateMachine::syncState currentStatus play Jan 21 18:40:04 volumio-soundbox volumio[983]: info: Received an update from plugin. extracting info from payload Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output update for this device Jan 21 18:40:04 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreStateMachine::pushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioPushState Jan 21 18:40:04 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output update for this device Jan 21 18:40:04 volumio-soundbox volumio[983]: info: MRS: Pushing multiroomSync output Jan 21 18:40:04 volumio-soundbox volumio[983]: info: ------------------------------ 172ms Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 18:40:10 volumio-soundbox volumio[983]: info: Retrieving Cloud Streaming UI Jan 21 18:40:10 volumio-soundbox volumio[983]: info: Getting Tidal Cloud Configuration Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 18:40:10 volumio-soundbox volumio[983]: info: Getting Qobuz Cloud Configuration Jan 21 18:40:10 volumio-soundbox volumio[983]: info: Asking plugin for UI Config Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 18:40:10 volumio-soundbox volumio[983]: info: Getting Spotify Cloud Configuration Jan 21 18:40:10 volumio-soundbox volumio[983]: info: Asking plugin for UI Config Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 18:40:10 volumio-soundbox volumio[983]: info: Saving Spotify Acccount Jan 21 18:40:10 volumio-soundbox volumio[983]: info: Got it Jan 21 18:40:10 volumio-soundbox volumio[983]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 21 18:40:10 volumio-soundbox volumio[983]: info: Got Tidal Cloud Configuration Jan 21 18:40:10 volumio-soundbox volumio[983]: info: Got it Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 18:40:10 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 21 18:40:14 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 18:40:14 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jan 21 18:40:14 volumio-soundbox volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jan 21 18:40:15 volumio-soundbox volumio[983]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 18:40:15 volumio-soundbox volumio[983]: Error: dns service error: unknown Jan 21 18:40:15 volumio-soundbox volumio[983]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jan 21 18:40:15 volumio-soundbox volumio[983]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jan 21 18:40:15 volumio-soundbox volumio[983]: errorCode: -65537 Jan 21 18:40:15 volumio-soundbox volumio[983]: } Jan 21 18:40:15 volumio-soundbox volumio[983]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 18:40:15 volumio-soundbox sudo[2168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-21 18:39 Jan 21 18:40:15 volumio-soundbox sudo[2168]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"