-- Logs begin at Fri 2024-10-18 00:35:48 CEST, end at Fri 2024-10-18 00:37:04 CEST. -- Oct 18 00:36:00 volumio-metal sudo[856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 18 00:36:00 volumio-metal sudo[856]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:00 volumio-metal sudo[856]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:00 volumio-metal sudo[858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set FR Oct 18 00:36:00 volumio-metal sudo[858]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:00 volumio-metal sudo[858]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:00 volumio-metal wireless.js[631]: SUCCESSFULLY SET NEW REGDOMAIN: FR Oct 18 00:36:00 volumio-metal sudo[865]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 18 00:36:00 volumio-metal sudo[865]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:00 volumio-metal sudo[865]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:00 volumio-metal sudo[867]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 18 00:36:00 volumio-metal sudo[867]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:00 volumio-metal volumio-remote-updater[626]: [2024-10-18 00:36:00] [info] asio async_connect error: system:111 (Connection refused) Oct 18 00:36:00 volumio-metal volumio-remote-updater[626]: [2024-10-18 00:36:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 18 00:36:00 volumio-metal volumio-remote-updater[626]: [2024-10-18 00:36:00] [error] handle_connect error: Underlying Transport Error Oct 18 00:36:00 volumio-metal sudo[867]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:00 volumio-metal ntpd[790]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 18 00:36:00 volumio-metal avahi-daemon[634]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Oct 18 00:36:00 volumio-metal avahi-daemon[634]: New relevant interface wlan0.IPv4 for mDNS. Oct 18 00:36:00 volumio-metal avahi-daemon[634]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Oct 18 00:36:00 volumio-metal kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 18 00:36:00 volumio-metal systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Oct 18 00:36:01 volumio-metal systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 18 00:36:01 volumio-metal dnsmasq[873]: dnsmasq: syntax check OK. Oct 18 00:36:01 volumio-metal hostapd[874]: Configuration file: /etc/hostapd/hostapd.conf Oct 18 00:36:01 volumio-metal hostapd[874]: wlan0: Could not connect to kernel driver Oct 18 00:36:01 volumio-metal hostapd[874]: Using interface wlan0 with hwaddr b8:27:eb:a8:f6:ce and ssid "Volumio-20C57" Oct 18 00:36:01 volumio-metal dnsmasq[880]: started, version 2.80 cachesize 150 Oct 18 00:36:01 volumio-metal dnsmasq[880]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Oct 18 00:36:01 volumio-metal dnsmasq-dhcp[880]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Oct 18 00:36:01 volumio-metal dnsmasq[880]: reading /etc/resolv.conf Oct 18 00:36:01 volumio-metal dnsmasq[880]: using nameserver 208.67.222.222#53 Oct 18 00:36:01 volumio-metal dnsmasq[880]: using nameserver 208.67.220.220#53 Oct 18 00:36:01 volumio-metal dnsmasq[880]: read /etc/hosts - 1 addresses Oct 18 00:36:01 volumio-metal hostapd[874]: wlan0: interface state UNINITIALIZED->ENABLED Oct 18 00:36:01 volumio-metal hostapd[874]: wlan0: AP-ENABLED Oct 18 00:36:01 volumio-metal systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 18 00:36:01 volumio-metal systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Oct 18 00:36:01 volumio-metal systemd[1]: Reached target Host and Network Name Lookups. Oct 18 00:36:01 volumio-metal iwconfig[888]: Error for wireless request "Set Power Management" (8B2C) : Oct 18 00:36:01 volumio-metal iwconfig[888]: SET failed on device wlan0 ; Invalid argument. Oct 18 00:36:01 volumio-metal systemd[1]: wireless.service: Succeeded. Oct 18 00:36:01 volumio-metal systemd[1]: Started Wireless Services. Oct 18 00:36:01 volumio-metal systemd[1]: Started Volumio Backend Module. Oct 18 00:36:01 volumio-metal systemd[1]: Started Volumio Cpu Tweaker. Oct 18 00:36:01 volumio-metal kernel: hwmon hwmon1: Undervoltage detected! Oct 18 00:36:01 volumio-metal volumio-cpu-tweak[890]: Setting RT Priority for mpd Oct 18 00:36:01 volumio-metal volumio-cpu-tweak[890]: pid 35's current scheduling policy: SCHED_OTHER Oct 18 00:36:01 volumio-metal volumio-cpu-tweak[890]: pid 35's current scheduling priority: 0 Oct 18 00:36:01 volumio-metal volumio-cpu-tweak[890]: Setting MPD Affinity Oct 18 00:36:01 volumio-metal volumio-cpu-tweak[890]: pid 3's current affinity mask: f Oct 18 00:36:01 volumio-metal volumio-cpu-tweak[890]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 18 00:36:01 volumio-metal volumio-cpu-tweak[890]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 18 00:36:01 volumio-metal bthelper[737]: Changing power off succeeded Oct 18 00:36:01 volumio-metal systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 18 00:36:01 volumio-metal bthelper[737]: [CHG] Controller B8:27:EB:57:09:31 Class: 0x0000041c Oct 18 00:36:01 volumio-metal bthelper[737]: Changing power on succeeded Oct 18 00:36:02 volumio-metal ntpd[790]: Listen normally on 3 wlan0 192.168.211.1:123 Oct 18 00:36:02 volumio-metal ntpd[790]: new interface(s) found: waking up resolver Oct 18 00:36:02 volumio-metal systemd[1]: Started Samba NMB Daemon. Oct 18 00:36:02 volumio-metal nmbd[743]: [2024/10/18 00:36:02.966425, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 18 00:36:02 volumio-metal nmbd[743]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 18 00:36:02 volumio-metal systemd[1]: Starting Samba Winbind Daemon... Oct 18 00:36:03 volumio-metal winbindd[912]: [2024/10/18 00:36:03.675530, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 18 00:36:03 volumio-metal winbindd[912]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 18 00:36:03 volumio-metal systemd[1]: Started Samba Winbind Daemon. Oct 18 00:36:03 volumio-metal winbindd[912]: [2024/10/18 00:36:03.761460, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 18 00:36:03 volumio-metal winbindd[912]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 18 00:36:03 volumio-metal systemd[1]: Starting Samba SMB Daemon... Oct 18 00:36:04 volumio-metal sh[533]: eth0: leased 10.0.4.93 for 86400 seconds Oct 18 00:36:04 volumio-metal sh[533]: eth0: adding route to 10.0.4.0/24 Oct 18 00:36:04 volumio-metal sh[533]: eth0: adding default route via 10.0.4.1 Oct 18 00:36:04 volumio-metal dhcpcd[578]: eth0: leased 10.0.4.93 for 86400 seconds Oct 18 00:36:04 volumio-metal dhcpcd[578]: eth0: adding route to 10.0.4.0/24 Oct 18 00:36:04 volumio-metal dhcpcd[578]: eth0: adding default route via 10.0.4.1 Oct 18 00:36:04 volumio-metal avahi-daemon[634]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.0.4.93. Oct 18 00:36:04 volumio-metal avahi-daemon[634]: New relevant interface eth0.IPv4 for mDNS. Oct 18 00:36:04 volumio-metal avahi-daemon[634]: Registering new address record for 10.0.4.93 on eth0.IPv4. Oct 18 00:36:04 volumio-metal dnsmasq[880]: reading /etc/resolv.conf Oct 18 00:36:04 volumio-metal dnsmasq[880]: using nameserver 10.0.4.2#53 Oct 18 00:36:04 volumio-metal dnsmasq[880]: using nameserver 208.67.222.222#53 Oct 18 00:36:04 volumio-metal dnsmasq[880]: using nameserver 208.67.220.220#53 Oct 18 00:36:04 volumio-metal sh[533]: forked to background, child pid 938 Oct 18 00:36:04 volumio-metal dhcpcd[578]: forked to background, child pid 938 Oct 18 00:36:04 volumio-metal ifplugd(eth0)[796]: client: ifup: interface eth0 already configured Oct 18 00:36:04 volumio-metal sh[533]: eth0=eth0 Oct 18 00:36:04 volumio-metal ifplugd(eth0)[796]: Program executed successfully. Oct 18 00:36:04 volumio-metal ifplugd[731]: Network Interface Plugging Daemon...start eth0...done. Oct 18 00:36:04 volumio-metal systemd[1]: Started LSB: Brings up/down network automatically. Oct 18 00:36:04 volumio-metal smbd[917]: [2024/10/18 00:36:04.794080, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 18 00:36:04 volumio-metal smbd[917]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 18 00:36:04 volumio-metal systemd[1]: Started Samba SMB Daemon. Oct 18 00:36:04 volumio-metal systemd[1]: Reached target Multi-User System. Oct 18 00:36:04 volumio-metal systemd[1]: Reached target Graphical Interface. Oct 18 00:36:04 volumio-metal systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 18 00:36:04 volumio-metal systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 18 00:36:04 volumio-metal systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 18 00:36:04 volumio-metal systemd[1]: Startup finished in 17.055s (kernel) + 19.039s (userspace) = 36.094s. Oct 18 00:36:05 volumio-metal ntpd[790]: Listen normally on 4 eth0 10.0.4.93:123 Oct 18 00:36:05 volumio-metal ntpd[790]: new interface(s) found: waking up resolver Oct 18 00:36:06 volumio-metal volumio[889]: info: ------------------------------------------- Oct 18 00:36:06 volumio-metal volumio[889]: info: ----- Volumio3 ---- Oct 18 00:36:06 volumio-metal volumio[889]: info: ------------------------------------------- Oct 18 00:36:06 volumio-metal volumio[889]: info: ----- System startup ---- Oct 18 00:36:06 volumio-metal volumio[889]: info: ------------------------------------------- Oct 18 00:36:08 volumio-metal volumio-remote-updater[626]: [2024-10-18 00:36:08] [connect] Successful connection Oct 18 00:36:08 volumio-metal volumio[889]: info: MYVOLUMIO Environment detected Oct 18 00:36:08 volumio-metal volumio[889]: info: Plugin folders cleanup Oct 18 00:36:08 volumio-metal volumio[889]: info: Scanning into folder /volumio/app/plugins/ Oct 18 00:36:08 volumio-metal volumio[889]: info: Scanning category audio_interface Oct 18 00:36:08 volumio-metal volumio[889]: info: Scanning category miscellanea Oct 18 00:36:08 volumio-metal volumio[889]: info: Scanning category music_service Oct 18 00:36:08 volumio-metal volumio[889]: info: Scanning category plugins.json Oct 18 00:36:08 volumio-metal volumio[889]: info: Scanning category system_controller Oct 18 00:36:08 volumio-metal volumio[889]: info: Scanning category user_interface Oct 18 00:36:08 volumio-metal volumio[889]: info: Scanning into folder /data/plugins/ Oct 18 00:36:08 volumio-metal volumio[889]: info: Plugin folders cleanup completed Oct 18 00:36:08 volumio-metal volumio[889]: info: ------------------------------------------- Oct 18 00:36:08 volumio-metal volumio[889]: info: ----- Core plugins startup ---- Oct 18 00:36:08 volumio-metal volumio[889]: info: ------------------------------------------- Oct 18 00:36:08 volumio-metal volumio[889]: info: Loading plugins from folder /volumio/app/plugins/ Oct 18 00:36:08 volumio-metal volumio[889]: info: Adding plugin upnp to MyMusic Plugins Oct 18 00:36:08 volumio-metal volumio[889]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 18 00:36:08 volumio-metal volumio[889]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 18 00:36:08 volumio-metal volumio[889]: info: Loading plugins from folder /data/plugins/ Oct 18 00:36:08 volumio-metal volumio[889]: info: Loading plugin "system"... Oct 18 00:36:08 volumio-metal volumio[889]: info: Loading plugin "appearance"... Oct 18 00:36:09 volumio-metal kernel: hwmon hwmon1: Voltage normalised Oct 18 00:36:10 volumio-metal volumio[889]: info: Loading plugin "network"... Oct 18 00:36:10 volumio-metal volumio[889]: info: Refreshing Cached IP Addresses Oct 18 00:36:10 volumio-metal sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 00:36:10 volumio-metal sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:10 volumio-metal volumio[889]: info: Loading plugin "services"... Oct 18 00:36:10 volumio-metal sudo[987]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:10 volumio-metal sudo[989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 00:36:10 volumio-metal sudo[989]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:10 volumio-metal volumio[889]: info: Loading plugin "alsa_controller"... Oct 18 00:36:10 volumio-metal sudo[989]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:10 volumio-metal sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 18 00:36:10 volumio-metal sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:10 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 00:36:10 volumio-metal volumio[889]: info: Loading plugin "wizard"... Oct 18 00:36:10 volumio-metal volumio[889]: info: Loading plugin "networkfs"... Oct 18 00:36:10 volumio-metal volumio[889]: info: Starting Udev Watcher for removable devices Oct 18 00:36:10 volumio-metal volumio[889]: info: Ignoring mount for partition: boot Oct 18 00:36:10 volumio-metal volumio[889]: info: Ignoring mount for partition: volumio Oct 18 00:36:10 volumio-metal volumio[889]: info: Ignoring mount for partition: volumio_data Oct 18 00:36:10 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 18 00:36:10 volumio-metal volumio[889]: info: Loading plugin "volumio_command_line_client"... Oct 18 00:36:10 volumio-metal volumio[889]: info: Loading plugin "upnp"... Oct 18 00:36:10 volumio-metal volumio[889]: info: [1729204570821] Starting Upmpd Daemon Oct 18 00:36:10 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 18 00:36:10 volumio-metal volumio[889]: info: Loading plugin "my_music"... Oct 18 00:36:10 volumio-metal volumio[889]: info: Loading plugin "mpd"... Oct 18 00:36:11 volumio-metal sudo[992]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:11 volumio-metal volumio[889]: info: Loading plugin "upnp_browser"... Oct 18 00:36:11 volumio-metal kernel: hwmon hwmon1: Undervoltage detected! Oct 18 00:36:12 volumio-metal volumio[889]: info: Loading plugin "alarm-clock"... Oct 18 00:36:12 volumio-metal volumio[889]: info: Loading plugin "airplay_emulation"... Oct 18 00:36:12 volumio-metal volumio[889]: info: Starting Shairport Sync Oct 18 00:36:12 volumio-metal volumio[889]: info: Loading plugin "last_100"... Oct 18 00:36:12 volumio-metal volumio[889]: info: Loading plugin "webradio"... Oct 18 00:36:13 volumio-metal volumio[889]: info: Loading plugin "i2s_dacs"... Oct 18 00:36:13 volumio-metal volumio[889]: info: I2S DAC not set, start Auto-detection Oct 18 00:36:13 volumio-metal volumio[889]: info: Loading plugin "volumiodiscovery"... Oct 18 00:36:13 volumio-metal volumio[889]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 18 00:36:13 volumio-metal volumio[889]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 18 00:36:13 volumio-metal volumio[889]: *** WARNING *** For more information see Oct 18 00:36:13 volumio-metal node[889]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 18 00:36:13 volumio-metal volumio[889]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 18 00:36:13 volumio-metal volumio[889]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 18 00:36:13 volumio-metal volumio[889]: *** WARNING *** For more information see Oct 18 00:36:13 volumio-metal node[889]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 18 00:36:13 volumio-metal node[889]: *** WARNING *** For more information see Oct 18 00:36:13 volumio-metal node[889]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 18 00:36:13 volumio-metal node[889]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 18 00:36:13 volumio-metal node[889]: *** WARNING *** For more information see Oct 18 00:36:13 volumio-metal volumio[889]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 18 00:36:13 volumio-metal volumio[889]: info: Discovery: Started advertising with name: Volumio Metal Oct 18 00:36:13 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 18 00:36:13 volumio-metal volumio[889]: info: Loading plugin "outputs"... Oct 18 00:36:13 volumio-metal volumio[889]: info: Loading plugin "albumart"... Oct 18 00:36:13 volumio-metal volumio[889]: info: Plugin example_plugin is not enabled Oct 18 00:36:13 volumio-metal volumio[889]: info: Loading plugin "inputs"... Oct 18 00:36:13 volumio-metal volumio[889]: info: Loading plugin "updater_comm"... Oct 18 00:36:13 volumio-metal volumio[889]: info: Plugin mpdemulation is not enabled Oct 18 00:36:13 volumio-metal volumio[889]: info: Loading plugin "rest_api"... Oct 18 00:36:13 volumio-metal volumio[889]: info: Loading plugin "websocket"... Oct 18 00:36:13 volumio-metal volumio[889]: info: Starting Socket.io Server version 2.3.0 Oct 18 00:36:13 volumio-metal volumio[889]: info: Loading i18n strings for locale fr Oct 18 00:36:13 volumio-metal volumio[889]: Updating browse sources language Oct 18 00:36:13 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 00:36:13 volumio-metal volumio[889]: Forking 3 albumart workers Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreCommandRouter::initPlayerControls Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 00:36:14 volumio-metal volumio[889]: Express server listening on port 3000 Oct 18 00:36:14 volumio-metal volumio[889]: [Metrics] WebUI: 9s 360.98ms Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreStateMachine::resetVolumioState Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreStateMachine::getcurrentVolume Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreCommandRouter::volumioRetrievevolume Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreStateMachine::pushState Oct 18 00:36:14 volumio-metal volumio[889]: info: CorePlayQueue::getTrack 0 Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreCommandRouter::volumioPushState Oct 18 00:36:14 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:14 volumio-metal volumio[889]: info: Cannot read play queue from file Oct 18 00:36:15 volumio-metal volumio[889]: info: CoreStateMachine::setRepeat null single undefined Oct 18 00:36:15 volumio-metal volumio[889]: info: CoreStateMachine::pushState Oct 18 00:36:15 volumio-metal volumio[889]: info: CorePlayQueue::getTrack 0 Oct 18 00:36:15 volumio-metal volumio[889]: info: CoreCommandRouter::volumioPushState Oct 18 00:36:15 volumio-metal volumio[889]: info: CoreStateMachine::setRandom null Oct 18 00:36:15 volumio-metal volumio[889]: info: CoreStateMachine::pushState Oct 18 00:36:15 volumio-metal volumio[889]: info: CorePlayQueue::getTrack 0 Oct 18 00:36:15 volumio-metal volumio[889]: info: CoreCommandRouter::volumioPushState Oct 18 00:36:15 volumio-metal volumio[889]: info: Setting Device type: Raspberry PI Oct 18 00:36:15 volumio-metal volumio[889]: info: Completed loading Core Plugins Oct 18 00:36:15 volumio-metal volumio[889]: info: Preparing to generate the ALSA configuration file Oct 18 00:36:15 volumio-metal volumio[889]: info: Discovery: adding e66a84c9-dc18-43c7-a5b3-c42d44bb7c84 Oct 18 00:36:15 volumio-metal volumio[889]: info: Discovery: Found device Volumio Metal Oct 18 00:36:15 volumio-metal volumio[889]: info: CoreCommandRouter::volumioGetState Oct 18 00:36:15 volumio-metal volumio[889]: info: CorePlayQueue::getTrack 0 Oct 18 00:36:15 volumio-metal volumio[889]: info: Discovery: this is already registered, e66a84c9-dc18-43c7-a5b3-c42d44bb7c84 Oct 18 00:36:15 volumio-metal volumio[889]: info: Discovery: Found device Volumio Metal Oct 18 00:36:15 volumio-metal volumio[889]: info: CoreCommandRouter::volumioGetState Oct 18 00:36:15 volumio-metal volumio[889]: info: CorePlayQueue::getTrack 0 Oct 18 00:36:15 volumio-metal volumio[889]: info: Asound.conf file unchanged, so no further update is needed Oct 18 00:36:15 volumio-metal volumio[889]: info: Output device has changed, restarting MPD Oct 18 00:36:15 volumio-metal volumio[889]: info: Output device has changed, restarting Shairport Sync Oct 18 00:36:15 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:15 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:15 volumio-metal sudo[1039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 18 00:36:15 volumio-metal sudo[1039]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:15 volumio-metal sudo[1038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 18 00:36:15 volumio-metal sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:15 volumio-metal sudo[1038]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:15 volumio-metal volumio[889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 18 00:36:15 volumio-metal volumio[889]: info: ___________ START PLUGINS ___________ Oct 18 00:36:15 volumio-metal volumio[889]: info: ControllerMpd::onStart: Initializing MPD Oct 18 00:36:15 volumio-metal volumio[889]: info: Creating MPD Configuration file Oct 18 00:36:15 volumio-metal systemd[1]: Listening on mpd.socket. Oct 18 00:36:15 volumio-metal volumio[889]: Starting albumart workers Oct 18 00:36:15 volumio-metal systemd[1]: Starting Music Player Daemon... Oct 18 00:36:15 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 00:36:15 volumio-metal volumio[889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 18 00:36:15 volumio-metal volumio[889]: info: [1729204575875] CoreMusicLibrary::Adding element Serveurs Média Oct 18 00:36:15 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 00:36:15 volumio-metal sudo[1048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 18 00:36:15 volumio-metal sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:15 volumio-metal volumio[889]: Starting albumart workers Oct 18 00:36:15 volumio-metal sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 18 00:36:15 volumio-metal sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:15 volumio-metal sudo[1048]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:15 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:15 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:16 volumio-metal sudo[1046]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:36:16 volumio-metal sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:16 volumio-metal sudo[1046]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:16 volumio-metal systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 18 00:36:16 volumio-metal systemd[1]: mpd.service: Succeeded. Oct 18 00:36:16 volumio-metal systemd[1]: Stopped Music Player Daemon. Oct 18 00:36:16 volumio-metal volumio[889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 18 00:36:16 volumio-metal systemd[1]: Starting Music Player Daemon... Oct 18 00:36:16 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:16 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:16 volumio-metal volumio[889]: Starting albumart workers Oct 18 00:36:16 volumio-metal volumio[889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 18 00:36:16 volumio-metal volumio[889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 18 00:36:16 volumio-metal volumio[889]: info: [1729204576259] CoreMusicLibrary::Adding element Last_100 Oct 18 00:36:16 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 00:36:16 volumio-metal volumio[889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 18 00:36:16 volumio-metal volumio[889]: info: [1729204576266] CoreMusicLibrary::Adding element Webradio Oct 18 00:36:16 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 00:36:16 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 00:36:16 volumio-metal volumio[889]: info: Initializing BBC Radios Oct 18 00:36:16 volumio-metal sudo[1056]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:36:16 volumio-metal sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:16 volumio-metal sudo[1056]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 18 00:36:16 volumio-metal sudo[1056]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:16 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 00:36:16 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:16 volumio-metal volumio[889]: info: Volumio Calling Home Oct 18 00:36:16 volumio-metal systemd[1071]: mpd.service: Failed to execute command: Permission denied Oct 18 00:36:16 volumio-metal systemd[1071]: mpd.service: Failed at step EXEC spawning /usr/bin/mpd: Permission denied Oct 18 00:36:16 volumio-metal systemd[1]: mpd.service: Main process exited, code=exited, status=203/EXEC Oct 18 00:36:16 volumio-metal systemd[1]: mpd.service: Failed with result 'exit-code'. Oct 18 00:36:16 volumio-metal systemd[1]: Failed to start Music Player Daemon. Oct 18 00:36:16 volumio-metal sudo[1039]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:16 volumio-metal sudo[1050]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:17 volumio-metal volumio[889]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Oct 18 00:36:17 volumio-metal volumio[889]: Job for mpd.service failed because the control process exited with error code. Oct 18 00:36:17 volumio-metal volumio[889]: See "systemctl status mpd.service" and "journalctl -xe" for details. Oct 18 00:36:17 volumio-metal volumio[889]: info: Completed starting Core Plugins Oct 18 00:36:17 volumio-metal volumio[889]: info: ------------------------------------------- Oct 18 00:36:17 volumio-metal volumio[889]: info: ----- MyVolumio plugins startup ---- Oct 18 00:36:17 volumio-metal volumio[889]: info: ------------------------------------------- Oct 18 00:36:17 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 18 00:36:17 volumio-metal volumio[889]: info: MPD Permissions set Oct 18 00:36:17 volumio-metal volumio[889]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Oct 18 00:36:17 volumio-metal volumio[889]: Job for mpd.service failed because the control process exited with error code. Oct 18 00:36:17 volumio-metal volumio[889]: See "systemctl status mpd.service" and "journalctl -xe" for details. Oct 18 00:36:17 volumio-metal volumio[889]: info: MPD Permissions set Oct 18 00:36:17 volumio-metal systemd[1]: Starting Music Player Daemon... Oct 18 00:36:17 volumio-metal volumio[889]: info: Volumio called home Oct 18 00:36:17 volumio-metal sudo[1075]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:36:17 volumio-metal sudo[1075]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:17 volumio-metal sudo[1075]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 18 00:36:17 volumio-metal sudo[1075]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:17 volumio-metal volumio[889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:17 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:17 volumio-metal systemd[1085]: mpd.service: Failed to execute command: Permission denied Oct 18 00:36:17 volumio-metal systemd[1085]: mpd.service: Failed at step EXEC spawning /usr/bin/mpd: Permission denied Oct 18 00:36:17 volumio-metal systemd[1]: mpd.service: Main process exited, code=exited, status=203/EXEC Oct 18 00:36:17 volumio-metal systemd[1]: mpd.service: Failed with result 'exit-code'. Oct 18 00:36:17 volumio-metal systemd[1]: Failed to start Music Player Daemon. Oct 18 00:36:17 volumio-metal systemd[1]: Starting Music Player Daemon... Oct 18 00:36:17 volumio-metal volumio[889]: info: Starting Shairport Sync Oct 18 00:36:17 volumio-metal volumio[889]: info: Starting Shairport Sync Oct 18 00:36:18 volumio-metal volumio[889]: info: Starting Shairport Sync Oct 18 00:36:18 volumio-metal sudo[1112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 18 00:36:18 volumio-metal sudo[1112]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:18 volumio-metal sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 18 00:36:18 volumio-metal sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:18 volumio-metal volumio[889]: info: CoreCommandRouter::volumioGetState Oct 18 00:36:18 volumio-metal volumio[889]: info: CorePlayQueue::getTrack 0 Oct 18 00:36:18 volumio-metal sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 18 00:36:18 volumio-metal sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:18 volumio-metal systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 18 00:36:18 volumio-metal systemd[1]: shairport-sync.service: Succeeded. Oct 18 00:36:18 volumio-metal systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 18 00:36:18 volumio-metal sudo[1110]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:36:18 volumio-metal sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:18 volumio-metal sudo[1110]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 18 00:36:18 volumio-metal sudo[1110]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:18 volumio-metal systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 18 00:36:18 volumio-metal sudo[1114]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:18 volumio-metal sudo[1112]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:18 volumio-metal sudo[1117]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:18 volumio-metal sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 00:36:18 volumio-metal sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:18 volumio-metal sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 00:36:18 volumio-metal volumio[889]: info: Shairport-Sync Started Oct 18 00:36:18 volumio-metal sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:18 volumio-metal volumio[889]: Error adding Membership: Error: addMembership EINVAL Oct 18 00:36:18 volumio-metal volumio[889]: info: Shairport-Sync Started Oct 18 00:36:18 volumio-metal volumio[889]: info: Shairport-Sync Started Oct 18 00:36:18 volumio-metal sudo[1127]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:18 volumio-metal sudo[1125]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:18 volumio-metal systemd[1122]: mpd.service: Failed to execute command: Permission denied Oct 18 00:36:18 volumio-metal systemd[1122]: mpd.service: Failed at step EXEC spawning /usr/bin/mpd: Permission denied Oct 18 00:36:18 volumio-metal systemd[1]: mpd.service: Main process exited, code=exited, status=203/EXEC Oct 18 00:36:18 volumio-metal systemd[1]: mpd.service: Failed with result 'exit-code'. Oct 18 00:36:18 volumio-metal systemd[1]: Failed to start Music Player Daemon. Oct 18 00:36:18 volumio-metal volumio[889]: verbose: New Socket.io Connection to 10.0.4.93 from 10.0.4.58 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 18 00:36:18 volumio-metal systemd[1]: Starting Music Player Daemon... Oct 18 00:36:18 volumio-metal sudo[1132]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:36:18 volumio-metal sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:18 volumio-metal sudo[1132]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 18 00:36:18 volumio-metal sudo[1132]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:18 volumio-metal sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 00:36:18 volumio-metal sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:18 volumio-metal sudo[1138]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:18 volumio-metal sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 00:36:18 volumio-metal sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:18 volumio-metal sudo[1141]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:18 volumio-metal volumio[889]: verbose: New Socket.io Connection to 10.0.4.93 from 10.0.4.58 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 18 00:36:19 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 18 00:36:19 volumio-metal systemd[1140]: mpd.service: Failed to execute command: Permission denied Oct 18 00:36:19 volumio-metal systemd[1140]: mpd.service: Failed at step EXEC spawning /usr/bin/mpd: Permission denied Oct 18 00:36:19 volumio-metal systemd[1]: mpd.service: Main process exited, code=exited, status=203/EXEC Oct 18 00:36:19 volumio-metal systemd[1]: mpd.service: Failed with result 'exit-code'. Oct 18 00:36:19 volumio-metal systemd[1]: Failed to start Music Player Daemon. Oct 18 00:36:19 volumio-metal systemd[1]: Starting Music Player Daemon... Oct 18 00:36:19 volumio-metal volumio[889]: info: CoreCommandRouter::volumioGetVisibleSources Oct 18 00:36:19 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 00:36:19 volumio-metal volumio[889]: info: CoreCommandRouter::volumioGetState Oct 18 00:36:19 volumio-metal volumio[889]: info: CorePlayQueue::getTrack 0 Oct 18 00:36:19 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 18 00:36:19 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 18 00:36:19 volumio-metal volumio[889]: info: Received Get System Info Oct 18 00:36:19 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 00:36:19 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 00:36:19 volumio-metal volumio[889]: info: Discovery: Getting this device information Oct 18 00:36:19 volumio-metal volumio[889]: info: CoreCommandRouter::volumioGetState Oct 18 00:36:19 volumio-metal volumio[889]: info: CorePlayQueue::getTrack 0 Oct 18 00:36:19 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 00:36:19 volumio-metal volumio[889]: info: CoreCommandRouter::volumioGetState Oct 18 00:36:19 volumio-metal volumio[889]: info: CorePlayQueue::getTrack 0 Oct 18 00:36:19 volumio-metal volumio[889]: info: Listing playlists Oct 18 00:36:19 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 18 00:36:19 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 18 00:36:19 volumio-metal sudo[1144]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:36:19 volumio-metal sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:19 volumio-metal sudo[1144]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 18 00:36:19 volumio-metal sudo[1144]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:19 volumio-metal systemd[1146]: mpd.service: Failed to execute command: Permission denied Oct 18 00:36:19 volumio-metal systemd[1146]: mpd.service: Failed at step EXEC spawning /usr/bin/mpd: Permission denied Oct 18 00:36:19 volumio-metal systemd[1]: mpd.service: Main process exited, code=exited, status=203/EXEC Oct 18 00:36:19 volumio-metal systemd[1]: mpd.service: Failed with result 'exit-code'. Oct 18 00:36:19 volumio-metal systemd[1]: Failed to start Music Player Daemon. Oct 18 00:36:19 volumio-metal systemd[1]: Starting Music Player Daemon... Oct 18 00:36:19 volumio-metal volumio[889]: info: CoreCommandRouter::volumioGetState Oct 18 00:36:19 volumio-metal volumio[889]: info: CorePlayQueue::getTrack 0 Oct 18 00:36:19 volumio-metal sudo[1147]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:36:19 volumio-metal sudo[1147]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:19 volumio-metal sudo[1147]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 18 00:36:19 volumio-metal sudo[1147]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:20 volumio-metal systemd[1149]: mpd.service: Failed to execute command: Permission denied Oct 18 00:36:20 volumio-metal systemd[1149]: mpd.service: Failed at step EXEC spawning /usr/bin/mpd: Permission denied Oct 18 00:36:20 volumio-metal systemd[1]: mpd.service: Main process exited, code=exited, status=203/EXEC Oct 18 00:36:20 volumio-metal systemd[1]: mpd.service: Failed with result 'exit-code'. Oct 18 00:36:20 volumio-metal systemd[1]: Failed to start Music Player Daemon. Oct 18 00:36:20 volumio-metal systemd[1]: Starting Music Player Daemon... Oct 18 00:36:20 volumio-metal sudo[1150]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:36:20 volumio-metal sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:20 volumio-metal sudo[1150]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 18 00:36:20 volumio-metal sudo[1150]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:20 volumio-metal systemd[1152]: mpd.service: Failed to execute command: Permission denied Oct 18 00:36:20 volumio-metal systemd[1152]: mpd.service: Failed at step EXEC spawning /usr/bin/mpd: Permission denied Oct 18 00:36:20 volumio-metal systemd[1]: mpd.service: Main process exited, code=exited, status=203/EXEC Oct 18 00:36:20 volumio-metal systemd[1]: mpd.service: Failed with result 'exit-code'. Oct 18 00:36:20 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 18 00:36:20 volumio-metal volumio[889]: info: Received Get System Info Oct 18 00:36:20 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 00:36:20 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 00:36:20 volumio-metal volumio[889]: info: Discovery: Getting this device information Oct 18 00:36:20 volumio-metal systemd[1]: Failed to start Music Player Daemon. Oct 18 00:36:20 volumio-metal volumio[889]: info: CoreCommandRouter::volumioGetState Oct 18 00:36:20 volumio-metal volumio[889]: info: CorePlayQueue::getTrack 0 Oct 18 00:36:20 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 00:36:20 volumio-metal systemd[1]: Starting Music Player Daemon... Oct 18 00:36:20 volumio-metal sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 00:36:20 volumio-metal sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:20 volumio-metal sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 00:36:20 volumio-metal sudo[1155]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:20 volumio-metal sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:20 volumio-metal sudo[1157]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:20 volumio-metal sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 18 00:36:20 volumio-metal sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:20 volumio-metal systemd[1]: Started UPnP Renderer front-end to MPD. Oct 18 00:36:20 volumio-metal sudo[1160]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:20 volumio-metal volumio[889]: info: Upmpdcli Daemon Started Oct 18 00:36:20 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 18 00:36:20 volumio-metal sudo[1153]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:36:20 volumio-metal sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:20 volumio-metal sudo[1153]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 18 00:36:20 volumio-metal sudo[1153]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:21 volumio-metal volumio[889]: info: CoreCommandRouter::volumioGetQueue Oct 18 00:36:21 volumio-metal volumio[889]: info: CoreStateMachine::getQueue Oct 18 00:36:21 volumio-metal volumio[889]: info: CorePlayQueue::getQueue Oct 18 00:36:21 volumio-metal systemd[1165]: mpd.service: Failed to execute command: Permission denied Oct 18 00:36:21 volumio-metal systemd[1165]: mpd.service: Failed at step EXEC spawning /usr/bin/mpd: Permission denied Oct 18 00:36:21 volumio-metal systemd[1]: mpd.service: Main process exited, code=exited, status=203/EXEC Oct 18 00:36:21 volumio-metal systemd[1]: mpd.service: Failed with result 'exit-code'. Oct 18 00:36:21 volumio-metal systemd[1]: Failed to start Music Player Daemon. Oct 18 00:36:21 volumio-metal systemd[1]: Starting Music Player Daemon... Oct 18 00:36:21 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 18 00:36:21 volumio-metal volumio[889]: info: Received Get System Info Oct 18 00:36:21 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 00:36:21 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 00:36:21 volumio-metal volumio[889]: info: Discovery: Getting this device information Oct 18 00:36:21 volumio-metal volumio[889]: info: CoreCommandRouter::volumioGetState Oct 18 00:36:21 volumio-metal volumio[889]: info: CorePlayQueue::getTrack 0 Oct 18 00:36:21 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 00:36:21 volumio-metal sudo[1166]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:36:21 volumio-metal sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:21 volumio-metal sudo[1166]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 18 00:36:21 volumio-metal sudo[1166]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:21 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 18 00:36:21 volumio-metal systemd[1168]: mpd.service: Failed to execute command: Permission denied Oct 18 00:36:21 volumio-metal systemd[1168]: mpd.service: Failed at step EXEC spawning /usr/bin/mpd: Permission denied Oct 18 00:36:21 volumio-metal systemd[1]: mpd.service: Main process exited, code=exited, status=203/EXEC Oct 18 00:36:21 volumio-metal systemd[1]: mpd.service: Failed with result 'exit-code'. Oct 18 00:36:21 volumio-metal systemd[1]: Failed to start Music Player Daemon. Oct 18 00:36:21 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 18 00:36:21 volumio-metal systemd[1]: Starting Music Player Daemon... Oct 18 00:36:22 volumio-metal systemd[1]: systemd-fsckd.service: Succeeded. Oct 18 00:36:22 volumio-metal sudo[1169]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:36:22 volumio-metal sudo[1169]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:22 volumio-metal sudo[1169]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 18 00:36:22 volumio-metal sudo[1169]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:22 volumio-metal systemd[1171]: mpd.service: Failed to execute command: Permission denied Oct 18 00:36:22 volumio-metal systemd[1171]: mpd.service: Failed at step EXEC spawning /usr/bin/mpd: Permission denied Oct 18 00:36:22 volumio-metal systemd[1]: mpd.service: Main process exited, code=exited, status=203/EXEC Oct 18 00:36:22 volumio-metal systemd[1]: mpd.service: Failed with result 'exit-code'. Oct 18 00:36:22 volumio-metal systemd[1]: Failed to start Music Player Daemon. Oct 18 00:36:22 volumio-metal systemd[1]: Starting Music Player Daemon... Oct 18 00:36:22 volumio-metal sudo[1172]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:36:22 volumio-metal sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:22 volumio-metal sudo[1172]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 18 00:36:22 volumio-metal sudo[1172]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:22 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 00:36:22 volumio-metal volumio[889]: info: Preload queue cleared Oct 18 00:36:22 volumio-metal systemd[1174]: mpd.service: Failed to execute command: Permission denied Oct 18 00:36:22 volumio-metal systemd[1174]: mpd.service: Failed at step EXEC spawning /usr/bin/mpd: Permission denied Oct 18 00:36:22 volumio-metal systemd[1]: mpd.service: Main process exited, code=exited, status=203/EXEC Oct 18 00:36:22 volumio-metal systemd[1]: mpd.service: Failed with result 'exit-code'. Oct 18 00:36:22 volumio-metal systemd[1]: Failed to start Music Player Daemon. Oct 18 00:36:23 volumio-metal systemd[1]: Starting Music Player Daemon... Oct 18 00:36:23 volumio-metal sudo[1175]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:36:23 volumio-metal sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:23 volumio-metal sudo[1175]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 18 00:36:23 volumio-metal sudo[1175]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:23 volumio-metal volumio-remote-updater[626]: [2024-10-18 00:36:23] [connect] Successful connection Oct 18 00:36:23 volumio-metal volumio-remote-updater[626]: [2024-10-18 00:36:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729204583 101 Oct 18 00:36:23 volumio-metal volumio[889]: 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: 4 Oct 18 00:36:23 volumio-metal systemd[1177]: mpd.service: Failed to execute command: Permission denied Oct 18 00:36:23 volumio-metal systemd[1177]: mpd.service: Failed at step EXEC spawning /usr/bin/mpd: Permission denied Oct 18 00:36:23 volumio-metal systemd[1]: mpd.service: Main process exited, code=exited, status=203/EXEC Oct 18 00:36:23 volumio-metal systemd[1]: mpd.service: Failed with result 'exit-code'. Oct 18 00:36:23 volumio-metal systemd[1]: Failed to start Music Player Daemon. Oct 18 00:36:23 volumio-metal systemd[1]: Starting Music Player Daemon... Oct 18 00:36:23 volumio-metal sudo[1178]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:36:23 volumio-metal sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:23 volumio-metal sudo[1178]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 18 00:36:23 volumio-metal sudo[1178]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:24 volumio-metal systemd[1180]: mpd.service: Failed to execute command: Permission denied Oct 18 00:36:24 volumio-metal systemd[1180]: mpd.service: Failed at step EXEC spawning /usr/bin/mpd: Permission denied Oct 18 00:36:24 volumio-metal systemd[1]: mpd.service: Main process exited, code=exited, status=203/EXEC Oct 18 00:36:24 volumio-metal systemd[1]: mpd.service: Failed with result 'exit-code'. Oct 18 00:36:24 volumio-metal systemd[1]: Failed to start Music Player Daemon. Oct 18 00:36:24 volumio-metal systemd[1]: Starting Music Player Daemon... Oct 18 00:36:24 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 00:36:24 volumio-metal sudo[1181]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:36:24 volumio-metal sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:24 volumio-metal sudo[1181]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 18 00:36:24 volumio-metal sudo[1181]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:24 volumio-metal volumio[889]: info: Preload queue cleared Oct 18 00:36:24 volumio-metal systemd[1183]: mpd.service: Failed to execute command: Permission denied Oct 18 00:36:24 volumio-metal systemd[1183]: mpd.service: Failed at step EXEC spawning /usr/bin/mpd: Permission denied Oct 18 00:36:24 volumio-metal systemd[1]: mpd.service: Main process exited, code=exited, status=203/EXEC Oct 18 00:36:24 volumio-metal systemd[1]: mpd.service: Failed with result 'exit-code'. Oct 18 00:36:24 volumio-metal systemd[1]: Failed to start Music Player Daemon. Oct 18 00:36:24 volumio-metal systemd[1]: mpd.service: Start request repeated too quickly. Oct 18 00:36:24 volumio-metal systemd[1]: mpd.service: Failed with result 'exit-code'. Oct 18 00:36:24 volumio-metal systemd[1]: Failed to start Music Player Daemon. Oct 18 00:36:24 volumio-metal systemd[1]: mpd.socket: Failed with result 'service-start-limit-hit'. Oct 18 00:36:24 volumio-metal volumio[889]: error: MPD error: Error: read ECONNRESET Oct 18 00:36:24 volumio-metal volumio[889]: error: MPD error: Error: read ECONNRESET Oct 18 00:36:24 volumio-metal volumio[889]: error: Upnp client error: Error: read ECONNRESET Oct 18 00:36:24 volumio-metal volumio[889]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 18 00:36:25 volumio-metal volumio[889]: info: Adding plugin bluetooth to MyMusic Plugins Oct 18 00:36:25 volumio-metal volumio[889]: info: Adding plugin multiroom to MyMusic Plugins Oct 18 00:36:25 volumio-metal volumio[889]: info: Adding plugin metavolumio to MyMusic Plugins Oct 18 00:36:25 volumio-metal volumio[889]: info: Adding plugin cd_controller to MyMusic Plugins Oct 18 00:36:25 volumio-metal volumio[889]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 18 00:36:25 volumio-metal volumio[889]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 18 00:36:25 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 18 00:36:26 volumio-metal kernel: hwmon hwmon1: Voltage normalised Oct 18 00:36:27 volumio-metal volumio[889]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 18 00:36:27 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 18 00:36:27 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:27 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:27 volumio-metal volumio[889]: info: Starting MyVolumio Remote Streaming Endpoints Oct 18 00:36:27 volumio-metal volumio[889]: info: MyVolumio login type: Token Oct 18 00:36:27 volumio-metal volumio[889]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 18 00:36:27 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 18 00:36:27 volumio-metal systemd[1]: systemd-hostnamed.service: Succeeded. Oct 18 00:36:28 volumio-metal kernel: hwmon hwmon1: Undervoltage detected! Oct 18 00:36:30 volumio-metal volumio[889]: info: Starting Streaming Service Transparent Proxy Oct 18 00:36:30 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 18 00:36:30 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 18 00:36:30 volumio-metal volumio[889]: info: Streaming services startup Oct 18 00:36:30 volumio-metal volumio[889]: info: Starting Streaming Daemon Oct 18 00:36:30 volumio-metal sudo[1194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 18 00:36:30 volumio-metal sudo[1194]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:30 volumio-metal volumio[889]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 18 00:36:30 volumio-metal sudo[1194]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:30 volumio-metal volumio[889]: error: MPD error: Error: connect ECONNREFUSED /run/mpd/socket Oct 18 00:36:30 volumio-metal volumio[889]: error: connect ECONNREFUSED /run/mpd/socket {"errno":-111,"code":"ECONNREFUSED","syscall":"connect","address":"/run/mpd/socket"} Oct 18 00:36:30 volumio-metal volumio[889]: error: MPD error: Error: connect ECONNREFUSED /run/mpd/socket Oct 18 00:36:30 volumio-metal volumio[889]: error: connect ECONNREFUSED /run/mpd/socket {"errno":-111,"code":"ECONNREFUSED","syscall":"connect","address":"/run/mpd/socket"} Oct 18 00:36:30 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 00:36:30 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 00:36:30 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 18 00:36:30 volumio-metal volumio[889]: error: Cannot start Volumio Streaming Daemon Oct 18 00:36:30 volumio-metal volumio[889]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 18 00:36:30 volumio-metal volumio[889]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 18 00:36:30 volumio-metal volumio[889]: info: Preload queue cleared Oct 18 00:36:30 volumio-metal volumio[889]: info: Preload queue cleared Oct 18 00:36:30 volumio-metal volumio[889]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 18 00:36:31 volumio-metal volumio[889]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 18 00:36:31 volumio-metal volumio[889]: info: MyVolumio token set successfully Oct 18 00:36:31 volumio-metal volumio[889]: info: MYVOLUMIO: Adding device Oct 18 00:36:31 volumio-metal volumio[889]: info: MYVOLUMIO: Evaluating Server Oct 18 00:36:31 volumio-metal volumio[889]: STREAMING PROXY: Starting server on port 3245 Oct 18 00:36:31 volumio-metal volumio[889]: Node JS runtime: 14 Oct 18 00:36:31 volumio-metal volumio[889]: info: MyVolumio status changed Oct 18 00:36:31 volumio-metal volumio[889]: info: Streaming services startup Oct 18 00:36:31 volumio-metal volumio[889]: info: Starting Streaming Daemon Oct 18 00:36:31 volumio-metal volumio[889]: info: Removing browser output: myVolumio user plan is not superstar Oct 18 00:36:31 volumio-metal volumio[889]: info: Removing audio output: Oct 18 00:36:31 volumio-metal volumio[889]: info: Stoppping Tunnel 1 Oct 18 00:36:31 volumio-metal sudo[1219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 18 00:36:31 volumio-metal sudo[1219]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:31 volumio-metal sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 18 00:36:31 volumio-metal sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:36:31 volumio-metal sudo[1219]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:31 volumio-metal volumio[889]: error: Cannot start Volumio Streaming Daemon Oct 18 00:36:31 volumio-metal volumio[889]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 18 00:36:31 volumio-metal volumio[889]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 18 00:36:31 volumio-metal sudo[1221]: pam_unix(sudo:session): session closed for user root Oct 18 00:36:31 volumio-metal volumio[889]: info: Remote SSH Stopped Oct 18 00:36:32 volumio-metal volumio[889]: info: CoreCommandRouter::Close All Modals sent Oct 18 00:36:32 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Oct 18 00:36:32 volumio-metal kernel: hwmon hwmon1: Voltage normalised Oct 18 00:36:34 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 00:36:34 volumio-metal volumio[889]: info: Preload queue cleared Oct 18 00:36:34 volumio-metal kernel: hwmon hwmon1: Undervoltage detected! Oct 18 00:36:35 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 00:36:36 volumio-metal volumio[889]: info: Preload queue cleared Oct 18 00:36:36 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 18 00:36:36 volumio-metal volumio[889]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 18 00:36:36 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 18 00:36:36 volumio-metal volumio[889]: info: Received Get System Version Oct 18 00:36:36 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 00:36:36 volumio-metal volumio[889]: info: Received Get System Info Oct 18 00:36:36 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 00:36:36 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 00:36:36 volumio-metal volumio[889]: info: Discovery: Getting this device information Oct 18 00:36:36 volumio-metal volumio[889]: info: CoreCommandRouter::volumioGetState Oct 18 00:36:36 volumio-metal volumio[889]: info: CorePlayQueue::getTrack 0 Oct 18 00:36:36 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 00:36:41 volumio-metal volumio[889]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 18 00:36:41 volumio-metal volumio[889]: error: Failed to ping endpoint us3.myvolumio.org : unknown error Oct 18 00:36:41 volumio-metal volumio[889]: error: Failed to ping endpoint as1.myvolumio.org : unknown error Oct 18 00:36:41 volumio-metal volumio[889]: error: Failed to ping endpoint as2.myvolumio.org : unknown error Oct 18 00:36:41 volumio-metal volumio[889]: error: Failed to ping endpoint au1.myvolumio.org : unknown error Oct 18 00:36:41 volumio-metal volumio[889]: info: Setting Geolocation for MyVolumio to eu4 Oct 18 00:36:41 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:41 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:41 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:42 volumio-metal volumio[889]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 18 00:36:42 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 18 00:36:42 volumio-metal volumio[889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 18 00:36:42 volumio-metal volumio[889]: info: Updating MyVolumio device info Oct 18 00:36:42 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:42 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:42 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:42 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 00:36:43 volumio-metal volumio[889]: info: Preload queue cleared Oct 18 00:36:45 volumio-metal kernel: hwmon hwmon1: Voltage normalised Oct 18 00:36:46 volumio-metal volumio[889]: info: MYVOLUMIO: Adding device Oct 18 00:36:46 volumio-metal volumio[889]: info: MYVOLUMIO: Evaluating Server Oct 18 00:36:46 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 00:36:46 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:46 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 18 00:36:46 volumio-metal volumio-remote-updater[626]: No test mode Oct 18 00:36:46 volumio-metal volumio-remote-updater[626]: No alpha test mode Oct 18 00:36:46 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 18 00:36:47 volumio-metal volumio[889]: info: CoreCommandRouter::volumioGetState Oct 18 00:36:47 volumio-metal volumio[889]: info: CorePlayQueue::getTrack 0 Oct 18 00:36:47 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:47 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:47 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 18 00:36:47 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 18 00:36:47 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 18 00:36:47 volumio-metal volumio[889]: info: CoreCommandRouter::volumioGetBrowseSources Oct 18 00:36:47 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 00:36:48 volumio-metal volumio[889]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 18 00:36:50 volumio-metal volumio[889]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 18 00:36:50 volumio-metal volumio[889]: info: Completed starting MyVolumio Plugin Oct 18 00:36:50 volumio-metal volumio[889]: info: BOOT COMPLETED Oct 18 00:36:50 volumio-metal volumio[889]: [Metrics] CommandRouter: 43s 345.48ms Oct 18 00:36:50 volumio-metal volumio[889]: info: CoreCommandRouter::volumiosetStartupVolume Oct 18 00:36:50 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:36:50 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:50 volumio-metal volumio[889]: info: CoreCommandRouter::Close All Modals sent Oct 18 00:36:50 volumio-metal volumio[889]: info: CoreCommandRouter::Close All Modals sent Oct 18 00:36:51 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 18 00:36:51 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 18 00:36:51 volumio-metal kernel: hwmon hwmon1: Undervoltage detected! Oct 18 00:36:51 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 18 00:36:51 volumio-metal volumio[889]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 18 00:36:51 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 18 00:36:51 volumio-metal volumio[889]: info: Setting Geolocation for MyVolumio to eu7 Oct 18 00:36:51 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:51 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:51 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:52 volumio-metal volumio[889]: info: Updating MyVolumio device info Oct 18 00:36:52 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:52 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:52 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:36:53 volumio-metal volumio[889]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 18 00:36:53 volumio-metal volumio[889]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 18 00:36:55 volumio-metal volumiologrotate[640]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 18 00:36:55 volumio-metal volumiologrotate[640]: ls: cannot access 'METAL': No such file or directory Oct 18 00:36:57 volumio-metal kernel: hwmon hwmon1: Voltage normalised Oct 18 00:36:58 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 00:36:58 volumio-metal volumio[889]: info: Preload queue cleared Oct 18 00:36:59 volumio-metal volumio[889]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 18 00:37:00 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 00:37:00 volumio-metal volumio[889]: info: Preload queue cleared Oct 18 00:37:01 volumio-metal kernel: hwmon hwmon1: Undervoltage detected! Oct 18 00:37:02 volumio-metal volumio[889]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 00:37:02 volumio-metal volumio[889]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 00:37:02 volumio-metal volumio[889]: TypeError: Cannot read property 'length' of undefined Oct 18 00:37:02 volumio-metal volumio[889]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 18 00:37:02 volumio-metal volumio[889]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 18 00:37:02 volumio-metal volumio[889]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 18 00:37:02 volumio-metal volumio[889]: at Parser.emit (events.js:315:20) Oct 18 00:37:02 volumio-metal volumio[889]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 18 00:37:02 volumio-metal volumio[889]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 18 00:37:02 volumio-metal volumio[889]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 18 00:37:02 volumio-metal volumio[889]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 18 00:37:02 volumio-metal volumio[889]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 18 00:37:02 volumio-metal volumio[889]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 18 00:37:02 volumio-metal volumio[889]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 18 00:37:02 volumio-metal volumio[889]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 18 00:37:02 volumio-metal volumio[889]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 18 00:37:02 volumio-metal volumio[889]: at IncomingMessage.emit (events.js:327:22) Oct 18 00:37:02 volumio-metal volumio[889]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 18 00:37:02 volumio-metal volumio[889]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 18 00:37:02 volumio-metal volumio[889]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 00:37:03 volumio-metal ntpd[790]: Soliciting pool server 51.15.191.239 Oct 18 00:37:04 volumio-metal sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-18 00:36 Oct 18 00:37:04 volumio-metal sudo[1301]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"