-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Sat 2024-11-30 17:41:25 UTC. -- Nov 30 17:40:39 volumio systemd-timedated[1022]: Changed local time to Sat Nov 30 17:40:39 2024 Nov 30 17:40:39 volumio sudo[1020]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:39 volumio volumio-time-update[615]: volumio-time-update-util: System time updated successfully. Nov 30 17:40:39 volumio systemd[1]: Started Volumio Time Update Utility. Nov 30 17:40:39 volumio kernel: usb 1-1.3: new high-speed USB device number 20 using dwc_otg Nov 30 17:40:39 volumio wireless.js[636]: trying... Nov 30 17:40:39 volumio sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 17:40:39 volumio sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:39 volumio sudo[1024]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:39 volumio kernel: hwmon hwmon1: Voltage normalised Nov 30 17:40:40 volumio wireless.js[636]: trying... Nov 30 17:40:40 volumio sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 17:40:40 volumio sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:40 volumio sudo[1028]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:40 volumio volumio-remote-updater[613]: [2024-11-30 17:40:40] [info] asio async_connect error: system:111 (Connection refused) Nov 30 17:40:40 volumio volumio-remote-updater[613]: [2024-11-30 17:40:40] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 30 17:40:40 volumio volumio-remote-updater[613]: [2024-11-30 17:40:40] [error] handle_connect error: Underlying Transport Error Nov 30 17:40:41 volumio wireless.js[636]: trying... Nov 30 17:40:41 volumio sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 17:40:41 volumio sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:41 volumio sudo[1031]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:42 volumio wireless.js[636]: trying... Nov 30 17:40:42 volumio sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 17:40:42 volumio sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:42 volumio sudo[1034]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:43 volumio wireless.js[636]: trying... Nov 30 17:40:43 volumio sudo[1037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 17:40:43 volumio sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:43 volumio sudo[1037]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:44 volumio wireless.js[636]: trying... Nov 30 17:40:44 volumio sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 17:40:44 volumio sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:44 volumio sudo[1040]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:45 volumio wireless.js[636]: trying... Nov 30 17:40:45 volumio sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 17:40:45 volumio sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:45 volumio sudo[1046]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:46 volumio kernel: hwmon hwmon1: Undervoltage detected! Nov 30 17:40:46 volumio wireless.js[636]: trying... Nov 30 17:40:46 volumio sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 17:40:46 volumio sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:46 volumio sudo[1050]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:46 volumio wpa_supplicant[908]: wlan0: Failed to initiate sched scan Nov 30 17:40:47 volumio wireless.js[636]: trying... Nov 30 17:40:47 volumio sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 17:40:47 volumio sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:47 volumio sudo[1053]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:47 volumio kernel: usb 1-1-port3: Cannot enable. Maybe the USB cable is bad? Nov 30 17:40:48 volumio wireless.js[636]: trying... Nov 30 17:40:48 volumio sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 17:40:48 volumio sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:48 volumio sudo[1056]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:49 volumio wireless.js[636]: trying... Nov 30 17:40:49 volumio sudo[1059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 17:40:49 volumio sudo[1059]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:49 volumio sudo[1059]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:50 volumio wireless.js[636]: trying... Nov 30 17:40:50 volumio sudo[1062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 17:40:50 volumio sudo[1062]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:50 volumio kernel: hwmon hwmon1: Voltage normalised Nov 30 17:40:50 volumio sudo[1062]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:50 volumio volumio-remote-updater[613]: [2024-11-30 17:40:50] [info] asio async_connect error: system:111 (Connection refused) Nov 30 17:40:50 volumio volumio-remote-updater[613]: [2024-11-30 17:40:50] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 30 17:40:50 volumio volumio-remote-updater[613]: [2024-11-30 17:40:50] [error] handle_connect error: Underlying Transport Error Nov 30 17:40:51 volumio wireless.js[636]: trying... Nov 30 17:40:51 volumio sudo[1066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 17:40:51 volumio sudo[1066]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:51 volumio sudo[1066]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:51 volumio kernel: usb 1-1.3: new high-speed USB device number 24 using dwc_otg Nov 30 17:40:51 volumio wpa_supplicant[908]: wlan0: Failed to initiate sched scan Nov 30 17:40:52 volumio wireless.js[636]: trying... Nov 30 17:40:52 volumio sudo[1069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 17:40:52 volumio sudo[1069]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:52 volumio sudo[1069]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:52 volumio kernel: usb 1-1-port3: Cannot enable. Maybe the USB cable is bad? Nov 30 17:40:52 volumio kernel: usb 1-1.3: new high-speed USB device number 25 using dwc_otg Nov 30 17:40:53 volumio wireless.js[636]: trying... Nov 30 17:40:53 volumio sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 17:40:53 volumio sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:53 volumio sudo[1072]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:53 volumio systemd[1]: systemd-fsckd.service: Succeeded. Nov 30 17:40:54 volumio wireless.js[636]: trying... Nov 30 17:40:54 volumio sudo[1075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 17:40:54 volumio sudo[1075]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:54 volumio sudo[1075]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:55 volumio wireless.js[636]: Overtime, starting plan B Nov 30 17:40:55 volumio wireless.js[636]: STARTING HOTSPOT Nov 30 17:40:55 volumio wpa_supplicant[908]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Nov 30 17:40:55 volumio wpa_supplicant[908]: nl80211: Failed to set IPv4 unicast in multicast filter Nov 30 17:40:55 volumio wpa_supplicant[908]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Nov 30 17:40:55 volumio wpa_supplicant[908]: nl80211: Failed to set IPv4 unicast in multicast filter Nov 30 17:40:55 volumio wpa_supplicant[908]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Nov 30 17:40:55 volumio wpa_supplicant[908]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Nov 30 17:40:55 volumio wpa_supplicant[908]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Nov 30 17:40:55 volumio kernel: usb 1-1.3: new high-speed USB device number 26 using dwc_otg Nov 30 17:40:55 volumio wpa_supplicant[908]: wlan0: CTRL-EVENT-TERMINATING Nov 30 17:40:56 volumio kernel: usb 1-1-port3: Cannot enable. Maybe the USB cable is bad? Nov 30 17:40:58 volumio sudo[1087]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 30 17:40:58 volumio sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:58 volumio sudo[1087]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:58 volumio sudo[1089]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 30 17:40:58 volumio sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:40:58 volumio sudo[1089]: pam_unix(sudo:session): session closed for user root Nov 30 17:40:58 volumio wireless.js[636]: Hotspot is disabled, not starting it Nov 30 17:40:58 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 30 17:40:58 volumio systemd[1]: Started Wireless Services. Nov 30 17:40:58 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 30 17:40:58 volumio systemd[1]: Started Volumio Backend Module. Nov 30 17:40:58 volumio systemd[1]: Started Volumio Cpu Tweaker. Nov 30 17:40:58 volumio systemd[1]: Reached target Multi-User System. Nov 30 17:40:58 volumio systemd[1]: Reached target Graphical Interface. Nov 30 17:40:58 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 30 17:40:58 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 30 17:40:58 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 30 17:40:58 volumio systemd[1]: Startup finished in 15.902s (kernel) + 39.900s (userspace) = 55.802s. Nov 30 17:40:58 volumio volumio-cpu-tweak[1096]: Setting RT Priority for mpd Nov 30 17:40:58 volumio volumio-cpu-tweak[1096]: Setting MPD Affinity Nov 30 17:40:58 volumio volumio-cpu-tweak[1096]: pid 834's current affinity mask: f Nov 30 17:40:58 volumio volumio-cpu-tweak[1096]: pid 834's new affinity mask: 3 Nov 30 17:40:58 volumio volumio-cpu-tweak[1096]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Nov 30 17:40:58 volumio volumio-cpu-tweak[1096]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Nov 30 17:40:58 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Nov 30 17:41:00 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Nov 30 17:41:00 volumio kernel: hwmon hwmon1: Undervoltage detected! Nov 30 17:41:00 volumio volumio-remote-updater[613]: [2024-11-30 17:41:00] [info] asio async_connect error: system:111 (Connection refused) Nov 30 17:41:00 volumio volumio-remote-updater[613]: [2024-11-30 17:41:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 30 17:41:00 volumio volumio-remote-updater[613]: [2024-11-30 17:41:00] [error] handle_connect error: Underlying Transport Error Nov 30 17:41:01 volumio kernel: usb 1-1-port3: Cannot enable. Maybe the USB cable is bad? Nov 30 17:41:01 volumio kernel: usb 1-1.3: new high-speed USB device number 29 using dwc_otg Nov 30 17:41:02 volumio kernel: usb 1-1-port3: Cannot enable. Maybe the USB cable is bad? Nov 30 17:41:02 volumio kernel: usb 1-1-port3: attempt power cycle Nov 30 17:41:02 volumio volumio[1095]: info: ------------------------------------------- Nov 30 17:41:02 volumio volumio[1095]: info: ----- Volumio3 ---- Nov 30 17:41:02 volumio volumio[1095]: info: ------------------------------------------- Nov 30 17:41:02 volumio volumio[1095]: info: ----- System startup ---- Nov 30 17:41:02 volumio volumio[1095]: info: ------------------------------------------- Nov 30 17:41:04 volumio volumio[1095]: info: MYVOLUMIO Environment detected Nov 30 17:41:04 volumio volumio[1095]: info: Plugin folders cleanup Nov 30 17:41:04 volumio volumio[1095]: info: Scanning into folder /volumio/app/plugins/ Nov 30 17:41:04 volumio volumio[1095]: info: Scanning category audio_interface Nov 30 17:41:04 volumio volumio[1095]: info: Scanning category miscellanea Nov 30 17:41:04 volumio volumio[1095]: info: Scanning category music_service Nov 30 17:41:04 volumio volumio[1095]: info: Scanning category plugins.json Nov 30 17:41:04 volumio volumio[1095]: info: Scanning category system_controller Nov 30 17:41:04 volumio volumio[1095]: info: Scanning category user_interface Nov 30 17:41:04 volumio volumio[1095]: info: Scanning into folder /data/plugins/ Nov 30 17:41:04 volumio volumio[1095]: info: Scanning category audio_interface Nov 30 17:41:04 volumio volumio[1095]: info: Scanning category music_service Nov 30 17:41:04 volumio volumio[1095]: info: Scanning category user_interface Nov 30 17:41:04 volumio volumio[1095]: info: Plugin folders cleanup completed Nov 30 17:41:04 volumio volumio[1095]: info: ------------------------------------------- Nov 30 17:41:04 volumio volumio[1095]: info: ----- Core plugins startup ---- Nov 30 17:41:04 volumio volumio[1095]: info: ------------------------------------------- Nov 30 17:41:04 volumio volumio[1095]: info: Loading plugins from folder /volumio/app/plugins/ Nov 30 17:41:04 volumio volumio[1095]: info: Adding plugin upnp to MyMusic Plugins Nov 30 17:41:04 volumio volumio[1095]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 30 17:41:04 volumio volumio[1095]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 30 17:41:04 volumio volumio[1095]: info: Loading plugins from folder /data/plugins/ Nov 30 17:41:04 volumio volumio[1095]: info: Loading plugin "system"... Nov 30 17:41:04 volumio volumio[1095]: info: Loading plugin "appearance"... Nov 30 17:41:05 volumio kernel: usb 1-1-port3: Cannot enable. Maybe the USB cable is bad? Nov 30 17:41:06 volumio kernel: usb 1-1-port3: Cannot enable. Maybe the USB cable is bad? Nov 30 17:41:06 volumio kernel: usb 1-1-port3: unable to enumerate USB device Nov 30 17:41:07 volumio volumio[1095]: info: Loading plugin "network"... Nov 30 17:41:07 volumio volumio[1095]: info: Refreshing Cached IP Addresses Nov 30 17:41:07 volumio sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 30 17:41:07 volumio sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:07 volumio sudo[1134]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:07 volumio sudo[1136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 30 17:41:07 volumio sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:07 volumio sudo[1136]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:07 volumio volumio[1095]: info: Loading plugin "services"... Nov 30 17:41:07 volumio volumio[1095]: info: Loading plugin "alsa_controller"... Nov 30 17:41:07 volumio sudo[1145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 30 17:41:07 volumio sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:07 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 30 17:41:07 volumio volumio[1095]: info: Loading plugin "wizard"... Nov 30 17:41:07 volumio volumio[1095]: info: Loading plugin "networkfs"... Nov 30 17:41:07 volumio volumio[1095]: info: Starting Udev Watcher for removable devices Nov 30 17:41:07 volumio sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=AZ,password=OnedayPF8!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.52/Flac D1 /mnt/NAS/NAScita_D1 Nov 30 17:41:07 volumio sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:07 volumio sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=AZ,password=OnedayPF8!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.52/Flac D2 /mnt/NAS/NAScita_D2 Nov 30 17:41:07 volumio sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:07 volumio volumio[1095]: info: Ignoring mount for partition: boot Nov 30 17:41:07 volumio volumio[1095]: info: Ignoring mount for partition: volumio Nov 30 17:41:07 volumio volumio[1095]: info: Ignoring mount for partition: volumio_data Nov 30 17:41:07 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 30 17:41:07 volumio volumio[1095]: info: Loading plugin "volumio_command_line_client"... Nov 30 17:41:07 volumio volumio[1095]: info: Loading plugin "upnp"... Nov 30 17:41:07 volumio volumio[1095]: info: [1732988467972] Starting Upmpd Daemon Nov 30 17:41:07 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 30 17:41:07 volumio volumio[1095]: info: Loading plugin "my_music"... Nov 30 17:41:08 volumio volumio[1095]: info: Loading plugin "mpd"... Nov 30 17:41:08 volumio sudo[1145]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:08 volumio kernel: Key type cifs.spnego registered Nov 30 17:41:08 volumio kernel: Key type cifs.idmap registered Nov 30 17:41:08 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Nov 30 17:41:08 volumio kernel: CIFS: Attempting to mount //192.168.1.52/Flac D2 Nov 30 17:41:08 volumio volumio[1095]: info: Loading plugin "upnp_browser"... Nov 30 17:41:09 volumio systemd[1]: systemd-timedated.service: Succeeded. Nov 30 17:41:09 volumio volumio[1095]: info: Loading plugin "alarm-clock"... Nov 30 17:41:10 volumio volumio[1095]: info: Loading plugin "airplay_emulation"... Nov 30 17:41:10 volumio volumio[1095]: info: Starting Shairport Sync Nov 30 17:41:10 volumio volumio[1095]: info: Loading plugin "last_100"... Nov 30 17:41:10 volumio volumio[1095]: info: Loading plugin "webradio"... Nov 30 17:41:10 volumio volumio[1095]: info: Loading plugin "i2s_dacs"... Nov 30 17:41:10 volumio volumio[1095]: info: Loading plugin "volumiodiscovery"... Nov 30 17:41:10 volumio volumio-remote-updater[613]: [2024-11-30 17:41:10] [connect] Successful connection Nov 30 17:41:10 volumio volumio[1095]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 30 17:41:10 volumio volumio[1095]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 30 17:41:10 volumio volumio[1095]: *** WARNING *** For more information see Nov 30 17:41:10 volumio volumio[1095]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 30 17:41:10 volumio volumio[1095]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 30 17:41:10 volumio volumio[1095]: *** WARNING *** For more information see Nov 30 17:41:10 volumio node[1095]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 30 17:41:10 volumio node[1095]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 30 17:41:10 volumio node[1095]: *** WARNING *** For more information see Nov 30 17:41:10 volumio node[1095]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 30 17:41:10 volumio node[1095]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 30 17:41:10 volumio node[1095]: *** WARNING *** For more information see Nov 30 17:41:10 volumio volumio[1095]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 30 17:41:10 volumio volumio[1095]: info: Discovery: Started advertising with name: Volumio Nov 30 17:41:10 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 30 17:41:10 volumio volumio[1095]: info: Plugin now_playing is not enabled Nov 30 17:41:10 volumio volumio[1095]: info: Loading plugin "outputs"... Nov 30 17:41:10 volumio volumio[1095]: info: Loading plugin "albumart"... Nov 30 17:41:10 volumio volumio[1095]: info: Plugin example_plugin is not enabled Nov 30 17:41:10 volumio volumio[1095]: info: Loading plugin "inputs"... Nov 30 17:41:10 volumio volumio[1095]: info: Loading plugin "updater_comm"... Nov 30 17:41:11 volumio volumio[1095]: info: Plugin mpdemulation is not enabled Nov 30 17:41:11 volumio volumio[1095]: info: Loading plugin "rest_api"... Nov 30 17:41:11 volumio volumio[1095]: info: Loading plugin "websocket"... Nov 30 17:41:11 volumio kernel: usb 1-1-port3: Cannot enable. Maybe the USB cable is bad? Nov 30 17:41:11 volumio volumio[1095]: info: Starting Socket.io Server version 2.3.0 Nov 30 17:41:11 volumio volumio[1095]: info: Loading plugin "fusiondsp"... Nov 30 17:41:11 volumio volumio[1095]: Forking 3 albumart workers Nov 30 17:41:11 volumio kernel: usb 1-1.3: new high-speed USB device number 34 using dwc_otg Nov 30 17:41:12 volumio volumio[1095]: info: Applying required configuration parameters for plugin fusiondsp Nov 30 17:41:12 volumio volumio[1095]: info: Loading plugin "radio_paradise"... Nov 30 17:41:12 volumio kernel: usb 1-1-port3: Cannot enable. Maybe the USB cable is bad? Nov 30 17:41:12 volumio kernel: usb 1-1-port3: attempt power cycle Nov 30 17:41:12 volumio volumio[1095]: Starting albumart workers Nov 30 17:41:13 volumio volumio[1095]: Starting albumart workers Nov 30 17:41:13 volumio volumio[1095]: Starting albumart workers Nov 30 17:41:13 volumio volumio[1095]: info: Applying required configuration parameters for plugin radio_paradise Nov 30 17:41:13 volumio volumio[1095]: info: [1732988473205] [RadioParadise] API delay: 5 Nov 30 17:41:13 volumio volumio[1095]: info: Plugin lastfm is not enabled Nov 30 17:41:13 volumio volumio[1095]: info: Loading plugin "randomizer"... Nov 30 17:41:14 volumio volumio[1095]: info: Applying required configuration parameters for plugin randomizer Nov 30 17:41:14 volumio kernel: usb 1-1-port3: Cannot enable. Maybe the USB cable is bad? Nov 30 17:41:14 volumio volumio[1095]: info: Loading i18n strings for locale it Nov 30 17:41:14 volumio sudo[1164]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:14 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 30 17:41:14 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Nov 30 17:41:14 volumio kernel: CIFS: Attempting to mount //192.168.1.52/Flac D1 Nov 30 17:41:14 volumio kernel: usb 1-1.3: new high-speed USB device number 36 using dwc_otg Nov 30 17:41:14 volumio volumio[1095]: Updating browse sources language Nov 30 17:41:14 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 17:41:14 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 30 17:41:14 volumio volumio[1095]: info: CoreCommandRouter::initPlayerControls Nov 30 17:41:14 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:14 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:14 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:14 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:14 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:14 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:14 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:14 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:14 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 30 17:41:14 volumio volumio[1095]: Express server listening on port 3000 Nov 30 17:41:14 volumio volumio[1095]: [Metrics] WebUI: 12s 945.98ms Nov 30 17:41:14 volumio volumio[1095]: info: CoreStateMachine::resetVolumioState Nov 30 17:41:14 volumio volumio[1095]: info: CoreStateMachine::getcurrentVolume Nov 30 17:41:14 volumio volumio[1095]: info: CoreCommandRouter::volumioRetrievevolume Nov 30 17:41:14 volumio kernel: usb 1-1.3: device not accepting address 36, error -71 Nov 30 17:41:14 volumio kernel: usb 1-1-port3: unable to enumerate USB device Nov 30 17:41:15 volumio volumio[1095]: info: Volumio Network Manager: Network status updated: 1 Nov 30 17:41:15 volumio volumio[1095]: info: Cannot mount NAS NAScita_D2 at system boot, trial number 1 ,retrying in 5 seconds Nov 30 17:41:15 volumio volumio-remote-updater[613]: [2024-11-30 17:41:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732988470 101 Nov 30 17:41:15 volumio volumio[1095]: 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: 1 Nov 30 17:41:15 volumio volumio[1095]: info: Reloading queue from file Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 17:41:15 volumio volumio[1095]: info: CoreStateMachine::setRepeat false single undefined Nov 30 17:41:15 volumio volumio[1095]: info: CoreStateMachine::pushState Nov 30 17:41:15 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Nov 30 17:41:15 volumio volumio[1095]: info: CoreStateMachine::setRandom false Nov 30 17:41:15 volumio volumio[1095]: info: CoreStateMachine::pushState Nov 30 17:41:15 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Nov 30 17:41:15 volumio volumio[1095]: info: Setting Device type: Raspberry PI Nov 30 17:41:15 volumio volumio[1095]: info: VolumeController:: Volume=49 Mute =false Nov 30 17:41:15 volumio volumio[1095]: info: CoreStateMachine::pushState Nov 30 17:41:15 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Nov 30 17:41:15 volumio volumio[1095]: info: CoreStateMachine::updateTrackBlock Nov 30 17:41:15 volumio volumio[1095]: info: CorePlayQueue::getTrackBlock Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::volumioRetrievevolume Nov 30 17:41:15 volumio volumio[1095]: info: Discovery: adding d7eec2e7-77c6-4799-bed1-53123b62474b Nov 30 17:41:15 volumio volumio[1095]: info: Discovery: Found device Volumio Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Nov 30 17:41:15 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Nov 30 17:41:15 volumio volumio[1095]: info: VolumeController:: Volume=49 Mute =false Nov 30 17:41:15 volumio volumio[1095]: info: CoreStateMachine::pushState Nov 30 17:41:15 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Nov 30 17:41:15 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.77 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_1_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 2 Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::volumioGetVisibleSources Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Nov 30 17:41:15 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 30 17:41:15 volumio volumio[1095]: info: Received Get System Info Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 30 17:41:15 volumio volumio[1095]: info: Discovery: Getting this device information Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Nov 30 17:41:15 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 30 17:41:15 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Nov 30 17:41:15 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Nov 30 17:41:15 volumio volumio[1095]: info: Listing playlists Nov 30 17:41:16 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 30 17:41:16 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 30 17:41:16 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 30 17:41:16 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 30 17:41:16 volumio kernel: usb 1-1-port3: Cannot enable. Maybe the USB cable is bad? Nov 30 17:41:16 volumio kernel: usb 1-1.3: new high-speed USB device number 38 using dwc_otg Nov 30 17:41:17 volumio kernel: usb 1-1.3: device not accepting address 38, error -71 Nov 30 17:41:17 volumio kernel: usb 1-1-port3: attempt power cycle Nov 30 17:41:18 volumio sudo[1269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 30 17:41:18 volumio sudo[1269]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:18 volumio sudo[1269]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:18 volumio sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 30 17:41:18 volumio sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:18 volumio sudo[1271]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:18 volumio sudo[1275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 30 17:41:18 volumio sudo[1275]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:18 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Nov 30 17:41:18 volumio sudo[1275]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:18 volumio volumio[1095]: info: Upmpdcli Daemon Started Nov 30 17:41:18 volumio volumio[1277]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 30 17:41:18 volumio kernel: usb 1-1-port3: Cannot enable. Maybe the USB cable is bad? Nov 30 17:41:19 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 30 17:41:19 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 30 17:41:19 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 30 17:41:19 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 30 17:41:19 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 30 17:41:19 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 30 17:41:19 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 30 17:41:19 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 30 17:41:19 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 30 17:41:19 volumio volumio[1095]: info: CoreCommandRouter::volumioGetBrowseSources Nov 30 17:41:19 volumio volumio[1095]: info: CoreCommandRouter::volumioGetBrowseSources Nov 30 17:41:19 volumio volumio[1095]: info: CoreCommandRouter::volumioGetBrowseSources Nov 30 17:41:19 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 17:41:19 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 30 17:41:20 volumio sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=AZ,password=OnedayPF8!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.52/Flac D2 /mnt/NAS/NAScita_D2 Nov 30 17:41:20 volumio sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:20 volumio sudo[1162]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:20 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 30 17:41:20 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Nov 30 17:41:20 volumio kernel: CIFS: Attempting to mount //192.168.1.52/Flac D2 Nov 30 17:41:20 volumio volumio[1095]: info: Cannot mount NAS NAScita_D1 at system boot, trial number 1 ,retrying in 5 seconds Nov 30 17:41:20 volumio volumio[1095]: info: Completed loading Core Plugins Nov 30 17:41:20 volumio volumio[1095]: info: Preparing to generate the ALSA configuration file Nov 30 17:41:20 volumio volumio[1095]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 30 17:41:20 volumio volumio[1095]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 30 17:41:20 volumio volumio[1095]: info: Reading ALSA contributions from plugins. Nov 30 17:41:20 volumio volumio[1095]: info: Asound.conf file unchanged, so no further update is needed Nov 30 17:41:20 volumio volumio[1095]: info: Output device has changed, restarting MPD Nov 30 17:41:20 volumio volumio[1095]: info: Output device has changed, restarting Shairport Sync Nov 30 17:41:20 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:20 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 17:41:20 volumio sudo[1305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 30 17:41:20 volumio sudo[1305]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:20 volumio sudo[1305]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:20 volumio sudo[1307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 30 17:41:20 volumio sudo[1307]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:20 volumio systemd[1]: Stopping Music Player Daemon... Nov 30 17:41:20 volumio volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 30 17:41:20 volumio volumio[1095]: info: ___________ START PLUGINS ___________ Nov 30 17:41:20 volumio volumio[1095]: info: ControllerMpd::onStart: Initializing MPD Nov 30 17:41:20 volumio volumio[1095]: info: Creating MPD Configuration file Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 30 17:41:21 volumio volumio[1095]: info: [1732988481052] CoreMusicLibrary::Adding element Server multimediali Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 17:41:21 volumio sudo[1314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 30 17:41:21 volumio sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:21 volumio sudo[1314]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:21 volumio sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 17:41:21 volumio sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:21 volumio systemd[1]: mpd.service: Succeeded. Nov 30 17:41:21 volumio systemd[1]: Stopped Music Player Daemon. Nov 30 17:41:21 volumio kernel: usb 1-1.3: new high-speed USB device number 41 using dwc_otg Nov 30 17:41:21 volumio volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 17:41:21 volumio systemd[1]: Starting Music Player Daemon... Nov 30 17:41:21 volumio volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 30 17:41:21 volumio volumio[1095]: info: [1732988481362] CoreMusicLibrary::Adding element Last_100 Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 30 17:41:21 volumio volumio[1095]: info: [1732988481369] CoreMusicLibrary::Adding element Webradio Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 30 17:41:21 volumio volumio[1095]: info: Initializing BBC Radios Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 17:41:21 volumio volumio[1095]: info: Loading i18n strings for locale it Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:21 volumio volumio[1095]: info: FusionDsp - mixtype--------------------- Hardware Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 30 17:41:21 volumio volumio[1095]: info: [1732988481558] CoreMusicLibrary::Adding element Radio Paradise Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 17:41:21 volumio volumio[1095]: Cannot find translation for source Radio Paradise Nov 30 17:41:21 volumio sudo[1322]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 30 17:41:21 volumio sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:21 volumio sudo[1322]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 30 17:41:21 volumio volumio[1095]: info: [1732988481651] CoreMusicLibrary::Adding element Randomizer Nov 30 17:41:21 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 17:41:21 volumio volumio[1095]: Cannot find translation for source Radio Paradise Nov 30 17:41:21 volumio volumio[1095]: Cannot find translation for source Randomizer Nov 30 17:41:21 volumio volumio[1095]: info: Volumio Calling Home Nov 30 17:41:21 volumio kernel: usb 1-1.3: device not accepting address 41, error -71 Nov 30 17:41:21 volumio volumio[1095]: info: Preparing to generate the ALSA configuration file Nov 30 17:41:22 volumio volumio[1095]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 30 17:41:22 volumio volumio[1095]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 30 17:41:22 volumio volumio[1095]: info: Reading ALSA contributions from plugins. Nov 30 17:41:22 volumio volumio[1095]: info: MPD Permissions set Nov 30 17:41:22 volumio volumio[1095]: info: MPD Permissions set Nov 30 17:41:22 volumio volumio[1095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 30 17:41:22 volumio volumio[1095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: Volumio called home Nov 30 17:41:22 volumio volumio[1095]: info: Starting Shairport Sync Nov 30 17:41:22 volumio volumio[1095]: info: Starting Shairport Sync Nov 30 17:41:22 volumio volumio[1095]: info: Starting Shairport Sync Nov 30 17:41:22 volumio sudo[1353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 30 17:41:22 volumio sudo[1353]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Nov 30 17:41:22 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Nov 30 17:41:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 30 17:41:22 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 30 17:41:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 30 17:41:22 volumio sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 30 17:41:22 volumio sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:22 volumio sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 30 17:41:22 volumio sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 30 17:41:22 volumio sudo[1353]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:22 volumio volumio[1095]: info: Asound.conf file unchanged, so no further update is needed Nov 30 17:41:22 volumio volumio[1095]: info: Output device has changed, restarting MPD Nov 30 17:41:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 30 17:41:22 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 30 17:41:22 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 30 17:41:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 30 17:41:22 volumio volumio[1095]: info: Output device has changed, restarting Shairport Sync Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 17:41:22 volumio sudo[1363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 30 17:41:22 volumio sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 30 17:41:22 volumio sudo[1358]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:22 volumio sudo[1363]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:22 volumio sudo[1355]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:22 volumio sudo[1366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 30 17:41:22 volumio sudo[1366]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:23 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 30 17:41:23 volumio systemd[1]: mpd.service: Succeeded. Nov 30 17:41:23 volumio systemd[1]: Stopped Music Player Daemon. Nov 30 17:41:23 volumio volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 30 17:41:23 volumio systemd[1]: Starting Music Player Daemon... Nov 30 17:41:23 volumio kernel: usb 1-1-port3: Cannot enable. Maybe the USB cable is bad? Nov 30 17:41:23 volumio kernel: usb 1-1-port3: attempt power cycle Nov 30 17:41:23 volumio volumio[1095]: info: Shairport-Sync Started Nov 30 17:41:23 volumio volumio[1095]: Error adding Membership: Error: addMembership EINVAL Nov 30 17:41:23 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 17:41:23 volumio volumio[1095]: info: MPD Permissions set Nov 30 17:41:23 volumio volumio[1095]: info: Shairport-Sync Started Nov 30 17:41:23 volumio volumio[1095]: info: Shairport-Sync Started Nov 30 17:41:23 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:23 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:23 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:23 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:23 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:23 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:23 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:23 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:23 volumio sudo[1374]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 30 17:41:23 volumio sudo[1374]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:23 volumio sudo[1374]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:23 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 17:41:23 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:23 volumio volumio[1095]: info: Starting Shairport Sync Nov 30 17:41:23 volumio sudo[1382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 30 17:41:23 volumio sudo[1382]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 30 17:41:23 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 30 17:41:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 30 17:41:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 30 17:41:23 volumio volumio[1277]: ..................++++ Nov 30 17:41:23 volumio sudo[1382]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:23 volumio volumio[1095]: info: camilladsp spawned new process with pid 1394, instance 1, run: true Nov 30 17:41:23 volumio volumio[1095]: info: camilladsp service started and running in background, instance 1 Nov 30 17:41:23 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 17:41:23 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Nov 30 17:41:23 volumio volumio[1095]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Nov 30 17:41:23 volumio volumio[1095]: info: FusionDsp loaded Nov 30 17:41:23 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 30 17:41:23 volumio sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 30 17:41:23 volumio sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:23 volumio volumio[1095]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 30 17:41:23 volumio volumio[1095]: info: Adding Signal Path Element [object Object] Nov 30 17:41:23 volumio volumio[1095]: info: Adding fusiondspeq DSP Signal Path Element Nov 30 17:41:23 volumio volumio[1095]: info: FusionDsp - ---- installed callbackRead Nov 30 17:41:23 volumio volumio[1095]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 30 17:41:24 volumio volumio[1095]: info: Shairport-Sync Started Nov 30 17:41:24 volumio systemd[1]: Started FusionDsp Daemon. Nov 30 17:41:24 volumio volumio[1095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Nov 30 17:41:24 volumio sudo[1400]: pam_unix(sudo:session): session closed for user root Nov 30 17:41:24 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Nov 30 17:41:24 volumio volumio[1095]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 30 17:41:24 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Nov 30 17:41:24 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand update Nov 30 17:41:24 volumio volumio[1095]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 30 17:41:24 volumio volumio[1095]: TypeError: Cannot read property 'then' of undefined Nov 30 17:41:24 volumio volumio[1095]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Nov 30 17:41:24 volumio volumio[1095]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1899:15) Nov 30 17:41:24 volumio volumio[1095]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1097:32) Nov 30 17:41:24 volumio volumio[1095]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1085:26) Nov 30 17:41:24 volumio volumio[1095]: at Socket.emit (events.js:315:20) Nov 30 17:41:24 volumio volumio[1095]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 30 17:41:24 volumio volumio[1095]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 30 17:41:24 volumio volumio[1095]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 30 17:41:24 volumio kernel: usb 1-1-port3: Cannot enable. Maybe the USB cable is bad? Nov 30 17:41:25 volumio kernel: usb 1-1.3: new high-speed USB device number 44 using dwc_otg Nov 30 17:41:25 volumio kernel: usb 1-1.3: device descriptor read/8, error -71 Nov 30 17:41:25 volumio kernel: usb 1-1.3: device descriptor read/8, error -71 Nov 30 17:41:25 volumio kernel: usb 1-1-port3: unable to enumerate USB device Nov 30 17:41:25 volumio sudo[1419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-30 17:40 Nov 30 17:41:25 volumio sudo[1419]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 17:41:25 volumio kernel: usb 1-1.3: new high-speed USB device number 45 using dwc_otg PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"