-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-09-07 23:39:10 CEST. -- Sep 07 23:38:00 adiyogi wireless.js[647]: Cleaning previous... Sep 07 23:38:00 adiyogi sudo[891]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 07 23:38:00 adiyogi sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:00 adiyogi sudo[891]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:00 adiyogi sudo[893]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 07 23:38:00 adiyogi sudo[893]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:00 adiyogi systemd[1]: systemd-rfkill.service: Succeeded. Sep 07 23:38:00 adiyogi dockerd[800]: time="2025-09-07T23:38:00.662585939+02:00" level=info msg="libcontainerd: started new docker-containerd process" pid=895 Sep 07 23:38:00 adiyogi dockerd[800]: time="2025-09-07T23:38:00.674347384+02:00" level=info msg="parsed scheme: \"unix\"" module=grpc Sep 07 23:38:00 adiyogi dockerd[800]: time="2025-09-07T23:38:00.674404513+02:00" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc Sep 07 23:38:00 adiyogi dockerd[800]: time="2025-09-07T23:38:00.688058417+02:00" level=info msg="ccResolverWrapper: sending new addresses to cc: [{unix:///var/run/docker/containerd/containerd.sock 0 }]" module=grpc Sep 07 23:38:00 adiyogi dockerd[800]: time="2025-09-07T23:38:00.688145823+02:00" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc Sep 07 23:38:00 adiyogi dockerd[800]: time="2025-09-07T23:38:00.688311339+02:00" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0x452f060, CONNECTING" module=grpc Sep 07 23:38:00 adiyogi ntpd[858]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 07 23:38:00 adiyogi sudo[893]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:01 adiyogi wireless.js[647]: Stopped aP Sep 07 23:38:01 adiyogi sudo[907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 07 23:38:01 adiyogi sudo[907]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:01 adiyogi sudo[907]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:01 adiyogi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 07 23:38:01 adiyogi sudo[909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Sep 07 23:38:01 adiyogi sudo[909]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:01 adiyogi sudo[909]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:01 adiyogi sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 07 23:38:01 adiyogi sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:01 adiyogi sudo[916]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:01 adiyogi sudo[918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Sep 07 23:38:01 adiyogi sudo[918]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:01 adiyogi kernel: rc rc0: two consecutive events of type space Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.527590433+02:00" level=info msg="starting containerd" revision=9754871865f7fe2f4e74d43e2fc7ccd237edcbce version=18.09.1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.528788733+02:00" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.530041624+02:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.530750097+02:00" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.btrfs" error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.btrfs must be a btrfs filesystem to be used with the btrfs snapshotter" Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.530838466+02:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.542809057+02:00" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.aufs" error="modprobe aufs failed: \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.62-v7l+\\n\": exit status 1" Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.542940741+02:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.547888994+02:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.548963073+02:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.551203619+02:00" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.zfs" error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter" Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.551297173+02:00" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.551464356+02:00" level=warning msg="could not use snapshotter aufs in metadata plugin" error="modprobe aufs failed: \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.62-v7l+\\n\": exit status 1" Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.551557522+02:00" level=warning msg="could not use snapshotter zfs in metadata plugin" error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter" Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.551607188+02:00" level=warning msg="could not use snapshotter btrfs in metadata plugin" error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.btrfs must be a btrfs filesystem to be used with the btrfs snapshotter" Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.559260481+02:00" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.559370406+02:00" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.559509182+02:00" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.559586810+02:00" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.559649958+02:00" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.559721160+02:00" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.559788919+02:00" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.559895010+02:00" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.559954935+02:00" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.560010916+02:00" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.560418874+02:00" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.560734777+02:00" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.562466533+02:00" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.562628050+02:00" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.562770622+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.562855825+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.562932805+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.562997527+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.563048341+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.563104525+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.563158395+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.563217172+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.563276468+02:00" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.564475879+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.564579970+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.564643284+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.564729116+02:00" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.565247351+02:00" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.565511069+02:00" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.565616994+02:00" level=info msg="containerd successfully booted in 0.046615s" Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.570871280+02:00" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0x452f060, READY" module=grpc Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.630733846+02:00" level=info msg="parsed scheme: \"unix\"" module=grpc Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.630809197+02:00" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.631387949+02:00" level=info msg="ccResolverWrapper: sending new addresses to cc: [{unix:///var/run/docker/containerd/containerd.sock 0 }]" module=grpc Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.631502207+02:00" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.631618224+02:00" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0x4547f00, CONNECTING" module=grpc Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.632458695+02:00" level=info msg="parsed scheme: \"unix\"" module=grpc Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.632545972+02:00" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.632738247+02:00" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0x4547f00, READY" module=grpc Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.632990873+02:00" level=info msg="ccResolverWrapper: sending new addresses to cc: [{unix:///var/run/docker/containerd/containerd.sock 0 }]" module=grpc Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.633147001+02:00" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.633314573+02:00" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0x449c4b0, CONNECTING" module=grpc Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.633889696+02:00" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0x449c4b0, READY" module=grpc Sep 07 23:38:01 adiyogi kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 07 23:38:01 adiyogi kernel: loop1: detected capacity change from 0 to 209715200 Sep 07 23:38:01 adiyogi ntpd[858]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 07 23:38:01 adiyogi kernel: loop2: detected capacity change from 0 to 4194304 Sep 07 23:38:01 adiyogi dockerd[800]: time="2025-09-07T23:38:01.963099929+02:00" level=warning msg="Usage of loopback devices is strongly discouraged for production use. Please use `--storage-opt dm.thinpooldev` or use `man dockerd` to refer to dm.thinpooldev section." storage-driver=devicemapper Sep 07 23:38:02 adiyogi dockerd[800]: time="2025-09-07T23:38:02.119666285+02:00" level=warning msg="Base device already exists and has filesystem ext4 on it. User specified filesystem will be ignored." storage-driver=devicemapper Sep 07 23:38:02 adiyogi mpd[862]: Sep 07 23:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 07 23:38:02 adiyogi dockerd[800]: time="2025-09-07T23:38:02.200368942+02:00" level=info msg="[graphdriver] using prior storage driver: devicemapper" Sep 07 23:38:02 adiyogi dockerd[800]: time="2025-09-07T23:38:02.200446534+02:00" level=warning msg="[graphdriver] WARNING: the devicemapper storage-driver is deprecated, and will be removed in a future release" Sep 07 23:38:02 adiyogi bthelper[763]: Changing power off succeeded Sep 07 23:38:02 adiyogi systemd[1]: Started Music Player Daemon. Sep 07 23:38:02 adiyogi mpd_monitor.sh[659]: MPD Monitor Service: MPD restarted due to no mpc output. Sep 07 23:38:02 adiyogi dockerd[800]: time="2025-09-07T23:38:02.416445387+02:00" level=info msg="Graph migration to content-addressability took 0.00 seconds" Sep 07 23:38:02 adiyogi dockerd[800]: time="2025-09-07T23:38:02.416977714+02:00" level=warning msg="Your kernel does not support cgroup memory limit" Sep 07 23:38:02 adiyogi dockerd[800]: time="2025-09-07T23:38:02.417115398+02:00" level=warning msg="Your kernel does not support cgroup rt period" Sep 07 23:38:02 adiyogi dockerd[800]: time="2025-09-07T23:38:02.417180341+02:00" level=warning msg="Your kernel does not support cgroup rt runtime" Sep 07 23:38:02 adiyogi dockerd[800]: time="2025-09-07T23:38:02.417232211+02:00" level=warning msg="Your kernel does not support cgroup blkio weight" Sep 07 23:38:02 adiyogi dockerd[800]: time="2025-09-07T23:38:02.417274322+02:00" level=warning msg="Your kernel does not support cgroup blkio weight_device" Sep 07 23:38:02 adiyogi dockerd[800]: time="2025-09-07T23:38:02.418495862+02:00" level=info msg="Loading containers: start." Sep 07 23:38:02 adiyogi bthelper[763]: [CHG] Controller 2C:CF:67:90:DB:ED Class: 0x0000041c Sep 07 23:38:02 adiyogi bthelper[763]: Changing power on succeeded Sep 07 23:38:02 adiyogi 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 07 23:38:02 adiyogi kernel: Bridge firewalling registered Sep 07 23:38:02 adiyogi volumio-remote-updater[648]: [2025-09-07 23:38:02] [info] asio async_connect error: system:111 (Connection refused) Sep 07 23:38:02 adiyogi volumio-remote-updater[648]: [2025-09-07 23:38:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 07 23:38:02 adiyogi volumio-remote-updater[648]: [2025-09-07 23:38:02] [error] handle_connect error: Underlying Transport Error Sep 07 23:38:02 adiyogi volumio-time-update[667]: volumio-time-update-util: Fetching time from Volumio... Sep 07 23:38:02 adiyogi systemd-udevd[388]: Using default interface naming scheme 'v240'. Sep 07 23:38:02 adiyogi systemd-udevd[388]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 07 23:38:02 adiyogi sh[581]: docker0: new hardware address: 02:42:0a:1c:a5:aa Sep 07 23:38:02 adiyogi dhcpcd[627]: docker0: new hardware address: 02:42:0a:1c:a5:aa Sep 07 23:38:02 adiyogi volumio-time-update[667]: volumio-time-update-util: Date not found in response Sep 07 23:38:02 adiyogi volumio-time-update[667]: volumio-time-update-util: Retrying in 5 seconds... Sep 07 23:38:03 adiyogi avahi-daemon[653]: Joining mDNS multicast group on interface docker0.IPv4 with address 172.17.0.1. Sep 07 23:38:03 adiyogi avahi-daemon[653]: New relevant interface docker0.IPv4 for mDNS. Sep 07 23:38:03 adiyogi avahi-daemon[653]: Registering new address record for 172.17.0.1 on docker0.IPv4. Sep 07 23:38:03 adiyogi sudo[918]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:03 adiyogi wireless.js[647]: SETTING APPROPRIATE REG DOMAIN: FR Sep 07 23:38:03 adiyogi sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 07 23:38:03 adiyogi sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:03 adiyogi sudo[1034]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:03 adiyogi sudo[1036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set FR Sep 07 23:38:03 adiyogi sudo[1036]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:03 adiyogi sudo[1036]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:04 adiyogi wireless.js[647]: SUCCESSFULLY SET NEW REGDOMAIN: FR Sep 07 23:38:04 adiyogi wireless.js[647]: Start wireless flow Sep 07 23:38:04 adiyogi wireless.js[647]: Stopped hotspot (if there).. Sep 07 23:38:04 adiyogi sudo[1042]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 07 23:38:04 adiyogi sudo[1042]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:04 adiyogi sudo[1042]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:04 adiyogi sudo[1045]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 07 23:38:04 adiyogi sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:04 adiyogi sudo[1045]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:04 adiyogi nmbd[816]: [2025/09/07 23:38:04.772643, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 07 23:38:04 adiyogi nmbd[816]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 07 23:38:04 adiyogi systemd[1]: Started Samba NMB Daemon. Sep 07 23:38:04 adiyogi wireless.js[647]: DHCP IP Sep 07 23:38:04 adiyogi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 07 23:38:04 adiyogi systemd[1]: Starting Samba Winbind Daemon... Sep 07 23:38:04 adiyogi wireless.js[647]: Start ap Sep 07 23:38:04 adiyogi wpa_supplicant[1061]: Successfully initialized wpa_supplicant Sep 07 23:38:04 adiyogi sudo[1063]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Sep 07 23:38:04 adiyogi sudo[1063]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:04 adiyogi dhcpcd[1065]: dev: loaded udev Sep 07 23:38:04 adiyogi dhcpcd-run-hooks[1085]: wlan0: starting wpa_supplicant Sep 07 23:38:04 adiyogi dockerd[800]: time="2025-09-07T23:38:04.951106031+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 07 23:38:05 adiyogi winbindd[1062]: [2025/09/07 23:38:05.091554, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 07 23:38:05 adiyogi winbindd[1062]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 07 23:38:05 adiyogi dhcpcd-run-hooks[1115]: wlan0: failed to start wpa_supplicant Sep 07 23:38:05 adiyogi dhcpcd-run-hooks[1116]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Sep 07 23:38:05 adiyogi dhcpcd[1065]: wlan0: connected to Access Point `' Sep 07 23:38:05 adiyogi dhcpcd[1065]: no interfaces have a carrier Sep 07 23:38:05 adiyogi dhcpcd[1065]: forked to background, child pid 1126 Sep 07 23:38:05 adiyogi dhcpcd[1126]: eth0: waiting for carrier Sep 07 23:38:05 adiyogi dhcpcd[1126]: wlan0: waiting for carrier Sep 07 23:38:05 adiyogi sudo[1063]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:05 adiyogi wireless.js[647]: trying... Sep 07 23:38:05 adiyogi sudo[1128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 23:38:05 adiyogi sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:07 adiyogi winbindd[1062]: [2025/09/07 23:38:07.844958, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 07 23:38:07 adiyogi winbindd[1062]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 07 23:38:07 adiyogi volumio-time-update[667]: volumio-time-update-util: Fetching time from Volumio... Sep 07 23:38:07 adiyogi systemd[1]: Started Samba Winbind Daemon. Sep 07 23:38:07 adiyogi sudo[1128]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:07 adiyogi volumio-time-update[667]: volumio-time-update-util: Date not found in response Sep 07 23:38:07 adiyogi volumio-time-update[667]: volumio-time-update-util: Retrying in 5 seconds... Sep 07 23:38:07 adiyogi wireless.js[647]: trying... Sep 07 23:38:07 adiyogi sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 23:38:07 adiyogi systemd[1]: Starting Samba SMB Daemon... Sep 07 23:38:07 adiyogi sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:07 adiyogi sudo[1137]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:08 adiyogi wireless.js[647]: trying... Sep 07 23:38:08 adiyogi sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 23:38:08 adiyogi sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:08 adiyogi sudo[1141]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:09 adiyogi dockerd[800]: time="2025-09-07T23:38:09.699180245+02:00" level=info msg="Loading containers: done." Sep 07 23:38:09 adiyogi wireless.js[647]: trying... Sep 07 23:38:09 adiyogi sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 23:38:09 adiyogi sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:09 adiyogi sudo[1157]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:09 adiyogi dockerd[800]: time="2025-09-07T23:38:09.936313075+02:00" level=info msg="Docker daemon" commit=4c52b90 graphdriver(s)=devicemapper version=18.09.1 Sep 07 23:38:09 adiyogi dockerd[800]: time="2025-09-07T23:38:09.940126102+02:00" level=info msg="Daemon has completed initialization" Sep 07 23:38:10 adiyogi systemd[1]: Started Docker Application Container Engine. Sep 07 23:38:10 adiyogi dockerd[800]: time="2025-09-07T23:38:10.012963468+02:00" level=info msg="API listen on /var/run/docker.sock" Sep 07 23:38:10 adiyogi smbd[1143]: [2025/09/07 23:38:10.035082, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 07 23:38:10 adiyogi smbd[1143]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 07 23:38:10 adiyogi systemd[1]: Started Samba SMB Daemon. Sep 07 23:38:10 adiyogi volumio-remote-updater[648]: [2025-09-07 23:38:10] [info] asio async_connect error: system:111 (Connection refused) Sep 07 23:38:10 adiyogi volumio-remote-updater[648]: [2025-09-07 23:38:10] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 07 23:38:10 adiyogi volumio-remote-updater[648]: [2025-09-07 23:38:10] [error] handle_connect error: Underlying Transport Error Sep 07 23:38:10 adiyogi wireless.js[647]: trying... Sep 07 23:38:10 adiyogi sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 23:38:10 adiyogi sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:10 adiyogi sudo[1168]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:11 adiyogi wpa_supplicant[1093]: wlan0: Trying to associate with SSID 'adiyogi' Sep 07 23:38:11 adiyogi dhcpcd[1126]: wlan0: carrier acquired Sep 07 23:38:11 adiyogi wpa_supplicant[1093]: wlan0: Associated with ac:3b:77:62:43:00 Sep 07 23:38:11 adiyogi dhcpcd[1126]: wlan0: connected to Access Point `adiyogi' Sep 07 23:38:11 adiyogi wpa_supplicant[1093]: wlan0: CTRL-EVENT-CONNECTED - Connection to ac:3b:77:62:43:00 completed [id=0 id_str=] Sep 07 23:38:11 adiyogi wpa_supplicant[1093]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 07 23:38:11 adiyogi dhcpcd[1126]: DUID 00:01:00:01:2d:3d:6c:96:e4:5f:01:6c:e6:dc Sep 07 23:38:11 adiyogi dhcpcd[1126]: wlan0: IAID 67:90:db:eb Sep 07 23:38:11 adiyogi dhcpcd[1126]: wlan0: adding address fe80::e365:f35:1928:e5f Sep 07 23:38:11 adiyogi dhcpcd[1126]: ipv6_addaddr1: Permission denied Sep 07 23:38:11 adiyogi dhcpcd[1126]: wlan0: carrier lost Sep 07 23:38:11 adiyogi dhcpcd[1126]: wlan0: carrier acquired Sep 07 23:38:11 adiyogi dhcpcd[1126]: wlan0: IAID 67:90:db:eb Sep 07 23:38:11 adiyogi wireless.js[647]: trying... Sep 07 23:38:11 adiyogi sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 23:38:11 adiyogi sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:11 adiyogi sudo[1183]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:12 adiyogi dhcpcd[1126]: wlan0: soliciting an IPv6 router Sep 07 23:38:12 adiyogi dhcpcd[1126]: wlan0: rebinding lease of 192.168.1.140 Sep 07 23:38:12 adiyogi dhcpcd[1126]: wlan0: probing address 192.168.1.140/24 Sep 07 23:38:12 adiyogi wireless.js[647]: trying... Sep 07 23:38:12 adiyogi sudo[1186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 23:38:12 adiyogi sudo[1186]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:12 adiyogi volumio-time-update[667]: volumio-time-update-util: Fetching time from Volumio... Sep 07 23:38:12 adiyogi sudo[1186]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:12 adiyogi volumio-time-update[667]: volumio-time-update-util: Date not found in response Sep 07 23:38:12 adiyogi volumio-time-update[667]: volumio-time-update-util: Retrying in 5 seconds... Sep 07 23:38:13 adiyogi wireless.js[647]: trying... Sep 07 23:38:13 adiyogi sudo[1194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 23:38:13 adiyogi sudo[1194]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:13 adiyogi sudo[1194]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:14 adiyogi wireless.js[647]: trying... Sep 07 23:38:14 adiyogi sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 23:38:14 adiyogi sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:14 adiyogi sudo[1197]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:15 adiyogi wireless.js[647]: trying... Sep 07 23:38:15 adiyogi sudo[1200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 23:38:15 adiyogi sudo[1200]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:15 adiyogi sudo[1200]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:16 adiyogi wireless.js[647]: trying... Sep 07 23:38:16 adiyogi sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 23:38:16 adiyogi sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:16 adiyogi sudo[1203]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:17 adiyogi dhcpcd[1126]: wlan0: leased 192.168.1.140 for 86400 seconds Sep 07 23:38:17 adiyogi avahi-daemon[653]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.140. Sep 07 23:38:17 adiyogi dhcpcd[1126]: wlan0: adding route to 192.168.1.0/24 Sep 07 23:38:17 adiyogi avahi-daemon[653]: New relevant interface wlan0.IPv4 for mDNS. Sep 07 23:38:17 adiyogi dhcpcd[1126]: wlan0: adding default route via 192.168.1.254 Sep 07 23:38:17 adiyogi avahi-daemon[653]: Registering new address record for 192.168.1.140 on wlan0.IPv4. Sep 07 23:38:17 adiyogi wireless.js[647]: trying... Sep 07 23:38:17 adiyogi sudo[1227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 23:38:17 adiyogi sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:17 adiyogi sudo[1227]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:17 adiyogi wireless.js[647]: Connected to: ----adiyogi Sep 07 23:38:17 adiyogi wireless.js[647]: ---- Sep 07 23:38:17 adiyogi volumio-time-update[667]: volumio-time-update-util: Fetching time from Volumio... Sep 07 23:38:17 adiyogi sudo[1230]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 07 23:38:17 adiyogi sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:17 adiyogi sudo[1230]: pam_unix(sudo:session): session closed for user root Sep 07 23:38:17 adiyogi wireless.js[647]: ... joined AP, wlan0 IPv4 is 192.168.1.140, ipV6 is undefined Sep 07 23:38:17 adiyogi wireless.js[647]: It's done! AP Sep 07 23:38:17 adiyogi volumio-time-update[667]: volumio-time-update-util: Setting system time to: 2025-09-07 23:39:01 Sep 07 23:38:17 adiyogi sudo[1239]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-07 23:39:01 Sep 07 23:38:17 adiyogi sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:38:17 adiyogi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 07 23:38:17 adiyogi systemd[1]: Started Wireless Services. Sep 07 23:38:17 adiyogi systemd[1]: Started Volumio Backend Module. Sep 07 23:38:17 adiyogi systemd[1]: Starting LCD Display Service for Volumio... Sep 07 23:38:18 adiyogi systemd[1]: Started Volumio Cpu Tweaker. Sep 07 23:38:18 adiyogi dbus-daemon[663]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.11' (uid=0 pid=1241 comm="timedatectl set-time 2025-09-07 23:39:01 ") Sep 07 23:38:18 adiyogi systemd[1]: Started LCD Display Service for Volumio. Sep 07 23:38:18 adiyogi systemd[1]: Starting Time & Date Service... Sep 07 23:38:18 adiyogi volumio-cpu-tweak[1244]: Setting RT Priority for mpd Sep 07 23:38:18 adiyogi volumio-cpu-tweak[1244]: Setting MPD Affinity Sep 07 23:38:18 adiyogi volumio-cpu-tweak[1244]: pid 862's current affinity mask: f Sep 07 23:38:18 adiyogi volumio-cpu-tweak[1244]: pid 862's new affinity mask: 3 Sep 07 23:38:18 adiyogi volumio-cpu-tweak[1244]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Sep 07 23:38:18 adiyogi volumio-cpu-tweak[1244]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Sep 07 23:38:18 adiyogi dbus-daemon[663]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 07 23:38:18 adiyogi systemd[1]: Started Time & Date Service. Sep 07 23:39:01 adiyogi systemd-timedated[1257]: Changed local time to Sun Sep 7 23:39:01 2025 Sep 07 23:39:01 adiyogi sudo[1239]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:01 adiyogi volumio-time-update[667]: volumio-time-update-util: System time updated successfully. Sep 07 23:39:01 adiyogi systemd[1]: Started Volumio Time Update Utility. Sep 07 23:39:01 adiyogi systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 07 23:39:01 adiyogi systemd[1]: Reached target Multi-User System. Sep 07 23:39:01 adiyogi systemd[1]: Reached target Graphical Interface. Sep 07 23:39:01 adiyogi systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 07 23:39:01 adiyogi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 07 23:39:01 adiyogi systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 07 23:39:01 adiyogi systemd[1]: Startup finished in 15.276s (kernel) + 25.213s (userspace) = 40.489s. Sep 07 23:39:01 adiyogi ntpd[858]: Listen normally on 3 wlan0 192.168.1.140:123 Sep 07 23:39:01 adiyogi ntpd[858]: new interface(s) found: waking up resolver Sep 07 23:39:02 adiyogi dhcpcd[1126]: wlan0: carrier lost Sep 07 23:39:02 adiyogi wpa_supplicant[1093]: wlan0: Associated with ac:3b:77:62:43:04 Sep 07 23:39:02 adiyogi wpa_supplicant[1093]: wlan0: CTRL-EVENT-CONNECTED - Connection to ac:3b:77:62:43:04 completed [id=0 id_str=] Sep 07 23:39:02 adiyogi wpa_supplicant[1093]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 07 23:39:02 adiyogi avahi-daemon[653]: Withdrawing address record for 192.168.1.140 on wlan0. Sep 07 23:39:02 adiyogi avahi-daemon[653]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.140. Sep 07 23:39:02 adiyogi dhcpcd[1126]: wlan0: deleting route to 192.168.1.0/24 Sep 07 23:39:02 adiyogi avahi-daemon[653]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 07 23:39:02 adiyogi dhcpcd[1126]: wlan0: deleting default route via 192.168.1.254 Sep 07 23:39:02 adiyogi dhcpcd[1126]: wlan0: carrier acquired Sep 07 23:39:02 adiyogi dhcpcd[1126]: wlan0: IAID 67:90:db:eb Sep 07 23:39:03 adiyogi volumio-remote-updater[648]: [2025-09-07 23:39:03] [connect] Successful connection Sep 07 23:39:03 adiyogi dhcpcd[1126]: wlan0: soliciting an IPv6 router Sep 07 23:39:03 adiyogi volumio[1242]: info: ------------------------------------------- Sep 07 23:39:03 adiyogi volumio[1242]: info: ----- Volumio3 ---- Sep 07 23:39:03 adiyogi volumio[1242]: info: ------------------------------------------- Sep 07 23:39:03 adiyogi volumio[1242]: info: ----- System startup ---- Sep 07 23:39:03 adiyogi volumio[1242]: info: ------------------------------------------- Sep 07 23:39:03 adiyogi dhcpcd[1126]: wlan0: rebinding lease of 192.168.1.140 Sep 07 23:39:03 adiyogi dhcpcd[1126]: wlan0: probing address 192.168.1.140/24 Sep 07 23:39:03 adiyogi volumio[1242]: info: MYVOLUMIO Environment detected Sep 07 23:39:03 adiyogi volumio[1242]: info: Plugin folders cleanup Sep 07 23:39:03 adiyogi volumio[1242]: info: Scanning into folder /volumio/app/plugins/ Sep 07 23:39:03 adiyogi volumio[1242]: info: Scanning category audio_interface Sep 07 23:39:03 adiyogi volumio[1242]: info: Scanning category miscellanea Sep 07 23:39:03 adiyogi volumio[1242]: info: Scanning category music_service Sep 07 23:39:03 adiyogi volumio[1242]: info: Scanning category plugins.json Sep 07 23:39:03 adiyogi volumio[1242]: info: Scanning category system_controller Sep 07 23:39:03 adiyogi volumio[1242]: info: Scanning category user_interface Sep 07 23:39:03 adiyogi volumio[1242]: info: Scanning into folder /data/plugins/ Sep 07 23:39:03 adiyogi volumio[1242]: info: Scanning category audio_interface Sep 07 23:39:03 adiyogi volumio[1242]: info: Scanning category system_controller Sep 07 23:39:03 adiyogi volumio[1242]: info: Plugin folders cleanup completed Sep 07 23:39:03 adiyogi volumio[1242]: info: ------------------------------------------- Sep 07 23:39:03 adiyogi volumio[1242]: info: ----- Core plugins startup ---- Sep 07 23:39:03 adiyogi volumio[1242]: info: ------------------------------------------- Sep 07 23:39:03 adiyogi volumio[1242]: info: Loading plugins from folder /volumio/app/plugins/ Sep 07 23:39:03 adiyogi volumio[1242]: info: Adding plugin upnp to MyMusic Plugins Sep 07 23:39:03 adiyogi volumio[1242]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 07 23:39:03 adiyogi volumio[1242]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 07 23:39:03 adiyogi volumio[1242]: info: Loading plugins from folder /data/plugins/ Sep 07 23:39:03 adiyogi volumio[1242]: info: Loading plugin "system"... Sep 07 23:39:03 adiyogi volumio[1242]: info: Loading plugin "appearance"... Sep 07 23:39:04 adiyogi ntpd[858]: Deleting interface #3 wlan0, 192.168.1.140#123, interface stats: received=0, sent=0, dropped=0, active_time=3 secs Sep 07 23:39:04 adiyogi volumio[1242]: info: Loading plugin "network"... Sep 07 23:39:04 adiyogi volumio[1242]: info: Refreshing Cached IP Addresses Sep 07 23:39:04 adiyogi sudo[1363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 07 23:39:04 adiyogi sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:04 adiyogi sudo[1363]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:04 adiyogi sudo[1365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 07 23:39:04 adiyogi sudo[1365]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:04 adiyogi sudo[1365]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:04 adiyogi volumio[1242]: info: Loading plugin "services"... Sep 07 23:39:04 adiyogi volumio[1242]: info: Loading plugin "alsa_controller"... Sep 07 23:39:04 adiyogi sudo[1375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 07 23:39:04 adiyogi sudo[1375]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:05 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 23:39:05 adiyogi volumio[1242]: info: Loading plugin "wizard"... Sep 07 23:39:05 adiyogi volumio[1242]: info: Loading plugin "networkfs"... Sep 07 23:39:05 adiyogi volumio[1242]: info: Starting Udev Watcher for removable devices Sep 07 23:39:05 adiyogi volumio[1242]: info: Ignoring mount for partition: boot Sep 07 23:39:05 adiyogi volumio[1242]: info: Ignoring mount for partition: volumio Sep 07 23:39:05 adiyogi volumio[1242]: info: Ignoring mount for partition: volumio_data Sep 07 23:39:05 adiyogi volumio[1242]: info: Mounting Device ADIYOGI Sep 07 23:39:05 adiyogi sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/ADIYOGI -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 07 23:39:05 adiyogi sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:05 adiyogi kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Sep 07 23:39:05 adiyogi sudo[1397]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:05 adiyogi kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Sep 07 23:39:05 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 23:39:05 adiyogi volumio[1242]: info: Loading plugin "volumio_command_line_client"... Sep 07 23:39:05 adiyogi volumio[1242]: info: Loading plugin "upnp"... Sep 07 23:39:05 adiyogi volumio[1242]: info: [1757281145281] Starting Upmpd Daemon Sep 07 23:39:05 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 23:39:05 adiyogi volumio[1242]: info: Loading plugin "my_music"... Sep 07 23:39:05 adiyogi volumio[1242]: info: Loading plugin "mpd"... Sep 07 23:39:05 adiyogi volumio[1242]: info: Loading plugin "upnp_browser"... Sep 07 23:39:05 adiyogi volumio[1242]: info: Loading plugin "alarm-clock"... Sep 07 23:39:06 adiyogi volumio[1242]: info: Alarm: Scheduling Playlist fullmoon at 0:0 Sep 07 23:39:06 adiyogi volumio[1242]: info: Loading plugin "airplay_emulation"... Sep 07 23:39:06 adiyogi volumio[1242]: info: Starting Shairport Sync Sep 07 23:39:06 adiyogi volumio[1242]: info: Loading plugin "last_100"... Sep 07 23:39:06 adiyogi volumio[1242]: info: Loading plugin "webradio"... Sep 07 23:39:06 adiyogi volumio[1242]: info: Loading plugin "i2s_dacs"... Sep 07 23:39:06 adiyogi volumio[1242]: info: Loading plugin "volumiodiscovery"... Sep 07 23:39:06 adiyogi volumio[1242]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 23:39:06 adiyogi volumio[1242]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 23:39:06 adiyogi volumio[1242]: *** WARNING *** For more information see Sep 07 23:39:06 adiyogi volumio[1242]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 23:39:06 adiyogi volumio[1242]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 23:39:06 adiyogi node[1242]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 23:39:06 adiyogi volumio[1242]: *** WARNING *** For more information see Sep 07 23:39:06 adiyogi node[1242]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 23:39:06 adiyogi node[1242]: *** WARNING *** For more information see Sep 07 23:39:06 adiyogi node[1242]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 23:39:06 adiyogi node[1242]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 23:39:06 adiyogi node[1242]: *** WARNING *** For more information see Sep 07 23:39:06 adiyogi volumio[1242]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 07 23:39:06 adiyogi volumio[1242]: info: Discovery: Started advertising with name: Adiyogi Sep 07 23:39:06 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 23:39:06 adiyogi volumio[1242]: info: Loading plugin "outputs"... Sep 07 23:39:06 adiyogi volumio[1242]: info: Loading plugin "albumart"... Sep 07 23:39:06 adiyogi volumio[1242]: info: Plugin example_plugin is not enabled Sep 07 23:39:06 adiyogi volumio[1242]: info: Loading plugin "inputs"... Sep 07 23:39:06 adiyogi volumio[1242]: info: Loading plugin "updater_comm"... Sep 07 23:39:06 adiyogi volumio[1242]: info: Plugin mpdemulation is not enabled Sep 07 23:39:06 adiyogi volumio[1242]: info: Loading plugin "rest_api"... Sep 07 23:39:06 adiyogi volumio[1242]: info: Loading plugin "websocket"... Sep 07 23:39:06 adiyogi volumio[1242]: info: Starting Socket.io Server version 2.3.0 Sep 07 23:39:06 adiyogi volumio[1242]: info: Loading plugin "fusiondsp"... Sep 07 23:39:06 adiyogi volumio[1242]: Forking 3 albumart workers Sep 07 23:39:06 adiyogi volumio[1242]: info: Applying required configuration parameters for plugin fusiondsp Sep 07 23:39:07 adiyogi volumio[1242]: Starting albumart workers Sep 07 23:39:07 adiyogi volumio[1242]: info: Loading plugin "backup_restore"... Sep 07 23:39:07 adiyogi volumio[1242]: Starting albumart workers Sep 07 23:39:07 adiyogi volumio[1242]: Starting albumart workers Sep 07 23:39:07 adiyogi volumio[1242]: info: Applying required configuration parameters for plugin backup_restore Sep 07 23:39:07 adiyogi volumio[1242]: info: Loading i18n strings for locale en Sep 07 23:39:07 adiyogi volumio[1242]: Updating browse sources language Sep 07 23:39:07 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 23:39:07 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 23:39:07 adiyogi volumio[1242]: info: CoreCommandRouter::initPlayerControls Sep 07 23:39:07 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:07 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:07 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:07 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:07 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:07 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:07 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:07 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:07 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 23:39:07 adiyogi volumio[1242]: Express server listening on port 3000 Sep 07 23:39:07 adiyogi volumio[1242]: [Metrics] WebUI: 5s 450.17ms Sep 07 23:39:07 adiyogi volumio[1242]: info: CoreStateMachine::resetVolumioState Sep 07 23:39:07 adiyogi volumio[1242]: info: CoreStateMachine::getcurrentVolume Sep 07 23:39:07 adiyogi volumio[1242]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 23:39:07 adiyogi volumio[1242]: info: Volumio Network Manager: Network status updated: 0 Sep 07 23:39:07 adiyogi volumio[1242]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 07 23:39:07 adiyogi volumio-remote-updater[648]: [2025-09-07 23:39:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757281143 101 Sep 07 23:39:07 adiyogi volumio[1242]: 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: 2 Sep 07 23:39:07 adiyogi volumio[1242]: info: Reloading queue from file Sep 07 23:39:07 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::volumioGetState Sep 07 23:39:08 adiyogi volumio[1242]: info: CorePlayQueue::getTrack 0 Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreStateMachine::setRepeat true single undefined Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreStateMachine::pushState Sep 07 23:39:08 adiyogi volumio[1242]: info: CorePlayQueue::getTrack 0 Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::volumioPushState Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreStateMachine::setRandom false Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreStateMachine::pushState Sep 07 23:39:08 adiyogi volumio[1242]: info: CorePlayQueue::getTrack 0 Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::volumioPushState Sep 07 23:39:08 adiyogi volumio[1242]: info: Setting Device type: Raspberry PI Sep 07 23:39:08 adiyogi volumio[1242]: info: Completed loading Core Plugins Sep 07 23:39:08 adiyogi volumio[1242]: info: Preparing to generate the ALSA configuration file Sep 07 23:39:08 adiyogi volumio[1242]: info: VolumeController:: Volume=0 Mute =false Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreStateMachine::pushState Sep 07 23:39:08 adiyogi volumio[1242]: info: CorePlayQueue::getTrack 0 Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::volumioPushState Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreStateMachine::updateTrackBlock Sep 07 23:39:08 adiyogi volumio[1242]: info: CorePlayQueue::getTrackBlock Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 23:39:08 adiyogi volumio[1242]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 07 23:39:08 adiyogi volumio[1242]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Sep 07 23:39:08 adiyogi volumio[1242]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 07 23:39:08 adiyogi volumio[1242]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 07 23:39:08 adiyogi volumio[1242]: info: Reading ALSA contributions from plugins. Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::volumioGetQueue Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreStateMachine::getQueue Sep 07 23:39:08 adiyogi volumio[1242]: info: CorePlayQueue::getQueue Sep 07 23:39:08 adiyogi volumio[1242]: info: Discovery: adding 5049e3e3-faf1-48a9-a325-9b441a45cc77 Sep 07 23:39:08 adiyogi volumio[1242]: info: Discovery: Found device Adiyogi Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::volumioGetState Sep 07 23:39:08 adiyogi volumio[1242]: info: CorePlayQueue::getTrack 0 Sep 07 23:39:08 adiyogi volumio[1242]: info: Asound.conf file unchanged, so no further update is needed Sep 07 23:39:08 adiyogi volumio[1242]: info: Output device has changed, restarting MPD Sep 07 23:39:08 adiyogi volumio[1242]: info: Output device has changed, restarting Shairport Sync Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi sudo[1482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 23:39:08 adiyogi sudo[1482]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:08 adiyogi sudo[1482]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:08 adiyogi sudo[1484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 23:39:08 adiyogi sudo[1484]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:08 adiyogi systemd[1]: Stopping Music Player Daemon... Sep 07 23:39:08 adiyogi volumio[1242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 07 23:39:08 adiyogi volumio[1242]: info: ___________ START PLUGINS ___________ Sep 07 23:39:08 adiyogi volumio[1242]: info: ControllerMpd::onStart: Initializing MPD Sep 07 23:39:08 adiyogi volumio[1242]: info: Creating MPD Configuration file Sep 07 23:39:08 adiyogi sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 23:39:08 adiyogi volumio[1242]: info: [1757281148222] CoreMusicLibrary::Adding element Media Servers Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 23:39:08 adiyogi sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:08 adiyogi sudo[1491]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:08 adiyogi sudo[1493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 23:39:08 adiyogi sudo[1493]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 23:39:08 adiyogi systemd[1]: mpd.service: Succeeded. Sep 07 23:39:08 adiyogi systemd[1]: Stopped Music Player Daemon. Sep 07 23:39:08 adiyogi volumio[1242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 23:39:08 adiyogi volumio[1242]: info: [1757281148307] CoreMusicLibrary::Adding element Last_100 Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 23:39:08 adiyogi volumio[1242]: info: [1757281148309] CoreMusicLibrary::Adding element Webradio Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 23:39:08 adiyogi volumio[1242]: info: Initializing BBC Radios Sep 07 23:39:08 adiyogi systemd[1]: Starting Music Player Daemon... Sep 07 23:39:08 adiyogi systemd[1]: systemd-fsckd.service: Succeeded. Sep 07 23:39:08 adiyogi sudo[1375]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: Loading i18n strings for locale en Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: FusionDsp - mixtype--------------------- Hardware Sep 07 23:39:08 adiyogi volumio[1242]: info: Volumio Calling Home Sep 07 23:39:08 adiyogi sudo[1502]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 23:39:08 adiyogi sudo[1502]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:08 adiyogi sudo[1502]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:08 adiyogi volumio[1242]: info: Preparing to generate the ALSA configuration file Sep 07 23:39:08 adiyogi volumio[1242]: Unhandled rejection Error: No sockets available, cannot start. Sep 07 23:39:08 adiyogi volumio[1242]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Sep 07 23:39:08 adiyogi volumio[1242]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Sep 07 23:39:08 adiyogi volumio[1242]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Sep 07 23:39:08 adiyogi volumio[1242]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Sep 07 23:39:08 adiyogi volumio[1242]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Sep 07 23:39:08 adiyogi volumio[1242]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Sep 07 23:39:08 adiyogi volumio[1242]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Sep 07 23:39:08 adiyogi volumio[1242]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Sep 07 23:39:08 adiyogi volumio[1242]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Sep 07 23:39:08 adiyogi volumio[1242]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Sep 07 23:39:08 adiyogi volumio[1242]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Sep 07 23:39:08 adiyogi volumio[1242]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 07 23:39:08 adiyogi volumio[1242]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 07 23:39:08 adiyogi volumio[1242]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Sep 07 23:39:08 adiyogi volumio[1242]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Sep 07 23:39:08 adiyogi volumio[1242]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 07 23:39:08 adiyogi volumio[1242]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 07 23:39:08 adiyogi volumio[1242]: info: Reading ALSA contributions from plugins. Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::volumioGetState Sep 07 23:39:08 adiyogi volumio[1242]: info: CorePlayQueue::getTrack 0 Sep 07 23:39:08 adiyogi volumio[1242]: info: MPD Permissions set Sep 07 23:39:08 adiyogi volumio[1242]: info: MPD Permissions set Sep 07 23:39:08 adiyogi volumio[1242]: info: VolumeController:: Volume=0 Mute =false Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreStateMachine::pushState Sep 07 23:39:08 adiyogi volumio[1242]: info: CorePlayQueue::getTrack 0 Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::volumioPushState Sep 07 23:39:08 adiyogi volumio[1242]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: Starting Shairport Sync Sep 07 23:39:08 adiyogi volumio[1242]: info: Starting Shairport Sync Sep 07 23:39:08 adiyogi volumio[1242]: info: Starting Shairport Sync Sep 07 23:39:08 adiyogi sudo[1527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 07 23:39:08 adiyogi sudo[1527]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:08 adiyogi sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 07 23:39:08 adiyogi sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:08 adiyogi dhcpcd[1126]: wlan0: leased 192.168.1.140 for 86400 seconds Sep 07 23:39:08 adiyogi avahi-daemon[653]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.140. Sep 07 23:39:08 adiyogi dhcpcd[1126]: wlan0: adding route to 192.168.1.0/24 Sep 07 23:39:08 adiyogi dhcpcd[1126]: wlan0: adding default route via 192.168.1.254 Sep 07 23:39:08 adiyogi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 07 23:39:08 adiyogi avahi-daemon[653]: New relevant interface wlan0.IPv4 for mDNS. Sep 07 23:39:08 adiyogi avahi-daemon[653]: Registering new address record for 192.168.1.140 on wlan0.IPv4. Sep 07 23:39:08 adiyogi systemd[1]: shairport-sync.service: Succeeded. Sep 07 23:39:08 adiyogi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 07 23:39:08 adiyogi sudo[1532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 07 23:39:08 adiyogi sudo[1532]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::volumioGetState Sep 07 23:39:08 adiyogi volumio[1242]: info: CorePlayQueue::getTrack 0 Sep 07 23:39:08 adiyogi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 07 23:39:08 adiyogi sudo[1527]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:08 adiyogi sudo[1529]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:08 adiyogi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 07 23:39:08 adiyogi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 07 23:39:08 adiyogi systemd[1]: shairport-sync.service: Succeeded. Sep 07 23:39:08 adiyogi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 07 23:39:08 adiyogi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 07 23:39:08 adiyogi sudo[1532]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:08 adiyogi volumio[1242]: info: Asound.conf file unchanged, so no further update is needed Sep 07 23:39:08 adiyogi volumio[1242]: info: Output device has changed, restarting MPD Sep 07 23:39:08 adiyogi sudo[1574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 23:39:08 adiyogi sudo[1574]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:08 adiyogi volumio[1242]: info: Output device has changed, restarting Shairport Sync Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:08 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 23:39:08 adiyogi sudo[1574]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:08 adiyogi sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 23:39:08 adiyogi sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:08 adiyogi volumio[1242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 07 23:39:08 adiyogi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 07 23:39:08 adiyogi systemd[1]: mpd.service: Succeeded. Sep 07 23:39:08 adiyogi systemd[1]: Stopped Music Player Daemon. Sep 07 23:39:08 adiyogi volumio[1242]: info: Shairport-Sync Started Sep 07 23:39:09 adiyogi volumio[1242]: Error adding Membership: Error: addMembership EINVAL Sep 07 23:39:09 adiyogi volumio[1242]: info: Shairport-Sync Started Sep 07 23:39:09 adiyogi volumio[1242]: info: Shairport-Sync Started Sep 07 23:39:09 adiyogi volumio[1242]: verbose: New Socket.io Connection to 192.168.1.140 from 192.168.1.98 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 07 23:39:09 adiyogi volumio[1242]: info: MPD Permissions set Sep 07 23:39:09 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:09 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:09 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:09 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:09 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:09 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:09 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:09 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:09 adiyogi systemd[1]: Starting Music Player Daemon... Sep 07 23:39:09 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 23:39:09 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:09 adiyogi volumio[1242]: verbose: New Socket.io Connection to 192.168.1.140 from 192.168.1.98 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 07 23:39:09 adiyogi volumio[1242]: info: Starting Shairport Sync Sep 07 23:39:09 adiyogi volumio[1242]: verbose: New Socket.io Connection to 192.168.1.140 from 192.168.1.98 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 07 23:39:09 adiyogi sudo[1591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 07 23:39:09 adiyogi sudo[1591]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:09 adiyogi volumio[1242]: verbose: New Socket.io Connection to 192.168.1.140 from 192.168.1.98 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 07 23:39:09 adiyogi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 07 23:39:09 adiyogi systemd[1]: shairport-sync.service: Succeeded. Sep 07 23:39:09 adiyogi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 07 23:39:09 adiyogi sudo[1584]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 23:39:09 adiyogi sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:09 adiyogi sudo[1584]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:09 adiyogi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 07 23:39:09 adiyogi sudo[1591]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:09 adiyogi volumio[1242]: info: Shairport-Sync Started Sep 07 23:39:09 adiyogi sh[581]: timed out Sep 07 23:39:09 adiyogi dhcpcd[627]: timed out Sep 07 23:39:09 adiyogi sh[581]: dhcpcd exited Sep 07 23:39:09 adiyogi dhcpcd[627]: dhcpcd exited Sep 07 23:39:09 adiyogi sh[581]: ifup: failed to bring up eth0 Sep 07 23:39:09 adiyogi systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Sep 07 23:39:09 adiyogi systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Sep 07 23:39:09 adiyogi volumio[1242]: info: Discovery: this is already registered, 5049e3e3-faf1-48a9-a325-9b441a45cc77 Sep 07 23:39:09 adiyogi volumio[1242]: info: Discovery: Found device Adiyogi Sep 07 23:39:09 adiyogi volumio[1242]: info: CoreCommandRouter::volumioGetState Sep 07 23:39:09 adiyogi volumio[1242]: info: CorePlayQueue::getTrack 0 Sep 07 23:39:09 adiyogi mpd[1595]: Sep 07 23:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 07 23:39:09 adiyogi systemd[1]: Started Music Player Daemon. Sep 07 23:39:09 adiyogi sudo[1484]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:09 adiyogi sudo[1579]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:09 adiyogi sudo[1493]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:09 adiyogi volumio[1242]: info: Completed starting Core Plugins Sep 07 23:39:09 adiyogi volumio[1242]: info: ------------------------------------------- Sep 07 23:39:09 adiyogi volumio[1242]: info: ----- MyVolumio plugins startup ---- Sep 07 23:39:09 adiyogi volumio[1242]: info: ------------------------------------------- Sep 07 23:39:09 adiyogi volumio[1242]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 07 23:39:09 adiyogi volumio[1242]: error: MPD error: The expression evaluated to a falsy value: Sep 07 23:39:09 adiyogi volumio[1242]: assert.ok(self.idling) Sep 07 23:39:09 adiyogi volumio[1242]: error: The expression evaluated to a falsy value: Sep 07 23:39:09 adiyogi volumio[1242]: assert.ok(self.idling) Sep 07 23:39:09 adiyogi volumio[1242]: error: MPD error: The expression evaluated to a falsy value: Sep 07 23:39:09 adiyogi volumio[1242]: assert.ok(self.idling) Sep 07 23:39:09 adiyogi volumio[1242]: error: The expression evaluated to a falsy value: Sep 07 23:39:09 adiyogi volumio[1242]: assert.ok(self.idling) Sep 07 23:39:09 adiyogi volumio[1242]: info: MPD running with PID1595 Sep 07 23:39:09 adiyogi volumio[1242]: ,establishing connection Sep 07 23:39:09 adiyogi volumio[1242]: error: updateQueue error: null Sep 07 23:39:09 adiyogi volumio[1242]: error: updateQueue error: null Sep 07 23:39:10 adiyogi volumio[1242]: info: camilladsp service started and running in background, instance 1 Sep 07 23:39:10 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 23:39:10 adiyogi volumio[1242]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 07 23:39:10 adiyogi volumio[1242]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 07 23:39:10 adiyogi volumio[1242]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 07 23:39:10 adiyogi volumio[1242]: info: FusionDsp loaded Sep 07 23:39:10 adiyogi volumio[1242]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 23:39:10 adiyogi sudo[1613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 07 23:39:10 adiyogi sudo[1613]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 23:39:10 adiyogi volumio[1242]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 07 23:39:10 adiyogi volumio[1242]: info: Adding Signal Path Element [object Object] Sep 07 23:39:10 adiyogi volumio[1242]: info: Adding fusiondspeq DSP Signal Path Element Sep 07 23:39:10 adiyogi volumio[1242]: info: FusionDsp - ---- installed callbackRead Sep 07 23:39:10 adiyogi volumio[1242]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 23:39:10 adiyogi sudo[1613]: pam_unix(sudo:session): session closed for user root Sep 07 23:39:10 adiyogi volumio[1242]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 07 23:39:10 adiyogi volumio[1242]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 07 23:39:10 adiyogi volumio[1242]: at onErrorNT (internal/child_process.js:465:16) Sep 07 23:39:10 adiyogi volumio[1242]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 07 23:39:10 adiyogi volumio[1242]: errno: -2, Sep 07 23:39:10 adiyogi volumio[1242]: code: 'ENOENT', Sep 07 23:39:10 adiyogi volumio[1242]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 07 23:39:10 adiyogi volumio[1242]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 07 23:39:10 adiyogi volumio[1242]: spawnargs: [ Sep 07 23:39:10 adiyogi volumio[1242]: '-p', Sep 07 23:39:10 adiyogi volumio[1242]: 9876, Sep 07 23:39:10 adiyogi volumio[1242]: '-o', Sep 07 23:39:10 adiyogi volumio[1242]: '/tmp/camilladsp.log', Sep 07 23:39:10 adiyogi volumio[1242]: '-l', Sep 07 23:39:10 adiyogi volumio[1242]: 'warn', Sep 07 23:39:10 adiyogi volumio[1242]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 07 23:39:10 adiyogi volumio[1242]: ] Sep 07 23:39:10 adiyogi volumio[1242]: } Sep 07 23:39:10 adiyogi volumio[1242]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 23:39:10 adiyogi ntpd[858]: Listen normally on 4 wlan0 192.168.1.140:123 Sep 07 23:39:10 adiyogi ntpd[858]: new interface(s) found: waking up resolver Sep 07 23:39:10 adiyogi sudo[1638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-07 23:38 Sep 07 23:39:10 adiyogi sudo[1638]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"