-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Fri 2025-09-05 10:13:26 CEST. -- Sep 05 10:12:00 musicplayer 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. Sep 05 10:12:00 musicplayer ntpd[825]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 05 10:12:00 musicplayer nmbd[811]: [2025/09/05 10:12:00.899294, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Sep 05 10:12:00 musicplayer nmbd[811]: started asyncdns process 870 Sep 05 10:12:00 musicplayer nmbd[811]: [2025/09/05 10:12:00.903129, 0] ../lib/util/become_daemon.c:149(daemon_status) Sep 05 10:12:00 musicplayer nmbd[811]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Sep 05 10:12:00 musicplayer nmbd[811]: [2025/09/05 10:12:00.903407, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Sep 05 10:12:00 musicplayer nmbd[811]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Sep 05 10:12:01 musicplayer systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 05 10:12:01 musicplayer systemd[1]: volumiossh.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 05 10:12:01 musicplayer wireless.js[542]: Cleaning previous... Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01+02:00" level=warning msg="containerd config version `1` has been deprecated and will be converted on each startup in containerd v2.0, use `containerd config migrate` after upgrading to containerd 2.0 to avoid conversion on startup" Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.362776086+02:00" level=info msg="starting containerd" revision=d2d58213f83a351ca8f528a95fbd145f5654e957 version=1.6.33 Sep 05 10:12:01 musicplayer systemd[1]: systemd-rfkill.service: Succeeded. Sep 05 10:12:01 musicplayer sudo[884]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 05 10:12:01 musicplayer sudo[884]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 10:12:01 musicplayer sudo[884]: pam_unix(sudo:session): session closed for user root Sep 05 10:12:01 musicplayer ntpd[825]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 05 10:12:01 musicplayer sudo[886]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 05 10:12:01 musicplayer sudo[886]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.801339742+02:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.826197881+02:00" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.56-v7+\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.826570794+02:00" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.827923753+02:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.829721811+02:00" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (overlay) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.830013059+02:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.830236287+02:00" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.830404514+02:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.833628500+02:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.836457436+02:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.838135183+02:00" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.838425285+02:00" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.838826376+02:00" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.849609937+02:00" level=info msg="metadata content store policy set" policy=shared Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.866077048+02:00" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.866428400+02:00" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.866687304+02:00" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.867100790+02:00" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.867874951+02:00" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.868577706+02:00" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.873491834+02:00" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.874118964+02:00" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.883611963+02:00" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.884010398+02:00" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.884375916+02:00" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.884618362+02:00" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.884855599+02:00" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.885129503+02:00" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.885498094+02:00" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.885772415+02:00" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.886022569+02:00" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.886361785+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.886647773+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.886920114+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.887160217+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.887374069+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.887599380+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.887812087+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.888052397+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.888288593+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.888524633+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.888769579+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.888976765+02:00" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.889220044+02:00" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.889689103+02:00" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.890154620+02:00" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.890538576+02:00" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.896827797+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.913565844+02:00" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.914095840+02:00" level=info msg=serving... address=/run/containerd/containerd.sock Sep 05 10:12:01 musicplayer containerd[808]: time="2025-09-05T10:12:01.914666043+02:00" level=info msg="containerd successfully booted in 0.564835s" Sep 05 10:12:01 musicplayer systemd[1]: Started containerd container runtime. Sep 05 10:12:01 musicplayer systemd[1]: Starting Docker Application Container Engine... Sep 05 10:12:01 musicplayer kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Sep 05 10:12:01 musicplayer kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Sep 05 10:12:01 musicplayer kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Sep 05 10:12:01 musicplayer kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 05 10:12:02 musicplayer systemd[1]: Reloading. Sep 05 10:12:02 musicplayer kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 05 10:12:02 musicplayer kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 05 10:12:02 musicplayer kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 05 10:12:02 musicplayer volumio-time-update[524]: volumio-time-update-util: Fetching time from Volumio... Sep 05 10:12:02 musicplayer volumio-time-update[524]: volumio-time-update-util: Date not found in response Sep 05 10:12:02 musicplayer volumio-time-update[524]: volumio-time-update-util: Retrying in 5 seconds... Sep 05 10:12:02 musicplayer bthelper[659]: Changing power off succeeded Sep 05 10:12:02 musicplayer ntpd[825]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 05 10:12:02 musicplayer 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. Sep 05 10:12:02 musicplayer bthelper[659]: [CHG] Controller B8:27:EB:31:E3:56 Class: 0x0000041c Sep 05 10:12:02 musicplayer bthelper[659]: Changing power on succeeded Sep 05 10:12:03 musicplayer dhcpcd[640]: eth0: using static address 192.168.2.31/24 Sep 05 10:12:03 musicplayer systemd[1]: Reloading. Sep 05 10:12:04 musicplayer 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. Sep 05 10:12:05 musicplayer volumio-remote-updater[521]: [2025-09-05 10:12:05] [info] asio async_connect error: system:111 (Connection refused) Sep 05 10:12:05 musicplayer volumio-remote-updater[521]: [2025-09-05 10:12:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 05 10:12:05 musicplayer volumio-remote-updater[521]: [2025-09-05 10:12:05] [error] handle_connect error: Underlying Transport Error Sep 05 10:12:05 musicplayer sudo[886]: pam_unix(sudo:session): session closed for user root Sep 05 10:12:05 musicplayer avahi-daemon[520]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.31. Sep 05 10:12:05 musicplayer dhcpcd[640]: eth0: adding route to 192.168.2.0/24 Sep 05 10:12:05 musicplayer dhcpcd[640]: eth0: adding default route via 192.168.2.1 Sep 05 10:12:05 musicplayer avahi-daemon[520]: New relevant interface eth0.IPv4 for mDNS. Sep 05 10:12:05 musicplayer avahi-daemon[520]: Registering new address record for 192.168.2.31 on eth0.IPv4. Sep 05 10:12:05 musicplayer wireless.js[542]: Stopped aP Sep 05 10:12:05 musicplayer systemd[1]: volumiossh.service: Succeeded. Sep 05 10:12:05 musicplayer systemd[1]: Started Volumio SSH enabler. Sep 05 10:12:05 musicplayer wireless.js[542]: Wireless Networking DISABLED, not starting wireless flow Sep 05 10:12:05 musicplayer systemd[1]: wireless.service: Succeeded. Sep 05 10:12:05 musicplayer systemd[1]: Started Wireless Services. Sep 05 10:12:05 musicplayer nmbd[811]: [2025/09/05 10:12:05.930262, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 05 10:12:05 musicplayer systemd[1]: Started Samba NMB Daemon. Sep 05 10:12:05 musicplayer nmbd[811]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 05 10:12:05 musicplayer systemd[1]: Starting Samba Winbind Daemon... Sep 05 10:12:05 musicplayer systemd[1]: Started Volumio Backend Module. Sep 05 10:12:05 musicplayer systemd[1]: Started pydPiper. Sep 05 10:12:05 musicplayer systemd[1]: Started Volumio Cpu Tweaker. Sep 05 10:12:06 musicplayer dockerd[889]: time="2025-09-05T10:12:06.000065573+02:00" level=info msg="Starting up" Sep 05 10:12:06 musicplayer volumio-cpu-tweak[966]: Setting RT Priority for mpd Sep 05 10:12:06 musicplayer volumio-cpu-tweak[966]: pid 35's current scheduling policy: SCHED_OTHER Sep 05 10:12:06 musicplayer volumio-cpu-tweak[966]: pid 35's current scheduling priority: 0 Sep 05 10:12:06 musicplayer volumio-cpu-tweak[966]: Setting MPD Affinity Sep 05 10:12:06 musicplayer volumio-cpu-tweak[966]: pid 3's current affinity mask: f Sep 05 10:12:06 musicplayer volumio-cpu-tweak[966]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Sep 05 10:12:06 musicplayer volumio-cpu-tweak[966]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Sep 05 10:12:06 musicplayer systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 05 10:12:06 musicplayer ntpd[825]: Listen normally on 3 eth0 192.168.2.31:123 Sep 05 10:12:06 musicplayer ntpd[825]: new interface(s) found: waking up resolver Sep 05 10:12:06 musicplayer winbindd[962]: [2025/09/05 10:12:06.809911, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 05 10:12:06 musicplayer winbindd[962]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 05 10:12:07 musicplayer volumio-time-update[524]: volumio-time-update-util: Fetching time from Volumio... Sep 05 10:12:07 musicplayer volumio-time-update[524]: volumio-time-update-util: Setting system time to: 2025-09-05 10:12:34 Sep 05 10:12:07 musicplayer sudo[999]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-05 10:12:34 Sep 05 10:12:07 musicplayer sudo[999]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 10:12:07 musicplayer winbindd[962]: [2025/09/05 10:12:07.749107, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 05 10:12:07 musicplayer winbindd[962]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 05 10:12:07 musicplayer systemd[1]: Started Samba Winbind Daemon. Sep 05 10:12:07 musicplayer dbus-daemon[522]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=1000 comm="timedatectl set-time 2025-09-05 10:12:34 ") Sep 05 10:12:07 musicplayer systemd[1]: Starting Samba SMB Daemon... Sep 05 10:12:07 musicplayer systemd[1]: Starting Time & Date Service... Sep 05 10:12:08 musicplayer mpd[853]: Sep 05 10:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 10:12:08 musicplayer dbus-daemon[522]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 05 10:12:08 musicplayer systemd[1]: Started Time & Date Service. Sep 05 10:12:34 musicplayer systemd-timedated[1003]: Changed local time to Fri Sep 5 10:12:34 2025 Sep 05 10:12:34 musicplayer sudo[999]: pam_unix(sudo:session): session closed for user root Sep 05 10:12:34 musicplayer volumio-time-update[524]: volumio-time-update-util: System time updated successfully. Sep 05 10:12:34 musicplayer systemd[1]: Started Volumio Time Update Utility. Sep 05 10:12:34 musicplayer systemd[1]: Started Music Player Daemon. Sep 05 10:12:34 musicplayer mpd_monitor.sh[519]: MPD restarted due to no mpc output. Sep 05 10:12:34 musicplayer dockerd[889]: time="2025-09-05T10:12:34.962949119+02:00" level=error msg="failed to mount overlay: invalid argument" storage-driver=overlay2 Sep 05 10:12:34 musicplayer kernel: overlay: filesystem on /var/lib/docker/check-overlayfs-support3530799881/upper not supported as upperdir Sep 05 10:12:34 musicplayer dockerd[889]: time="2025-09-05T10:12:34.981441162+02:00" level=error msg="exec: \"fuse-overlayfs\": executable file not found in $PATH" storage-driver=fuse-overlayfs Sep 05 10:12:36 musicplayer smbd[1004]: [2025/09/05 10:12:36.121428, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 05 10:12:36 musicplayer systemd[1]: Started Samba SMB Daemon. Sep 05 10:12:36 musicplayer smbd[1004]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 05 10:12:36 musicplayer dockerd[889]: time="2025-09-05T10:12:36.717172857+02:00" level=info msg="Loading containers: start." Sep 05 10:12:38 musicplayer kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 05 10:12:38 musicplayer kernel: Bridge firewalling registered Sep 05 10:12:38 musicplayer volumio-remote-updater[521]: [2025-09-05 10:12:38] [info] asio async_connect error: system:111 (Connection refused) Sep 05 10:12:38 musicplayer volumio-remote-updater[521]: [2025-09-05 10:12:38] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 05 10:12:38 musicplayer volumio-remote-updater[521]: [2025-09-05 10:12:38] [error] handle_connect error: Underlying Transport Error Sep 05 10:12:38 musicplayer systemd-udevd[1050]: Using default interface naming scheme 'v240'. Sep 05 10:12:38 musicplayer systemd-udevd[1050]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 05 10:12:39 musicplayer avahi-daemon[520]: Joining mDNS multicast group on interface docker0.IPv4 with address 172.17.0.1. Sep 05 10:12:39 musicplayer avahi-daemon[520]: New relevant interface docker0.IPv4 for mDNS. Sep 05 10:12:39 musicplayer avahi-daemon[520]: Registering new address record for 172.17.0.1 on docker0.IPv4. Sep 05 10:12:40 musicplayer dockerd[889]: time="2025-09-05T10:12:40.008481769+02:00" level=info msg="Default bridge (docker0) is assigned with an IP address 172.17.0.0/16. Daemon option --bip can be used to set a preferred IP address" Sep 05 10:12:40 musicplayer dockerd[889]: time="2025-09-05T10:12:40.446000589+02:00" level=info msg="Loading containers: done." Sep 05 10:12:40 musicplayer dockerd[889]: time="2025-09-05T10:12:40.524997732+02:00" level=warning msg="WARNING: No memory limit support" Sep 05 10:12:40 musicplayer dockerd[889]: time="2025-09-05T10:12:40.525234814+02:00" level=warning msg="WARNING: No swap limit support" Sep 05 10:12:40 musicplayer dockerd[889]: time="2025-09-05T10:12:40.525341740+02:00" level=warning msg="WARNING: No kernel memory TCP limit support" Sep 05 10:12:40 musicplayer dockerd[889]: time="2025-09-05T10:12:40.525444083+02:00" level=warning msg="WARNING: No oom kill disable support" Sep 05 10:12:40 musicplayer dockerd[889]: time="2025-09-05T10:12:40.525543197+02:00" level=warning msg="WARNING: No blkio throttle.read_bps_device support" Sep 05 10:12:40 musicplayer dockerd[889]: time="2025-09-05T10:12:40.525646061+02:00" level=warning msg="WARNING: No blkio throttle.write_bps_device support" Sep 05 10:12:40 musicplayer dockerd[889]: time="2025-09-05T10:12:40.525802049+02:00" level=warning msg="WARNING: No blkio throttle.read_iops_device support" Sep 05 10:12:40 musicplayer dockerd[889]: time="2025-09-05T10:12:40.525908871+02:00" level=warning msg="WARNING: No blkio throttle.write_iops_device support" Sep 05 10:12:40 musicplayer dockerd[889]: time="2025-09-05T10:12:40.526146369+02:00" level=info msg="Docker daemon" commit=de5c9cf containerd-snapshotter=false storage-driver=vfs version=26.1.4 Sep 05 10:12:40 musicplayer dockerd[889]: time="2025-09-05T10:12:40.530436700+02:00" level=info msg="Daemon has completed initialization" Sep 05 10:12:40 musicplayer dockerd[889]: time="2025-09-05T10:12:40.874639739+02:00" level=info msg="API listen on /run/docker.sock" Sep 05 10:12:40 musicplayer systemd[1]: Started Docker Application Container Engine. Sep 05 10:12:40 musicplayer systemd[1]: Reached target Multi-User System. Sep 05 10:12:40 musicplayer systemd[1]: Reached target Graphical Interface. Sep 05 10:12:40 musicplayer systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 05 10:12:41 musicplayer systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 05 10:12:41 musicplayer systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 05 10:12:41 musicplayer systemd[1]: Startup finished in 18.074s (kernel) + 27.647s (userspace) = 45.721s. Sep 05 10:12:41 musicplayer volumio[963]: info: ------------------------------------------- Sep 05 10:12:41 musicplayer volumio[963]: info: ----- Volumio3 ---- Sep 05 10:12:41 musicplayer volumio[963]: info: ------------------------------------------- Sep 05 10:12:41 musicplayer volumio[963]: info: ----- System startup ---- Sep 05 10:12:41 musicplayer volumio[963]: info: ------------------------------------------- Sep 05 10:12:44 musicplayer volumio[963]: info: MYVOLUMIO Environment detected Sep 05 10:12:44 musicplayer volumio[963]: info: Plugin folders cleanup Sep 05 10:12:44 musicplayer volumio[963]: info: Scanning into folder /volumio/app/plugins/ Sep 05 10:12:44 musicplayer volumio[963]: info: Scanning category audio_interface Sep 05 10:12:44 musicplayer volumio[963]: info: Scanning category miscellanea Sep 05 10:12:44 musicplayer volumio[963]: info: Scanning category music_service Sep 05 10:12:44 musicplayer volumio[963]: info: Scanning category plugins.json Sep 05 10:12:44 musicplayer volumio[963]: info: Scanning category system_controller Sep 05 10:12:44 musicplayer volumio[963]: info: Scanning category user_interface Sep 05 10:12:44 musicplayer volumio[963]: info: Scanning into folder /data/plugins/ Sep 05 10:12:44 musicplayer volumio[963]: info: Scanning category music_service Sep 05 10:12:44 musicplayer volumio[963]: info: Plugin folders cleanup completed Sep 05 10:12:44 musicplayer volumio[963]: info: ------------------------------------------- Sep 05 10:12:44 musicplayer volumio[963]: info: ----- Core plugins startup ---- Sep 05 10:12:44 musicplayer volumio[963]: info: ------------------------------------------- Sep 05 10:12:44 musicplayer volumio[963]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 10:12:44 musicplayer volumio[963]: info: Adding plugin upnp to MyMusic Plugins Sep 05 10:12:44 musicplayer volumio[963]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 10:12:44 musicplayer volumio[963]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 10:12:44 musicplayer volumio[963]: info: Loading plugins from folder /data/plugins/ Sep 05 10:12:44 musicplayer volumio[963]: info: Loading plugin "system"... Sep 05 10:12:44 musicplayer volumio[963]: info: Loading plugin "appearance"... Sep 05 10:12:47 musicplayer volumio[963]: info: Loading plugin "network"... Sep 05 10:12:47 musicplayer volumio[963]: info: Refreshing Cached IP Addresses Sep 05 10:12:47 musicplayer sudo[1212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 10:12:47 musicplayer sudo[1212]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 10:12:47 musicplayer volumio[963]: info: Loading plugin "services"... Sep 05 10:12:47 musicplayer sudo[1212]: pam_unix(sudo:session): session closed for user root Sep 05 10:12:47 musicplayer sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 10:12:47 musicplayer sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 10:12:47 musicplayer volumio[963]: info: Loading plugin "alsa_controller"... Sep 05 10:12:47 musicplayer sudo[1214]: pam_unix(sudo:session): session closed for user root Sep 05 10:12:48 musicplayer systemd[1]: systemd-fsckd.service: Succeeded. Sep 05 10:12:48 musicplayer volumio-remote-updater[521]: [2025-09-05 10:12:48] [connect] Successful connection Sep 05 10:12:49 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 10:12:49 musicplayer volumio[963]: info: Loading plugin "wizard"... Sep 05 10:12:50 musicplayer volumio[963]: info: Loading plugin "networkfs"... Sep 05 10:12:51 musicplayer sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.2.250:/nfs/music/flac /mnt/NAS/Flac Sep 05 10:12:51 musicplayer sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 10:12:51 musicplayer volumio[963]: info: Starting Udev Watcher for removable devices Sep 05 10:12:51 musicplayer sudo[1237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.2.250:/nfs/wav /mnt/NAS/WAV Sep 05 10:12:51 musicplayer sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 10:12:51 musicplayer volumio[963]: info: Ignoring mount for partition: boot Sep 05 10:12:51 musicplayer volumio[963]: info: Ignoring mount for partition: volumio Sep 05 10:12:51 musicplayer volumio[963]: info: Ignoring mount for partition: volumio_data Sep 05 10:12:51 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 10:12:51 musicplayer volumio[963]: info: Loading plugin "volumio_command_line_client"... Sep 05 10:12:51 musicplayer volumio[963]: info: Loading plugin "upnp"... Sep 05 10:12:51 musicplayer volumio[963]: info: [1757059971327] Starting Upmpd Daemon Sep 05 10:12:51 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 10:12:51 musicplayer volumio[963]: info: Loading plugin "my_music"... Sep 05 10:12:51 musicplayer volumio[963]: info: Loading plugin "mpd"... Sep 05 10:12:52 musicplayer volumio[963]: info: Loading plugin "upnp_browser"... Sep 05 10:12:53 musicplayer volumio[963]: info: Loading plugin "alarm-clock"... Sep 05 10:12:53 musicplayer volumio[963]: info: Loading plugin "airplay_emulation"... Sep 05 10:12:53 musicplayer volumio[963]: info: Starting Shairport Sync Sep 05 10:12:53 musicplayer volumio[963]: info: Loading plugin "last_100"... Sep 05 10:12:53 musicplayer volumio[963]: info: Loading plugin "webradio"... Sep 05 10:12:54 musicplayer volumio[963]: info: Loading plugin "i2s_dacs"... Sep 05 10:12:54 musicplayer volumio[963]: info: Loading plugin "volumiodiscovery"... Sep 05 10:12:54 musicplayer volumio[963]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 10:12:54 musicplayer volumio[963]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 10:12:54 musicplayer volumio[963]: *** WARNING *** For more information see Sep 05 10:12:54 musicplayer node[963]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 10:12:54 musicplayer volumio[963]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 10:12:54 musicplayer volumio[963]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 10:12:54 musicplayer volumio[963]: *** WARNING *** For more information see Sep 05 10:12:54 musicplayer node[963]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 10:12:54 musicplayer node[963]: *** WARNING *** For more information see Sep 05 10:12:54 musicplayer node[963]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 10:12:54 musicplayer node[963]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 10:12:54 musicplayer node[963]: *** WARNING *** For more information see Sep 05 10:12:54 musicplayer volumio[963]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 10:12:54 musicplayer volumio[963]: info: Discovery: Started advertising with name: MusicPlayer Sep 05 10:12:54 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 10:12:54 musicplayer volumio[963]: info: Plugin spop is not enabled Sep 05 10:12:54 musicplayer volumio[963]: info: Loading plugin "outputs"... Sep 05 10:12:54 musicplayer volumio[963]: info: Loading plugin "albumart"... Sep 05 10:12:54 musicplayer volumio[963]: info: Plugin example_plugin is not enabled Sep 05 10:12:54 musicplayer volumio[963]: info: Loading plugin "inputs"... Sep 05 10:12:54 musicplayer volumio[963]: info: Loading plugin "updater_comm"... Sep 05 10:12:54 musicplayer volumio[963]: info: Plugin mpdemulation is not enabled Sep 05 10:12:54 musicplayer volumio[963]: info: Loading plugin "rest_api"... Sep 05 10:12:55 musicplayer volumio[963]: info: Loading plugin "websocket"... Sep 05 10:12:55 musicplayer systemd[1]: systemd-hostnamed.service: Succeeded. Sep 05 10:12:55 musicplayer volumio[963]: info: Starting Socket.io Server version 2.3.0 Sep 05 10:12:55 musicplayer volumio[963]: info: Loading i18n strings for locale nl Sep 05 10:12:55 musicplayer volumio[963]: Updating browse sources language Sep 05 10:12:55 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 10:12:55 musicplayer volumio[963]: Forking 3 albumart workers Sep 05 10:12:56 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 10:12:56 musicplayer volumio[963]: info: CoreCommandRouter::initPlayerControls Sep 05 10:12:56 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 10:12:56 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 10:12:56 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 10:12:56 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 10:12:56 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 10:12:56 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 10:12:56 musicplayer volumio[963]: Express server listening on port 3000 Sep 05 10:12:56 musicplayer volumio[963]: [Metrics] WebUI: 17s 41.57ms Sep 05 10:12:56 musicplayer volumio[963]: info: CoreStateMachine::resetVolumioState Sep 05 10:12:56 musicplayer volumio[963]: info: CoreStateMachine::getcurrentVolume Sep 05 10:12:56 musicplayer volumio[963]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 10:12:56 musicplayer volumio[963]: info: Volumio Network Manager: Network status updated: 1 Sep 05 10:12:57 musicplayer volumio[963]: verbose: New Socket.io Connection to 192.168.2.31 from 192.168.2.103 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/136.0.7103.91 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 1 Sep 05 10:12:57 musicplayer volumio[963]: verbose: New Socket.io Connection to 192.168.2.31 from 192.168.2.102 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Sep 05 10:12:57 musicplayer volumio[963]: verbose: New Socket.io Connection to 192.168.2.31 from 192.168.2.103 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/136.0.7103.91 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 3 Sep 05 10:12:57 musicplayer volumio[963]: verbose: New Socket.io Connection to 192.168.2.31 from 192.168.2.102 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 05 10:12:57 musicplayer volumio[963]: verbose: New Socket.io Connection to 192.168.2.31 from 192.168.2.103 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/136.0.7103.91 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 Sep 05 10:12:57 musicplayer volumio[963]: verbose: New Socket.io Connection to 192.168.2.31 from 192.168.2.103 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/136.0.7103.91 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 6 Sep 05 10:12:57 musicplayer volumio[963]: info: CoreCommandRouter::volumioGetState Sep 05 10:12:57 musicplayer volumio[963]: info: CorePlayQueue::getTrack 0 Sep 05 10:12:57 musicplayer volumio[963]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Sep 05 10:12:57 musicplayer volumio[963]: Starting albumart workers Sep 05 10:12:57 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 10:12:57 musicplayer volumio[963]: info: Reloading queue from file Sep 05 10:12:57 musicplayer volumio[963]: info: CoreStateMachine::setRepeat null single undefined Sep 05 10:12:57 musicplayer volumio[963]: info: CoreStateMachine::pushState Sep 05 10:12:57 musicplayer volumio[963]: info: CorePlayQueue::getTrack 0 Sep 05 10:12:57 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 10:12:57 musicplayer volumio[963]: info: CoreCommandRouter::volumioPushState Sep 05 10:12:57 musicplayer volumio[963]: info: CoreStateMachine::setRandom null Sep 05 10:12:57 musicplayer volumio[963]: info: CoreStateMachine::pushState Sep 05 10:12:57 musicplayer volumio[963]: info: CorePlayQueue::getTrack 0 Sep 05 10:12:57 musicplayer volumio[963]: info: CoreCommandRouter::volumioPushState Sep 05 10:12:57 musicplayer volumio[963]: info: Listing playlists Sep 05 10:12:57 musicplayer volumio[963]: info: Listing playlists Sep 05 10:12:57 musicplayer volumio[963]: info: VolumeController:: Volume=undefined Mute =false Sep 05 10:12:57 musicplayer volumio[963]: info: CoreStateMachine::pushState Sep 05 10:12:57 musicplayer volumio[963]: info: CorePlayQueue::getTrack 0 Sep 05 10:12:57 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 10:12:57 musicplayer volumio[963]: info: CoreCommandRouter::volumioPushState Sep 05 10:12:57 musicplayer volumio[963]: info: CoreStateMachine::updateTrackBlock Sep 05 10:12:57 musicplayer volumio[963]: info: CorePlayQueue::getTrackBlock Sep 05 10:12:57 musicplayer volumio[963]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 10:12:57 musicplayer volumio[963]: info: Setting Device type: Raspberry PI Sep 05 10:12:57 musicplayer volumio[963]: Starting albumart workers Sep 05 10:12:57 musicplayer volumio[963]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Sep 05 10:12:57 musicplayer volumio[963]: info: Discovery: adding 6aace2bd-032b-423b-9f15-465725540c1a Sep 05 10:12:57 musicplayer volumio[963]: info: Discovery: Found device MusicPlayer Sep 05 10:12:57 musicplayer volumio[963]: info: CoreCommandRouter::volumioGetState Sep 05 10:12:57 musicplayer volumio[963]: info: CorePlayQueue::getTrack 0 Sep 05 10:12:57 musicplayer volumio[963]: info: Discovery: this is already registered, 6aace2bd-032b-423b-9f15-465725540c1a Sep 05 10:12:57 musicplayer volumio[963]: Starting albumart workers Sep 05 10:12:57 musicplayer volumio[963]: info: Discovery: Found device MusicPlayer Sep 05 10:12:57 musicplayer volumio[963]: info: CoreCommandRouter::volumioGetState Sep 05 10:12:57 musicplayer volumio[963]: info: CorePlayQueue::getTrack 0 Sep 05 10:12:57 musicplayer volumio[963]: verbose: New Socket.io Connection to 192.168.2.31 from 192.168.2.102 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 05 10:12:57 musicplayer volumio[963]: info: VolumeController:: Volume=undefined Mute =false Sep 05 10:12:57 musicplayer volumio[963]: info: CoreStateMachine::pushState Sep 05 10:12:57 musicplayer volumio[963]: info: CorePlayQueue::getTrack 0 Sep 05 10:12:57 musicplayer volumio[963]: info: CoreCommandRouter::volumioPushState Sep 05 10:12:57 musicplayer volumio[963]: info: CoreCommandRouter::volumioGetState Sep 05 10:12:57 musicplayer volumio[963]: info: CorePlayQueue::getTrack 0 Sep 05 10:12:58 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 05 10:12:58 musicplayer volumio[963]: info: Listing playlists Sep 05 10:12:58 musicplayer volumio[963]: info: CoreCommandRouter::volumioGetVisibleSources Sep 05 10:12:58 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 10:12:58 musicplayer volumio[963]: info: CoreCommandRouter::volumioGetQueue Sep 05 10:12:58 musicplayer volumio[963]: info: CoreStateMachine::getQueue Sep 05 10:12:58 musicplayer volumio[963]: info: CorePlayQueue::getQueue Sep 05 10:13:02 musicplayer volumio[963]: verbose: New Socket.io Connection to 192.168.2.31 from 192.168.2.103 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/136.0.7103.91 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 8 Sep 05 10:13:02 musicplayer volumio[963]: info: CoreCommandRouter::volumioGetVisibleSources Sep 05 10:13:02 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 10:13:02 musicplayer volumio[963]: info: CoreCommandRouter::volumioGetState Sep 05 10:13:02 musicplayer volumio[963]: info: CorePlayQueue::getTrack 0 Sep 05 10:13:02 musicplayer volumio[963]: info: CoreCommandRouter::volumioGetQueue Sep 05 10:13:02 musicplayer volumio[963]: info: CoreStateMachine::getQueue Sep 05 10:13:02 musicplayer volumio[963]: info: CorePlayQueue::getQueue Sep 05 10:13:02 musicplayer volumio[963]: info: Listing playlists Sep 05 10:13:02 musicplayer sudo[1314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 10:13:02 musicplayer sudo[1312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 10:13:02 musicplayer sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 10:13:02 musicplayer sudo[1312]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 10:13:02 musicplayer sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 10:13:02 musicplayer sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 10:13:02 musicplayer sudo[1310]: pam_unix(sudo:session): session closed for user root Sep 05 10:13:02 musicplayer sudo[1312]: pam_unix(sudo:session): session closed for user root Sep 05 10:13:02 musicplayer systemd[1]: Started UPnP Renderer front-end to MPD. Sep 05 10:13:02 musicplayer sudo[1314]: pam_unix(sudo:session): session closed for user root Sep 05 10:13:02 musicplayer volumio[963]: info: Upmpdcli Daemon Started Sep 05 10:13:03 musicplayer volumio[1342]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 05 10:13:03 musicplayer volumio-remote-updater[521]: [2025-09-05 10:13:03] [connect] Successful connection Sep 05 10:13:03 musicplayer volumio-remote-updater[521]: [2025-09-05 10:13:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757059983 101 Sep 05 10:13:03 musicplayer volumio[963]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 9 Sep 05 10:13:04 musicplayer systemd[1]: systemd-timedated.service: Succeeded. Sep 05 10:13:04 musicplayer volumio[963]: info: CoreCommandRouter::volumioGetState Sep 05 10:13:04 musicplayer volumio[963]: info: CorePlayQueue::getTrack 0 Sep 05 10:13:14 musicplayer volumio[963]: info: CoreCommandRouter::volumioGetState Sep 05 10:13:14 musicplayer volumio[963]: info: CorePlayQueue::getTrack 0 Sep 05 10:13:14 musicplayer volumio[963]: info: Listing playlists Sep 05 10:13:14 musicplayer volumio[963]: info: Listing playlists Sep 05 10:13:22 musicplayer volumio[963]: info: Preload queue cleared Sep 05 10:13:22 musicplayer volumio[963]: info: CoreCommandRouter::volumioAddQueueItems Sep 05 10:13:22 musicplayer volumio[963]: info: CoreStateMachine::addQueueItems Sep 05 10:13:22 musicplayer volumio[963]: info: CorePlayQueue::addQueueItems Sep 05 10:13:22 musicplayer volumio[963]: info: Preload queue cleared Sep 05 10:13:22 musicplayer volumio[963]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s178298 Sep 05 10:13:22 musicplayer volumio[963]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 05 10:13:22 musicplayer volumio[963]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 10:13:23 musicplayer volumio[963]: TypeError: Cannot read property 'tune_radio' of undefined Sep 05 10:13:23 musicplayer volumio[963]: at ControllerWebradio.explodeUri (/volumio/app/plugins/music_service/webradio/index.js:538:33) Sep 05 10:13:23 musicplayer volumio[963]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1097:32) Sep 05 10:13:23 musicplayer volumio[963]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:179:33) Sep 05 10:13:23 musicplayer volumio[963]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Sep 05 10:13:23 musicplayer volumio[963]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Sep 05 10:13:23 musicplayer volumio[963]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:449:28) Sep 05 10:13:23 musicplayer volumio[963]: at CoreCommandRouter.addPlay (/volumio/app/index.js:473:10) Sep 05 10:13:23 musicplayer volumio[963]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:483:17) Sep 05 10:13:23 musicplayer volumio[963]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Sep 05 10:13:23 musicplayer volumio[963]: at Socket.emit (events.js:315:20) Sep 05 10:13:23 musicplayer volumio[963]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 05 10:13:23 musicplayer volumio[963]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 05 10:13:23 musicplayer volumio[963]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 10:13:24 musicplayer sudo[1454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 10:12 Sep 05 10:13:24 musicplayer sudo[1454]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 10:13:26 musicplayer volumio[1342]: ...................................................................................++++ 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"