-- Logs begin at Thu 2019-02-14 18:12:02 +08, end at Wed 2024-04-10 22:01:03 +08. -- Apr 10 22:00:00 volumio-jury wireless.js[504]: trying... Apr 10 22:00:00 volumio-jury sudo[690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 10 22:00:00 volumio-jury sudo[690]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:00 volumio-jury sudo[690]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:00 volumio-jury wpa_supplicant[679]: wlan0: SME: Trying to authenticate with 68:13:e2:08:ab:20 (SSID='RT-GPON-AB20' freq=2412 MHz) Apr 10 22:00:00 volumio-jury kernel: wlan0: authenticate with 68:13:e2:08:ab:20 Apr 10 22:00:00 volumio-jury kernel: wlan0: 80 MHz not supported, disabling VHT Apr 10 22:00:00 volumio-jury wpa_supplicant[679]: wlan0: Trying to associate with 68:13:e2:08:ab:20 (SSID='RT-GPON-AB20' freq=2412 MHz) Apr 10 22:00:00 volumio-jury kernel: wlan0: send auth to 68:13:e2:08:ab:20 (try 1/3) Apr 10 22:00:00 volumio-jury kernel: wlan0: authenticated Apr 10 22:00:00 volumio-jury kernel: wlan0: associate with 68:13:e2:08:ab:20 (try 1/3) Apr 10 22:00:01 volumio-jury kernel: wlan0: RX AssocResp from 68:13:e2:08:ab:20 (capab=0x1511 status=0 aid=4) Apr 10 22:00:01 volumio-jury wpa_supplicant[679]: wlan0: Associated with 68:13:e2:08:ab:20 Apr 10 22:00:01 volumio-jury wpa_supplicant[679]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 10 22:00:01 volumio-jury kernel: wlan0: associated Apr 10 22:00:01 volumio-jury wpa_supplicant[679]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=RU Apr 10 22:00:01 volumio-jury kernel: cryptd: max_cpu_qlen set to 1000 Apr 10 22:00:01 volumio-jury wireless.js[504]: trying... Apr 10 22:00:01 volumio-jury sudo[718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 10 22:00:01 volumio-jury sudo[718]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:01 volumio-jury sudo[718]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:01 volumio-jury kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 68:13:e2:08:ab:20 Apr 10 22:00:01 volumio-jury wpa_supplicant[679]: wlan0: WPA: Key negotiation completed with 68:13:e2:08:ab:20 [PTK=CCMP GTK=TKIP] Apr 10 22:00:01 volumio-jury wpa_supplicant[679]: wlan0: CTRL-EVENT-CONNECTED - Connection to 68:13:e2:08:ab:20 completed [id=0 id_str=] Apr 10 22:00:01 volumio-jury dhcpcd[688]: wlan0: carrier acquired Apr 10 22:00:01 volumio-jury dhcpcd[688]: wlan0: connected to Access Point `RT-GPON-AB20' Apr 10 22:00:01 volumio-jury dhcpcd[688]: DUID 00:01:00:01:2d:69:1c:d0:dc:a6:32:14:fd:6c Apr 10 22:00:01 volumio-jury dhcpcd[688]: wlan0: IAID 73:c4:58:60 Apr 10 22:00:01 volumio-jury dhcpcd[688]: wlan0: adding address fe80::522b:73ff:fec4:5860 Apr 10 22:00:01 volumio-jury dhcpcd[688]: ipv6_addaddr1: Permission denied Apr 10 22:00:01 volumio-jury dhcpcd[688]: wlan0: rebinding lease of 192.168.0.17 Apr 10 22:00:01 volumio-jury dhcpcd[688]: wlan0: probing address 192.168.0.17/24 Apr 10 22:00:02 volumio-jury dhcpcd[688]: wlan0: soliciting an IPv6 router Apr 10 22:00:02 volumio-jury wireless.js[504]: trying... Apr 10 22:00:02 volumio-jury sudo[722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 10 22:00:02 volumio-jury sudo[722]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:02 volumio-jury sudo[722]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:03 volumio-jury wireless.js[504]: trying... Apr 10 22:00:03 volumio-jury sudo[725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 10 22:00:03 volumio-jury sudo[725]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:03 volumio-jury sudo[725]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:04 volumio-jury wireless.js[504]: trying... Apr 10 22:00:04 volumio-jury sudo[728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 10 22:00:04 volumio-jury sudo[728]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:04 volumio-jury sudo[728]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:05 volumio-jury wireless.js[504]: trying... Apr 10 22:00:05 volumio-jury sudo[731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 10 22:00:05 volumio-jury sudo[731]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:05 volumio-jury sudo[731]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:05 volumio-jury volumio-remote-updater[501]: [2024-04-10 22:00:05] [info] asio async_connect error: system:111 (Connection refused) Apr 10 22:00:05 volumio-jury volumio-remote-updater[501]: [2024-04-10 22:00:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 10 22:00:05 volumio-jury volumio-remote-updater[501]: [2024-04-10 22:00:05] [error] handle_connect error: Underlying Transport Error Apr 10 22:00:06 volumio-jury wireless.js[504]: trying... Apr 10 22:00:06 volumio-jury sudo[734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 10 22:00:06 volumio-jury sudo[734]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:06 volumio-jury sudo[734]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:06 volumio-jury dhcpcd[688]: wlan0: leased 192.168.0.17 for 86400 seconds Apr 10 22:00:06 volumio-jury avahi-daemon[485]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.17. Apr 10 22:00:06 volumio-jury avahi-daemon[485]: New relevant interface wlan0.IPv4 for mDNS. Apr 10 22:00:06 volumio-jury avahi-daemon[485]: Registering new address record for 192.168.0.17 on wlan0.IPv4. Apr 10 22:00:06 volumio-jury dhcpcd[688]: wlan0: adding route to 192.168.0.0/24 Apr 10 22:00:06 volumio-jury dhcpcd[688]: wlan0: adding default route via 192.168.0.1 Apr 10 22:00:07 volumio-jury wireless.js[504]: trying... Apr 10 22:00:07 volumio-jury sudo[757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 10 22:00:07 volumio-jury sudo[757]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:07 volumio-jury sudo[757]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:07 volumio-jury wireless.js[504]: Connected to: ----RT-GPON-AB20 Apr 10 22:00:07 volumio-jury wireless.js[504]: ---- Apr 10 22:00:07 volumio-jury sudo[760]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 10 22:00:07 volumio-jury sudo[760]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:07 volumio-jury sudo[760]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:07 volumio-jury wireless.js[504]: ... joined AP, wlan0 IPv4 is 192.168.0.17, ipV6 is undefined Apr 10 22:00:07 volumio-jury wireless.js[504]: It's done! AP Apr 10 22:00:07 volumio-jury iwconfig[764]: Error for wireless request "Set Power Management" (8B2C) : Apr 10 22:00:07 volumio-jury iwconfig[764]: SET failed on device wlan0 ; Operation not supported. Apr 10 22:00:07 volumio-jury systemd[1]: Started Wireless Services. Apr 10 22:00:07 volumio-jury systemd[1]: Started Volumio Backend Module. Apr 10 22:00:07 volumio-jury systemd[1]: Started Volumio Cpu Tweaker. Apr 10 22:00:07 volumio-jury volumio-cpu-tweak[766]: Setting RT Priority for mpd Apr 10 22:00:07 volumio-jury volumio-cpu-tweak[766]: pid 35's current scheduling policy: SCHED_OTHER Apr 10 22:00:07 volumio-jury volumio-cpu-tweak[766]: pid 35's current scheduling priority: 0 Apr 10 22:00:07 volumio-jury volumio-cpu-tweak[766]: Setting MPD Affinity Apr 10 22:00:07 volumio-jury volumio-cpu-tweak[766]: pid 3's current affinity mask: f Apr 10 22:00:07 volumio-jury volumio-cpu-tweak[766]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 10 22:00:07 volumio-jury volumio-cpu-tweak[766]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 10 22:00:07 volumio-jury systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 10 22:00:08 volumio-jury ntpd[594]: Listen normally on 3 wlan0 192.168.0.17:123 Apr 10 22:00:08 volumio-jury ntpd[594]: new interface(s) found: waking up resolver Apr 10 22:00:10 volumio-jury nmbd[552]: [2024/04/10 22:00:10.726798, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 10 22:00:10 volumio-jury nmbd[552]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 10 22:00:10 volumio-jury systemd[1]: Started Samba NMB Daemon. Apr 10 22:00:10 volumio-jury systemd[1]: Starting Samba Winbind Daemon... Apr 10 22:00:11 volumio-jury winbindd[782]: [2024/04/10 22:00:11.586268, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 10 22:00:11 volumio-jury winbindd[782]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 10 22:00:11 volumio-jury winbindd[782]: [2024/04/10 22:00:11.818162, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 10 22:00:11 volumio-jury systemd[1]: Started Samba Winbind Daemon. Apr 10 22:00:11 volumio-jury winbindd[782]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 10 22:00:11 volumio-jury systemd[1]: Starting Samba SMB Daemon... Apr 10 22:00:12 volumio-jury winbindd[782]: [2024/04/10 22:00:12.955268, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Apr 10 22:00:12 volumio-jury winbindd[782]: res_names->count = 3, expected 4 Apr 10 22:00:13 volumio-jury smbd[787]: [2024/04/10 22:00:13.016899, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 10 22:00:13 volumio-jury smbd[787]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 10 22:00:13 volumio-jury systemd[1]: Started Samba SMB Daemon. Apr 10 22:00:13 volumio-jury systemd[1]: Reached target Multi-User System. Apr 10 22:00:13 volumio-jury systemd[1]: Reached target Graphical Interface. Apr 10 22:00:13 volumio-jury systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 10 22:00:13 volumio-jury systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 10 22:00:13 volumio-jury systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 10 22:00:13 volumio-jury systemd[1]: Startup finished in 14.321s (kernel) + 41.048s (userspace) = 55.369s. Apr 10 22:00:14 volumio-jury volumio[765]: info: ------------------------------------------- Apr 10 22:00:14 volumio-jury volumio[765]: info: ----- Volumio3 ---- Apr 10 22:00:14 volumio-jury volumio[765]: info: ------------------------------------------- Apr 10 22:00:14 volumio-jury volumio[765]: info: ----- System startup ---- Apr 10 22:00:14 volumio-jury volumio[765]: info: ------------------------------------------- Apr 10 22:00:15 volumio-jury volumio-remote-updater[501]: [2024-04-10 22:00:15] [connect] Successful connection Apr 10 22:00:16 volumio-jury volumio[765]: info: MYVOLUMIO Environment detected Apr 10 22:00:16 volumio-jury volumio[765]: info: Plugin folders cleanup Apr 10 22:00:16 volumio-jury volumio[765]: info: Scanning into folder /volumio/app/plugins/ Apr 10 22:00:16 volumio-jury volumio[765]: info: Scanning category audio_interface Apr 10 22:00:16 volumio-jury volumio[765]: info: Scanning category miscellanea Apr 10 22:00:16 volumio-jury volumio[765]: info: Scanning category music_service Apr 10 22:00:16 volumio-jury volumio[765]: info: Scanning category plugins.json Apr 10 22:00:16 volumio-jury volumio[765]: info: Scanning category system_controller Apr 10 22:00:16 volumio-jury volumio[765]: info: Scanning category user_interface Apr 10 22:00:16 volumio-jury volumio[765]: info: Scanning into folder /data/plugins/ Apr 10 22:00:16 volumio-jury volumio[765]: info: Scanning category music_service Apr 10 22:00:16 volumio-jury volumio[765]: info: Scanning category system_controller Apr 10 22:00:16 volumio-jury systemd[1]: systemd-fsckd.service: Succeeded. Apr 10 22:00:16 volumio-jury volumio[765]: info: Plugin folders cleanup completed Apr 10 22:00:16 volumio-jury volumio[765]: info: ------------------------------------------- Apr 10 22:00:16 volumio-jury volumio[765]: info: ----- Core plugins startup ---- Apr 10 22:00:16 volumio-jury volumio[765]: info: ------------------------------------------- Apr 10 22:00:16 volumio-jury volumio[765]: info: Loading plugins from folder /volumio/app/plugins/ Apr 10 22:00:16 volumio-jury volumio[765]: info: Adding plugin upnp to MyMusic Plugins Apr 10 22:00:16 volumio-jury volumio[765]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 10 22:00:16 volumio-jury volumio[765]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 10 22:00:16 volumio-jury volumio[765]: info: Loading plugins from folder /data/plugins/ Apr 10 22:00:16 volumio-jury volumio[765]: info: Loading plugin "system"... Apr 10 22:00:17 volumio-jury volumio[765]: info: Loading plugin "appearance"... Apr 10 22:00:18 volumio-jury sh[364]: timed out Apr 10 22:00:18 volumio-jury dhcpcd[415]: timed out Apr 10 22:00:18 volumio-jury sh[364]: dhcpcd exited Apr 10 22:00:18 volumio-jury dhcpcd[415]: dhcpcd exited Apr 10 22:00:18 volumio-jury sh[364]: ifup: failed to bring up eth0 Apr 10 22:00:18 volumio-jury systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 10 22:00:18 volumio-jury systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 10 22:00:19 volumio-jury volumio[765]: info: Loading plugin "network"... Apr 10 22:00:19 volumio-jury volumio[765]: info: Refreshing Cached IP Addresses Apr 10 22:00:19 volumio-jury sudo[811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 10 22:00:19 volumio-jury sudo[811]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:19 volumio-jury volumio[765]: info: Loading plugin "services"... Apr 10 22:00:19 volumio-jury sudo[811]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:19 volumio-jury sudo[813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 10 22:00:19 volumio-jury sudo[813]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:19 volumio-jury volumio[765]: info: Loading plugin "alsa_controller"... Apr 10 22:00:19 volumio-jury sudo[813]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:19 volumio-jury sudo[816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 10 22:00:19 volumio-jury sudo[816]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:20 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 10 22:00:20 volumio-jury volumio[765]: info: Loading plugin "wizard"... Apr 10 22:00:20 volumio-jury volumio[765]: info: Loading plugin "networkfs"... Apr 10 22:00:20 volumio-jury volumio[765]: info: Starting Udev Watcher for removable devices Apr 10 22:00:20 volumio-jury sudo[822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.27/volume1/music /mnt/NAS/Nas1 Apr 10 22:00:20 volumio-jury sudo[822]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:20 volumio-jury sudo[824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.0.27:/volume1/music /mnt/NAS/Nas1 Apr 10 22:00:20 volumio-jury sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:20 volumio-jury volumio[765]: info: Ignoring mount for partition: boot Apr 10 22:00:20 volumio-jury volumio[765]: info: Ignoring mount for partition: volumio Apr 10 22:00:20 volumio-jury volumio[765]: info: Ignoring mount for partition: volumio_data Apr 10 22:00:20 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 10 22:00:20 volumio-jury volumio[765]: info: Loading plugin "volumio_command_line_client"... Apr 10 22:00:20 volumio-jury volumio[765]: info: Loading plugin "upnp"... Apr 10 22:00:20 volumio-jury volumio[765]: info: [1712757620362] Starting Upmpd Daemon Apr 10 22:00:20 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 10 22:00:20 volumio-jury volumio[765]: info: Loading plugin "my_music"... Apr 10 22:00:20 volumio-jury volumio[765]: info: Loading plugin "mpd"... Apr 10 22:00:20 volumio-jury kernel: Key type cifs.spnego registered Apr 10 22:00:20 volumio-jury kernel: Key type cifs.idmap registered Apr 10 22:00:20 volumio-jury kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Apr 10 22:00:20 volumio-jury kernel: CIFS: Attempting to mount \\192.168.0.27\volume1 Apr 10 22:00:20 volumio-jury sudo[824]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:21 volumio-jury kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Apr 10 22:00:21 volumio-jury kernel: CIFS: VFS: \\192.168.0.27 Send error in SessSetup = -13 Apr 10 22:00:21 volumio-jury kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Apr 10 22:00:21 volumio-jury sudo[822]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:21 volumio-jury volumio[765]: info: Loading plugin "upnp_browser"... Apr 10 22:00:22 volumio-jury volumio[765]: info: Loading plugin "alarm-clock"... Apr 10 22:00:22 volumio-jury volumio[765]: info: Loading plugin "airplay_emulation"... Apr 10 22:00:22 volumio-jury volumio[765]: info: Starting Shairport Sync Apr 10 22:00:22 volumio-jury volumio[765]: info: Loading plugin "last_100"... Apr 10 22:00:22 volumio-jury volumio[765]: info: Loading plugin "webradio"... Apr 10 22:00:23 volumio-jury volumio[765]: info: Loading plugin "i2s_dacs"... Apr 10 22:00:23 volumio-jury volumio[765]: info: Loading plugin "volumiodiscovery"... Apr 10 22:00:23 volumio-jury volumio[765]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 10 22:00:23 volumio-jury node[765]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 10 22:00:23 volumio-jury node[765]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 10 22:00:23 volumio-jury volumio[765]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 10 22:00:23 volumio-jury volumio[765]: *** WARNING *** For more information see Apr 10 22:00:23 volumio-jury volumio[765]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 10 22:00:23 volumio-jury volumio[765]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 10 22:00:23 volumio-jury volumio[765]: *** WARNING *** For more information see Apr 10 22:00:23 volumio-jury node[765]: *** WARNING *** For more information see Apr 10 22:00:23 volumio-jury node[765]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 10 22:00:23 volumio-jury node[765]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 10 22:00:23 volumio-jury node[765]: *** WARNING *** For more information see Apr 10 22:00:23 volumio-jury volumio[765]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 10 22:00:23 volumio-jury volumio[765]: info: Discovery: Started advertising with name: Volumio Jury Apr 10 22:00:23 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 10 22:00:23 volumio-jury volumio[765]: info: Loading plugin "ytmusic"... Apr 10 22:00:25 volumio-jury wpa_supplicant[679]: nl80211: send_and_recv->nl_recvmsgs failed: -33 Apr 10 22:00:25 volumio-jury sudo[816]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:27 volumio-jury volumio[765]: info: Loading plugin "autostart"... Apr 10 22:00:27 volumio-jury volumio[765]: info: Applying required configuration parameters for plugin autostart Apr 10 22:00:27 volumio-jury volumio[765]: info: AutoStart - onVolumioStart - read config.json Apr 10 22:00:27 volumio-jury volumio[765]: info: Loading plugin "outputs"... Apr 10 22:00:27 volumio-jury volumio[765]: info: Loading plugin "albumart"... Apr 10 22:00:27 volumio-jury volumio[765]: info: Plugin example_plugin is not enabled Apr 10 22:00:27 volumio-jury volumio[765]: info: Loading plugin "inputs"... Apr 10 22:00:27 volumio-jury volumio[765]: info: Loading plugin "updater_comm"... Apr 10 22:00:28 volumio-jury volumio[765]: info: Plugin mpdemulation is not enabled Apr 10 22:00:28 volumio-jury volumio[765]: info: Loading plugin "rest_api"... Apr 10 22:00:28 volumio-jury volumio[765]: info: Loading plugin "websocket"... Apr 10 22:00:28 volumio-jury volumio[765]: info: Loading plugin "radio_paradise"... Apr 10 22:00:28 volumio-jury volumio[765]: Forking 3 albumart workers Apr 10 22:00:29 volumio-jury volumio[765]: info: Applying required configuration parameters for plugin radio_paradise Apr 10 22:00:29 volumio-jury volumio[765]: info: [1712757629021] [RadioParadise] API delay: 5 Apr 10 22:00:29 volumio-jury volumio[765]: info: Loading i18n strings for locale ru Apr 10 22:00:29 volumio-jury volumio[765]: Updating browse sources language Apr 10 22:00:29 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 22:00:29 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 22:00:29 volumio-jury volumio[765]: info: CoreCommandRouter::initPlayerControls Apr 10 22:00:29 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:00:29 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:00:29 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:00:29 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:00:29 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:00:29 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:00:29 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:00:29 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:00:29 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 22:00:29 volumio-jury volumio[765]: Express server listening on port 3000 Apr 10 22:00:29 volumio-jury volumio[765]: [Metrics] WebUI: 17s 584.19ms Apr 10 22:00:30 volumio-jury volumio[765]: info: CoreStateMachine::resetVolumioState Apr 10 22:00:30 volumio-jury volumio[765]: info: CoreStateMachine::getcurrentVolume Apr 10 22:00:30 volumio-jury volumio[765]: info: CoreCommandRouter::volumioRetrievevolume Apr 10 22:00:30 volumio-jury volumio[765]: info: CoreStateMachine::pushState Apr 10 22:00:30 volumio-jury volumio[765]: info: CorePlayQueue::getTrack 0 Apr 10 22:00:30 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 22:00:30 volumio-jury volumio[765]: info: CoreCommandRouter::volumioPushState Apr 10 22:00:30 volumio-jury volumio[765]: info: Cannot mount NAS Nas1 at system boot, trial number 1 ,retrying in 5 seconds Apr 10 22:00:30 volumio-jury sudo[898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 10 22:00:30 volumio-jury sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:30 volumio-jury sudo[900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 10 22:00:30 volumio-jury sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:30 volumio-jury sudo[898]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:30 volumio-jury sudo[900]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:30 volumio-jury volumio[765]: Starting albumart workers Apr 10 22:00:30 volumio-jury volumio[765]: Starting albumart workers Apr 10 22:00:30 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 22:00:30 volumio-jury volumio[765]: Starting albumart workers Apr 10 22:00:30 volumio-jury volumio[765]: info: CoreStateMachine::setRepeat null single undefined Apr 10 22:00:30 volumio-jury volumio[765]: info: CoreStateMachine::pushState Apr 10 22:00:30 volumio-jury volumio[765]: info: CorePlayQueue::getTrack 0 Apr 10 22:00:30 volumio-jury volumio[765]: info: CoreCommandRouter::volumioPushState Apr 10 22:00:30 volumio-jury volumio-remote-updater[501]: [2024-04-10 22:00:30] [connect] Successful connection Apr 10 22:00:30 volumio-jury volumio[765]: info: CoreStateMachine::setRandom true Apr 10 22:00:30 volumio-jury volumio[765]: info: CoreStateMachine::pushState Apr 10 22:00:30 volumio-jury volumio[765]: info: CorePlayQueue::getTrack 0 Apr 10 22:00:30 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 22:00:30 volumio-jury volumio[765]: info: CoreCommandRouter::volumioPushState Apr 10 22:00:30 volumio-jury volumio[765]: info: Setting Device type: Raspberry PI Apr 10 22:00:30 volumio-jury volumio-remote-updater[501]: [2024-04-10 22:00:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712757630 101 Apr 10 22:00:30 volumio-jury volumio[765]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 10 22:00:31 volumio-jury sudo[909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 10 22:00:31 volumio-jury sudo[909]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:31 volumio-jury systemd[1]: Started UPnP Renderer front-end to MPD. Apr 10 22:00:31 volumio-jury volumio[765]: info: Discovery: adding fae04827-40fb-409d-948b-638e93b8f81b Apr 10 22:00:31 volumio-jury volumio[765]: info: Discovery: Found device Volumio Jury Apr 10 22:00:31 volumio-jury volumio[765]: info: CoreCommandRouter::volumioGetState Apr 10 22:00:31 volumio-jury volumio[765]: info: CorePlayQueue::getTrack 0 Apr 10 22:00:31 volumio-jury sudo[909]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:31 volumio-jury volumio[765]: info: Upmpdcli Daemon Started Apr 10 22:00:31 volumio-jury volumio[765]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 10 22:00:35 volumio-jury volumio[765]: info: Cannot mount NAS Nas1 at system boot, trial number 2 ,retrying in 5 seconds Apr 10 22:00:35 volumio-jury volumio[765]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 10 22:00:40 volumio-jury volumio[765]: info: Cannot mount NAS Nas1 at system boot, trial number 3 ,retrying in 5 seconds Apr 10 22:00:41 volumio-jury volumio[765]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 10 22:00:45 volumio-jury volumio[765]: info: Cannot mount NAS Nas1 at system boot, trial number 4 ,retrying in 5 seconds Apr 10 22:00:49 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 22:00:49 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 22:00:49 volumio-jury volumio[765]: info: Discovery: Getting this device information Apr 10 22:00:49 volumio-jury volumio[765]: info: CoreCommandRouter::volumioGetState Apr 10 22:00:49 volumio-jury volumio[765]: info: CorePlayQueue::getTrack 0 Apr 10 22:00:49 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 22:00:49 volumio-jury volumio[765]: verbose: New Socket.io Connection to 192.168.0.17 from 192.168.0.22 UA: unknown Total Clients: 2 Apr 10 22:00:49 volumio-jury volumio[765]: info: CoreCommandRouter::volumioGetState Apr 10 22:00:49 volumio-jury volumio[765]: info: CorePlayQueue::getTrack 0 Apr 10 22:00:49 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 10 22:00:49 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 10 22:00:49 volumio-jury volumiologrotate[487]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 10 22:00:49 volumio-jury volumiologrotate[487]: ls: cannot access 'JURY': No such file or directory Apr 10 22:00:50 volumio-jury volumio[765]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Apr 10 22:00:51 volumio-jury sudo[975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 10 22:00:51 volumio-jury sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:51 volumio-jury sudo[975]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:51 volumio-jury sudo[977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 10 22:00:51 volumio-jury sudo[977]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:51 volumio-jury sudo[977]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:51 volumio-jury volumio[765]: verbose: New Socket.io Connection to 192.168.0.17 from 192.168.0.22 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 3 Apr 10 22:00:51 volumio-jury volumio[765]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 10 22:00:51 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 10 22:00:51 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 10 22:00:51 volumio-jury volumio[765]: info: CoreCommandRouter::volumioGetVisibleSources Apr 10 22:00:51 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 22:00:51 volumio-jury volumio[765]: info: CoreCommandRouter::volumioGetState Apr 10 22:00:51 volumio-jury volumio[765]: info: CorePlayQueue::getTrack 0 Apr 10 22:00:51 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 10 22:00:51 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 10 22:00:51 volumio-jury volumio[765]: info: Received Get System Info Apr 10 22:00:51 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 22:00:51 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 22:00:51 volumio-jury volumio[765]: info: Discovery: Getting this device information Apr 10 22:00:51 volumio-jury volumio[765]: info: CoreCommandRouter::volumioGetState Apr 10 22:00:51 volumio-jury volumio[765]: info: CorePlayQueue::getTrack 0 Apr 10 22:00:51 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 22:00:51 volumio-jury volumio[765]: info: CoreCommandRouter::volumioGetState Apr 10 22:00:51 volumio-jury volumio[765]: info: CorePlayQueue::getTrack 0 Apr 10 22:00:51 volumio-jury volumio[765]: info: Listing playlists Apr 10 22:00:51 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 10 22:00:51 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 10 22:00:52 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 10 22:00:52 volumio-jury volumio[765]: info: Received Get System Info Apr 10 22:00:52 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 22:00:52 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 22:00:52 volumio-jury volumio[765]: info: Discovery: Getting this device information Apr 10 22:00:52 volumio-jury volumio[765]: info: CoreCommandRouter::volumioGetState Apr 10 22:00:52 volumio-jury volumio[765]: info: CorePlayQueue::getTrack 0 Apr 10 22:00:52 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 22:00:54 volumio-jury volumio[765]: info: CoreCommandRouter::volumioGetQueue Apr 10 22:00:54 volumio-jury volumio[765]: info: CoreStateMachine::getQueue Apr 10 22:00:54 volumio-jury volumio[765]: info: CorePlayQueue::getQueue Apr 10 22:00:56 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 10 22:00:56 volumio-jury volumio[765]: info: Received Get System Info Apr 10 22:00:56 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 22:00:56 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 22:00:56 volumio-jury volumio[765]: info: Discovery: Getting this device information Apr 10 22:00:56 volumio-jury volumio[765]: info: CoreCommandRouter::volumioGetState Apr 10 22:00:56 volumio-jury volumio[765]: info: CorePlayQueue::getTrack 0 Apr 10 22:00:56 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 22:00:57 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 10 22:00:59 volumio-jury volumio[765]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Apr 10 22:00:59 volumio-jury volumio[765]: info: Preparing to generate the ALSA configuration file Apr 10 22:00:59 volumio-jury volumio[765]: info: Asound.conf file unchanged, so no further update is needed Apr 10 22:00:59 volumio-jury volumio[765]: info: Output device has changed, restarting MPD Apr 10 22:00:59 volumio-jury volumio[765]: info: Output device has changed, restarting Shairport Sync Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 22:00:59 volumio-jury sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 10 22:00:59 volumio-jury sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:59 volumio-jury sudo[981]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:59 volumio-jury sudo[983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 10 22:00:59 volumio-jury sudo[983]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:59 volumio-jury volumio[765]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 10 22:00:59 volumio-jury volumio[765]: info: ___________ START PLUGINS ___________ Apr 10 22:00:59 volumio-jury volumio[765]: info: ControllerMpd::onStart: Initializing MPD Apr 10 22:00:59 volumio-jury volumio[765]: info: Creating MPD Configuration file Apr 10 22:00:59 volumio-jury systemd[1]: Listening on mpd.socket. Apr 10 22:00:59 volumio-jury systemd[1]: Starting Music Player Daemon... Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 22:00:59 volumio-jury sudo[991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 10 22:00:59 volumio-jury sudo[991]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:59 volumio-jury volumio[765]: info: [1712757659353] CoreMusicLibrary::Adding element Медиа-серверы Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 22:00:59 volumio-jury sudo[991]: pam_unix(sudo:session): session closed for user root Apr 10 22:00:59 volumio-jury sudo[994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 10 22:00:59 volumio-jury sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 22:00:59 volumio-jury systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 10 22:00:59 volumio-jury systemd[1]: mpd.service: Succeeded. Apr 10 22:00:59 volumio-jury systemd[1]: Stopped Music Player Daemon. Apr 10 22:00:59 volumio-jury systemd[1]: Starting Music Player Daemon... Apr 10 22:00:59 volumio-jury volumio[765]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 22:00:59 volumio-jury volumio[765]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 10 22:00:59 volumio-jury volumio[765]: info: [1712757659620] CoreMusicLibrary::Adding element Last_100 Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 10 22:00:59 volumio-jury volumio[765]: info: [1712757659628] CoreMusicLibrary::Adding element Webradio Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 10 22:00:59 volumio-jury volumio[765]: info: [1712757659667] CoreMusicLibrary::Adding element YouTube Music Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 22:00:59 volumio-jury volumio[765]: Cannot find translation for source YouTube Music Apr 10 22:00:59 volumio-jury volumio[765]: info: AutoStart - onStart Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 10 22:00:59 volumio-jury volumio[765]: info: [1712757659690] CoreMusicLibrary::Adding element Radio Paradise Apr 10 22:00:59 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 22:00:59 volumio-jury volumio[765]: Cannot find translation for source YouTube Music Apr 10 22:00:59 volumio-jury volumio[765]: Cannot find translation for source Radio Paradise Apr 10 22:00:59 volumio-jury volumio[765]: info: Volumio Calling Home Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 10 22:01:00 volumio-jury volumio[765]: info: MPD Permissions set Apr 10 22:01:00 volumio-jury volumio[765]: info: MPD Permissions set Apr 10 22:01:00 volumio-jury volumio[765]: info: Volumio called home Apr 10 22:01:00 volumio-jury volumio[765]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::volumioGetBrowseSources Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::volumioGetBrowseSources Apr 10 22:01:00 volumio-jury volumio[765]: info: CoreCommandRouter::volumioGetBrowseSources Apr 10 22:01:00 volumio-jury volumio[765]: info: Starting Shairport Sync Apr 10 22:01:00 volumio-jury volumio[765]: info: Starting Shairport Sync Apr 10 22:01:00 volumio-jury volumio[765]: info: Starting Shairport Sync Apr 10 22:01:00 volumio-jury sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 10 22:01:00 volumio-jury sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:01:00 volumio-jury sudo[1018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 10 22:01:00 volumio-jury sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:01:00 volumio-jury sudo[1021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 10 22:01:00 volumio-jury sudo[1021]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:01:00 volumio-jury systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 10 22:01:01 volumio-jury systemd[1]: shairport-sync.service: Succeeded. Apr 10 22:01:01 volumio-jury systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 10 22:01:01 volumio-jury systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 10 22:01:01 volumio-jury sudo[1016]: pam_unix(sudo:session): session closed for user root Apr 10 22:01:01 volumio-jury sudo[1018]: pam_unix(sudo:session): session closed for user root Apr 10 22:01:01 volumio-jury sudo[1021]: pam_unix(sudo:session): session closed for user root Apr 10 22:01:01 volumio-jury ntpd[594]: Soliciting pool server 195.239.232.102 Apr 10 22:01:01 volumio-jury volumio[765]: info: Shairport-Sync Started Apr 10 22:01:01 volumio-jury volumio[765]: Error adding Membership: Error: addMembership EINVAL Apr 10 22:01:01 volumio-jury volumio[765]: info: Shairport-Sync Started Apr 10 22:01:01 volumio-jury volumio[765]: info: Shairport-Sync Started Apr 10 22:01:01 volumio-jury volumio[765]: info: CoreCommandRouter::volumioGetState Apr 10 22:01:01 volumio-jury volumio[765]: info: CorePlayQueue::getTrack 0 Apr 10 22:01:01 volumio-jury ntpd[594]: Soliciting pool server 192.36.143.130 Apr 10 22:01:01 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 10 22:01:01 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 10 22:01:02 volumio-jury ntpd[594]: Soliciting pool server 188.120.233.26 Apr 10 22:01:02 volumio-jury ntpd[594]: Soliciting pool server 162.159.200.1 Apr 10 22:01:02 volumio-jury ntpd[594]: Soliciting pool server 162.159.200.123 Apr 10 22:01:02 volumio-jury volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 10 22:01:02 volumio-jury volumio[765]: verbose: ControllerMpd::sendMpdCommand rescan Apr 10 22:01:02 volumio-jury volumio[765]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 10 22:01:02 volumio-jury volumio[765]: TypeError: Cannot read property 'then' of undefined Apr 10 22:01:02 volumio-jury volumio[765]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) Apr 10 22:01:02 volumio-jury volumio[765]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1885:15) Apr 10 22:01:02 volumio-jury volumio[765]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Apr 10 22:01:02 volumio-jury volumio[765]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1045:26) Apr 10 22:01:02 volumio-jury volumio[765]: at Socket.emit (events.js:315:20) Apr 10 22:01:02 volumio-jury volumio[765]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 10 22:01:02 volumio-jury volumio[765]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 10 22:01:02 volumio-jury volumio[765]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 10 22:01:03 volumio-jury ntpd[594]: Soliciting pool server 185.68.101.10 Apr 10 22:01:03 volumio-jury ntpd[594]: Soliciting pool server 91.207.136.55 Apr 10 22:01:03 volumio-jury ntpd[594]: Soliciting pool server 46.146.231.187 Apr 10 22:01:03 volumio-jury ntpd[594]: Soliciting pool server 195.3.254.2 Apr 10 22:01:03 volumio-jury sudo[1042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-10 22:00 Apr 10 22:01:03 volumio-jury sudo[1042]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 22:01:03 volumio-jury mpd[1000]: Apr 10 22:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"