-- Logs begin at Sat 2024-04-20 12:12:55 CEST, end at Sat 2024-04-20 12:14:02 CEST. --
Apr 20 12:13:00 volumio-rp5 ntpd[769]: error resolving pool 2.debian.pool.ntp.org: System error (-11)
Apr 20 12:13:00 volumio-rp5 ifplugd(eth0)[767]: Link beat detected.
Apr 20 12:13:00 volumio-rp5 ifplugd(eth0)[767]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Apr 20 12:13:00 volumio-rp5 ifplugd(eth0)[767]: client: ifup: waiting for lock on /run/network/ifstate.eth0
Apr 20 12:13:00 volumio-rp5 sudo[841]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:00 volumio-rp5 wireless.js[646]: SETTING APPROPRIATE REG DOMAIN: LU
Apr 20 12:13:00 volumio-rp5 sudo[852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Apr 20 12:13:00 volumio-rp5 sudo[852]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:00 volumio-rp5 sudo[852]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:00 volumio-rp5 sudo[854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set LU
Apr 20 12:13:00 volumio-rp5 sudo[854]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:00 volumio-rp5 sudo[854]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:00 volumio-rp5 wireless.js[646]: SUCCESSFULLY SET NEW REGDOMAIN: LU
Apr 20 12:13:00 volumio-rp5 wireless.js[646]: Start wireless flow
Apr 20 12:13:00 volumio-rp5 wireless.js[646]: Stopped hotspot (if there)..
Apr 20 12:13:00 volumio-rp5 sudo[859]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Apr 20 12:13:00 volumio-rp5 sudo[859]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:00 volumio-rp5 sudo[859]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:00 volumio-rp5 sudo[861]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Apr 20 12:13:00 volumio-rp5 sudo[861]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:00 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:13:01 volumio-rp5 ntpd[769]: error resolving pool 3.debian.pool.ntp.org: System error (-11)
Apr 20 12:13:01 volumio-rp5 systemd[1]: systemd-rfkill.service: Succeeded.
Apr 20 12:13:01 volumio-rp5 sh[544]: eth0: carrier lost
Apr 20 12:13:01 volumio-rp5 dhcpcd[588]: eth0: carrier lost
Apr 20 12:13:01 volumio-rp5 sudo[861]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:01 volumio-rp5 wireless.js[646]: DHCP IP
Apr 20 12:13:01 volumio-rp5 wireless.js[646]: Start ap
Apr 20 12:13:01 volumio-rp5 wpa_supplicant[866]: Successfully initialized wpa_supplicant
Apr 20 12:13:01 volumio-rp5 sudo[870]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd
Apr 20 12:13:01 volumio-rp5 sudo[870]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:01 volumio-rp5 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Apr 20 12:13:01 volumio-rp5 dhcpcd[877]: dev: loaded udev
Apr 20 12:13:01 volumio-rp5 dhcpcd-run-hooks[902]: wlan0: starting wpa_supplicant
Apr 20 12:13:01 volumio-rp5 dhcpcd-run-hooks[907]: wlan0: failed to start wpa_supplicant
Apr 20 12:13:01 volumio-rp5 dhcpcd-run-hooks[908]: 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
Apr 20 12:13:01 volumio-rp5 dhcpcd[877]: wlan0: connected to Access Point `'
Apr 20 12:13:01 volumio-rp5 dhcpcd[877]: no interfaces have a carrier
Apr 20 12:13:01 volumio-rp5 dhcpcd[877]: forked to background, child pid 918
Apr 20 12:13:01 volumio-rp5 dhcpcd[918]: eth0: waiting for carrier
Apr 20 12:13:01 volumio-rp5 dhcpcd[918]: wlan0: waiting for carrier
Apr 20 12:13:01 volumio-rp5 sudo[870]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:01 volumio-rp5 bthelper[649]: Changing power off succeeded
Apr 20 12:13:01 volumio-rp5 bthelper[649]: [CHG] Controller D8:3A:DD:A8:04:3E Class: 0x0000041c
Apr 20 12:13:01 volumio-rp5 bthelper[649]: Changing power on succeeded
Apr 20 12:13:01 volumio-rp5 volumio-remote-updater[641]: [2024-04-20 12:13:01] [info] asio async_connect error: system:111 (Connection refused)
Apr 20 12:13:01 volumio-rp5 volumio-remote-updater[641]: [2024-04-20 12:13:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Apr 20 12:13:01 volumio-rp5 volumio-remote-updater[641]: [2024-04-20 12:13:01] [error] handle_connect error: Underlying Transport Error
Apr 20 12:13:02 volumio-rp5 wireless.js[646]: trying...
Apr 20 12:13:02 volumio-rp5 sudo[921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 20 12:13:02 volumio-rp5 sudo[921]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:02 volumio-rp5 sudo[921]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:03 volumio-rp5 wireless.js[646]: trying...
Apr 20 12:13:03 volumio-rp5 sudo[924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 20 12:13:03 volumio-rp5 sudo[924]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:03 volumio-rp5 sudo[924]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:04 volumio-rp5 sh[544]: eth0: carrier acquired
Apr 20 12:13:04 volumio-rp5 dhcpcd[918]: eth0: carrier acquired
Apr 20 12:13:04 volumio-rp5 dhcpcd[588]: eth0: carrier acquired
Apr 20 12:13:04 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 20 12:13:04 volumio-rp5 sh[544]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:04 volumio-rp5 dhcpcd[918]: DUID 00:01:00:01:2c:c5:40:e5:d8:3a:dd:a8:04:3b
Apr 20 12:13:04 volumio-rp5 dhcpcd[588]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:04 volumio-rp5 dhcpcd[918]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:04 volumio-rp5 dhcpcd[918]: eth0: adding address fe80::618f:a2af:e4dc:caa8
Apr 20 12:13:04 volumio-rp5 dhcpcd[918]: ipv6_addaddr1: Permission denied
Apr 20 12:13:04 volumio-rp5 dhcpcd[918]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:04 volumio-rp5 wpa_supplicant[906]: wlan0: Trying to associate with SSID 'WLAN21'
Apr 20 12:13:04 volumio-rp5 wireless.js[646]: trying...
Apr 20 12:13:04 volumio-rp5 sudo[929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 20 12:13:04 volumio-rp5 sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:04 volumio-rp5 sudo[929]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:04 volumio-rp5 sh[544]: eth0: soliciting an IPv6 router
Apr 20 12:13:04 volumio-rp5 dhcpcd[588]: eth0: soliciting an IPv6 router
Apr 20 12:13:04 volumio-rp5 dhcpcd[918]: eth0: soliciting an IPv6 router
Apr 20 12:13:04 volumio-rp5 sh[544]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:04 volumio-rp5 dhcpcd[588]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:05 volumio-rp5 sh[544]: eth0: carrier lost
Apr 20 12:13:05 volumio-rp5 dhcpcd[918]: eth0: carrier lost
Apr 20 12:13:05 volumio-rp5 dhcpcd[588]: eth0: carrier lost
Apr 20 12:13:05 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:13:05 volumio-rp5 wireless.js[646]: trying...
Apr 20 12:13:05 volumio-rp5 sudo[978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 20 12:13:05 volumio-rp5 sudo[978]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:05 volumio-rp5 sudo[978]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:06 volumio-rp5 wireless.js[646]: trying...
Apr 20 12:13:06 volumio-rp5 sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 20 12:13:06 volumio-rp5 sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:06 volumio-rp5 sudo[981]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:06 volumio-rp5 dhcpcd[918]: wlan0: carrier acquired
Apr 20 12:13:06 volumio-rp5 dhcpcd[918]: wlan0: connected to Access Point `WLAN21'
Apr 20 12:13:06 volumio-rp5 wpa_supplicant[906]: wlan0: Associated with 3c:a6:2f:4b:2d:12
Apr 20 12:13:06 volumio-rp5 wpa_supplicant[906]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:a6:2f:4b:2d:12 completed [id=0 id_str=]
Apr 20 12:13:06 volumio-rp5 wpa_supplicant[906]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Apr 20 12:13:06 volumio-rp5 wpa_supplicant[906]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=LU
Apr 20 12:13:06 volumio-rp5 dhcpcd[918]: wlan0: IAID dd:a8:04:3d
Apr 20 12:13:06 volumio-rp5 dhcpcd[918]: wlan0: adding address fe80::5ff2:9570:7771:23ac
Apr 20 12:13:06 volumio-rp5 dhcpcd[918]: ipv6_addaddr1: Permission denied
Apr 20 12:13:06 volumio-rp5 dhcpcd[918]: wlan0: carrier lost
Apr 20 12:13:06 volumio-rp5 dhcpcd[918]: wlan0: carrier acquired
Apr 20 12:13:06 volumio-rp5 dhcpcd[918]: wlan0: IAID dd:a8:04:3d
Apr 20 12:13:06 volumio-rp5 dhcpcd[918]: wlan0: rebinding lease of 192.168.1.164
Apr 20 12:13:06 volumio-rp5 dhcpcd[918]: wlan0: probing address 192.168.1.164/24
Apr 20 12:13:07 volumio-rp5 dhcpcd[918]: wlan0: soliciting an IPv6 router
Apr 20 12:13:07 volumio-rp5 wireless.js[646]: trying...
Apr 20 12:13:07 volumio-rp5 sudo[998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 20 12:13:07 volumio-rp5 sudo[998]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:07 volumio-rp5 sudo[998]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:08 volumio-rp5 dhcpcd[918]: eth0: carrier acquired
Apr 20 12:13:08 volumio-rp5 dhcpcd[588]: eth0: carrier acquired
Apr 20 12:13:08 volumio-rp5 sh[544]: eth0: carrier acquired
Apr 20 12:13:08 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 20 12:13:08 volumio-rp5 sh[544]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:08 volumio-rp5 dhcpcd[588]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:08 volumio-rp5 dhcpcd[918]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:08 volumio-rp5 wireless.js[646]: trying...
Apr 20 12:13:08 volumio-rp5 sudo[1003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 20 12:13:08 volumio-rp5 sudo[1003]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:08 volumio-rp5 sudo[1003]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:08 volumio-rp5 sh[544]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:08 volumio-rp5 dhcpcd[588]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:08 volumio-rp5 dhcpcd[918]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:08 volumio-rp5 sh[544]: eth0: soliciting an IPv6 router
Apr 20 12:13:08 volumio-rp5 dhcpcd[588]: eth0: soliciting an IPv6 router
Apr 20 12:13:08 volumio-rp5 dhcpcd[918]: eth0: soliciting an IPv6 router
Apr 20 12:13:09 volumio-rp5 sh[544]: eth0: carrier lost
Apr 20 12:13:09 volumio-rp5 dhcpcd[588]: eth0: carrier lost
Apr 20 12:13:09 volumio-rp5 dhcpcd[918]: eth0: carrier lost
Apr 20 12:13:09 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:13:09 volumio-rp5 wireless.js[646]: trying...
Apr 20 12:13:09 volumio-rp5 sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 20 12:13:09 volumio-rp5 sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:09 volumio-rp5 sudo[1050]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:09 volumio-rp5 volumio-remote-updater[641]: [2024-04-20 12:13:09] [info] asio async_connect error: system:111 (Connection refused)
Apr 20 12:13:09 volumio-rp5 volumio-remote-updater[641]: [2024-04-20 12:13:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Apr 20 12:13:09 volumio-rp5 volumio-remote-updater[641]: [2024-04-20 12:13:09] [error] handle_connect error: Underlying Transport Error
Apr 20 12:13:10 volumio-rp5 wireless.js[646]: trying...
Apr 20 12:13:10 volumio-rp5 sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 20 12:13:10 volumio-rp5 sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:10 volumio-rp5 sudo[1053]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:11 volumio-rp5 wireless.js[646]: trying...
Apr 20 12:13:11 volumio-rp5 sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 20 12:13:11 volumio-rp5 sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:11 volumio-rp5 sudo[1056]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:11 volumio-rp5 dhcpcd[918]: wlan0: leased 192.168.1.164 for 864000 seconds
Apr 20 12:13:11 volumio-rp5 avahi-daemon[636]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.164.
Apr 20 12:13:11 volumio-rp5 avahi-daemon[636]: New relevant interface wlan0.IPv4 for mDNS.
Apr 20 12:13:11 volumio-rp5 dhcpcd[918]: wlan0: adding route to 192.168.1.0/24
Apr 20 12:13:11 volumio-rp5 avahi-daemon[636]: Registering new address record for 192.168.1.164 on wlan0.IPv4.
Apr 20 12:13:11 volumio-rp5 dhcpcd[918]: wlan0: adding default route via 192.168.1.1
Apr 20 12:13:12 volumio-rp5 sh[544]: eth0: carrier acquired
Apr 20 12:13:12 volumio-rp5 dhcpcd[918]: eth0: carrier acquired
Apr 20 12:13:12 volumio-rp5 dhcpcd[588]: eth0: carrier acquired
Apr 20 12:13:12 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 20 12:13:12 volumio-rp5 dhcpcd[918]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:12 volumio-rp5 sh[544]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:12 volumio-rp5 dhcpcd[588]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:12 volumio-rp5 dhcpcd[918]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:12 volumio-rp5 wireless.js[646]: trying...
Apr 20 12:13:12 volumio-rp5 sudo[1082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 20 12:13:12 volumio-rp5 sudo[1082]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:12 volumio-rp5 sudo[1082]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:12 volumio-rp5 wireless.js[646]: Connected to: ----WLAN21
Apr 20 12:13:12 volumio-rp5 wireless.js[646]: ----
Apr 20 12:13:12 volumio-rp5 sudo[1085]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 20 12:13:12 volumio-rp5 sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:12 volumio-rp5 sudo[1085]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:12 volumio-rp5 wireless.js[646]: ... joined AP, wlan0 IPv4 is 192.168.1.164, ipV6 is undefined
Apr 20 12:13:12 volumio-rp5 wireless.js[646]: It's done! AP
Apr 20 12:13:12 volumio-rp5 dhcpcd[918]: eth0: soliciting an IPv6 router
Apr 20 12:13:12 volumio-rp5 systemd[1]: Started Wireless Services.
Apr 20 12:13:12 volumio-rp5 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Apr 20 12:13:12 volumio-rp5 systemd[1]: Started Volumio Backend Module.
Apr 20 12:13:12 volumio-rp5 systemd[1]: Started Volumio Cpu Tweaker.
Apr 20 12:13:12 volumio-rp5 nmbd[739]: [2024/04/20 12:13:12.515803, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Apr 20 12:13:12 volumio-rp5 nmbd[739]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Apr 20 12:13:12 volumio-rp5 systemd[1]: Started Samba NMB Daemon.
Apr 20 12:13:12 volumio-rp5 systemd[1]: Starting Samba Winbind Daemon...
Apr 20 12:13:12 volumio-rp5 volumio-cpu-tweak[1091]: Setting RT Priority for mpd
Apr 20 12:13:12 volumio-rp5 volumio-cpu-tweak[1091]: pid 35's current scheduling policy: SCHED_OTHER
Apr 20 12:13:12 volumio-rp5 volumio-cpu-tweak[1091]: pid 35's current scheduling priority: 0
Apr 20 12:13:12 volumio-rp5 volumio-cpu-tweak[1091]: Setting MPD Affinity
Apr 20 12:13:12 volumio-rp5 volumio-cpu-tweak[1091]: pid 3's current affinity mask: f
Apr 20 12:13:12 volumio-rp5 volumio-cpu-tweak[1091]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input
Apr 20 12:13:12 volumio-rp5 volumio-cpu-tweak[1091]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative
Apr 20 12:13:12 volumio-rp5 systemd[1]: volumio_cpu_tweak.service: Succeeded.
Apr 20 12:13:12 volumio-rp5 sh[544]: eth0: soliciting an IPv6 router
Apr 20 12:13:12 volumio-rp5 dhcpcd[588]: eth0: soliciting an IPv6 router
Apr 20 12:13:12 volumio-rp5 winbindd[1102]: [2024/04/20 12:13:12.606485, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache)
Apr 20 12:13:12 volumio-rp5 winbindd[1102]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Apr 20 12:13:12 volumio-rp5 winbindd[1102]: [2024/04/20 12:13:12.621930, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Apr 20 12:13:12 volumio-rp5 winbindd[1102]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
Apr 20 12:13:12 volumio-rp5 systemd[1]: Started Samba Winbind Daemon.
Apr 20 12:13:12 volumio-rp5 systemd[1]: Starting Samba SMB Daemon...
Apr 20 12:13:12 volumio-rp5 smbd[1112]: [2024/04/20 12:13:12.807851, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Apr 20 12:13:12 volumio-rp5 smbd[1112]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Apr 20 12:13:12 volumio-rp5 systemd[1]: Started Samba SMB Daemon.
Apr 20 12:13:12 volumio-rp5 systemd[1]: Reached target Multi-User System.
Apr 20 12:13:12 volumio-rp5 systemd[1]: Reached target Graphical Interface.
Apr 20 12:13:12 volumio-rp5 systemd[1]: Starting Update UTMP about System Runlevel Changes...
Apr 20 12:13:12 volumio-rp5 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Apr 20 12:13:12 volumio-rp5 systemd[1]: Started Update UTMP about System Runlevel Changes.
Apr 20 12:13:12 volumio-rp5 systemd[1]: Startup finished in 6.534s (kernel) + 18.209s (userspace) = 24.744s.
Apr 20 12:13:13 volumio-rp5 sh[544]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:13 volumio-rp5 dhcpcd[588]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:13 volumio-rp5 sh[544]: eth0: carrier lost
Apr 20 12:13:13 volumio-rp5 dhcpcd[588]: eth0: carrier lost
Apr 20 12:13:13 volumio-rp5 dhcpcd[918]: eth0: carrier lost
Apr 20 12:13:13 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:13:13 volumio-rp5 sh[544]: cat: /var/run/dhcpcd/resolv.conf.eth0.link: No such file or directory
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: -------------------------------------------
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: ----- Volumio3 ----
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: -------------------------------------------
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: ----- System startup ----
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: -------------------------------------------
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: MYVOLUMIO Environment detected
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Plugin folders cleanup
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Scanning into folder /volumio/app/plugins/
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Scanning category audio_interface
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Scanning category miscellanea
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Scanning category music_service
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Scanning category plugins.json
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Scanning category system_controller
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Scanning category user_interface
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Scanning into folder /data/plugins/
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Plugin folders cleanup completed
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: -------------------------------------------
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: ----- Core plugins startup ----
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: -------------------------------------------
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Loading plugins from folder /volumio/app/plugins/
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Adding plugin upnp to MyMusic Plugins
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Adding plugin airplay_emulation to MyMusic Plugins
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Adding plugin upnp_browser to MyMusic Plugins
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Loading plugins from folder /data/plugins/
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Loading plugin "system"...
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Loading plugin "appearance"...
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Loading plugin "network"...
Apr 20 12:13:13 volumio-rp5 volumio[1090]: info: Refreshing Cached IP Addresses
Apr 20 12:13:14 volumio-rp5 sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 20 12:13:14 volumio-rp5 sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "services"...
Apr 20 12:13:14 volumio-rp5 sudo[1213]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:14 volumio-rp5 sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "alsa_controller"...
Apr 20 12:13:14 volumio-rp5 sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:14 volumio-rp5 sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Apr 20 12:13:14 volumio-rp5 sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:14 volumio-rp5 sudo[1215]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "wizard"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "networkfs"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Starting Udev Watcher for removable devices
Apr 20 12:13:14 volumio-rp5 sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Volumio,password=789456,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.132/Music\\FLAC /mnt/NAS/FLAC
Apr 20 12:13:14 volumio-rp5 sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:14 volumio-rp5 sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Volumio,password=789456,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.132/Music\\CD_FLAC /mnt/NAS/CD_FLAC
Apr 20 12:13:14 volumio-rp5 sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Ignoring mount for partition: boot
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Ignoring mount for partition: volumio
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Ignoring mount for partition: volumio_data
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "volumio_command_line_client"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "upnp"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: [1713607994091] Starting Upmpd Daemon
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "my_music"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "mpd"...
Apr 20 12:13:14 volumio-rp5 kernel: Key type cifs.spnego registered
Apr 20 12:13:14 volumio-rp5 kernel: Key type cifs.idmap registered
Apr 20 12:13:14 volumio-rp5 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount.
Apr 20 12:13:14 volumio-rp5 kernel: CIFS: Attempting to mount \\192.168.1.132\Music
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "upnp_browser"...
Apr 20 12:13:14 volumio-rp5 ntpd[769]: Listen normally on 3 wlan0 192.168.1.164:123
Apr 20 12:13:14 volumio-rp5 ntpd[769]: new interface(s) found: waking up resolver
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "alarm-clock"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "airplay_emulation"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Starting Shairport Sync
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "last_100"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "webradio"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "i2s_dacs"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "volumiodiscovery"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 20 12:13:14 volumio-rp5 node[1090]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 20 12:13:14 volumio-rp5 node[1090]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 20 12:13:14 volumio-rp5 volumio[1090]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 20 12:13:14 volumio-rp5 volumio[1090]: *** WARNING *** For more information see
Apr 20 12:13:14 volumio-rp5 volumio[1090]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 20 12:13:14 volumio-rp5 volumio[1090]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 20 12:13:14 volumio-rp5 volumio[1090]: *** WARNING *** For more information see
Apr 20 12:13:14 volumio-rp5 node[1090]: *** WARNING *** For more information see
Apr 20 12:13:14 volumio-rp5 node[1090]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 20 12:13:14 volumio-rp5 node[1090]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 20 12:13:14 volumio-rp5 node[1090]: *** WARNING *** For more information see
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Discovery: Started advertising with name: Volumio RP5
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "outputs"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "albumart"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Plugin example_plugin is not enabled
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "inputs"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "updater_comm"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Plugin mpdemulation is not enabled
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "rest_api"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading plugin "websocket"...
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Loading i18n strings for locale en
Apr 20 12:13:14 volumio-rp5 volumio[1090]: Updating browse sources language
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 20 12:13:14 volumio-rp5 sudo[1228]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:14 volumio-rp5 kernel: CIFS: Attempting to mount \\192.168.1.132\Music
Apr 20 12:13:14 volumio-rp5 volumio[1090]: Forking 3 albumart workers
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::initPlayerControls
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 20 12:13:14 volumio-rp5 volumio[1090]: Express server listening on port 3000
Apr 20 12:13:14 volumio-rp5 volumio[1090]: [Metrics] WebUI: 1s 624.74ms
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreStateMachine::resetVolumioState
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreStateMachine::getcurrentVolume
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioRetrievevolume
Apr 20 12:13:14 volumio-rp5 volumio[1090]: verbose: New Socket.io Connection to 192.168.1.164 from 192.168.1.145 UA: okhttp/4.9.2 Total Clients: 1
Apr 20 12:13:14 volumio-rp5 volumio[1090]: verbose: New Socket.io Connection to 192.168.1.164 from 192.168.1.145 UA: okhttp/4.9.2 Total Clients: 2
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Reloading queue from file
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreStateMachine::setRepeat null single undefined
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreStateMachine::pushState
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioPushState
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreStateMachine::setRandom null
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreStateMachine::pushState
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioPushState
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: VolumeController:: Volume=100 Mute =false
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreStateMachine::pushState
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioPushState
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreStateMachine::updateTrackBlock
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrackBlock
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioRetrievevolume
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Setting Device type: Raspberry PI
Apr 20 12:13:14 volumio-rp5 volumio[1090]: Starting albumart workers
Apr 20 12:13:14 volumio-rp5 volumio[1090]: Starting albumart workers
Apr 20 12:13:14 volumio-rp5 volumio[1090]: Starting albumart workers
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: VolumeController:: Volume=100 Mute =false
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreStateMachine::pushState
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioPushState
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Discovery: adding 9d5c641a-b086-4d65-902d-a54ea09d593b
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Discovery: Found device Volumio
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Discovery: Connecting to remote: 192.168.1.111
Apr 20 12:13:14 volumio-rp5 sudo[1226]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:14 volumio-rp5 volumio[1090]: info: Discovery: Connected to remote: 192.168.1.111
Apr 20 12:13:15 volumio-rp5 sh[544]: eth0: carrier acquired
Apr 20 12:13:15 volumio-rp5 dhcpcd[918]: eth0: carrier acquired
Apr 20 12:13:15 volumio-rp5 dhcpcd[588]: eth0: carrier acquired
Apr 20 12:13:15 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 20 12:13:15 volumio-rp5 dhcpcd[918]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:15 volumio-rp5 sh[544]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:15 volumio-rp5 dhcpcd[588]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:15 volumio-rp5 sh[544]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:15 volumio-rp5 dhcpcd[588]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:15 volumio-rp5 volumio[1090]: info: Discovery: adding 751f9377-6d30-4af3-8339-20a984765c8a
Apr 20 12:13:15 volumio-rp5 volumio[1090]: info: Discovery: Found device Volumio RP5
Apr 20 12:13:15 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioGetState
Apr 20 12:13:15 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:15 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 20 12:13:15 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 20 12:13:15 volumio-rp5 volumio[1090]: info: Discovery: Getting this device information
Apr 20 12:13:15 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioGetState
Apr 20 12:13:15 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:15 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 20 12:13:15 volumio-rp5 sh[544]: eth0: soliciting an IPv6 router
Apr 20 12:13:15 volumio-rp5 dhcpcd[588]: eth0: soliciting an IPv6 router
Apr 20 12:13:15 volumio-rp5 volumio[1090]: verbose: New Socket.io Connection to 192.168.1.164 from 192.168.1.145 UA: okhttp/4.9.2 Total Clients: 3
Apr 20 12:13:15 volumio-rp5 volumio[1090]: verbose: New Socket.io Connection to 192.168.1.164 from 192.168.1.145 UA: okhttp/4.9.2 Total Clients: 4
Apr 20 12:13:16 volumio-rp5 dhcpcd[918]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:16 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioGetState
Apr 20 12:13:16 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:16 volumio-rp5 dhcpcd[918]: eth0: soliciting an IPv6 router
Apr 20 12:13:16 volumio-rp5 sh[544]: eth0: carrier lost
Apr 20 12:13:16 volumio-rp5 dhcpcd[588]: eth0: carrier lost
Apr 20 12:13:16 volumio-rp5 dhcpcd[918]: eth0: carrier lost
Apr 20 12:13:16 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:13:16 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Apr 20 12:13:16 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Apr 20 12:13:17 volumio-rp5 sudo[1218]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:18 volumio-rp5 wpa_supplicant[906]: RRM: Ignoring radio measurement request: Not RRM network
Apr 20 12:13:19 volumio-rp5 sh[544]: eth0: carrier acquired
Apr 20 12:13:19 volumio-rp5 dhcpcd[588]: eth0: carrier acquired
Apr 20 12:13:19 volumio-rp5 dhcpcd[918]: eth0: carrier acquired
Apr 20 12:13:19 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 20 12:13:19 volumio-rp5 dhcpcd[918]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:19 volumio-rp5 dhcpcd[588]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:19 volumio-rp5 sh[544]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:19 volumio-rp5 sh[544]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:19 volumio-rp5 dhcpcd[588]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:19 volumio-rp5 dhcpcd[918]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:19 volumio-rp5 volumio-remote-updater[641]: [2024-04-20 12:13:19] [connect] Successful connection
Apr 20 12:13:19 volumio-rp5 volumio-remote-updater[641]: [2024-04-20 12:13:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713607999 101
Apr 20 12:13:19 volumio-rp5 volumio[1090]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 5
Apr 20 12:13:19 volumio-rp5 sh[544]: eth0: soliciting an IPv6 router
Apr 20 12:13:19 volumio-rp5 dhcpcd[588]: eth0: soliciting an IPv6 router
Apr 20 12:13:19 volumio-rp5 dhcpcd[918]: eth0: soliciting an IPv6 router
Apr 20 12:13:20 volumio-rp5 wpa_supplicant[906]: RRM: Ignoring radio measurement request: Not RRM network
Apr 20 12:13:20 volumio-rp5 sh[544]: eth0: carrier lost
Apr 20 12:13:20 volumio-rp5 dhcpcd[588]: eth0: carrier lost
Apr 20 12:13:20 volumio-rp5 dhcpcd[918]: eth0: carrier lost
Apr 20 12:13:20 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:13:21 volumio-rp5 sudo[1487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 20 12:13:21 volumio-rp5 sudo[1487]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:21 volumio-rp5 sudo[1489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 20 12:13:21 volumio-rp5 sudo[1489]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:21 volumio-rp5 sudo[1487]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:21 volumio-rp5 sudo[1489]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:21 volumio-rp5 volumio[1090]: verbose: New Socket.io Connection to 192.168.1.164 from 192.168.1.145 UA: okhttp/4.9.2 Total Clients: 6
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioGetState
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: Received Get System Info
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: Discovery: Getting this device information
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioGetState
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioGetState
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: Listing playlists
Apr 20 12:13:22 volumio-rp5 wpa_supplicant[906]: RRM: Ignoring radio measurement request: Not RRM network
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: Discovery: Getting this device information
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioGetState
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:22 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 20 12:13:23 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioGetState
Apr 20 12:13:23 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:23 volumio-rp5 dhcpcd[918]: eth0: carrier acquired
Apr 20 12:13:23 volumio-rp5 dhcpcd[588]: eth0: carrier acquired
Apr 20 12:13:23 volumio-rp5 sh[544]: eth0: carrier acquired
Apr 20 12:13:23 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 20 12:13:23 volumio-rp5 sh[544]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:23 volumio-rp5 dhcpcd[588]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:23 volumio-rp5 dhcpcd[918]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:23 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 20 12:13:23 volumio-rp5 volumio[1090]: info: Received Get System Info
Apr 20 12:13:23 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 20 12:13:23 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 20 12:13:23 volumio-rp5 volumio[1090]: info: Discovery: Getting this device information
Apr 20 12:13:23 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioGetState
Apr 20 12:13:23 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:23 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 20 12:13:24 volumio-rp5 dhcpcd[918]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:24 volumio-rp5 sh[544]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:24 volumio-rp5 dhcpcd[588]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:24 volumio-rp5 sh[544]: eth0: soliciting an IPv6 router
Apr 20 12:13:24 volumio-rp5 dhcpcd[588]: eth0: soliciting an IPv6 router
Apr 20 12:13:24 volumio-rp5 sudo[1495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 20 12:13:24 volumio-rp5 sudo[1495]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:24 volumio-rp5 sudo[1495]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:24 volumio-rp5 sudo[1497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 20 12:13:24 volumio-rp5 sudo[1497]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:24 volumio-rp5 sudo[1497]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:24 volumio-rp5 sudo[1501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Apr 20 12:13:24 volumio-rp5 sudo[1501]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:24 volumio-rp5 systemd[1]: Started UPnP Renderer front-end to MPD.
Apr 20 12:13:24 volumio-rp5 sudo[1501]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:24 volumio-rp5 volumio[1090]: info: Upmpdcli Daemon Started
Apr 20 12:13:24 volumio-rp5 volumio[1090]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 20 12:13:24 volumio-rp5 dhcpcd[918]: eth0: soliciting an IPv6 router
Apr 20 12:13:24 volumio-rp5 dhcpcd[918]: eth0: carrier lost
Apr 20 12:13:24 volumio-rp5 dhcpcd[588]: eth0: carrier lost
Apr 20 12:13:24 volumio-rp5 sh[544]: eth0: carrier lost
Apr 20 12:13:24 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:13:24 volumio-rp5 wpa_supplicant[906]: RRM: Ignoring radio measurement request: Not RRM network
Apr 20 12:13:24 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioGetQueue
Apr 20 12:13:24 volumio-rp5 volumio[1090]: info: CoreStateMachine::getQueue
Apr 20 12:13:24 volumio-rp5 volumio[1090]: info: CorePlayQueue::getQueue
Apr 20 12:13:26 volumio-rp5 systemd[1]: systemd-fsckd.service: Succeeded.
Apr 20 12:13:26 volumio-rp5 sh[544]: eth0: carrier acquired
Apr 20 12:13:26 volumio-rp5 dhcpcd[918]: eth0: carrier acquired
Apr 20 12:13:26 volumio-rp5 dhcpcd[588]: eth0: carrier acquired
Apr 20 12:13:26 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 20 12:13:26 volumio-rp5 sh[544]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:26 volumio-rp5 dhcpcd[588]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:26 volumio-rp5 dhcpcd[918]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:26 volumio-rp5 sh[544]: eth0: soliciting an IPv6 router
Apr 20 12:13:26 volumio-rp5 dhcpcd[588]: eth0: soliciting an IPv6 router
Apr 20 12:13:26 volumio-rp5 wpa_supplicant[906]: RRM: Ignoring radio measurement request: Not RRM network
Apr 20 12:13:26 volumio-rp5 sh[544]: timed out
Apr 20 12:13:26 volumio-rp5 dhcpcd[588]: timed out
Apr 20 12:13:26 volumio-rp5 sh[544]: dhcpcd exited
Apr 20 12:13:26 volumio-rp5 dhcpcd[588]: dhcpcd exited
Apr 20 12:13:26 volumio-rp5 sh[544]: ifup: failed to bring up eth0
Apr 20 12:13:26 volumio-rp5 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE
Apr 20 12:13:26 volumio-rp5 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'.
Apr 20 12:13:26 volumio-rp5 dhcpcd[1599]: sending commands to master dhcpcd process
Apr 20 12:13:26 volumio-rp5 ifplugd(eth0)[767]: client: sending commands to master dhcpcd process
Apr 20 12:13:26 volumio-rp5 dhcpcd[918]: control command: /sbin/dhcpcd eth0
Apr 20 12:13:26 volumio-rp5 dhcpcd[918]: eth0: soliciting an IPv6 router
Apr 20 12:13:27 volumio-rp5 ifplugd(eth0)[767]: Program executed successfully.
Apr 20 12:13:27 volumio-rp5 systemd[1]: systemd-hostnamed.service: Succeeded.
Apr 20 12:13:27 volumio-rp5 dhcpcd[918]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:27 volumio-rp5 dhcpcd[918]: eth0: carrier lost
Apr 20 12:13:27 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:13:28 volumio-rp5 ifplugd(eth0)[767]: Link beat lost.
Apr 20 12:13:28 volumio-rp5 volumio[1090]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 20 12:13:28 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 20 12:13:28 volumio-rp5 volumio[1090]: info: CURURI: music-library
Apr 20 12:13:28 volumio-rp5 volumio[1090]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined
Apr 20 12:13:28 volumio-rp5 wpa_supplicant[906]: RRM: Ignoring radio measurement request: Not RRM network
Apr 20 12:13:30 volumio-rp5 dhcpcd[918]: eth0: carrier acquired
Apr 20 12:13:30 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 20 12:13:30 volumio-rp5 dhcpcd[918]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:30 volumio-rp5 wpa_supplicant[906]: RRM: Ignoring radio measurement request: Not RRM network
Apr 20 12:13:31 volumio-rp5 dhcpcd[918]: eth0: soliciting an IPv6 router
Apr 20 12:13:31 volumio-rp5 dhcpcd[918]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:31 volumio-rp5 ifplugd(eth0)[767]: Link beat detected.
Apr 20 12:13:31 volumio-rp5 dhcpcd[918]: eth0: carrier lost
Apr 20 12:13:31 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:13:32 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 20 12:13:32 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 20 12:13:32 volumio-rp5 volumio[1090]: info: Discovery: Getting this device information
Apr 20 12:13:32 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioGetState
Apr 20 12:13:32 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:32 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 20 12:13:32 volumio-rp5 ifplugd(eth0)[767]: Link beat lost.
Apr 20 12:13:32 volumio-rp5 wpa_supplicant[906]: RRM: Ignoring radio measurement request: Not RRM network
Apr 20 12:13:34 volumio-rp5 volumio[1090]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 20 12:13:34 volumio-rp5 dhcpcd[918]: eth0: carrier acquired
Apr 20 12:13:34 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 20 12:13:34 volumio-rp5 dhcpcd[918]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:35 volumio-rp5 dhcpcd[918]: eth0: soliciting an IPv6 router
Apr 20 12:13:35 volumio-rp5 ifplugd(eth0)[767]: Link beat detected.
Apr 20 12:13:35 volumio-rp5 dhcpcd[918]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:35 volumio-rp5 dhcpcd[918]: eth0: carrier lost
Apr 20 12:13:35 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:13:36 volumio-rp5 ifplugd(eth0)[767]: Link beat lost.
Apr 20 12:13:38 volumio-rp5 dhcpcd[918]: eth0: carrier acquired
Apr 20 12:13:38 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 20 12:13:38 volumio-rp5 dhcpcd[918]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:39 volumio-rp5 dhcpcd[918]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:39 volumio-rp5 wpa_supplicant[906]: RRM: Ignoring radio measurement request: Not RRM network
Apr 20 12:13:39 volumio-rp5 ifplugd(eth0)[767]: Link beat detected.
Apr 20 12:13:39 volumio-rp5 dhcpcd[918]: eth0: soliciting an IPv6 router
Apr 20 12:13:39 volumio-rp5 dhcpcd[918]: eth0: carrier lost
Apr 20 12:13:39 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:13:40 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 20 12:13:40 volumio-rp5 volumio[1090]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Apr 20 12:13:40 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Apr 20 12:13:40 volumio-rp5 volumio[1090]: info: Received Get System Version
Apr 20 12:13:40 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 20 12:13:40 volumio-rp5 volumio[1090]: info: Received Get System Info
Apr 20 12:13:40 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 20 12:13:40 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 20 12:13:40 volumio-rp5 volumio[1090]: info: Discovery: Getting this device information
Apr 20 12:13:40 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioGetState
Apr 20 12:13:40 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:40 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 20 12:13:40 volumio-rp5 ifplugd(eth0)[767]: Link beat lost.
Apr 20 12:13:41 volumio-rp5 dhcpcd[918]: eth0: carrier acquired
Apr 20 12:13:41 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 20 12:13:41 volumio-rp5 dhcpcd[918]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:41 volumio-rp5 dhcpcd[918]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:42 volumio-rp5 dhcpcd[918]: eth0: soliciting an IPv6 router
Apr 20 12:13:42 volumio-rp5 ifplugd(eth0)[767]: Link beat detected.
Apr 20 12:13:42 volumio-rp5 dhcpcd[918]: eth0: carrier lost
Apr 20 12:13:42 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:13:43 volumio-rp5 ifplugd(eth0)[767]: Link beat lost.
Apr 20 12:13:44 volumio-rp5 volumio[1090]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 20 12:13:44 volumio-rp5 volumio[1090]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: Preparing to generate the ALSA configuration file
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: Asound.conf file unchanged, so no further update is needed
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: Output device has changed, restarting MPD
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: Output device has changed, restarting Shairport Sync
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 20 12:13:44 volumio-rp5 sudo[1829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 20 12:13:44 volumio-rp5 sudo[1829]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:44 volumio-rp5 sudo[1829]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:44 volumio-rp5 sudo[1831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 20 12:13:44 volumio-rp5 sudo[1831]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: ___________ START PLUGINS ___________
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: ControllerMpd::onStart: Initializing MPD
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: Creating MPD Configuration file
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: [1713608024603] CoreMusicLibrary::Adding element Media Servers
Apr 20 12:13:44 volumio-rp5 sudo[1839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 20 12:13:44 volumio-rp5 sudo[1839]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 20 12:13:44 volumio-rp5 sudo[1839]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:44 volumio-rp5 sudo[1841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 20 12:13:44 volumio-rp5 sudo[1841]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:44 volumio-rp5 systemd[1]: Listening on mpd.socket.
Apr 20 12:13:44 volumio-rp5 systemd[1]: Starting Music Player Daemon...
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 20 12:13:44 volumio-rp5 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Apr 20 12:13:44 volumio-rp5 systemd[1]: mpd.service: Succeeded.
Apr 20 12:13:44 volumio-rp5 systemd[1]: Stopped Music Player Daemon.
Apr 20 12:13:44 volumio-rp5 systemd[1]: Starting Music Player Daemon...
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: [1713608024657] CoreMusicLibrary::Adding element Last_100
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: [1713608024659] CoreMusicLibrary::Adding element Webradio
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: Volumio Calling Home
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: MPD Permissions set
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: MPD Permissions set
Apr 20 12:13:44 volumio-rp5 volumio[1090]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: Volumio called home
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: Starting Shairport Sync
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: Starting Shairport Sync
Apr 20 12:13:44 volumio-rp5 sudo[1864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 20 12:13:44 volumio-rp5 sudo[1864]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: Starting Shairport Sync
Apr 20 12:13:44 volumio-rp5 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 20 12:13:44 volumio-rp5 systemd[1]: shairport-sync.service: Succeeded.
Apr 20 12:13:44 volumio-rp5 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 20 12:13:44 volumio-rp5 sudo[1866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 20 12:13:44 volumio-rp5 sudo[1866]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:44 volumio-rp5 sudo[1869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 20 12:13:44 volumio-rp5 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 20 12:13:44 volumio-rp5 sudo[1869]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:44 volumio-rp5 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 20 12:13:44 volumio-rp5 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Apr 20 12:13:44 volumio-rp5 systemd[1]: shairport-sync.service: Succeeded.
Apr 20 12:13:44 volumio-rp5 sudo[1864]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:44 volumio-rp5 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioGetState
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:44 volumio-rp5 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 20 12:13:44 volumio-rp5 sudo[1866]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:44 volumio-rp5 sudo[1869]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: Shairport-Sync Started
Apr 20 12:13:44 volumio-rp5 volumio[1090]: Error adding Membership: Error: addMembership EINVAL
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: Shairport-Sync Started
Apr 20 12:13:44 volumio-rp5 volumio[1090]: info: Shairport-Sync Started
Apr 20 12:13:45 volumio-rp5 mpd[1846]: Apr 20 12:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 20 12:13:45 volumio-rp5 systemd[1]: Started Music Player Daemon.
Apr 20 12:13:45 volumio-rp5 sudo[1841]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:45 volumio-rp5 sudo[1831]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:45 volumio-rp5 volumio[1090]: info: Completed starting Core Plugins
Apr 20 12:13:45 volumio-rp5 volumio[1090]: info: -------------------------------------------
Apr 20 12:13:45 volumio-rp5 volumio[1090]: info: ----- MyVolumio plugins startup ----
Apr 20 12:13:45 volumio-rp5 volumio[1090]: info: -------------------------------------------
Apr 20 12:13:45 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Fetching plans data....
Apr 20 12:13:45 volumio-rp5 volumio[1090]: info: MPD running with PID1846
Apr 20 12:13:45 volumio-rp5 volumio[1090]: ,establishing connection
Apr 20 12:13:45 volumio-rp5 volumio[1090]: error: MPD error: The expression evaluated to a falsy value:
Apr 20 12:13:45 volumio-rp5 volumio[1090]: assert.ok(self.idling)
Apr 20 12:13:45 volumio-rp5 volumio[1090]: error: The expression evaluated to a falsy value:
Apr 20 12:13:45 volumio-rp5 volumio[1090]: assert.ok(self.idling)
Apr 20 12:13:45 volumio-rp5 volumio[1090]: error: MPD error: The expression evaluated to a falsy value:
Apr 20 12:13:45 volumio-rp5 volumio[1090]: assert.ok(self.idling)
Apr 20 12:13:45 volumio-rp5 volumio[1090]: error: The expression evaluated to a falsy value:
Apr 20 12:13:45 volumio-rp5 volumio[1090]: assert.ok(self.idling)
Apr 20 12:13:45 volumio-rp5 volumio[1090]: error: updateQueue error: null
Apr 20 12:13:45 volumio-rp5 dhcpcd[918]: eth0: carrier acquired
Apr 20 12:13:45 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 20 12:13:45 volumio-rp5 dhcpcd[918]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:46 volumio-rp5 dhcpcd[918]: eth0: soliciting an IPv6 router
Apr 20 12:13:46 volumio-rp5 dhcpcd[918]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:46 volumio-rp5 ifplugd(eth0)[767]: Link beat detected.
Apr 20 12:13:47 volumio-rp5 dhcpcd[918]: eth0: carrier lost
Apr 20 12:13:47 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:13:48 volumio-rp5 ifplugd(eth0)[767]: Link beat lost.
Apr 20 12:13:50 volumio-rp5 dhcpcd[918]: eth0: carrier acquired
Apr 20 12:13:50 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 20 12:13:50 volumio-rp5 dhcpcd[918]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:50 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioGetState
Apr 20 12:13:50 volumio-rp5 volumio[1090]: info: CorePlayQueue::getTrack 0
Apr 20 12:13:50 volumio-rp5 ifplugd(eth0)[767]: Link beat detected.
Apr 20 12:13:50 volumio-rp5 volumio[1090]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Apr 20 12:13:50 volumio-rp5 dhcpcd[918]: eth0: soliciting an IPv6 router
Apr 20 12:13:51 volumio-rp5 dhcpcd[918]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:51 volumio-rp5 dhcpcd[918]: eth0: carrier lost
Apr 20 12:13:51 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:13:51 volumio-rp5 ifplugd(eth0)[767]: Link beat lost.
Apr 20 12:13:51 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 20 12:13:51 volumio-rp5 volumio[1090]: info: CURURI: music-library
Apr 20 12:13:52 volumio-rp5 volumio[1090]: info: Preload queue cleared
Apr 20 12:13:52 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 20 12:13:52 volumio-rp5 volumio[1090]: info: CURURI: music-library/NAS
Apr 20 12:13:52 volumio-rp5 volumio[1090]: info: Preload queue cleared
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: CURURI: music-library/NAS/CD_FLAC
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: Preload queue cleared
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: Adding plugin bluetooth to MyMusic Plugins
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: Adding plugin multiroom to MyMusic Plugins
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: Adding plugin metavolumio to MyMusic Plugins
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: Adding plugin cd_controller to MyMusic Plugins
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: Adding plugin smart_inputs to MyMusic Plugins
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: Adding plugin tidalconnect to MyMusic Plugins
Apr 20 12:13:53 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Apr 20 12:13:54 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Apr 20 12:13:54 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Apr 20 12:13:54 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 20 12:13:54 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 20 12:13:54 volumio-rp5 volumio[1090]: info: Starting MyVolumio Remote Streaming Endpoints
Apr 20 12:13:54 volumio-rp5 volumio[1090]: info: MyVolumio not started
Apr 20 12:13:54 volumio-rp5 volumio[1090]: info: Initializing device activation check
Apr 20 12:13:54 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Apr 20 12:13:54 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Apr 20 12:13:54 volumio-rp5 dhcpcd[918]: eth0: carrier acquired
Apr 20 12:13:54 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 20 12:13:54 volumio-rp5 dhcpcd[918]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:54 volumio-rp5 ifplugd(eth0)[767]: Link beat detected.
Apr 20 12:13:54 volumio-rp5 volumio[1090]: info: Starting Streaming Service Transparent Proxy
Apr 20 12:13:54 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Apr 20 12:13:54 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Apr 20 12:13:54 volumio-rp5 volumio[1090]: info: Streaming services startup
Apr 20 12:13:54 volumio-rp5 volumio[1090]: info: Starting Streaming Daemon
Apr 20 12:13:54 volumio-rp5 sudo[1962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 20 12:13:54 volumio-rp5 sudo[1962]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:13:54 volumio-rp5 dhcpcd[918]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:54 volumio-rp5 volumio[1090]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Apr 20 12:13:54 volumio-rp5 sudo[1962]: pam_unix(sudo:session): session closed for user root
Apr 20 12:13:54 volumio-rp5 volumio[1090]: error: Cannot start Volumio Streaming Daemon
Apr 20 12:13:54 volumio-rp5 volumio[1090]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 20 12:13:54 volumio-rp5 volumio[1090]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 20 12:13:54 volumio-rp5 volumio[1090]: STREAMING PROXY: Starting server on port 3245
Apr 20 12:13:54 volumio-rp5 volumio[1090]: Node JS runtime: 14
Apr 20 12:13:55 volumio-rp5 dhcpcd[918]: eth0: soliciting an IPv6 router
Apr 20 12:13:55 volumio-rp5 dhcpcd[918]: eth0: carrier lost
Apr 20 12:13:55 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:13:55 volumio-rp5 ifplugd(eth0)[767]: Link beat lost.
Apr 20 12:13:56 volumio-rp5 volumiologrotate[632]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory
Apr 20 12:13:56 volumio-rp5 volumiologrotate[632]: ls: cannot access 'RP5': No such file or directory
Apr 20 12:13:57 volumio-rp5 dhcpcd[918]: eth0: carrier acquired
Apr 20 12:13:57 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 20 12:13:57 volumio-rp5 dhcpcd[918]: eth0: IAID dd:a8:04:3b
Apr 20 12:13:57 volumio-rp5 ifplugd(eth0)[767]: Link beat detected.
Apr 20 12:13:57 volumio-rp5 dhcpcd[918]: eth0: soliciting an IPv6 router
Apr 20 12:13:58 volumio-rp5 dhcpcd[918]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:13:58 volumio-rp5 dhcpcd[918]: eth0: carrier lost
Apr 20 12:13:58 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:13:58 volumio-rp5 ifplugd(eth0)[767]: Link beat lost.
Apr 20 12:13:59 volumio-rp5 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 20 12:13:59 volumio-rp5 volumio[1090]: info: CURURI: music-library/NAS/CD_FLAC/Diana Krall
Apr 20 12:13:59 volumio-rp5 volumio[1090]: info: Preload queue cleared
Apr 20 12:14:01 volumio-rp5 dhcpcd[918]: eth0: carrier acquired
Apr 20 12:14:01 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 20 12:14:01 volumio-rp5 dhcpcd[918]: eth0: IAID dd:a8:04:3b
Apr 20 12:14:01 volumio-rp5 dhcpcd[918]: eth0: soliciting an IPv6 router
Apr 20 12:14:01 volumio-rp5 ifplugd(eth0)[767]: Link beat detected.
Apr 20 12:14:02 volumio-rp5 volumio[1503]: Generating RSA private key, 4096 bit long modulus (2 primes)
Apr 20 12:14:02 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 20 12:14:02 volumio-rp5 volumio[1090]: info: CoreStateMachine::ClearQueue
Apr 20 12:14:02 volumio-rp5 volumio[1090]: info: CoreStateMachine::stop
Apr 20 12:14:02 volumio-rp5 volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 20 12:14:02 volumio-rp5 volumio[1090]: info: CorePlayQueue::clearPlayQueue
Apr 20 12:14:02 volumio-rp5 volumio[1090]: info: CorePlayQueue::saveQueue
Apr 20 12:14:02 volumio-rp5 volumio[1090]: info: CoreCommandRouter::volumioPushQueue
Apr 20 12:14:02 volumio-rp5 volumio[1090]: info: CoreStateMachine::addQueueItems
Apr 20 12:14:02 volumio-rp5 volumio[1090]: info: CorePlayQueue::addQueueItems
Apr 20 12:14:02 volumio-rp5 volumio[1090]: info: Preload queue cleared
Apr 20 12:14:02 volumio-rp5 volumio[1090]: info: Adding Item to queue: music-library/NAS/CD_FLAC/Diana Krall/Diana Krall - Live In Paris [2002]
Apr 20 12:14:02 volumio-rp5 volumio[1090]: info: Exploding uri music-library/NAS/CD_FLAC/Diana Krall/Diana Krall - Live In Paris [2002] in service mpd
Apr 20 12:14:02 volumio-rp5 volumio[1090]: info: ALBUMART /albumart?cacheid=24&web=Diana%20Krall/Live%20In%20Paris/extralarge&path=%2Fmnt%2FNAS%2FCD_FLAC%2FDiana%20Krall%2FDiana%20Krall%20-%20Live%20In%20Paris%20%5B2002%5D%2F00%20-%20Live%20In%20Paris.cue&metadata=false
Apr 20 12:14:02 volumio-rp5 volumio[1090]: info: URI /mnt/NAS/CD_FLAC/Diana Krall/Diana Krall - Live In Paris [2002]/00 - Live In Paris.cue
Apr 20 12:14:02 volumio-rp5 volumio[1090]: info: ALBUMART /albumart?cacheid=24&web=Diana%20Krall/Live%20In%20Paris/extralarge&path=%2Fmnt%2FNAS%2FCD_FLAC%2FDiana%20Krall%2FDiana%20Krall%20-%20Live%20In%20Paris%20%5B2002%5D%2F00%20-%20Live%20In%20Paris.cue&metadata=false
Apr 20 12:14:02 volumio-rp5 volumio[1090]: info: URI /mnt/NAS/CD_FLAC/Diana Krall/Diana Krall - Live In Paris [2002]/00 - Live In Paris.cue
Apr 20 12:14:02 volumio-rp5 volumio[1090]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 20 12:14:02 volumio-rp5 volumio[1090]: Error: Unable to resolve or reject the same promise twice
Apr 20 12:14:02 volumio-rp5 volumio[1090]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43)
Apr 20 12:14:02 volumio-rp5 volumio[1090]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21
Apr 20 12:14:02 volumio-rp5 volumio[1090]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3)
Apr 20 12:14:02 volumio-rp5 volumio[1090]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12)
Apr 20 12:14:02 volumio-rp5 volumio[1090]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12)
Apr 20 12:14:02 volumio-rp5 volumio[1090]: at Socket.emit (events.js:315:20)
Apr 20 12:14:02 volumio-rp5 volumio[1090]: at addChunk (internal/streams/readable.js:309:12)
Apr 20 12:14:02 volumio-rp5 volumio[1090]: at readableAddChunk (internal/streams/readable.js:280:11)
Apr 20 12:14:02 volumio-rp5 volumio[1090]: at Socket.Readable.push (internal/streams/readable.js:223:10)
Apr 20 12:14:02 volumio-rp5 volumio[1090]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23)
Apr 20 12:14:02 volumio-rp5 volumio[1090]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 20 12:14:02 volumio-rp5 dhcpcd[918]: eth0: rebinding lease of 192.168.1.165
Apr 20 12:14:02 volumio-rp5 dhcpcd[918]: eth0: carrier lost
Apr 20 12:14:02 volumio-rp5 kernel: macb 1f00100000.ethernet eth0: Link is Down
Apr 20 12:14:02 volumio-rp5 sudo[2144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 12:13
Apr 20 12:14:02 volumio-rp5 sudo[2144]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET"
VOLUMIO_VERSION="3.631"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"