-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-01-12 14:37:15 CET. -- Jan 12 14:36:29 rpi5voldisp systemd-timedated[1658]: Changed local time to Sun Jan 12 14:36:29 2025 Jan 12 14:36:29 rpi5voldisp sudo[1650]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:29 rpi5voldisp volumio-time-update[736]: volumio-time-update-util: System time updated successfully. Jan 12 14:36:29 rpi5voldisp sudo[1659]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 14:36:29 rpi5voldisp sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:36:29 rpi5voldisp systemd[1]: Started Volumio Time Update Utility. Jan 12 14:36:29 rpi5voldisp sudo[1659]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: touch_display: X display number found: 0 Jan 12 14:36:29 rpi5voldisp systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Jan 12 14:36:29 rpi5voldisp systemd[1]: Reached target Multi-User System. Jan 12 14:36:29 rpi5voldisp systemd[1]: Reached target Graphical Interface. Jan 12 14:36:29 rpi5voldisp sudo[1661]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:29 rpi5voldisp systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1651]: Notice: accepted new client on /var/run/lirc/lircd Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: ir_controller: systemctl restart irexec.service succeeded. Jan 12 14:36:29 rpi5voldisp lircd[1651]: lircd-0.10.1[1651]: Notice: accepted new client on /var/run/lirc/lircd Jan 12 14:36:29 rpi5voldisp lircd[1651]: lircd-0.10.1[1651]: Info: [lirc] protocol is enabled Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1651]: Info: [lirc] protocol is enabled Jan 12 14:36:29 rpi5voldisp systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 12 14:36:29 rpi5voldisp systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: touch_display: X display number found: 0 Jan 12 14:36:29 rpi5voldisp kernel: hwmon hwmon4: Undervoltage detected! Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: [IrActivityLed] lirc socket connect: attempt 3 of 20 Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: [IrActivityLed] lircd service status: active Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: [IrActivityLed] Connected to lirc socket Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: [IrActivityLed] initializing built-in LED Jan 12 14:36:29 rpi5voldisp lircd[1651]: lircd-0.10.1[1651]: Notice: accepted new client on /var/run/lirc/lircd Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1651]: Notice: accepted new client on /var/run/lirc/lircd Jan 12 14:36:29 rpi5voldisp sudo[1676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /sys/class/leds/led1/brightness /sys/class/leds/led1/trigger Jan 12 14:36:29 rpi5voldisp sudo[1676]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:36:29 rpi5voldisp sudo[1676]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: [IrActivityLed] Error: Command failed: /usr/bin/sudo /bin/chmod a+rw /sys/class/leds/led1/brightness /sys/class/leds/led1/trigger Jan 12 14:36:29 rpi5voldisp volumio[1266]: /bin/chmod: cannot access '/sys/class/leds/led1/brightness': No such file or directory Jan 12 14:36:29 rpi5voldisp volumio[1266]: /bin/chmod: cannot access '/sys/class/leds/led1/trigger': No such file or directory Jan 12 14:36:29 rpi5voldisp mpd[1666]: Jan 12 14:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 12 14:36:29 rpi5voldisp sudo[1679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=15 active_low=1 gpio_pull=up keycode=20 Jan 12 14:36:29 rpi5voldisp sudo[1679]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:36:29 rpi5voldisp kernel: input: button@f as /devices/platform/button@f/input/input11 Jan 12 14:36:29 rpi5voldisp sudo[1679]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:29 rpi5voldisp systemd[1]: Started Music Player Daemon. Jan 12 14:36:29 rpi5voldisp systemd[1]: Startup finished in 10.944s (kernel) + 27.281s (userspace) = 38.225s. Jan 12 14:36:29 rpi5voldisp sudo[1654]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:29 rpi5voldisp volumio[1266]: error: updateQueue error: null Jan 12 14:36:29 rpi5voldisp systemd-udevd[1449]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 12 14:36:29 rpi5voldisp sudo[1693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jan 12 14:36:29 rpi5voldisp sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:36:29 rpi5voldisp lircd[1651]: lircd-0.10.1[1651]: Notice: caught signal Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1651]: Notice: caught signal Jan 12 14:36:29 rpi5voldisp systemd[1]: Stopping Flexible IR remote input/output application support... Jan 12 14:36:29 rpi5voldisp systemd[1]: lircd.service: Main process exited, code=killed, status=15/TERM Jan 12 14:36:29 rpi5voldisp systemd[1]: lircd.service: Succeeded. Jan 12 14:36:29 rpi5voldisp systemd[1]: Stopped Flexible IR remote input/output application support. Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: [IrActivityLed] lirc socket closed Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: [IrActivityLed] lirc socket connect: attempt 1 of 20 Jan 12 14:36:29 rpi5voldisp systemd[1]: irexec.service: Succeeded. Jan 12 14:36:29 rpi5voldisp systemd[1]: Starting lircd(8) initialization helper tool... Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: [IrActivityLed] lircd service is inactive, skipping connection attempt. Error: Command failed: /bin/systemctl is-active lircd Jan 12 14:36:29 rpi5voldisp lircd-setup[1695]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Jan 12 14:36:29 rpi5voldisp lircd-setup[1695]: parser = configparser.SafeConfigParser() Jan 12 14:36:29 rpi5voldisp systemd[1]: lircd-setup.service: Succeeded. Jan 12 14:36:29 rpi5voldisp systemd[1]: Started lircd(8) initialization helper tool. Jan 12 14:36:29 rpi5voldisp systemd[1]: Starting Flexible IR remote input/output application support... Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Info: lircd: Opening log, level: Info Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Version: lircd 0.10.1 Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: System info: Linux rpi5voldisp 6.6.62-v8+ #1816 SMP PREEMPT Mon Nov 18 15:35:52 GMT 2024 aarch64 GNU/Linux Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Info: Initial device: /dev/lirc0 Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Info: [lirc] protocol is enabled Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Info: Initial device: /dev/lirc0 Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Info: [lirc] protocol is enabled Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: driver: default Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: output: /var/run/lirc/lircd Jan 12 14:36:29 rpi5voldisp lircd[1698]: lircd-0.10.1[1698]: Info: lircd: Opening log, level: Info Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: nodaemon: 1 Jan 12 14:36:29 rpi5voldisp lircd[1698]: lircd-0.10.1[1698]: Notice: Using systemd fd Jan 12 14:36:29 rpi5voldisp lircd[1698]: lircd-0.10.1[1698]: Warning: Running as root Jan 12 14:36:29 rpi5voldisp lircd[1698]: lircd-0.10.1[1698]: Info: Using remote: lircd.conf. Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: logfile: syslog Jan 12 14:36:29 rpi5voldisp lircd[1698]: lircd-0.10.1[1698]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: immediate-init: 0 Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: permission: 666 Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: driver-options: Jan 12 14:36:29 rpi5voldisp systemd[1]: Started Flexible IR remote input/output application support. Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: listen: 0 Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: connect: (null) Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: userelease: 0 Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: effective_user: (null) Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: release_suffix: _EVUP Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: allow_simulate: 0 Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: repeat_max: 600 Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: configfile: /etc/lirc/lircd.conf Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Options: dynamic_codes: (null) Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Current driver: default Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Driver API version: 3 Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Driver version: 0.10.0 Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Info: lircd: Opening log, level: Info Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: Using systemd fd Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Warning: Running as root Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Info: Using remote: lircd.conf. Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jan 12 14:36:29 rpi5voldisp sudo[1693]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: ir_controller: systemctl restart lircd.service succeeded. Jan 12 14:36:29 rpi5voldisp sudo[1702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jan 12 14:36:29 rpi5voldisp sudo[1702]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:36:29 rpi5voldisp startx[1619]: V3D 7.1 not supported by this version of Mesa. Jan 12 14:36:29 rpi5voldisp systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Jan 12 14:36:29 rpi5voldisp sudo[1702]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:29 rpi5voldisp lircd[1698]: lircd-0.10.1[1698]: Notice: accepted new client on /var/run/lirc/lircd Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: accepted new client on /var/run/lirc/lircd Jan 12 14:36:29 rpi5voldisp lircd[1698]: lircd-0.10.1[1698]: Info: [lirc] protocol is enabled Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Info: [lirc] protocol is enabled Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: ir_controller: systemctl restart irexec.service succeeded. Jan 12 14:36:29 rpi5voldisp kernel: broken atomic modeset userspace detected, disabling atomic Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: [IrActivityLed] lirc socket connect: attempt 2 of 20 Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: [IrActivityLed] lircd service status: active Jan 12 14:36:29 rpi5voldisp volumio[1266]: info: [IrActivityLed] Connected to lirc socket Jan 12 14:36:29 rpi5voldisp lircd-0.10.1[1698]: Notice: accepted new client on /var/run/lirc/lircd Jan 12 14:36:29 rpi5voldisp lircd[1698]: lircd-0.10.1[1698]: Notice: accepted new client on /var/run/lirc/lircd Jan 12 14:36:29 rpi5voldisp startx[1619]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Jan 12 14:36:30 rpi5voldisp sudo[1741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=6 pin_b=5 relative_axis=true steps-per-period=1 Jan 12 14:36:30 rpi5voldisp sudo[1741]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:36:30 rpi5voldisp kernel: rotary-encoder rotary@6: gray Jan 12 14:36:30 rpi5voldisp kernel: input: rotary@6 as /devices/platform/rotary@6/input/input12 Jan 12 14:36:30 rpi5voldisp sudo[1741]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:30 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioGetState Jan 12 14:36:30 rpi5voldisp volumio[1266]: info: CorePlayQueue::getTrack 0 Jan 12 14:36:30 rpi5voldisp volumio[1266]: info: touch_display: Setting screensaver timeout to 60 seconds. Jan 12 14:36:30 rpi5voldisp systemd-udevd[1449]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 12 14:36:30 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 12 14:36:30 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 14:36:30 rpi5voldisp volumio[1266]: info: Received Get System Info Jan 12 14:36:30 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 14:36:30 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 14:36:30 rpi5voldisp volumio[1266]: info: Discovery: Getting this device information Jan 12 14:36:30 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioGetState Jan 12 14:36:30 rpi5voldisp volumio[1266]: info: CorePlayQueue::getTrack 0 Jan 12 14:36:30 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 14:36:31 rpi5voldisp kernel: hwmon hwmon4: Voltage normalised Jan 12 14:36:31 rpi5voldisp sudo[1916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=12 active_low=1 gpio_pull=up keycode=20 Jan 12 14:36:31 rpi5voldisp sudo[1916]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:36:31 rpi5voldisp kernel: input: button@c as /devices/platform/button@c/input/input13 Jan 12 14:36:31 rpi5voldisp sudo[1916]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:31 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 12 14:36:31 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 14:36:31 rpi5voldisp volumio[1266]: info: Received Get System Info Jan 12 14:36:31 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 14:36:31 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 14:36:31 rpi5voldisp volumio[1266]: info: Discovery: Getting this device information Jan 12 14:36:31 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioGetState Jan 12 14:36:31 rpi5voldisp volumio[1266]: info: CorePlayQueue::getTrack 0 Jan 12 14:36:31 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 14:36:31 rpi5voldisp systemd-udevd[1449]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 12 14:36:31 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 12 14:36:32 rpi5voldisp volumio[1266]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jan 12 14:36:32 rpi5voldisp sudo[1948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jan 12 14:36:32 rpi5voldisp sudo[1948]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:36:32 rpi5voldisp sudo[1948]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:32 rpi5voldisp sudo[1952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jan 12 14:36:32 rpi5voldisp sudo[1952]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:36:32 rpi5voldisp sudo[1952]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:32 rpi5voldisp sudo[1956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jan 12 14:36:32 rpi5voldisp sudo[1956]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:36:32 rpi5voldisp sudo[1956]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:32 rpi5voldisp sudo[1960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jan 12 14:36:32 rpi5voldisp sudo[1960]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:36:32 rpi5voldisp sudo[1960]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:32 rpi5voldisp volumio[1266]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 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 12 14:36:32 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 14:36:32 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 14:36:32 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 14:36:32 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioGetState Jan 12 14:36:32 rpi5voldisp volumio[1266]: info: CorePlayQueue::getTrack 0 Jan 12 14:36:32 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 12 14:36:32 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 14:36:32 rpi5voldisp volumio[1266]: info: Received Get System Info Jan 12 14:36:32 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 14:36:32 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 14:36:32 rpi5voldisp volumio[1266]: info: Discovery: Getting this device information Jan 12 14:36:32 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioGetState Jan 12 14:36:32 rpi5voldisp volumio[1266]: info: CorePlayQueue::getTrack 0 Jan 12 14:36:32 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 14:36:32 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioGetState Jan 12 14:36:32 rpi5voldisp volumio[1266]: info: CorePlayQueue::getTrack 0 Jan 12 14:36:32 rpi5voldisp volumio[1266]: info: Listing playlists Jan 12 14:36:32 rpi5voldisp systemd[1]: systemd-fsckd.service: Succeeded. Jan 12 14:36:33 rpi5voldisp kernel: hwmon hwmon4: Undervoltage detected! Jan 12 14:36:33 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 12 14:36:33 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 14:36:33 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 14:36:33 rpi5voldisp sh[652]: timed out Jan 12 14:36:33 rpi5voldisp dhcpcd[699]: timed out Jan 12 14:36:33 rpi5voldisp sh[652]: dhcpcd exited Jan 12 14:36:33 rpi5voldisp dhcpcd[699]: dhcpcd exited Jan 12 14:36:33 rpi5voldisp sh[652]: ifup: failed to bring up eth0 Jan 12 14:36:33 rpi5voldisp systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jan 12 14:36:33 rpi5voldisp systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jan 12 14:36:33 rpi5voldisp volumio[1266]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 12 14:36:34 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 14:36:34 rpi5voldisp volumio[1266]: info: Received Get System Info Jan 12 14:36:34 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 14:36:34 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 14:36:34 rpi5voldisp volumio[1266]: info: Discovery: Getting this device information Jan 12 14:36:34 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioGetState Jan 12 14:36:34 rpi5voldisp volumio[1266]: info: CorePlayQueue::getTrack 0 Jan 12 14:36:34 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 14:36:34 rpi5voldisp systemd[1]: systemd-hostnamed.service: Succeeded. Jan 12 14:36:34 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 12 14:36:35 rpi5voldisp kernel: hwmon hwmon4: Voltage normalised Jan 12 14:36:35 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 12 14:36:35 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 14:36:35 rpi5voldisp volumio[1266]: info: Received Get System Info Jan 12 14:36:35 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 14:36:35 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 14:36:35 rpi5voldisp volumio[1266]: info: Discovery: Getting this device information Jan 12 14:36:35 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioGetState Jan 12 14:36:35 rpi5voldisp volumio[1266]: info: CorePlayQueue::getTrack 0 Jan 12 14:36:35 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 14:36:35 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 12 14:36:36 rpi5voldisp volumio[1266]: info: CoreCommandRouter::Close All Modals sent Jan 12 14:36:36 rpi5voldisp sudo[2159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 14:36:36 rpi5voldisp sudo[2159]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:36:36 rpi5voldisp sudo[2159]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:36 rpi5voldisp sudo[2162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 14:36:36 rpi5voldisp sudo[2162]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:36:36 rpi5voldisp sudo[2162]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:36 rpi5voldisp sudo[2165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 12 14:36:36 rpi5voldisp sudo[2165]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:36:36 rpi5voldisp systemd[1]: Started UPnP Renderer front-end to MPD. Jan 12 14:36:36 rpi5voldisp sudo[2165]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:36 rpi5voldisp volumio[1266]: info: Upmpdcli Daemon Started Jan 12 14:36:36 rpi5voldisp volumio[2167]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 12 14:36:37 rpi5voldisp volumio[2167]: ..........++++ Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: AutoStart - Plugin is starting Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioGetQueue Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: CoreStateMachine::getQueue Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: CorePlayQueue::getQueue Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: AutoStart - start playing Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: AutoStart - start playing with no specific position Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioPlay Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: CoreStateMachine::play index 0 Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: CoreStateMachine::stop Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: CoreStateMachine::play index undefined Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: CorePlayQueue::getTrack 0 Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: CoreStateMachine::startPlaybackTimer Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: CorePlayQueue::getTrack 0 Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: [1736688997967] ControllerWebradio::clearAddPlayTrack Jan 12 14:36:37 rpi5voldisp volumio[1266]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: sendMpdCommand stop took 1 milliseconds Jan 12 14:36:37 rpi5voldisp volumio[1266]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: Jan 12 14:36:37 rpi5voldisp volumio[1266]: ---------------------------- MPD announces system playlist update Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: Ignoring MPD Status Update Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: sendMpdCommand clear took 0 milliseconds Jan 12 14:36:37 rpi5voldisp volumio[1266]: verbose: ControllerMpd::sendMpdCommand load "http://icecast5.play.cz/radiobeat128.mp3#Beat" Jan 12 14:36:37 rpi5voldisp volumio[1266]: error: updateQueue error: null Jan 12 14:36:37 rpi5voldisp volumio[1266]: info: ------------------------------ 1ms Jan 12 14:36:38 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: ControllerMpd::sendMpdCommand add "http://icecast5.play.cz/radiobeat128.mp3#Beat" Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: Jan 12 14:36:39 rpi5voldisp volumio[1266]: ---------------------------- MPD announces system playlist update Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: Ignoring MPD Status Update Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: sendMpdCommand add "http://icecast5.play.cz/radiobeat128.mp3#Beat" took 2 milliseconds Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: ControllerMpd::sendMpdCommand play Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: ------------------------------ 1ms Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: sendMpdCommand play took 1 milliseconds Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: Jan 12 14:36:39 rpi5voldisp volumio[1266]: ---------------------------- MPD announces state update: player Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: ControllerMpd::getState Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: ControllerMpd::sendMpdCommand status Jan 12 14:36:39 rpi5voldisp kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: Jan 12 14:36:39 rpi5voldisp volumio[1266]: ---------------------------- MPD announces system playlist update Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: Ignoring MPD Status Update Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: Jan 12 14:36:39 rpi5voldisp volumio[1266]: ---------------------------- MPD announces state update: player Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: sendMpdCommand status took 22 milliseconds Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: ControllerMpd::getState Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: ControllerMpd::sendMpdCommand status Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: ControllerMpd::parseState Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: Jan 12 14:36:39 rpi5voldisp volumio[1266]: ---------------------------- MPD announces system playlist update Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: Ignoring MPD Status Update Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: Jan 12 14:36:39 rpi5voldisp volumio[1266]: ---------------------------- MPD announces state update: player Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: ControllerMpd::getState Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: ControllerMpd::sendMpdCommand status Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: ------------------------------ 2ms Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: sendMpdCommand status took 1 milliseconds Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: ControllerMpd::parseState Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: ControllerMpd::parseTrackInfo Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: ControllerMpd::pushState Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreCommandRouter::servicePushState Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CorePlayQueue::getTrack 0 Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio BEAT - Classic Rock","artist":"RADIO BEAT","album":null,"uri":"http://icecast5.play.cz/radiobeat128.mp3#Beat","trackType":"mp3#Beat"} Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: CURRENT POSITION 0 Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreStateMachine::syncState stateService play Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreStateMachine::syncState currentStatus stop Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: ------------------------------ 25ms Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: ------------------------------ 2ms Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: sendMpdCommand status took 2 milliseconds Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: ControllerMpd::parseState Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: ControllerMpd::parseTrackInfo Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: ControllerMpd::pushState Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreCommandRouter::servicePushState Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CorePlayQueue::getTrack 0 Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio BEAT - Classic Rock","artist":"RADIO BEAT","album":null,"uri":"http://icecast5.play.cz/radiobeat128.mp3#Beat","trackType":"mp3#Beat"} Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: CURRENT POSITION 0 Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreStateMachine::syncState stateService play Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreStateMachine::syncState currentStatus play Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: Received an update from plugin. extracting info from payload Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreStateMachine::pushState Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioPushState Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreStateMachine::pushState Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioPushState Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: ------------------------------ 10ms Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: ControllerMpd::parseTrackInfo Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: ControllerMpd::pushState Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreCommandRouter::servicePushState Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CorePlayQueue::getTrack 0 Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio BEAT - Classic Rock","artist":"RADIO BEAT","album":null,"uri":"http://icecast5.play.cz/radiobeat128.mp3#Beat","trackType":"mp3#Beat"} Jan 12 14:36:39 rpi5voldisp volumio[1266]: verbose: CURRENT POSITION 0 Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreStateMachine::syncState stateService play Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreStateMachine::syncState currentStatus play Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: Received an update from plugin. extracting info from payload Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreStateMachine::pushState Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioPushState Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreStateMachine::pushState Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioPushState Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: ------------------------------ 22ms Jan 12 14:36:39 rpi5voldisp volumio[1266]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 12 14:36:40 rpi5voldisp sudo[1323]: pam_unix(sudo:session): session closed for user root Jan 12 14:36:42 rpi5voldisp volumio[1266]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 14:36:42 rpi5voldisp volumio[2167]: ........................................................................................................++++ Jan 12 14:36:42 rpi5voldisp volumio[2167]: e is 65537 (0x010001) Jan 12 14:36:42 rpi5voldisp volumio[2167]: writing RSA key Jan 12 14:36:42 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 14:36:46 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 14:36:46 rpi5voldisp volumio[1266]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 12 14:36:46 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 12 14:36:46 rpi5voldisp volumio[1266]: info: Received Get System Version Jan 12 14:36:46 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 14:36:46 rpi5voldisp volumio[1266]: info: Received Get System Info Jan 12 14:36:46 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 14:36:46 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 14:36:46 rpi5voldisp volumio[1266]: info: Discovery: Getting this device information Jan 12 14:36:46 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioGetState Jan 12 14:36:46 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 14:36:50 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 14:36:50 rpi5voldisp volumio[1266]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 12 14:36:50 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 12 14:36:50 rpi5voldisp volumio[1266]: info: Received Get System Version Jan 12 14:36:50 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 14:36:50 rpi5voldisp volumio[1266]: info: Received Get System Info Jan 12 14:36:50 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 14:36:50 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 14:36:50 rpi5voldisp volumio[1266]: info: Discovery: Getting this device information Jan 12 14:36:50 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioGetState Jan 12 14:36:50 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 14:36:55 rpi5voldisp volumio[1266]: info: CALLMETHOD: system_hardware iractivityled saveSettings [object Object] Jan 12 14:36:55 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: iractivityled , saveSettings Jan 12 14:36:58 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 14:36:58 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 14:36:58 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 14:36:58 rpi5voldisp volumio[1266]: error: Plugin system_hardware iractivityled failed to complete 'onStart' in a timely fashion Jan 12 14:36:58 rpi5voldisp volumio[1266]: info: ------------------------------------------- Jan 12 14:36:58 rpi5voldisp volumio[1266]: info: ----- MyVolumio plugins startup ---- Jan 12 14:36:58 rpi5voldisp volumio[1266]: info: ------------------------------------------- Jan 12 14:36:58 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 12 14:36:59 rpi5voldisp systemd[1]: systemd-timedated.service: Succeeded. Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: Adding plugin bluetooth to MyMusic Plugins Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: Adding plugin multiroom to MyMusic Plugins Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: Adding plugin metavolumio to MyMusic Plugins Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: Adding plugin cd_controller to MyMusic Plugins Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: Starting MyVolumio Remote Streaming Endpoints Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: MyVolumio login type: Token Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 12 14:37:06 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 12 14:37:07 rpi5voldisp volumio[1266]: info: Starting Streaming Service Transparent Proxy Jan 12 14:37:07 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 12 14:37:07 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 12 14:37:07 rpi5voldisp volumio[1266]: info: Streaming services startup Jan 12 14:37:07 rpi5voldisp volumio[1266]: info: Starting Streaming Daemon Jan 12 14:37:07 rpi5voldisp sudo[2372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 14:37:07 rpi5voldisp sudo[2372]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:37:07 rpi5voldisp volumio[1266]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 12 14:37:07 rpi5voldisp sudo[2372]: pam_unix(sudo:session): session closed for user root Jan 12 14:37:07 rpi5voldisp volumio[1266]: error: Cannot start Volumio Streaming Daemon Jan 12 14:37:07 rpi5voldisp volumio[1266]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 14:37:07 rpi5voldisp volumio[1266]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 14:37:07 rpi5voldisp volumio[1266]: STREAMING PROXY: Starting server on port 3245 Jan 12 14:37:07 rpi5voldisp volumio[1266]: Node JS runtime: 14 Jan 12 14:37:07 rpi5voldisp volumio[1266]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 12 14:37:08 rpi5voldisp volumio[1266]: info: MyVolumio token set successfully Jan 12 14:37:08 rpi5voldisp volumio[1266]: info: MYVOLUMIO: Adding device Jan 12 14:37:08 rpi5voldisp volumio[1266]: info: MYVOLUMIO: Evaluating Server Jan 12 14:37:08 rpi5voldisp volumio[1266]: info: CoreCommandRouter::Close All Modals sent Jan 12 14:37:08 rpi5voldisp volumio[1266]: info: MyVolumio status changed Jan 12 14:37:08 rpi5voldisp volumio[1266]: info: Streaming services startup Jan 12 14:37:08 rpi5voldisp volumio[1266]: info: Starting Streaming Daemon Jan 12 14:37:08 rpi5voldisp volumio[1266]: info: Removing browser output: myVolumio user plan is not superstar Jan 12 14:37:08 rpi5voldisp volumio[1266]: info: Removing audio output: Jan 12 14:37:08 rpi5voldisp volumio[1266]: info: Stoppping Tunnel 1 Jan 12 14:37:08 rpi5voldisp sudo[2401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 14:37:08 rpi5voldisp sudo[2401]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:37:08 rpi5voldisp sudo[2401]: pam_unix(sudo:session): session closed for user root Jan 12 14:37:08 rpi5voldisp sudo[2404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 12 14:37:08 rpi5voldisp sudo[2404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 14:37:08 rpi5voldisp volumio[1266]: error: Cannot start Volumio Streaming Daemon Jan 12 14:37:08 rpi5voldisp volumio[1266]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 14:37:08 rpi5voldisp volumio[1266]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 14:37:08 rpi5voldisp sudo[2404]: pam_unix(sudo:session): session closed for user root Jan 12 14:37:08 rpi5voldisp volumio[1266]: info: Remote SSH Stopped Jan 12 14:37:08 rpi5voldisp volumio[1266]: info: Setting Geolocation for MyVolumio to eu4 Jan 12 14:37:08 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 14:37:08 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 14:37:08 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 14:37:09 rpi5voldisp volumio[1266]: info: Successfully Added MyVolumio device Jan 12 14:37:09 rpi5voldisp volumio[1266]: info: Updating MyVolumio device info Jan 12 14:37:09 rpi5voldisp volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 14:37:10 rpi5voldisp volumio[1266]: info: Successfully Updated MyVolumio device Jan 12 14:37:14 rpi5voldisp ntpd[1099]: Soliciting pool server 77.48.28.248 Jan 12 14:37:14 rpi5voldisp volumio[1266]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Jan 12 14:37:14 rpi5voldisp volumio[1266]: info: CoreCommandRouter::volumioGetState Jan 12 14:37:15 rpi5voldisp volumio[1266]: info: Starting Uninstall of plugin system_hardware - iractivityled Jan 12 14:37:15 rpi5voldisp volumio[1266]: info: Uninstalling plugin iractivityled Jan 12 14:37:15 rpi5voldisp volumio[1266]: info: [IrActivityLed] releasing built-in LED Jan 12 14:37:15 rpi5voldisp lircd[1698]: lircd-0.10.1[1698]: Info: removed client Jan 12 14:37:15 rpi5voldisp lircd-0.10.1[1698]: Info: removed client Jan 12 14:37:15 rpi5voldisp volumio[1266]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 14:37:15 rpi5voldisp volumio[1266]: TypeError: Cannot read property 'toString' of undefined Jan 12 14:37:15 rpi5voldisp volumio[1266]: at IrActivityLedController.releaseLed (/data/plugins/system_hardware/iractivityled/index.js:232:61) Jan 12 14:37:15 rpi5voldisp volumio[1266]: at IrActivityLedController.onStop (/data/plugins/system_hardware/iractivityled/index.js:68:17) Jan 12 14:37:15 rpi5voldisp volumio[1266]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jan 12 14:37:15 rpi5voldisp volumio[1266]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Jan 12 14:37:15 rpi5voldisp volumio[1266]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1478:22) Jan 12 14:37:15 rpi5voldisp volumio[1266]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1254:45) Jan 12 14:37:15 rpi5voldisp volumio[1266]: at Socket.emit (events.js:315:20) Jan 12 14:37:15 rpi5voldisp volumio[1266]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 12 14:37:15 rpi5voldisp volumio[1266]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jan 12 14:37:15 rpi5voldisp volumio[1266]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 14:37:15 rpi5voldisp ntpd[1099]: Soliciting pool server 147.251.48.140 Jan 12 14:37:15 rpi5voldisp sudo[2465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-12 14:36 Jan 12 14:37:15 rpi5voldisp sudo[2465]: 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"