-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Mon 2022-01-24 09:58:08 UTC. -- Jan 24 09:57:00 mcintosh ifplugd(eth0)[655]: Using detection mode: SIOCETHTOOL Jan 24 09:57:00 mcintosh ifplugd(eth0)[655]: Initialization complete, link beat not detected. Jan 24 09:57:00 mcintosh ntpd[659]: Listen normally on 2 lo 127.0.0.1:123 Jan 24 09:57:00 mcintosh ntpd[659]: Listening on routing socket on fd #19 for interface updates Jan 24 09:57:00 mcintosh ntpd[659]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 24 09:57:00 mcintosh ntpd[659]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 24 09:57:00 mcintosh ifplugd[626]: Network Interface Plugging Daemon...start eth0...done. Jan 24 09:57:00 mcintosh systemd[1]: Started LSB: Brings up/down network automatically. Jan 24 09:57:01 mcintosh ntpd[659]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 24 09:57:01 mcintosh kernel: Bluetooth: Core ver 2.22 Jan 24 09:57:01 mcintosh kernel: NET: Registered protocol family 31 Jan 24 09:57:01 mcintosh kernel: Bluetooth: HCI device and connection manager initialized Jan 24 09:57:01 mcintosh kernel: Bluetooth: HCI socket layer initialized Jan 24 09:57:01 mcintosh kernel: Bluetooth: L2CAP socket layer initialized Jan 24 09:57:01 mcintosh kernel: Bluetooth: SCO socket layer initialized Jan 24 09:57:01 mcintosh btuart[490]: bcm43xx_init Jan 24 09:57:01 mcintosh btuart[490]: Flash firmware /lib/firmware/brcm/BCM43430A1.hcd Jan 24 09:57:01 mcintosh btuart[490]: Set BDADDR UART: b8:27:eb:1a:25:03 Jan 24 09:57:01 mcintosh btuart[490]: Set Controller UART speed to 460800 bit/s Jan 24 09:57:01 mcintosh btuart[490]: Device setup complete Jan 24 09:57:01 mcintosh kernel: Bluetooth: HCI UART driver ver 2.3 Jan 24 09:57:01 mcintosh kernel: Bluetooth: HCI UART protocol H4 registered Jan 24 09:57:01 mcintosh kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jan 24 09:57:01 mcintosh kernel: Bluetooth: HCI UART protocol Broadcom registered Jan 24 09:57:01 mcintosh systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 24 09:57:01 mcintosh systemd[1]: Started Configure Bluetooth Modems connected by UART. Jan 24 09:57:02 mcintosh systemd[1]: Started Load/Save RF Kill Switch Status. Jan 24 09:57:02 mcintosh systemd[1]: Created slice system-bthelper.slice. Jan 24 09:57:02 mcintosh systemd[1]: Starting Raspberry Pi bluetooth helper... Jan 24 09:57:02 mcintosh ntpd[659]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 24 09:57:02 mcintosh bthelper[674]: Raspberry Pi BDADDR already set Jan 24 09:57:02 mcintosh systemd[1]: Started Raspberry Pi bluetooth helper. Jan 24 09:57:02 mcintosh systemd[1]: Starting Bluetooth service... Jan 24 09:57:02 mcintosh bluetoothd[683]: Bluetooth daemon 5.50 Jan 24 09:57:02 mcintosh bluetoothd[683]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 24 09:57:02 mcintosh systemd[1]: Started Bluetooth service. Jan 24 09:57:02 mcintosh systemd[1]: Reached target Bluetooth. Jan 24 09:57:02 mcintosh nmbd[641]: [2022/01/24 09:57:02.967986, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 24 09:57:02 mcintosh nmbd[641]: started asyncdns process 685 Jan 24 09:57:03 mcintosh bluetoothd[683]: Starting SDP server Jan 24 09:57:03 mcintosh bluetoothd[683]: Excluding (cli) sap Jan 24 09:57:03 mcintosh nmbd[641]: [2022/01/24 09:57:03.010757, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 24 09:57:03 mcintosh nmbd[641]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 24 09:57:03 mcintosh nmbd[641]: [2022/01/24 09:57:03.012581, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 24 09:57:03 mcintosh nmbd[641]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 24 09:57:03 mcintosh kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 24 09:57:03 mcintosh kernel: Bluetooth: BNEP filters: protocol multicast Jan 24 09:57:03 mcintosh kernel: Bluetooth: BNEP socket layer initialized Jan 24 09:57:03 mcintosh bluetoothd[683]: Bluetooth management interface 1.18 initialized Jan 24 09:57:03 mcintosh dbus-daemon[469]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=683 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 24 09:57:03 mcintosh ntpd[659]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 24 09:57:03 mcintosh systemd[1]: Starting Hostname Service... Jan 24 09:57:03 mcintosh bluetoothd[683]: Failed to set privacy: Rejected (0x0b) Jan 24 09:57:03 mcintosh volumio-remote-updater[487]: [2022-01-24 09:57:03] [info] asio async_connect error: system:111 (Connection refused) Jan 24 09:57:03 mcintosh volumio-remote-updater[487]: [2022-01-24 09:57:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 24 09:57:03 mcintosh volumio-remote-updater[487]: [2022-01-24 09:57:03] [error] handle_connect error: Underlying Transport Error Jan 24 09:57:03 mcintosh dbus-daemon[469]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 24 09:57:03 mcintosh systemd[1]: Started Hostname Service. Jan 24 09:57:04 mcintosh ntpd[659]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 24 09:57:05 mcintosh wireless.js[493]: WIRELESS DAEMON: start Jan 24 09:57:05 mcintosh wireless.js[493]: WIRELESS: Loaded configuration Jan 24 09:57:05 mcintosh wireless.js[493]: Cleaning previous... Jan 24 09:57:05 mcintosh sudo[692]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 24 09:57:05 mcintosh sudo[692]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:05 mcintosh sudo[692]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:05 mcintosh sudo[694]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 24 09:57:05 mcintosh sudo[694]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:06 mcintosh sudo[694]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:06 mcintosh wireless.js[493]: Stopped aP Jan 24 09:57:06 mcintosh wireless.js[493]: Start wireless flow Jan 24 09:57:06 mcintosh wireless.js[493]: Stopped hotspot (if there).. Jan 24 09:57:06 mcintosh sudo[701]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 24 09:57:06 mcintosh sudo[701]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:06 mcintosh sudo[701]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:06 mcintosh mpd[657]: Jan 24 09:57 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 24 09:57:06 mcintosh systemd[1]: systemd-rfkill.service: Succeeded. Jan 24 09:57:07 mcintosh mpd[657]: Jan 24 09:57 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 24 09:57:07 mcintosh mpd[657]: Jan 24 09:57 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 24 09:57:07 mcintosh bthelper[674]: Changing power off succeeded Jan 24 09:57:07 mcintosh bthelper[674]: [CHG] Controller B8:27:EB:1A:25:03 Class: 0x0000041c Jan 24 09:57:07 mcintosh bthelper[674]: Changing power on succeeded Jan 24 09:57:08 mcintosh systemd[1]: Started Music Player Daemon. Jan 24 09:57:08 mcintosh sudo[703]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 24 09:57:08 mcintosh sudo[703]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:08 mcintosh sudo[703]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:08 mcintosh wireless.js[493]: DHCP IP Jan 24 09:57:08 mcintosh wireless.js[493]: Start ap Jan 24 09:57:08 mcintosh wpa_supplicant[714]: Successfully initialized wpa_supplicant Jan 24 09:57:08 mcintosh sudo[715]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jan 24 09:57:08 mcintosh sudo[715]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:08 mcintosh kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 24 09:57:08 mcintosh dhcpcd[716]: dev: loaded udev Jan 24 09:57:08 mcintosh dhcpcd-run-hooks[734]: wlan0: starting wpa_supplicant Jan 24 09:57:08 mcintosh dhcpcd-run-hooks[739]: wlan0: failed to start wpa_supplicant Jan 24 09:57:08 mcintosh dhcpcd-run-hooks[740]: 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 Jan 24 09:57:08 mcintosh dhcpcd[716]: wlan0: connected to Access Point `' Jan 24 09:57:08 mcintosh dhcpcd[716]: no interfaces have a carrier Jan 24 09:57:08 mcintosh dhcpcd[716]: forked to background, child pid 750 Jan 24 09:57:08 mcintosh dhcpcd[750]: eth0: waiting for carrier Jan 24 09:57:08 mcintosh dhcpcd[750]: wlan0: waiting for carrier Jan 24 09:57:08 mcintosh sudo[715]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:08 mcintosh wpa_supplicant[738]: wlan0: Trying to associate with SSID 'This Lan Is My Lan' Jan 24 09:57:09 mcintosh wireless.js[493]: trying... Jan 24 09:57:10 mcintosh kernel: cam1-reg: disabling Jan 24 09:57:10 mcintosh kernel: cam-dummy-reg: disabling Jan 24 09:57:10 mcintosh dhcpcd[750]: wlan0: carrier acquired Jan 24 09:57:10 mcintosh sudo[752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 24 09:57:10 mcintosh sudo[752]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:10 mcintosh dhcpcd[750]: wlan0: connected to Access Point `This Lan Is My Lan' Jan 24 09:57:10 mcintosh wpa_supplicant[738]: wlan0: Associated with 9a:9d:5d:da:0d:56 Jan 24 09:57:10 mcintosh wpa_supplicant[738]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9a:9d:5d:da:0d:56 completed [id=0 id_str=] Jan 24 09:57:10 mcintosh wpa_supplicant[738]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 24 09:57:10 mcintosh wpa_supplicant[738]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jan 24 09:57:10 mcintosh sudo[752]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:10 mcintosh dhcpcd[750]: DUID 00:01:00:01:29:81:34:8d:b8:27:eb:e5:da:fc Jan 24 09:57:10 mcintosh dhcpcd[750]: wlan0: IAID eb:e5:da:fc Jan 24 09:57:10 mcintosh dhcpcd[750]: wlan0: adding address fe80::3a14:e923:a56e:4ae9 Jan 24 09:57:10 mcintosh dhcpcd[750]: ipv6_addaddr1: Permission denied Jan 24 09:57:10 mcintosh dhcpcd[750]: wlan0: carrier lost Jan 24 09:57:10 mcintosh wireless.js[493]: trying... Jan 24 09:57:10 mcintosh sudo[759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 24 09:57:10 mcintosh sudo[759]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:10 mcintosh dhcpcd[750]: wlan0: carrier acquired Jan 24 09:57:10 mcintosh sudo[759]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:10 mcintosh dhcpcd[750]: wlan0: IAID eb:e5:da:fc Jan 24 09:57:10 mcintosh dhcpcd[750]: wlan0: soliciting an IPv6 router Jan 24 09:57:10 mcintosh volumio-remote-updater[487]: [2022-01-24 09:57:10] [info] asio async_connect error: system:111 (Connection refused) Jan 24 09:57:10 mcintosh volumio-remote-updater[487]: [2022-01-24 09:57:10] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 24 09:57:10 mcintosh volumio-remote-updater[487]: [2022-01-24 09:57:10] [error] handle_connect error: Underlying Transport Error Jan 24 09:57:10 mcintosh dhcpcd[750]: wlan0: rebinding lease of 10.0.0.29 Jan 24 09:57:11 mcintosh dhcpcd[750]: wlan0: probing address 10.0.0.29/24 Jan 24 09:57:11 mcintosh wireless.js[493]: trying... Jan 24 09:57:13 mcintosh sudo[770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 24 09:57:13 mcintosh sudo[770]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:13 mcintosh sudo[770]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:13 mcintosh wireless.js[493]: trying... Jan 24 09:57:13 mcintosh sudo[773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 24 09:57:13 mcintosh sudo[773]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:13 mcintosh sudo[773]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:14 mcintosh wireless.js[493]: trying... Jan 24 09:57:15 mcintosh sudo[776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 24 09:57:15 mcintosh sudo[776]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:15 mcintosh sudo[776]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:15 mcintosh wireless.js[493]: trying... Jan 24 09:57:15 mcintosh sudo[779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 24 09:57:15 mcintosh sudo[779]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:15 mcintosh sudo[779]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:15 mcintosh dhcpcd[750]: wlan0: leased 10.0.0.29 for 172800 seconds Jan 24 09:57:15 mcintosh avahi-daemon[495]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.0.29. Jan 24 09:57:15 mcintosh dhcpcd[750]: wlan0: adding route to 10.0.0.0/24 Jan 24 09:57:15 mcintosh dhcpcd[750]: wlan0: adding default route via 10.0.0.1 Jan 24 09:57:15 mcintosh avahi-daemon[495]: New relevant interface wlan0.IPv4 for mDNS. Jan 24 09:57:15 mcintosh avahi-daemon[495]: Registering new address record for 10.0.0.29 on wlan0.IPv4. Jan 24 09:57:16 mcintosh wireless.js[493]: trying... Jan 24 09:57:18 mcintosh ntpd[659]: Listen normally on 3 wlan0 10.0.0.29:123 Jan 24 09:57:18 mcintosh ntpd[659]: new interface(s) found: waking up resolver Jan 24 09:57:18 mcintosh nmbd[641]: [2022/01/24 09:57:18.065329, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 24 09:57:18 mcintosh systemd[1]: Started Samba NMB Daemon. Jan 24 09:57:18 mcintosh nmbd[641]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 24 09:57:18 mcintosh systemd[1]: Starting Samba Winbind Daemon... Jan 24 09:57:18 mcintosh sudo[802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 24 09:57:18 mcintosh sudo[802]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:18 mcintosh sudo[802]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:18 mcintosh wireless.js[493]: Connected to: ----This Lan Is My Lan Jan 24 09:57:18 mcintosh wireless.js[493]: ---- Jan 24 09:57:18 mcintosh wireless.js[493]: trying... Jan 24 09:57:18 mcintosh sudo[807]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 24 09:57:18 mcintosh sudo[809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 24 09:57:18 mcintosh sudo[809]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:18 mcintosh sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:18 mcintosh sudo[809]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:18 mcintosh sudo[807]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:18 mcintosh wireless.js[493]: Connected to: ----This Lan Is My Lan Jan 24 09:57:18 mcintosh wireless.js[493]: ---- Jan 24 09:57:18 mcintosh wireless.js[493]: ... joined AP, wlan0 IPv4 is 10.0.0.29, ipV6 is undefined Jan 24 09:57:18 mcintosh wireless.js[493]: It's done! AP Jan 24 09:57:18 mcintosh sudo[813]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 24 09:57:18 mcintosh sudo[813]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:18 mcintosh sudo[813]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:18 mcintosh wireless.js[493]: ... joined AP, wlan0 IPv4 is 10.0.0.29, ipV6 is undefined Jan 24 09:57:18 mcintosh wireless.js[493]: It's done! AP Jan 24 09:57:18 mcintosh systemd[1]: Started Wireless Services. Jan 24 09:57:18 mcintosh systemd[1]: Started Volumio Backend Module. Jan 24 09:57:18 mcintosh systemd[1]: Started Volumio Streaming Daemon. Jan 24 09:57:18 mcintosh volumio-streaming-daemon[819]: ############################ Jan 24 09:57:18 mcintosh volumio-streaming-daemon[819]: # Volumio Streaming Daemon # Jan 24 09:57:18 mcintosh volumio-streaming-daemon[819]: # Running on port 7777 # Jan 24 09:57:18 mcintosh volumio-streaming-daemon[819]: ############################ Jan 24 09:57:18 mcintosh volumio-streaming-daemon[819]: Environment: production Jan 24 09:57:18 mcintosh winbindd[803]: [2022/01/24 09:57:18.762770, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 24 09:57:18 mcintosh winbindd[803]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 24 09:57:18 mcintosh winbindd[803]: [2022/01/24 09:57:18.794207, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 24 09:57:18 mcintosh winbindd[803]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 24 09:57:18 mcintosh systemd[1]: Started Samba Winbind Daemon. Jan 24 09:57:18 mcintosh systemd[1]: Starting Samba SMB Daemon... Jan 24 09:57:20 mcintosh smbd[835]: [2022/01/24 09:57:20.882258, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 24 09:57:20 mcintosh smbd[835]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 24 09:57:20 mcintosh systemd[1]: Started Samba SMB Daemon. Jan 24 09:57:20 mcintosh systemd[1]: Reached target Multi-User System. Jan 24 09:57:20 mcintosh systemd[1]: Reached target Graphical Interface. Jan 24 09:57:20 mcintosh systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 24 09:57:20 mcintosh volumio-remote-updater[487]: [2022-01-24 09:57:20] [info] asio async_connect error: system:111 (Connection refused) Jan 24 09:57:20 mcintosh volumio-remote-updater[487]: [2022-01-24 09:57:20] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 24 09:57:20 mcintosh volumio-remote-updater[487]: [2022-01-24 09:57:20] [error] handle_connect error: Underlying Transport Error Jan 24 09:57:21 mcintosh systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 24 09:57:21 mcintosh systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 24 09:57:21 mcintosh systemd[1]: Startup finished in 11.158s (kernel) + 33.981s (userspace) = 45.139s. Jan 24 09:57:23 mcintosh volumio[818]: info: ------------------------------------------- Jan 24 09:57:23 mcintosh volumio[818]: info: ----- Volumio3 ---- Jan 24 09:57:23 mcintosh volumio[818]: info: ------------------------------------------- Jan 24 09:57:23 mcintosh volumio[818]: info: ----- System startup ---- Jan 24 09:57:23 mcintosh volumio[818]: info: ------------------------------------------- Jan 24 09:57:24 mcintosh systemd[1]: systemd-fsckd.service: Succeeded. Jan 24 09:57:25 mcintosh volumio[818]: info: MYVOLUMIO Environment detected Jan 24 09:57:26 mcintosh sh[398]: timed out Jan 24 09:57:26 mcintosh dhcpcd[444]: timed out Jan 24 09:57:26 mcintosh sh[398]: dhcpcd exited Jan 24 09:57:26 mcintosh dhcpcd[444]: dhcpcd exited Jan 24 09:57:26 mcintosh sh[398]: ifup: failed to bring up eth0 Jan 24 09:57:26 mcintosh systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jan 24 09:57:26 mcintosh systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jan 24 09:57:26 mcintosh volumio[818]: info: Plugin folders cleanup Jan 24 09:57:26 mcintosh volumio[818]: info: Scanning into folder /volumio/app/plugins/ Jan 24 09:57:26 mcintosh volumio[818]: info: Scanning category audio_interface Jan 24 09:57:26 mcintosh volumio[818]: info: Scanning category miscellanea Jan 24 09:57:26 mcintosh volumio[818]: info: Scanning category music_service Jan 24 09:57:26 mcintosh volumio[818]: info: Scanning category plugins.json Jan 24 09:57:26 mcintosh volumio[818]: info: Scanning category system_controller Jan 24 09:57:26 mcintosh volumio[818]: info: Scanning category user_interface Jan 24 09:57:26 mcintosh volumio[818]: info: Scanning into folder /data/plugins/ Jan 24 09:57:26 mcintosh volumio[818]: info: Plugin folders cleanup completed Jan 24 09:57:26 mcintosh volumio[818]: info: ------------------------------------------- Jan 24 09:57:26 mcintosh volumio[818]: info: ----- Core plugins startup ---- Jan 24 09:57:26 mcintosh volumio[818]: info: ------------------------------------------- Jan 24 09:57:26 mcintosh volumio[818]: info: Loading plugins from folder /volumio/app/plugins/ Jan 24 09:57:26 mcintosh volumio[818]: info: Adding plugin upnp to MyMusic Plugins Jan 24 09:57:26 mcintosh volumio[818]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 24 09:57:26 mcintosh volumio[818]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 24 09:57:26 mcintosh volumio[818]: info: Loading plugins from folder /data/plugins/ Jan 24 09:57:26 mcintosh volumio[818]: info: Loading plugin "system"... Jan 24 09:57:26 mcintosh volumio[818]: info: Loading plugin "appearance"... Jan 24 09:57:28 mcintosh volumio[818]: info: Loading plugin "network"... Jan 24 09:57:29 mcintosh volumio[818]: info: Refreshing Cached IP Addresses Jan 24 09:57:29 mcintosh sudo[856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 24 09:57:29 mcintosh sudo[856]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:29 mcintosh volumio[818]: info: Loading plugin "services"... Jan 24 09:57:29 mcintosh sudo[858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 24 09:57:29 mcintosh sudo[858]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:29 mcintosh volumio[818]: info: Loading plugin "alsa_controller"... Jan 24 09:57:29 mcintosh volumio[818]: info: Loading plugin "wizard"... Jan 24 09:57:29 mcintosh volumio[818]: info: Loading plugin "volumio_command_line_client"... Jan 24 09:57:29 mcintosh volumio[818]: info: Loading plugin "upnp"... Jan 24 09:57:29 mcintosh volumio[818]: info: [1643018249350] Starting Upmpd Daemon Jan 24 09:57:29 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 24 09:57:29 mcintosh volumio[818]: info: Loading plugin "my_music"... Jan 24 09:57:29 mcintosh volumio[818]: info: Loading plugin "mpd"... Jan 24 09:57:30 mcintosh volumio[818]: info: Creating MPD Configuration file Jan 24 09:57:30 mcintosh volumio[818]: info: Loading plugin "upnp_browser"... Jan 24 09:57:30 mcintosh sudo[856]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:30 mcintosh sudo[858]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:30 mcintosh sudo[861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 24 09:57:30 mcintosh sudo[861]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:30 mcintosh sudo[866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 24 09:57:30 mcintosh sudo[866]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:30 mcintosh sudo[868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 24 09:57:30 mcintosh sudo[868]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:30 mcintosh sudo[866]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:30 mcintosh systemd[1]: Stopping Music Player Daemon... Jan 24 09:57:30 mcintosh volumio-remote-updater[487]: [2022-01-24 09:57:30] [connect] Successful connection Jan 24 09:57:30 mcintosh systemd[1]: mpd.service: Succeeded. Jan 24 09:57:30 mcintosh systemd[1]: Stopped Music Player Daemon. Jan 24 09:57:31 mcintosh systemd[1]: Starting Music Player Daemon... Jan 24 09:57:31 mcintosh volumio[818]: info: Loading plugin "networkfs"... Jan 24 09:57:31 mcintosh sudo[861]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:32 mcintosh volumio[818]: info: Starting Udev Watcher for removable devices Jan 24 09:57:32 mcintosh volumio[818]: info: Ignoring mount for partition: boot Jan 24 09:57:32 mcintosh volumio[818]: info: Ignoring mount for partition: volumio Jan 24 09:57:32 mcintosh volumio[818]: info: Ignoring mount for partition: volumio_data Jan 24 09:57:32 mcintosh volumio[818]: info: Mounting Device Mcintosh_Storage Jan 24 09:57:33 mcintosh sudo[880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Mcintosh_Storage -o noatime,dmask=0000,fmask=0000 Jan 24 09:57:33 mcintosh sudo[880]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:33 mcintosh systemd[1]: systemd-hostnamed.service: Succeeded. Jan 24 09:57:33 mcintosh mpd[874]: Jan 24 09:57 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 24 09:57:34 mcintosh mpd[874]: Jan 24 09:57 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 24 09:57:34 mcintosh mpd[874]: Jan 24 09:57 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 24 09:57:34 mcintosh kernel: fuse: init (API version 7.32) Jan 24 09:57:34 mcintosh systemd[1]: Mounting FUSE Control File System... Jan 24 09:57:34 mcintosh systemd[1]: Mounted FUSE Control File System. Jan 24 09:57:35 mcintosh systemd[1]: Started Music Player Daemon. Jan 24 09:57:35 mcintosh sudo[868]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:35 mcintosh ntfs-3g[891]: Version 2017.3.23AR.3 integrated FUSE 28 Jan 24 09:57:35 mcintosh ntfs-3g[891]: Mounted /dev/sda1 (Read-Write, label "Mcintosh Storage", NTFS 3.1) Jan 24 09:57:35 mcintosh ntfs-3g[891]: Cmdline options: rw,noatime,dmask=0000,fmask=0000 Jan 24 09:57:35 mcintosh ntfs-3g[891]: Mount options: allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Jan 24 09:57:35 mcintosh ntfs-3g[891]: Global ownership and permissions enforced, configuration type 7 Jan 24 09:57:35 mcintosh sudo[880]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:35 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 24 09:57:35 mcintosh volumio[818]: info: Loading plugin "alarm-clock"... Jan 24 09:57:36 mcintosh volumio[818]: info: Loading plugin "airplay_emulation"... Jan 24 09:57:36 mcintosh volumio[818]: info: Starting Shairport Sync Jan 24 09:57:36 mcintosh volumio[818]: info: Loading plugin "last_100"... Jan 24 09:57:36 mcintosh volumio[818]: info: Loading plugin "webradio"... Jan 24 09:57:36 mcintosh volumio[818]: info: Loading plugin "i2s_dacs"... Jan 24 09:57:36 mcintosh volumio[818]: info: I2S DAC not set, start Auto-detection Jan 24 09:57:36 mcintosh volumio[818]: info: Loading plugin "volumiodiscovery"... Jan 24 09:57:36 mcintosh volumio[818]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 24 09:57:36 mcintosh volumio[818]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 24 09:57:36 mcintosh volumio[818]: *** WARNING *** For more information see Jan 24 09:57:36 mcintosh volumio[818]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 24 09:57:36 mcintosh node[818]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 24 09:57:36 mcintosh volumio[818]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 24 09:57:36 mcintosh volumio[818]: *** WARNING *** For more information see Jan 24 09:57:36 mcintosh node[818]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 24 09:57:36 mcintosh node[818]: *** WARNING *** For more information see Jan 24 09:57:36 mcintosh node[818]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 24 09:57:36 mcintosh node[818]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 24 09:57:36 mcintosh node[818]: *** WARNING *** For more information see Jan 24 09:57:36 mcintosh volumio[818]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 24 09:57:36 mcintosh volumio[818]: info: Discovery: Started advertising with name: McIntosh Jan 24 09:57:36 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 24 09:57:36 mcintosh volumio[818]: info: Loading plugin "outputs"... Jan 24 09:57:36 mcintosh volumio[818]: info: Loading plugin "albumart"... Jan 24 09:57:37 mcintosh volumio[818]: info: Plugin example_plugin is not enabled Jan 24 09:57:37 mcintosh volumio[818]: info: Loading plugin "inputs"... Jan 24 09:57:37 mcintosh volumio[818]: info: Loading plugin "updater_comm"... Jan 24 09:57:37 mcintosh volumio[818]: info: Plugin mpdemulation is not enabled Jan 24 09:57:37 mcintosh volumio[818]: info: Loading plugin "rest_api"... Jan 24 09:57:37 mcintosh volumio[818]: info: Loading plugin "websocket"... Jan 24 09:57:37 mcintosh volumio[818]: info: Loading i18n strings for locale en Jan 24 09:57:37 mcintosh volumio[818]: Updating browse sources language Jan 24 09:57:37 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 24 09:57:37 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 24 09:57:37 mcintosh volumio[818]: info: CoreCommandRouter::initPlayerControls Jan 24 09:57:37 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:37 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:37 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:37 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:37 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:37 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:37 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:37 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:37 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 24 09:57:37 mcintosh volumio[818]: Express server listening on port 3000 Jan 24 09:57:37 mcintosh volumio[818]: [Metrics] WebUI: 15s 673.18ms Jan 24 09:57:37 mcintosh volumio[818]: info: CoreStateMachine::resetVolumioState Jan 24 09:57:37 mcintosh volumio[818]: info: CoreStateMachine::getcurrentVolume Jan 24 09:57:37 mcintosh volumio[818]: info: CoreCommandRouter::volumioRetrievevolume Jan 24 09:57:37 mcintosh volumio[818]: Forking 3 albumart workers Jan 24 09:57:37 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 24 09:57:37 mcintosh volumio[818]: info: Cannot read play queue from file Jan 24 09:57:38 mcintosh volumio[818]: info: MPD Permissions set Jan 24 09:57:38 mcintosh volumio[818]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 24 09:57:38 mcintosh volumio[818]: info: MPD running with PID874 Jan 24 09:57:38 mcintosh volumio[818]: ,establishing connection Jan 24 09:57:38 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:38 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:38 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:38 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:38 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:38 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:38 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:38 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:38 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 24 09:57:38 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:38 mcintosh volumio[818]: error: updateQueue error: null Jan 24 09:57:38 mcintosh volumio[818]: info: CoreStateMachine::setRepeat null single undefined Jan 24 09:57:38 mcintosh volumio[818]: info: CoreStateMachine::pushState Jan 24 09:57:38 mcintosh volumio[818]: info: CorePlayQueue::getTrack 0 Jan 24 09:57:38 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 24 09:57:38 mcintosh volumio[818]: info: CoreCommandRouter::volumioPushState Jan 24 09:57:38 mcintosh volumio[818]: info: CoreStateMachine::setRandom null Jan 24 09:57:38 mcintosh volumio[818]: info: CoreStateMachine::pushState Jan 24 09:57:38 mcintosh volumio[818]: info: CorePlayQueue::getTrack 0 Jan 24 09:57:38 mcintosh volumio[818]: info: CoreCommandRouter::volumioPushState Jan 24 09:57:38 mcintosh volumio[818]: info: Setting Device type: Raspberry PI Jan 24 09:57:38 mcintosh volumio[818]: info: VolumeController:: Volume=undefined Mute =false Jan 24 09:57:38 mcintosh volumio[818]: info: CoreStateMachine::pushState Jan 24 09:57:38 mcintosh volumio[818]: info: CorePlayQueue::getTrack 0 Jan 24 09:57:38 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 24 09:57:38 mcintosh volumio[818]: info: CoreCommandRouter::volumioPushState Jan 24 09:57:38 mcintosh volumio[818]: info: CoreStateMachine::updateTrackBlock Jan 24 09:57:38 mcintosh volumio[818]: info: CorePlayQueue::getTrackBlock Jan 24 09:57:38 mcintosh volumio[818]: info: CoreCommandRouter::volumioRetrievevolume Jan 24 09:57:38 mcintosh volumio[818]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 24 09:57:38 mcintosh volumio[818]: info: Completed loading Core Plugins Jan 24 09:57:38 mcintosh volumio[818]: info: Preparing to generate the ALSA configuration file Jan 24 09:57:39 mcintosh volumio[818]: info: VolumeController:: Volume=undefined Mute =false Jan 24 09:57:39 mcintosh volumio[818]: info: CoreStateMachine::pushState Jan 24 09:57:39 mcintosh volumio[818]: info: CorePlayQueue::getTrack 0 Jan 24 09:57:39 mcintosh volumio[818]: info: CoreCommandRouter::volumioPushState Jan 24 09:57:39 mcintosh volumio[818]: info: Asound.conf file unchanged, so no further update is needed Jan 24 09:57:39 mcintosh volumio[818]: info: Output device has changed, restarting MPD Jan 24 09:57:39 mcintosh volumio[818]: info: Output device has changed, restarting Shairport Sync Jan 24 09:57:39 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:39 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 24 09:57:39 mcintosh sudo[942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 24 09:57:39 mcintosh sudo[942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:39 mcintosh sudo[942]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:39 mcintosh volumio[818]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 24 09:57:39 mcintosh volumio[818]: info: ___________ START PLUGINS ___________ Jan 24 09:57:39 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 24 09:57:39 mcintosh volumio[818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 24 09:57:39 mcintosh volumio[818]: info: [1643018259316] CoreMusicLibrary::Adding element Media Servers Jan 24 09:57:39 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 24 09:57:39 mcintosh volumio[818]: Cannot find translation for sourceMedia Servers Jan 24 09:57:39 mcintosh volumio[818]: Starting albumart workers Jan 24 09:57:39 mcintosh volumio[818]: Starting albumart workers Jan 24 09:57:40 mcintosh volumio[818]: Starting albumart workers Jan 24 09:57:40 mcintosh sudo[943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 24 09:57:40 mcintosh sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:40 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:40 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 24 09:57:40 mcintosh systemd[1]: Stopping Music Player Daemon... Jan 24 09:57:40 mcintosh systemd[1]: mpd.service: Succeeded. Jan 24 09:57:40 mcintosh systemd[1]: Stopped Music Player Daemon. Jan 24 09:57:40 mcintosh systemd[1]: Starting Music Player Daemon... Jan 24 09:57:41 mcintosh volumio[818]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 24 09:57:41 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:41 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 24 09:57:41 mcintosh volumio[818]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 24 09:57:41 mcintosh volumio[818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 24 09:57:41 mcintosh volumio[818]: info: [1643018261165] CoreMusicLibrary::Adding element Last_100 Jan 24 09:57:41 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 24 09:57:41 mcintosh volumio[818]: Cannot find translation for sourceMedia Servers Jan 24 09:57:41 mcintosh volumio[818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 24 09:57:41 mcintosh volumio[818]: info: [1643018261182] CoreMusicLibrary::Adding element Webradio Jan 24 09:57:41 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 24 09:57:41 mcintosh volumio[818]: Cannot find translation for sourceMedia Servers Jan 24 09:57:41 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 24 09:57:41 mcintosh volumio[818]: info: Volumio Calling Home Jan 24 09:57:41 mcintosh volumio[818]: info: Completed starting Core Plugins Jan 24 09:57:41 mcintosh volumio[818]: info: ------------------------------------------- Jan 24 09:57:41 mcintosh volumio[818]: info: ----- MyVolumio plugins startup ---- Jan 24 09:57:41 mcintosh volumio[818]: info: ------------------------------------------- Jan 24 09:57:41 mcintosh volumio[818]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 24 09:57:41 mcintosh volumio[818]: error: MPD error: Error: write EPIPE Jan 24 09:57:42 mcintosh volumio[818]: info: MPD Permissions set Jan 24 09:57:42 mcintosh volumio[818]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Jan 24 09:57:42 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:42 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:42 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:42 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:42 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:42 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:42 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:42 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:42 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 24 09:57:42 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:57:42 mcintosh volumio[818]: info: Starting Shairport Sync Jan 24 09:57:42 mcintosh volumio[818]: info: Starting Shairport Sync Jan 24 09:57:42 mcintosh volumio[818]: info: Starting Shairport Sync Jan 24 09:57:43 mcintosh sudo[986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 24 09:57:43 mcintosh sudo[994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 24 09:57:43 mcintosh sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:43 mcintosh sudo[988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 24 09:57:43 mcintosh sudo[998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 24 09:57:43 mcintosh sudo[986]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:43 mcintosh sudo[998]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:43 mcintosh sudo[988]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:43 mcintosh sudo[996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 24 09:57:43 mcintosh sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:43 mcintosh sudo[986]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:43 mcintosh sudo[988]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:43 mcintosh sudo[1000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 24 09:57:43 mcintosh sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:43 mcintosh systemd[1]: Started UPnP Renderer front-end to MPD. Jan 24 09:57:43 mcintosh systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 24 09:57:43 mcintosh systemd[1]: shairport-sync.service: Succeeded. Jan 24 09:57:43 mcintosh systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 24 09:57:43 mcintosh sudo[1000]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:43 mcintosh systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 24 09:57:43 mcintosh sudo[994]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:43 mcintosh sudo[998]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:43 mcintosh sudo[996]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:43 mcintosh volumio[818]: info: Shairport-Sync Started Jan 24 09:57:43 mcintosh volumio[818]: Error adding Membership: Error: addMembership EINVAL Jan 24 09:57:43 mcintosh volumio[818]: info: Shairport-Sync Started Jan 24 09:57:43 mcintosh volumio[818]: info: Upmpdcli Daemon Started Jan 24 09:57:43 mcintosh volumio[818]: info: Shairport-Sync Started Jan 24 09:57:43 mcintosh mpd[975]: Jan 24 09:57 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 24 09:57:44 mcintosh volumio[818]: info: Discovery: adding 04762a42-1f26-4716-83f3-0be25727a555 Jan 24 09:57:44 mcintosh volumio[818]: info: mDNS: Found device McIntosh Jan 24 09:57:44 mcintosh volumio[818]: info: CoreCommandRouter::volumioGetState Jan 24 09:57:44 mcintosh volumio[818]: info: CorePlayQueue::getTrack 0 Jan 24 09:57:44 mcintosh mpd[975]: Jan 24 09:57 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 24 09:57:44 mcintosh mpd[975]: Jan 24 09:57 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 24 09:57:45 mcintosh systemd[1]: Started Music Player Daemon. Jan 24 09:57:45 mcintosh sudo[943]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:45 mcintosh volumio[818]: Upnp client error: Error: This socket has been ended by the other party Jan 24 09:57:45 mcintosh sudo[1012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 24 09:57:45 mcintosh sudo[1012]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:45 mcintosh sudo[1014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 24 09:57:45 mcintosh sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:45 mcintosh sudo[1012]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:45 mcintosh volumio[818]: info: CoreCommandRouter::volumioGetState Jan 24 09:57:45 mcintosh volumio[818]: info: CorePlayQueue::getTrack 0 Jan 24 09:57:45 mcintosh sudo[1014]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:45 mcintosh volumio[818]: error: updateQueue error: null Jan 24 09:57:45 mcintosh volumio[818]: verbose: New Socket.io Connection to 10.0.0.29 from 10.0.0.102 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Total Clients: 2 Jan 24 09:57:45 mcintosh volumio-remote-updater[487]: [2022-01-24 09:57:45] [connect] Successful connection Jan 24 09:57:45 mcintosh volumio-remote-updater[487]: [2022-01-24 09:57:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1643018265 101 Jan 24 09:57:45 mcintosh volumio[818]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Jan 24 09:57:46 mcintosh volumio[818]: info: CoreCommandRouter::volumioGetState Jan 24 09:57:46 mcintosh volumio[818]: info: CorePlayQueue::getTrack 0 Jan 24 09:57:46 mcintosh volumio[818]: info: CoreCommandRouter::volumioGetQueue Jan 24 09:57:46 mcintosh volumio[818]: info: CoreStateMachine::getQueue Jan 24 09:57:46 mcintosh volumio[818]: info: CorePlayQueue::getQueue Jan 24 09:57:46 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 24 09:57:46 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 24 09:57:46 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 24 09:57:46 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 24 09:57:46 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 24 09:57:46 mcintosh sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 24 09:57:46 mcintosh sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:46 mcintosh sudo[1024]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:46 mcintosh sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 24 09:57:46 mcintosh sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:57:46 mcintosh sudo[1026]: pam_unix(sudo:session): session closed for user root Jan 24 09:57:46 mcintosh volumio[818]: verbose: New Socket.io Connection to 10.0.0.29 from 10.0.0.102 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Total Clients: 3 Jan 24 09:57:46 mcintosh volumio[818]: info: CoreCommandRouter::volumioGetState Jan 24 09:57:46 mcintosh volumio[818]: info: CorePlayQueue::getTrack 0 Jan 24 09:57:46 mcintosh volumio[818]: info: CoreCommandRouter::volumioGetQueue Jan 24 09:57:46 mcintosh volumio[818]: info: CoreStateMachine::getQueue Jan 24 09:57:46 mcintosh volumio[818]: info: CorePlayQueue::getQueue Jan 24 09:57:46 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 24 09:57:46 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 24 09:57:46 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 24 09:57:46 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 24 09:57:46 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 24 09:57:46 mcintosh volumio[818]: info: Volumio called home Jan 24 09:57:47 mcintosh volumio[818]: error: updateQueue error: null Jan 24 09:57:48 mcintosh volumio[1007]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 24 09:57:49 mcintosh volumio[818]: info: CoreCommandRouter::volumioGetVisibleSources Jan 24 09:57:49 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 24 09:57:49 mcintosh volumio[818]: info: Listing playlists Jan 24 09:57:53 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 24 09:57:55 mcintosh volumio[1007]: ............................++++ Jan 24 09:57:57 mcintosh volumio[1007]: ...++++ Jan 24 09:57:57 mcintosh volumio[1007]: e is 65537 (0x010001) Jan 24 09:57:57 mcintosh volumio[1007]: writing RSA key Jan 24 09:58:01 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 24 09:58:01 mcintosh volumio[818]: info: CURURI: genres:// Jan 24 09:58:01 mcintosh volumio[818]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jan 24 09:58:01 mcintosh volumio[818]: error: MyVolumio Plugin failed to start in a timely fashion Jan 24 09:58:01 mcintosh volumio[818]: info: BOOT COMPLETED Jan 24 09:58:01 mcintosh volumio[818]: [Metrics] CommandRouter: 37s 972.12ms Jan 24 09:58:01 mcintosh volumio[818]: info: CoreCommandRouter::volumiosetStartupVolume Jan 24 09:58:01 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 24 09:58:01 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 24 09:58:01 mcintosh volumio[818]: info: CoreCommandRouter::Close All Modals sent Jan 24 09:58:01 mcintosh volumio[818]: info: CoreCommandRouter::Close All Modals sent Jan 24 09:58:02 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 24 09:58:02 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 24 09:58:02 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 24 09:58:03 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 24 09:58:03 mcintosh volumio[818]: info: CURURI: genres://Acoustic Jan 24 09:58:05 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 24 09:58:05 mcintosh ntpd[659]: Soliciting pool server 23.159.16.194 Jan 24 09:58:05 mcintosh volumio[818]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 24 09:58:05 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 24 09:58:05 mcintosh volumio[818]: info: Received Get System Version Jan 24 09:58:05 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 24 09:58:06 mcintosh volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 24 09:58:06 mcintosh volumio[818]: info: CURURI: genres://Acoustic/Kina%20Grannis/Stairwells Jan 24 09:58:06 mcintosh ntpd[659]: Soliciting pool server 23.142.248.9 Jan 24 09:58:06 mcintosh ntpd[659]: Soliciting pool server 23.186.168.127 Jan 24 09:58:06 mcintosh volumio[818]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 24 09:58:06 mcintosh volumio[818]: Error: certificate is not yet valid Jan 24 09:58:06 mcintosh volumio[818]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jan 24 09:58:06 mcintosh volumio[818]: at TLSSocket.emit (events.js:315:20) Jan 24 09:58:06 mcintosh volumio[818]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jan 24 09:58:06 mcintosh volumio[818]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jan 24 09:58:06 mcintosh volumio[818]: code: 'CERT_NOT_YET_VALID' Jan 24 09:58:06 mcintosh volumio[818]: } Jan 24 09:58:06 mcintosh volumio[818]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 24 09:58:07 mcintosh ntpd[659]: Soliciting pool server 23.157.160.168 Jan 24 09:58:07 mcintosh ntpd[659]: Soliciting pool server 74.6.168.72 Jan 24 09:58:08 mcintosh ntpd[659]: Soliciting pool server 69.89.207.99 Jan 24 09:58:08 mcintosh ntpd[659]: Soliciting pool server 207.58.172.126 Jan 24 09:58:08 mcintosh sudo[1079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-01-24 09:57 Jan 24 09:58:08 mcintosh sudo[1079]: 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="e5f8795c07ba36ff2b02d7ba0a21c7a0f29b897e" VOLUMIO_FE_VERSION="473d8facbed5f9754db84a81b093baace7aa4b64" VOLUMIO_FE3_VERSION="934cddf6d46478e7a53a8d3664cd346f4de7cb34" VOLUMIO_BE_VERSION="359b2717b79cfc72b7d1c918d0fc2885a93bca70" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 24 Jan 2022 10:51:57 AM CET" VOLUMIO_VERSION="3.198" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="74e6a9ad035e912732893c8d6b060b17"