-- Logs begin at Tue 2024-10-01 10:21:50 CST, end at Tue 2024-10-01 10:23:34 CST. --
Oct 01 10:22:00 volumio sudo[1041]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:00 volumio wireless.js[765]: DHCP IP
Oct 01 10:22:00 volumio wireless.js[765]: Start ap
Oct 01 10:22:00 volumio wpa_supplicant[1043]: Successfully initialized wpa_supplicant
Oct 01 10:22:00 volumio sudo[1044]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd
Oct 01 10:22:00 volumio sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:00 volumio dhcpcd[1045]: sending commands to master dhcpcd process
Oct 01 10:22:00 volumio dhcpcd[769]: control command: /sbin/dhcpcd
Oct 01 10:22:00 volumio dhcpcd[769]: control command: /sbin/dhcpcd
Oct 01 10:22:00 volumio sudo[1044]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:00 volumio kernel: b43-phy0: Loading firmware version 666.2 (2011-02-23 01:15:07)
Oct 01 10:22:01 volumio wireless.js[765]: trying...
Oct 01 10:22:01 volumio sudo[1049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 01 10:22:01 volumio sudo[1049]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:01 volumio sudo[1049]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:02 volumio wireless.js[765]: trying...
Oct 01 10:22:02 volumio sudo[1052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 01 10:22:02 volumio sudo[1052]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:02 volumio sudo[1052]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:02 volumio wpa_supplicant[1047]: wlan0: SME: Trying to authenticate with 88:c3:97:32:0a:ef (SSID='Xiaomi_4272' freq=5785 MHz)
Oct 01 10:22:02 volumio kernel: wlan0: authenticate with 88:c3:97:32:0a:ef
Oct 01 10:22:02 volumio kernel: wlan0: send auth to 88:c3:97:32:0a:ef (try 1/3)
Oct 01 10:22:02 volumio wpa_supplicant[1047]: wlan0: Trying to associate with 88:c3:97:32:0a:ef (SSID='Xiaomi_4272' freq=5785 MHz)
Oct 01 10:22:02 volumio kernel: wlan0: authenticated
Oct 01 10:22:02 volumio kernel: wlan0: associate with 88:c3:97:32:0a:ef (try 1/3)
Oct 01 10:22:02 volumio wpa_supplicant[1047]: wlan0: Associated with 88:c3:97:32:0a:ef
Oct 01 10:22:02 volumio wpa_supplicant[1047]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Oct 01 10:22:02 volumio wpa_supplicant[1047]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN
Oct 01 10:22:02 volumio kernel: wlan0: RX AssocResp from 88:c3:97:32:0a:ef (capab=0x1511 status=0 aid=3)
Oct 01 10:22:02 volumio kernel: wlan0: associated
Oct 01 10:22:02 volumio kernel: wlan0: Limiting TX power to 30 (33 - 3) dBm as advertised by 88:c3:97:32:0a:ef
Oct 01 10:22:02 volumio wpa_supplicant[1047]: wlan0: WPA: Key negotiation completed with 88:c3:97:32:0a:ef [PTK=CCMP GTK=TKIP]
Oct 01 10:22:02 volumio dhcpcd[769]: wlan0: carrier acquired
Oct 01 10:22:02 volumio wpa_supplicant[1047]: wlan0: CTRL-EVENT-CONNECTED - Connection to 88:c3:97:32:0a:ef completed [id=0 id_str=]
Oct 01 10:22:02 volumio dhcpcd[769]: wlan0: carrier acquired
Oct 01 10:22:02 volumio dhcpcd[769]: wlan0: IAID 10:69:fa:ce
Oct 01 10:22:02 volumio dhcpcd[769]: wlan0: IAID 10:69:fa:ce
Oct 01 10:22:03 volumio dhcpcd[769]: wlan0: soliciting an IPv6 router
Oct 01 10:22:03 volumio dhcpcd[769]: wlan0: soliciting an IPv6 router
Oct 01 10:22:03 volumio wireless.js[765]: trying...
Oct 01 10:22:03 volumio sudo[1071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 01 10:22:03 volumio sudo[1071]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:03 volumio sudo[1071]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:03 volumio dhcpcd[769]: wlan0: rebinding lease of 192.168.31.96
Oct 01 10:22:03 volumio dhcpcd[769]: wlan0: rebinding lease of 192.168.31.96
Oct 01 10:22:04 volumio wireless.js[765]: trying...
Oct 01 10:22:04 volumio sudo[1074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 01 10:22:04 volumio sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:04 volumio sudo[1074]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:04 volumio screensaver.sh[771]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused
Oct 01 10:22:04 volumio dhcpcd[769]: wlan0: probing address 192.168.31.96/24
Oct 01 10:22:04 volumio dhcpcd[769]: wlan0: probing address 192.168.31.96/24
Oct 01 10:22:05 volumio wireless.js[765]: trying...
Oct 01 10:22:05 volumio sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 01 10:22:05 volumio sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:05 volumio sudo[1084]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:06 volumio wireless.js[765]: trying...
Oct 01 10:22:06 volumio sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 01 10:22:06 volumio sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:06 volumio sudo[1087]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:07 volumio wireless.js[765]: trying...
Oct 01 10:22:07 volumio sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 01 10:22:07 volumio sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:07 volumio sudo[1090]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:07 volumio volumio-remote-updater[762]: [2024-10-01 10:22:07] [info] asio async_connect error: system:111 (Connection refused)
Oct 01 10:22:07 volumio volumio-remote-updater[762]: [2024-10-01 10:22:07] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Oct 01 10:22:07 volumio volumio-remote-updater[762]: [2024-10-01 10:22:07] [error] handle_connect error: Underlying Transport Error
Oct 01 10:22:08 volumio wireless.js[765]: trying...
Oct 01 10:22:08 volumio sudo[1093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 01 10:22:08 volumio sudo[1093]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:08 volumio sudo[1093]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:09 volumio wireless.js[765]: trying...
Oct 01 10:22:09 volumio sudo[1096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 01 10:22:09 volumio sudo[1096]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:09 volumio sudo[1096]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:09 volumio dhcpcd[769]: wlan0: leased 192.168.31.96 for 43200 seconds
Oct 01 10:22:09 volumio dhcpcd[769]: wlan0: leased 192.168.31.96 for 43200 seconds
Oct 01 10:22:09 volumio avahi-daemon[775]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.96.
Oct 01 10:22:09 volumio dhcpcd[769]: wlan0: adding route to 192.168.31.0/24
Oct 01 10:22:09 volumio dhcpcd[769]: wlan0: adding default route via 192.168.31.1
Oct 01 10:22:09 volumio dhcpcd[769]: wlan0: adding route to 192.168.31.0/24
Oct 01 10:22:09 volumio dhcpcd[769]: wlan0: adding default route via 192.168.31.1
Oct 01 10:22:09 volumio avahi-daemon[775]: New relevant interface wlan0.IPv4 for mDNS.
Oct 01 10:22:09 volumio avahi-daemon[775]: Registering new address record for 192.168.31.96 on wlan0.IPv4.
Oct 01 10:22:09 volumio ntpd[987]: ntpd exiting on signal 15 (Terminated)
Oct 01 10:22:09 volumio systemd[1]: Stopping Network Time Service...
Oct 01 10:22:09 volumio systemd[1]: ntp.service: Succeeded.
Oct 01 10:22:09 volumio systemd[1]: Stopped Network Time Service.
Oct 01 10:22:09 volumio systemd[1]: Starting Network Time Service...
Oct 01 10:22:09 volumio ntpd[1131]: ntpd 4.2.8p12@1.3728-o (1): Starting
Oct 01 10:22:09 volumio ntpd[1131]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103
Oct 01 10:22:09 volumio systemd[1]: Started Network Time Service.
Oct 01 10:22:09 volumio dhcpcd[769]: forked to background, child pid 1138
Oct 01 10:22:09 volumio dhcpcd[769]: forked to background, child pid 1138
Oct 01 10:22:09 volumio systemd[1]: Started DHCP Client Daemon.
Oct 01 10:22:09 volumio ntpd[1137]: proto: precision = 0.162 usec (-22)
Oct 01 10:22:09 volumio ntpd[1137]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
Oct 01 10:22:09 volumio ntpd[1137]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
Oct 01 10:22:09 volumio ntpd[1137]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 644 days ago
Oct 01 10:22:09 volumio ntpd[1137]: Listen and drop on 0 v6wildcard [::]:123
Oct 01 10:22:09 volumio ntpd[1137]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Oct 01 10:22:09 volumio ntpd[1137]: Listen normally on 2 lo 127.0.0.1:123
Oct 01 10:22:09 volumio ntpd[1137]: Listen normally on 3 wlan0 192.168.31.96:123
Oct 01 10:22:09 volumio ntpd[1137]: Listening on routing socket on fd #20 for interface updates
Oct 01 10:22:09 volumio ntpd[1137]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Oct 01 10:22:09 volumio ntpd[1137]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Oct 01 10:22:10 volumio wireless.js[765]: trying...
Oct 01 10:22:10 volumio sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 01 10:22:10 volumio sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:10 volumio sudo[1141]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:10 volumio wireless.js[765]: Connected to: ----Xiaomi_4272
Oct 01 10:22:10 volumio wireless.js[765]: ----
Oct 01 10:22:10 volumio sudo[1144]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 01 10:22:10 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:10 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:10 volumio wireless.js[765]: ... joined AP, wlan0 IPv4 is 192.168.31.96, ipV6 is undefined
Oct 01 10:22:10 volumio wireless.js[765]: It's done! AP
Oct 01 10:22:10 volumio iwconfig[1148]: Error for wireless request "Set Power Management" (8B2C) :
Oct 01 10:22:10 volumio iwconfig[1148]: SET failed on device wlan0 ; Operation not supported.
Oct 01 10:22:10 volumio systemd[1]: Started Wireless Services.
Oct 01 10:22:10 volumio systemd[1]: Started Volumio Backend Module.
Oct 01 10:22:10 volumio systemd[1]: Started Start Volumio Kiosk.
Oct 01 10:22:10 volumio systemd[1]: Started Volumio Cpu Tweaker.
Oct 01 10:22:10 volumio systemd[1]: Started Intel SST and HDA soundcard init service.
Oct 01 10:22:10 volumio volumio-cpu-tweak[1151]: Setting RT Priority for mpd
Oct 01 10:22:10 volumio volumio-cpu-tweak[1151]: pid 35's current scheduling policy: SCHED_OTHER
Oct 01 10:22:10 volumio volumio-cpu-tweak[1151]: pid 35's current scheduling priority: 0
Oct 01 10:22:10 volumio volumio-cpu-tweak[1151]: Not enough cores to set MPD affinity
Oct 01 10:22:10 volumio volumio-cpu-tweak[1151]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance
Oct 01 10:22:10 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded.
Oct 01 10:22:10 volumio ntpd[1137]: Soliciting pool server 117.80.231.60
Oct 01 10:22:10 volumio startx[1150]: X.Org X Server 1.20.4
Oct 01 10:22:10 volumio startx[1150]: X Protocol Version 11, Revision 0
Oct 01 10:22:10 volumio startx[1150]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian
Oct 01 10:22:10 volumio startx[1150]: Current Operating System: Linux volumio 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64
Oct 01 10:22:10 volumio startx[1150]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=83125ab9-7cff-452f-8210-9223f709c04c bootpart=UUID=B95D-F2FA datapart=UUID=77928202-acf9-4f81-9fc8-5a8e5cff6a61 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no
Oct 01 10:22:10 volumio startx[1150]: Build Date: 18 December 2021 09:05:36AM
Oct 01 10:22:10 volumio startx[1150]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support)
Oct 01 10:22:10 volumio startx[1150]: Current version of pixman: 0.36.0
Oct 01 10:22:10 volumio startx[1150]: Before reporting problems, check http://wiki.x.org
Oct 01 10:22:10 volumio startx[1150]: to make sure that you have the latest version.
Oct 01 10:22:10 volumio startx[1150]: Markers: (--) probed, (**) from config file, (==) default setting,
Oct 01 10:22:10 volumio startx[1150]: (++) from command line, (!!) notice, (II) informational,
Oct 01 10:22:10 volumio startx[1150]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown.
Oct 01 10:22:10 volumio startx[1150]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue Oct 1 10:22:10 2024
Oct 01 10:22:11 volumio startx[1150]: (==) Using system config directory "/usr/share/X11/xorg.conf.d"
Oct 01 10:22:11 volumio startx[1150]: (II) [KMS] Kernel modesetting enabled.
Oct 01 10:22:11 volumio ntpd[1137]: Soliciting pool server 193.182.111.143
Oct 01 10:22:11 volumio ntpd[1137]: Soliciting pool server 202.118.1.130
Oct 01 10:22:11 volumio nmbd[963]: [2024/10/01 10:22:11.959050, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Oct 01 10:22:11 volumio systemd[1]: Started Samba NMB Daemon.
Oct 01 10:22:11 volumio nmbd[963]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Oct 01 10:22:11 volumio systemd[1]: Starting Samba Winbind Daemon...
Oct 01 10:22:12 volumio nmbd[963]: [2024/10/01 10:22:12.038490, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Oct 01 10:22:12 volumio nmbd[963]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.31.96 for name WORKGROUP<1d>.
Oct 01 10:22:12 volumio nmbd[963]: This response was from IP 192.168.31.196, reporting an IP address of 192.168.31.196.
Oct 01 10:22:12 volumio ntpd[1137]: Soliciting pool server 139.199.215.251
Oct 01 10:22:12 volumio ntpd[1137]: Soliciting pool server 116.203.151.74
Oct 01 10:22:12 volumio ntpd[1137]: Soliciting pool server 162.159.200.123
Oct 01 10:22:12 volumio winbindd[1253]: [2024/10/01 10:22:12.937826, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache)
Oct 01 10:22:12 volumio winbindd[1253]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Oct 01 10:22:12 volumio winbindd[1253]: [2024/10/01 10:22:12.963774, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Oct 01 10:22:12 volumio systemd[1]: Started Samba Winbind Daemon.
Oct 01 10:22:12 volumio winbindd[1253]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
Oct 01 10:22:12 volumio systemd[1]: Starting Samba SMB Daemon...
Oct 01 10:22:13 volumio systemd[1]: soundcard-init.service: Succeeded.
Oct 01 10:22:13 volumio ntpd[1137]: Soliciting pool server 193.182.111.142
Oct 01 10:22:13 volumio ntpd[1137]: Soliciting pool server 45.76.221.157
Oct 01 10:22:13 volumio ntpd[1137]: Soliciting pool server 193.182.111.142
Oct 01 10:22:13 volumio ntpd[1137]: Soliciting pool server 78.46.102.180
Oct 01 10:22:13 volumio smbd[1298]: [2024/10/01 10:22:13.979473, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Oct 01 10:22:13 volumio smbd[1298]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Oct 01 10:22:13 volumio systemd[1]: Started Samba SMB Daemon.
Oct 01 10:22:13 volumio systemd[1]: Reached target Multi-User System.
Oct 01 10:22:13 volumio systemd[1]: Reached target Graphical Interface.
Oct 01 10:22:14 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
Oct 01 10:22:14 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Oct 01 10:22:14 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
Oct 01 10:22:14 volumio systemd[1]: Startup finished in 10.537s (firmware) + 4.572s (loader) + 35.531s (kernel) + 24.961s (userspace) = 1min 15.602s.
Oct 01 10:22:14 volumio ntpd[1137]: Soliciting pool server 202.112.31.197
Oct 01 10:22:14 volumio ntpd[1137]: Soliciting pool server 162.159.200.1
Oct 01 10:22:14 volumio ntpd[1137]: Soliciting pool server 162.159.200.1
Oct 01 10:22:15 volumio volumio[1149]: info: -------------------------------------------
Oct 01 10:22:15 volumio volumio[1149]: info: ----- Volumio3 ----
Oct 01 10:22:15 volumio volumio[1149]: info: -------------------------------------------
Oct 01 10:22:15 volumio volumio[1149]: info: ----- System startup ----
Oct 01 10:22:15 volumio volumio[1149]: info: -------------------------------------------
Oct 01 10:22:15 volumio ntpd[1137]: Soliciting pool server 202.112.29.82
Oct 01 10:22:16 volumio ntpd[1137]: Soliciting pool server 108.59.2.24
Oct 01 10:22:17 volumio volumio-remote-updater[762]: [2024-10-01 10:22:17] [connect] Successful connection
Oct 01 10:22:17 volumio volumio[1149]: info: MYVOLUMIO Environment detected
Oct 01 10:22:17 volumio volumio[1149]: info: Plugin folders cleanup
Oct 01 10:22:17 volumio volumio[1149]: info: Scanning into folder /volumio/app/plugins/
Oct 01 10:22:17 volumio volumio[1149]: info: Scanning category audio_interface
Oct 01 10:22:17 volumio volumio[1149]: info: Scanning category miscellanea
Oct 01 10:22:17 volumio volumio[1149]: info: Scanning category music_service
Oct 01 10:22:17 volumio volumio[1149]: info: Scanning category plugins.json
Oct 01 10:22:17 volumio volumio[1149]: info: Scanning category system_controller
Oct 01 10:22:17 volumio volumio[1149]: info: Scanning category user_interface
Oct 01 10:22:17 volumio volumio[1149]: info: Scanning into folder /data/plugins/
Oct 01 10:22:17 volumio volumio[1149]: info: Scanning category music_service
Oct 01 10:22:17 volumio volumio[1149]: info: Scanning category system_controller
Oct 01 10:22:17 volumio volumio[1149]: info: Scanning category system_hardware
Oct 01 10:22:17 volumio volumio[1149]: info: Scanning category user_interface
Oct 01 10:22:17 volumio volumio[1149]: info: Plugin folders cleanup completed
Oct 01 10:22:17 volumio volumio[1149]: info: -------------------------------------------
Oct 01 10:22:17 volumio volumio[1149]: info: ----- Core plugins startup ----
Oct 01 10:22:17 volumio volumio[1149]: info: -------------------------------------------
Oct 01 10:22:17 volumio volumio[1149]: info: Loading plugins from folder /volumio/app/plugins/
Oct 01 10:22:17 volumio volumio[1149]: info: Adding plugin upnp to MyMusic Plugins
Oct 01 10:22:17 volumio volumio[1149]: info: Adding plugin airplay_emulation to MyMusic Plugins
Oct 01 10:22:17 volumio volumio[1149]: info: Adding plugin upnp_browser to MyMusic Plugins
Oct 01 10:22:17 volumio volumio[1149]: info: Loading plugins from folder /data/plugins/
Oct 01 10:22:17 volumio volumio[1149]: info: Loading plugin "system"...
Oct 01 10:22:18 volumio volumio[1149]: info: Loading plugin "appearance"...
Oct 01 10:22:18 volumio ntpd[1137]: receive: Unexpected origin timestamp 0xeaa5db5a.b1702658 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xeaa5db5a.95856e5e
Oct 01 10:22:18 volumio ntpd[1137]: receive: Unexpected origin timestamp 0xeaa5db5a.b16c34c3 does not match aorg 0000000000.00000000 from server@45.76.221.157 xmt 0xeaa5db5a.a1c71f13
Oct 01 10:22:18 volumio ntpd[1137]: receive: Unexpected origin timestamp 0xeaa5db5a.b16e2567 does not match aorg 0000000000.00000000 from server@116.203.151.74 xmt 0xeaa5db5a.a1b45e00
Oct 01 10:22:18 volumio ntpd[1137]: receive: Unexpected origin timestamp 0xeaa5db5a.b16a62e5 does not match aorg 0000000000.00000000 from server@78.46.102.180 xmt 0xeaa5db5a.a1d8f5c4
Oct 01 10:22:18 volumio ntpd[1137]: receive: Unexpected origin timestamp 0xeaa5db5a.b162a38e does not match aorg 0000000000.00000000 from server@193.182.111.142 xmt 0xeaa5db5a.9b8c525e
Oct 01 10:22:20 volumio volumio[1149]: info: Loading plugin "network"...
Oct 01 10:22:20 volumio volumio[1149]: info: Refreshing Cached IP Addresses
Oct 01 10:22:20 volumio sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 01 10:22:20 volumio sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:20 volumio sudo[1397]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:20 volumio volumio[1149]: info: Loading plugin "services"...
Oct 01 10:22:20 volumio volumio[1149]: info: Loading plugin "alsa_controller"...
Oct 01 10:22:20 volumio sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 01 10:22:20 volumio sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:20 volumio sudo[1399]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:20 volumio sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Oct 01 10:22:20 volumio sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:20 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 01 10:22:20 volumio volumio[1149]: info: Loading plugin "wizard"...
Oct 01 10:22:20 volumio volumio[1149]: info: Loading plugin "networkfs"...
Oct 01 10:22:21 volumio volumio[1149]: info: Starting Udev Watcher for removable devices
Oct 01 10:22:21 volumio volumio[1149]: info: Mounting Device 7868-8FE3
Oct 01 10:22:21 volumio sudo[1413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/7868-8FE3 -o noatime
Oct 01 10:22:21 volumio sudo[1413]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:21 volumio sudo[1413]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:21 volumio volumio[1149]: info: Mounting Device 18FFC09BE3834CA6
Oct 01 10:22:21 volumio sudo[1422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/18FFC09BE3834CA6 -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Oct 01 10:22:21 volumio sudo[1422]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:22 volumio sudo[1422]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:22 volumio ntfs-3g[1428]: Version 2017.3.23AR.3 integrated FUSE 28
Oct 01 10:22:22 volumio ntfs-3g[1428]: Mounted /dev/sda1 (Read-Write, label "", NTFS 3.1)
Oct 01 10:22:22 volumio ntfs-3g[1428]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8
Oct 01 10:22:22 volumio ntfs-3g[1428]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096
Oct 01 10:22:22 volumio ntfs-3g[1428]: Global ownership and permissions enforced, configuration type 7
Oct 01 10:22:22 volumio volumio[1149]: info: Ignoring mount for partition: boot
Oct 01 10:22:22 volumio volumio[1149]: info: Ignoring mount for partition: volumio
Oct 01 10:22:22 volumio volumio[1149]: info: Ignoring mount for partition: volumio_data
Oct 01 10:22:22 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 01 10:22:22 volumio volumio[1149]: info: Loading plugin "volumio_command_line_client"...
Oct 01 10:22:22 volumio volumio[1149]: info: Loading plugin "upnp"...
Oct 01 10:22:22 volumio volumio[1149]: info: [1727749342642] Starting Upmpd Daemon
Oct 01 10:22:22 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 01 10:22:22 volumio volumio[1149]: info: Loading plugin "my_music"...
Oct 01 10:22:22 volumio volumio[1149]: info: Loading plugin "mpd"...
Oct 01 10:22:23 volumio volumio[1149]: info: Loading plugin "upnp_browser"...
Oct 01 10:22:23 volumio systemd[1]: systemd-fsckd.service: Succeeded.
Oct 01 10:22:24 volumio volumio[1149]: info: Loading plugin "alarm-clock"...
Oct 01 10:22:24 volumio volumio[1149]: info: Plugin airplay_emulation is not enabled
Oct 01 10:22:24 volumio volumio[1149]: info: Loading plugin "last_100"...
Oct 01 10:22:24 volumio volumio[1149]: info: Loading plugin "webradio"...
Oct 01 10:22:24 volumio volumio[1149]: info: Loading plugin "i2s_dacs"...
Oct 01 10:22:24 volumio volumio[1149]: info: I2S DAC not set, start Auto-detection
Oct 01 10:22:24 volumio volumio[1149]: info: Loading plugin "volumiodiscovery"...
Oct 01 10:22:24 volumio volumio[1149]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Oct 01 10:22:24 volumio node[1149]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Oct 01 10:22:24 volumio volumio[1149]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 01 10:22:24 volumio volumio[1149]: *** WARNING *** For more information see
Oct 01 10:22:24 volumio volumio[1149]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Oct 01 10:22:24 volumio volumio[1149]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 01 10:22:24 volumio volumio[1149]: *** WARNING *** For more information see
Oct 01 10:22:24 volumio node[1149]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 01 10:22:24 volumio node[1149]: *** WARNING *** For more information see
Oct 01 10:22:24 volumio node[1149]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Oct 01 10:22:24 volumio node[1149]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 01 10:22:24 volumio node[1149]: *** WARNING *** For more information see
Oct 01 10:22:24 volumio volumio[1149]: info: Applying required configuration parameters for plugin volumiodiscovery
Oct 01 10:22:24 volumio volumio[1149]: info: Discovery: Started advertising with name: Volumio
Oct 01 10:22:24 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 01 10:22:24 volumio volumio[1149]: info: Plugin jellyfin is not enabled
Oct 01 10:22:24 volumio volumio[1149]: info: Plugin jellyfin_server is not enabled
Oct 01 10:22:24 volumio volumio[1149]: info: Plugin autostart is not enabled
Oct 01 10:22:24 volumio volumio[1149]: info: Loading plugin "now_playing"...
Oct 01 10:22:24 volumio sh[698]: timed out
Oct 01 10:22:24 volumio sh[698]: dhcpcd exited
Oct 01 10:22:24 volumio dhcpcd[746]: timed out
Oct 01 10:22:24 volumio sh[698]: ifup: failed to bring up eth0
Oct 01 10:22:24 volumio dhcpcd[746]: dhcpcd exited
Oct 01 10:22:24 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE
Oct 01 10:22:24 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'.
Oct 01 10:22:25 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
Oct 01 10:22:28 volumio volumio[1149]: info: Loading plugin "peppy_screensaver"...
Oct 01 10:22:29 volumio volumio[1149]: info: Applying required configuration parameters for plugin peppy_screensaver
Oct 01 10:22:29 volumio volumio[1149]: info: Loading plugin "outputs"...
Oct 01 10:22:29 volumio volumio[1149]: info: Loading plugin "albumart"...
Oct 01 10:22:29 volumio volumio[1149]: info: Plugin example_plugin is not enabled
Oct 01 10:22:29 volumio volumio[1149]: info: Loading plugin "inputs"...
Oct 01 10:22:29 volumio volumio[1149]: info: Loading plugin "updater_comm"...
Oct 01 10:22:29 volumio volumio[1149]: Forking 1 albumart workers
Oct 01 10:22:29 volumio volumio[1149]: info: Plugin mpdemulation is not enabled
Oct 01 10:22:29 volumio volumio[1149]: info: Loading plugin "rest_api"...
Oct 01 10:22:30 volumio volumio[1149]: info: Loading plugin "websocket"...
Oct 01 10:22:30 volumio volumio[1149]: info: Starting Socket.io Server version 2.3.0
Oct 01 10:22:30 volumio volumio[1149]: info: Plugin RoonBridge is not enabled
Oct 01 10:22:30 volumio volumio[1149]: info: Plugin lms is not enabled
Oct 01 10:22:30 volumio volumio[1149]: info: Loading plugin "minidlna"...
Oct 01 10:22:30 volumio volumio[1149]: info: Applying required configuration parameters for plugin minidlna
Oct 01 10:22:30 volumio sudo[1402]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:30 volumio volumio[1149]: info: Loading plugin "backup_restore"...
Oct 01 10:22:31 volumio volumio[1149]: Starting albumart workers
Oct 01 10:22:31 volumio volumio[1149]: info: Applying required configuration parameters for plugin backup_restore
Oct 01 10:22:31 volumio volumio[1149]: info: Loading plugin "music_services_shield"...
Oct 01 10:22:31 volumio volumio[1149]: info: Applying required configuration parameters for plugin music_services_shield
Oct 01 10:22:31 volumio volumio[1149]: info: Plugin onkyo_control is not enabled
Oct 01 10:22:31 volumio volumio[1149]: info: Plugin Systeminfo is not enabled
Oct 01 10:22:32 volumio volumio[1149]: info: Loading i18n strings for locale zh
Oct 01 10:22:32 volumio volumio[1149]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
Oct 01 10:22:32 volumio volumio[1149]: Updating browse sources language
Oct 01 10:22:32 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 01 10:22:32 volumio volumio-remote-updater[762]: [2024-10-01 10:22:32] [connect] Successful connection
Oct 01 10:22:32 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 01 10:22:32 volumio volumio[1149]: info: CoreCommandRouter::initPlayerControls
Oct 01 10:22:32 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:32 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:32 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:32 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 01 10:22:32 volumio volumio[1149]: Express server listening on port 3000
Oct 01 10:22:32 volumio volumio[1149]: [Metrics] WebUI: 18s 246.41ms
Oct 01 10:22:32 volumio volumio[1149]: info: Setting Device type: x86
Oct 01 10:22:32 volumio volumio[1149]: info: CoreStateMachine::resetVolumioState
Oct 01 10:22:32 volumio volumio[1149]: info: CoreStateMachine::getcurrentVolume
Oct 01 10:22:32 volumio volumio[1149]: info: CoreCommandRouter::volumioRetrievevolume
Oct 01 10:22:32 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 01 10:22:32 volumio volumio[1149]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
Oct 01 10:22:32 volumio volumio[1149]: info: Completed loading Core Plugins
Oct 01 10:22:32 volumio volumio[1149]: info: Preparing to generate the ALSA configuration file
Oct 01 10:22:32 volumio volumio[1149]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
Oct 01 10:22:32 volumio volumio[1149]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf
Oct 01 10:22:32 volumio volumio[1149]: info: Reading ALSA contributions from plugins.
Oct 01 10:22:32 volumio volumio[1149]: verbose: New Socket.io Connection to 192.168.31.96 from 192.168.31.21 UA: Mozilla/5.0 (Linux; Android 13; SM-G988N Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/128.0.6613.146 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1
Oct 01 10:22:32 volumio volumio-remote-updater[762]: [2024-10-01 10:22:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1727749352 101
Oct 01 10:22:32 volumio volumio[1149]: 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: 2
Oct 01 10:22:32 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:22:32 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:32 volumio volumio[1149]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Oct 01 10:22:32 volumio sudo[1544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 01 10:22:32 volumio sudo[1544]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:32 volumio sudo[1544]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:32 volumio sudo[1547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 01 10:22:32 volumio sudo[1547]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:32 volumio sudo[1547]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:32 volumio volumio[1149]: info: Reloading queue from file
Oct 01 10:22:32 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 01 10:22:33 volumio volumio[1149]: info: VolumeController:: Volume=82 Mute =false
Oct 01 10:22:33 volumio volumio[1149]: info: CoreStateMachine::pushState
Oct 01 10:22:33 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::volumioPushState
Oct 01 10:22:33 volumio volumio[1149]: info: CoreStateMachine::updateTrackBlock
Oct 01 10:22:33 volumio volumio[1149]: info: CorePlayQueue::getTrackBlock
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::volumioRetrievevolume
Oct 01 10:22:33 volumio volumio[1149]: info: CoreStateMachine::setRepeat true single undefined
Oct 01 10:22:33 volumio volumio[1149]: info: CoreStateMachine::pushState
Oct 01 10:22:33 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::volumioPushState
Oct 01 10:22:33 volumio volumio[1149]: info: CoreStateMachine::setRandom true
Oct 01 10:22:33 volumio volumio[1149]: info: CoreStateMachine::pushState
Oct 01 10:22:33 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::volumioPushState
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:22:33 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 01 10:22:33 volumio volumio[1149]: info: Received Get System Info
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 01 10:22:33 volumio volumio[1149]: info: Discovery: Getting this device information
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:22:33 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:22:33 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:33 volumio volumio[1149]: info: Listing playlists
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::volumioGetQueue
Oct 01 10:22:33 volumio volumio[1149]: info: CoreStateMachine::getQueue
Oct 01 10:22:33 volumio volumio[1149]: info: CorePlayQueue::getQueue
Oct 01 10:22:33 volumio volumio[1149]: info: Discovery: adding 492524ff-0d18-4e9b-b0b7-e00a3046faf7
Oct 01 10:22:33 volumio volumio[1149]: info: Discovery: Found device Volumio
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:22:33 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:33 volumio volumio[1149]: info: VolumeController:: Volume=82 Mute =false
Oct 01 10:22:33 volumio volumio[1149]: info: CoreStateMachine::pushState
Oct 01 10:22:33 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::volumioPushState
Oct 01 10:22:33 volumio volumio[1149]: info: Asound.conf file unchanged, so no further update is needed
Oct 01 10:22:33 volumio volumio[1149]: info: Output device has changed, restarting MPD
Oct 01 10:22:33 volumio sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 01 10:22:33 volumio sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:33 volumio volumio[1149]: info: ___________ START PLUGINS ___________
Oct 01 10:22:33 volumio sudo[1557]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:33 volumio volumio[1149]: info: ControllerMpd::onStart: Initializing MPD
Oct 01 10:22:33 volumio volumio[1149]: info: Creating MPD Configuration file
Oct 01 10:22:33 volumio sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 01 10:22:33 volumio sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:33 volumio systemd[1]: musicservicesshield.service: Succeeded.
Oct 01 10:22:33 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set.
Oct 01 10:22:33 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set...
Oct 01 10:22:33 volumio systemd[1]: Listening on mpd.socket.
Oct 01 10:22:33 volumio systemd[1]: Starting Music Player Daemon...
Oct 01 10:22:33 volumio sudo[1564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 01 10:22:33 volumio sudo[1564]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 01 10:22:33 volumio sudo[1564]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 01 10:22:33 volumio volumio[1149]: info: [1727749353906] CoreMusicLibrary::Adding element 媒体服务器
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 01 10:22:33 volumio sudo[1568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 01 10:22:33 volumio sudo[1568]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:33 volumio volumio[1149]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 01 10:22:34 volumio volumio[1149]: info: [1727749354021] CoreMusicLibrary::Adding element Last_100
Oct 01 10:22:34 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 01 10:22:34 volumio volumio[1149]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 01 10:22:34 volumio volumio[1149]: info: [1727749354024] CoreMusicLibrary::Adding element Webradio
Oct 01 10:22:34 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 01 10:22:34 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 01 10:22:34 volumio volumio[1149]: info: Initializing BBC Radios
Oct 01 10:22:34 volumio sudo[1565]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 01 10:22:34 volumio sudo[1565]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:34 volumio sudo[1565]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:34 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Oct 01 10:22:34 volumio systemd[1]: mpd.service: Succeeded.
Oct 01 10:22:34 volumio systemd[1]: Stopped Music Player Daemon.
Oct 01 10:22:34 volumio systemd[1]: Starting Music Player Daemon...
Oct 01 10:22:34 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 01 10:22:34 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 01 10:22:34 volumio sudo[1571]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 01 10:22:34 volumio sudo[1571]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:34 volumio sudo[1571]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Oct 01 10:22:34 volumio sudo[1571]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:34 volumio volumio[1149]: info: [now-playing] ConfigUpdater: config is up to date.
Oct 01 10:22:34 volumio volumio[1149]: info: Loading i18n strings for locale zh
Oct 01 10:22:34 volumio volumio[1149]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
Oct 01 10:22:34 volumio volumio[1149]: info: /tmp/myfifo created
Oct 01 10:22:34 volumio volumio[1149]: info: /tmp/myfifosa created
Oct 01 10:22:34 volumio sudo[1589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0
Oct 01 10:22:34 volumio sudo[1589]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:34 volumio sudo[1589]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:34 volumio volumio[1149]: info: snd-dummy loaded
Oct 01 10:22:34 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 01 10:22:35 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:35 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:35 volumio volumio[1149]: info: Loading i18n strings for locale zh
Oct 01 10:22:35 volumio volumio[1149]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
Oct 01 10:22:36 volumio volumio[1149]: info: Volumio Calling Home
Oct 01 10:22:37 volumio sudo[1633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh
Oct 01 10:22:37 volumio sudo[1633]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:37 volumio volumio[1149]: info: [now-playing] App is listening on port 4004.
Oct 01 10:22:37 volumio volumio[1149]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds
Oct 01 10:22:38 volumio sudo[1650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Oct 01 10:22:38 volumio sudo[1650]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:38 volumio volumio[1149]: info: minidlna: Found miniDLNA version 1.2.1
Oct 01 10:22:38 volumio volumio[1149]: info: minidlna: Starting minidlna.service
Oct 01 10:22:38 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Oct 01 10:22:38 volumio sudo[1650]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:38 volumio volumio[1149]: info: MPD Permissions set
Oct 01 10:22:38 volumio volumio[1149]: info: MPD Permissions set
Oct 01 10:22:38 volumio mpd[1583]: Oct 01 10:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 01 10:22:38 volumio sudo[1654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service
Oct 01 10:22:38 volumio sudo[1654]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:38 volumio volumio[1149]: info: Upmpdcli Daemon Started
Oct 01 10:22:38 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server.
Oct 01 10:22:38 volumio sudo[1654]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:39 volumio systemd[1]: Started Music Player Daemon.
Oct 01 10:22:39 volumio sudo[1561]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:39 volumio sudo[1568]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:39 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set...
Oct 01 10:22:39 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:22:39 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:39 volumio volumio[1149]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Oct 01 10:22:39 volumio wpa_supplicant[1047]: wlan0: CTRL-EVENT-BEACON-LOSS
Oct 01 10:22:39 volumio systemd[1]: minidlna.service: Succeeded.
Oct 01 10:22:39 volumio sudo[1673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl
Oct 01 10:22:39 volumio sudo[1673]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:39 volumio volumio[1149]: info: minidlna: systemctl start minidlna.service succeeded.
Oct 01 10:22:39 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:39 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:39 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:39 volumio sudo[1673]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:39 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 01 10:22:39 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 01 10:22:40 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:40 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:40 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:40 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:40 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:40 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:40 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:40 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:40 volumio volumio[1662]: cset: --> shielding system active with
Oct 01 10:22:40 volumio volumio[1662]: cset: "system" cpuset of CPUSPEC(1) with 184 tasks running
Oct 01 10:22:40 volumio volumio[1662]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running
Oct 01 10:22:40 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set.
Oct 01 10:22:40 volumio sudo[1633]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:40 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 01 10:22:40 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 01 10:22:40 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:40 volumio volumio[1149]: info: Completed starting Core Plugins
Oct 01 10:22:40 volumio volumio[1149]: info: -------------------------------------------
Oct 01 10:22:40 volumio volumio[1149]: info: ----- MyVolumio plugins startup ----
Oct 01 10:22:40 volumio volumio[1149]: info: -------------------------------------------
Oct 01 10:22:40 volumio volumio[1149]: info: [MyVolumio PluginManager] Fetching plans data....
Oct 01 10:22:40 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile
Oct 01 10:22:41 volumio sudo[1723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 01 10:22:41 volumio sudo[1717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh
Oct 01 10:22:41 volumio sudo[1717]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:41 volumio sudo[1723]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:41 volumio sudo[1723]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:41 volumio systemd[1]: Reloading.
Oct 01 10:22:41 volumio volumio[1149]: error: MPD error: The expression evaluated to a falsy value:
Oct 01 10:22:41 volumio volumio[1149]: assert.ok(self.idling)
Oct 01 10:22:41 volumio volumio[1149]: error: The expression evaluated to a falsy value:
Oct 01 10:22:41 volumio volumio[1149]: assert.ok(self.idling)
Oct 01 10:22:41 volumio volumio[1149]: info: MPD running with PID1583
Oct 01 10:22:41 volumio volumio[1149]: ,establishing connection
Oct 01 10:22:41 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Oct 01 10:22:41 volumio volumio[1149]: error: updateQueue error: null
Oct 01 10:22:41 volumio volumio[1149]: info: MPD Permissions set
Oct 01 10:22:41 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:41 volumio sudo[1765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 01 10:22:41 volumio sudo[1765]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:41 volumio dhcpcd[1138]: wlan0: carrier lost
Oct 01 10:22:42 volumio wpa_supplicant[1047]: wlan0: CTRL-EVENT-DISCONNECTED bssid=88:c3:97:32:0a:ef reason=4 locally_generated=1
Oct 01 10:22:42 volumio wpa_supplicant[1047]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Oct 01 10:22:42 volumio wpa_supplicant[1047]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN
Oct 01 10:22:42 volumio avahi-daemon[775]: Withdrawing address record for 192.168.31.96 on wlan0.
Oct 01 10:22:42 volumio avahi-daemon[775]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.96.
Oct 01 10:22:42 volumio avahi-daemon[775]: Interface wlan0.IPv4 no longer relevant for mDNS.
Oct 01 10:22:42 volumio volumio[1652]: Generating RSA private key, 4096 bit long modulus (2 primes)
Oct 01 10:22:42 volumio dhcpcd[1138]: wlan0: deleting route to 192.168.31.0/24
Oct 01 10:22:42 volumio dhcpcd[1138]: wlan0: deleting default route via 192.168.31.1
Oct 01 10:22:42 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 01 10:22:42 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 01 10:22:42 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:22:42 volumio wpa_supplicant[1047]: wlan0: SME: Trying to authenticate with 88:c3:97:32:0a:f0 (SSID='Xiaomi_4272' freq=2437 MHz)
Oct 01 10:22:42 volumio kernel: wlan0: authenticate with 88:c3:97:32:0a:f0
Oct 01 10:22:42 volumio kernel: wlan0: 80 MHz not supported, disabling VHT
Oct 01 10:22:42 volumio volumio[1149]: error: updateQueue error: null
Oct 01 10:22:42 volumio volumio[1149]: info: Discovery: A device disappeared from network
Oct 01 10:22:42 volumio volumio[1149]: info: Discovery: Device volumio disappeared from network
Oct 01 10:22:42 volumio kernel: wlan0: send auth to 88:c3:97:32:0a:f0 (try 1/3)
Oct 01 10:22:42 volumio wpa_supplicant[1047]: wlan0: Trying to associate with 88:c3:97:32:0a:f0 (SSID='Xiaomi_4272' freq=2437 MHz)
Oct 01 10:22:42 volumio kernel: wlan0: authenticated
Oct 01 10:22:42 volumio kernel: wlan0: associate with 88:c3:97:32:0a:f0 (try 1/3)
Oct 01 10:22:42 volumio wpa_supplicant[1047]: wlan0: Associated with 88:c3:97:32:0a:f0
Oct 01 10:22:42 volumio wpa_supplicant[1047]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Oct 01 10:22:42 volumio wpa_supplicant[1047]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN
Oct 01 10:22:42 volumio kernel: wlan0: RX AssocResp from 88:c3:97:32:0a:f0 (capab=0x1031 status=0 aid=5)
Oct 01 10:22:42 volumio kernel: wlan0: associated
Oct 01 10:22:42 volumio wpa_supplicant[1047]: wlan0: WPA: Key negotiation completed with 88:c3:97:32:0a:f0 [PTK=CCMP GTK=TKIP]
Oct 01 10:22:42 volumio wpa_supplicant[1047]: wlan0: CTRL-EVENT-CONNECTED - Connection to 88:c3:97:32:0a:f0 completed [id=0 id_str=]
Oct 01 10:22:42 volumio dhcpcd[1138]: wlan0: carrier acquired
Oct 01 10:22:42 volumio dhcpcd[1138]: wlan0: IAID 10:69:fa:ce
Oct 01 10:22:42 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 88:c3:97:32:0a:f0
Oct 01 10:22:42 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly.
Oct 01 10:22:43 volumio dhcpcd[1138]: wlan0: rebinding lease of 192.168.31.96
Oct 01 10:22:43 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:22:43 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:43 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed.
Oct 01 10:22:43 volumio dhcpcd[1138]: wlan0: soliciting an IPv6 router
Oct 01 10:22:43 volumio sudo[1717]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:43 volumio volumio[1149]: info: succeeded
Oct 01 10:22:43 volumio systemd[1]: musicservicesshield.service: Succeeded.
Oct 01 10:22:43 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set.
Oct 01 10:22:43 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set...
Oct 01 10:22:43 volumio systemd[1]: Stopping Music Player Daemon...
Oct 01 10:22:43 volumio systemd[1]: mpd.service: Succeeded.
Oct 01 10:22:43 volumio systemd[1]: Stopped Music Player Daemon.
Oct 01 10:22:43 volumio systemd[1]: Starting Music Player Daemon...
Oct 01 10:22:43 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:22:43 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:44 volumio sudo[1881]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 01 10:22:44 volumio sudo[1881]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:22:44 volumio sudo[1881]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:44 volumio ntpd[1137]: Deleting interface #3 wlan0, 192.168.31.96#123, interface stats: received=43, sent=58, dropped=0, active_time=35 secs
Oct 01 10:22:44 volumio ntpd[1137]: 108.59.2.24 local addr 192.168.31.96 ->
Oct 01 10:22:44 volumio ntpd[1137]: 202.112.29.82 local addr 192.168.31.96 ->
Oct 01 10:22:44 volumio ntpd[1137]: 202.112.31.197 local addr 192.168.31.96 ->
Oct 01 10:22:44 volumio ntpd[1137]: 193.182.111.142 local addr 192.168.31.96 ->
Oct 01 10:22:44 volumio ntpd[1137]: 78.46.102.180 local addr 192.168.31.96 ->
Oct 01 10:22:44 volumio ntpd[1137]: 45.76.221.157 local addr 192.168.31.96 ->
Oct 01 10:22:44 volumio ntpd[1137]: 116.203.151.74 local addr 192.168.31.96 ->
Oct 01 10:22:44 volumio ntpd[1137]: 162.159.200.123 local addr 192.168.31.96 ->
Oct 01 10:22:44 volumio ntpd[1137]: 139.199.215.251 local addr 192.168.31.96 ->
Oct 01 10:22:44 volumio ntpd[1137]: 193.182.111.143 local addr 192.168.31.96 ->
Oct 01 10:22:44 volumio ntpd[1137]: 202.118.1.130 local addr 192.168.31.96 ->
Oct 01 10:22:44 volumio ntpd[1137]: 117.80.231.60 local addr 192.168.31.96 ->
Oct 01 10:22:46 volumio volumio[1149]: error: MPD error: Error: This socket has been ended by the other party
Oct 01 10:22:46 volumio volumio[1149]: error: This socket has been ended by the other party {"code":"EPIPE"}
Oct 01 10:22:46 volumio volumio[1149]: error: MPD error: Error: This socket has been ended by the other party
Oct 01 10:22:46 volumio volumio[1149]: error: This socket has been ended by the other party {"code":"EPIPE"}
Oct 01 10:22:46 volumio volumio[1149]: error: MPD error: Error: This socket has been ended by the other party
Oct 01 10:22:46 volumio volumio[1149]: error: This socket has been ended by the other party {"code":"EPIPE"}
Oct 01 10:22:46 volumio volumio[1149]: error: MPD error: Error: This socket has been ended by the other party
Oct 01 10:22:46 volumio volumio[1149]: error: This socket has been ended by the other party {"code":"EPIPE"}
Oct 01 10:22:46 volumio volumio[1149]: error: MPD error: Error: This socket has been ended by the other party
Oct 01 10:22:46 volumio volumio[1149]: error: This socket has been ended by the other party {"code":"EPIPE"}
Oct 01 10:22:46 volumio volumio[1149]: error: MPD error: Error: This socket has been ended by the other party
Oct 01 10:22:46 volumio volumio[1149]: error: This socket has been ended by the other party {"code":"EPIPE"}
Oct 01 10:22:46 volumio volumio[1149]: error: MPD error: Error: This socket has been ended by the other party
Oct 01 10:22:46 volumio volumio[1149]: error: This socket has been ended by the other party {"code":"EPIPE"}
Oct 01 10:22:46 volumio volumio[1149]: error: MPD error: Error: This socket has been ended by the other party
Oct 01 10:22:46 volumio volumio[1149]: error: This socket has been ended by the other party {"code":"EPIPE"}
Oct 01 10:22:46 volumio volumio[1149]: error: MPD error: Error: This socket has been ended by the other party
Oct 01 10:22:46 volumio volumio[1149]: error: This socket has been ended by the other party {"code":"EPIPE"}
Oct 01 10:22:47 volumio mpd[1900]: Oct 01 10:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 01 10:22:47 volumio systemd[1]: Started Music Player Daemon.
Oct 01 10:22:47 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set...
Oct 01 10:22:47 volumio volumio[1149]: error: updateQueue error: null
Oct 01 10:22:47 volumio sudo[1765]: pam_unix(sudo:session): session closed for user root
Oct 01 10:22:48 volumio dhcpcd[1138]: wlan0: probing for an IPv4LL address
Oct 01 10:22:48 volumio dhcpcd[1138]: wlan0: DHCP lease expired
Oct 01 10:22:48 volumio dhcpcd[1138]: wlan0: soliciting a DHCP lease
Oct 01 10:22:48 volumio dhcpcd[1138]: wlan0: offered 192.168.31.96 from 192.168.31.1
Oct 01 10:22:48 volumio volumio[1652]: ......................................................................................................++++
Oct 01 10:22:48 volumio volumio[1959]: cset: --> shielding system active with
Oct 01 10:22:48 volumio volumio[1652]: ...++++
Oct 01 10:22:48 volumio volumio[1652]: e is 65537 (0x010001)
Oct 01 10:22:48 volumio volumio[1959]: cset: "system" cpuset of CPUSPEC(1) with 185 tasks running
Oct 01 10:22:48 volumio volumio[1959]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running
Oct 01 10:22:48 volumio volumio[1652]: writing RSA key
Oct 01 10:22:48 volumio dhcpcd[1138]: wlan0: probing address 192.168.31.96/24
Oct 01 10:22:48 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set.
Oct 01 10:22:48 volumio volumio[1149]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Oct 01 10:22:50 volumio volumio[1149]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Oct 01 10:22:51 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 01 10:22:51 volumio volumio[1149]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 01 10:22:51 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 01 10:22:51 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:22:51 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:51 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 01 10:22:51 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 01 10:22:51 volumio volumio[1149]: info: Received Get System Info
Oct 01 10:22:51 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 01 10:22:51 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 01 10:22:51 volumio volumio[1149]: info: Discovery: Getting this device information
Oct 01 10:22:51 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:22:51 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:51 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 01 10:22:51 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:22:51 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:51 volumio volumio[1149]: info: Listing playlists
Oct 01 10:22:51 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:22:51 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:51 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Oct 01 10:22:51 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 01 10:22:52 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 01 10:22:52 volumio volumio[1149]: info: Received Get System Info
Oct 01 10:22:52 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 01 10:22:52 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 01 10:22:52 volumio volumio[1149]: info: Discovery: Getting this device information
Oct 01 10:22:52 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:22:52 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:52 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 01 10:22:52 volumio dhcpcd[1138]: wlan0: using IPv4LL address 169.254.180.244
Oct 01 10:22:52 volumio dhcpcd[1138]: wlan0: adding route to 169.254.0.0/16
Oct 01 10:22:52 volumio dhcpcd[1138]: wlan0: adding default route
Oct 01 10:22:52 volumio avahi-daemon[775]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.180.244.
Oct 01 10:22:52 volumio avahi-daemon[775]: New relevant interface wlan0.IPv4 for mDNS.
Oct 01 10:22:52 volumio avahi-daemon[775]: Registering new address record for 169.254.180.244 on wlan0.IPv4.
Oct 01 10:22:53 volumio dhcpcd[1138]: wlan0: leased 192.168.31.96 for 43200 seconds
Oct 01 10:22:53 volumio avahi-daemon[775]: Registering new address record for 192.168.31.96 on wlan0.IPv4.
Oct 01 10:22:53 volumio dhcpcd[1138]: wlan0: adding route to 192.168.31.0/24
Oct 01 10:22:53 volumio dhcpcd[1138]: wlan0: changing default route via 192.168.31.1
Oct 01 10:22:54 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Oct 01 10:22:54 volumio systemd[1]: Stopping Network Time Service...
Oct 01 10:22:54 volumio ntpd[1137]: ntpd exiting on signal 15 (Terminated)
Oct 01 10:22:54 volumio systemd[1]: ntp.service: Succeeded.
Oct 01 10:22:54 volumio systemd[1]: Stopped Network Time Service.
Oct 01 10:22:54 volumio systemd[1]: Starting Network Time Service...
Oct 01 10:22:54 volumio ntpd[2026]: ntpd 4.2.8p12@1.3728-o (1): Starting
Oct 01 10:22:54 volumio ntpd[2026]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103
Oct 01 10:22:54 volumio systemd[1]: Started Network Time Service.
Oct 01 10:22:54 volumio dhcpcd[1138]: wlan0: deleting route to 169.254.0.0/16
Oct 01 10:22:54 volumio avahi-daemon[775]: Withdrawing address record for 169.254.180.244 on wlan0.
Oct 01 10:22:54 volumio avahi-daemon[775]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.180.244.
Oct 01 10:22:54 volumio avahi-daemon[775]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.96.
Oct 01 10:22:54 volumio ntpd[2032]: proto: precision = 0.162 usec (-22)
Oct 01 10:22:54 volumio ntpd[2032]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
Oct 01 10:22:54 volumio ntpd[2032]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
Oct 01 10:22:54 volumio ntpd[2032]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 644 days ago
Oct 01 10:22:54 volumio ntpd[2032]: Listen and drop on 0 v6wildcard [::]:123
Oct 01 10:22:54 volumio ntpd[2032]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Oct 01 10:22:54 volumio ntpd[2032]: Listen normally on 2 lo 127.0.0.1:123
Oct 01 10:22:54 volumio ntpd[2032]: Listen normally on 3 wlan0 192.168.31.96:123
Oct 01 10:22:54 volumio ntpd[2032]: Listening on routing socket on fd #20 for interface updates
Oct 01 10:22:54 volumio ntpd[2032]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Oct 01 10:22:54 volumio ntpd[2032]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Oct 01 10:22:55 volumio ntpd[2032]: Soliciting pool server 116.203.151.74
Oct 01 10:22:55 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:22:55 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:22:56 volumio ntpd[2032]: Soliciting pool server 193.182.111.142
Oct 01 10:22:56 volumio ntpd[2032]: Soliciting pool server 139.199.214.202
Oct 01 10:22:57 volumio ntpd[2032]: Soliciting pool server 84.16.73.33
Oct 01 10:22:57 volumio ntpd[2032]: Soliciting pool server 193.182.111.143
Oct 01 10:22:57 volumio ntpd[2032]: Soliciting pool server 108.59.2.24
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Oct 01 10:22:58 volumio volumio[1149]: info: Adding plugin bluetooth to MyMusic Plugins
Oct 01 10:22:58 volumio volumio[1149]: info: Adding plugin multiroom to MyMusic Plugins
Oct 01 10:22:58 volumio volumio[1149]: info: Adding plugin metavolumio to MyMusic Plugins
Oct 01 10:22:58 volumio volumio[1149]: info: Adding plugin cd_controller to MyMusic Plugins
Oct 01 10:22:58 volumio volumio[1149]: info: Adding plugin smart_inputs to MyMusic Plugins
Oct 01 10:22:58 volumio volumio[1149]: info: Adding plugin tidalconnect to MyMusic Plugins
Oct 01 10:22:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Oct 01 10:22:58 volumio ntpd[2032]: Soliciting pool server 193.182.111.141
Oct 01 10:22:58 volumio ntpd[2032]: Soliciting pool server 193.182.111.12
Oct 01 10:22:58 volumio ntpd[2032]: Soliciting pool server 117.80.112.205
Oct 01 10:22:58 volumio ntpd[2032]: Soliciting pool server 78.46.102.180
Oct 01 10:22:59 volumio ntpd[2032]: Soliciting pool server 202.112.29.82
Oct 01 10:22:59 volumio ntpd[2032]: Soliciting pool server 84.16.67.12
Oct 01 10:22:59 volumio ntpd[2032]: Soliciting pool server 84.16.67.12
Oct 01 10:22:59 volumio volumio[1149]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Oct 01 10:22:59 volumio volumio[1149]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Oct 01 10:22:59 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 01 10:22:59 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 01 10:22:59 volumio volumio[1149]: info: Starting MyVolumio Remote Streaming Endpoints
Oct 01 10:22:59 volumio volumio[1149]: info: MyVolumio login type: Token
Oct 01 10:22:59 volumio volumio[1149]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Oct 01 10:22:59 volumio volumio[1149]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Oct 01 10:23:00 volumio ntpd[2032]: Soliciting pool server 202.112.31.197
Oct 01 10:23:00 volumio ntpd[2032]: Soliciting pool server 2001:da8:215:4020::118:123
Oct 01 10:23:00 volumio volumio[1149]: info: Starting Streaming Service Transparent Proxy
Oct 01 10:23:00 volumio volumio[1149]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Oct 01 10:23:00 volumio volumio[1149]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Oct 01 10:23:00 volumio volumio[1149]: info: Streaming services startup
Oct 01 10:23:00 volumio volumio[1149]: info: Starting Streaming Daemon
Oct 01 10:23:00 volumio sudo[2107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Oct 01 10:23:00 volumio sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:23:00 volumio sudo[2107]: pam_unix(sudo:session): session closed for user root
Oct 01 10:23:00 volumio volumio[1149]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Oct 01 10:23:00 volumio volumio[1149]: info: Discovery: adding 492524ff-0d18-4e9b-b0b7-e00a3046faf7
Oct 01 10:23:00 volumio volumio[1149]: info: Discovery: Found device Volumio
Oct 01 10:23:00 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:23:00 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:23:00 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Oct 01 10:23:00 volumio volumio[1149]: error: Cannot start Volumio Streaming Daemon
Oct 01 10:23:00 volumio volumio[1149]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Oct 01 10:23:00 volumio volumio[1149]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Oct 01 10:23:01 volumio volumio[1149]: STREAMING PROXY: Starting server on port 3245
Oct 01 10:23:01 volumio volumio[1149]: Node JS runtime: 14
Oct 01 10:23:05 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 01 10:23:05 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 01 10:23:05 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 01 10:23:05 volumio volumio-remote-updater[762]: No test mode
Oct 01 10:23:05 volumio volumio-remote-updater[762]: No alpha test mode
Oct 01 10:23:05 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Oct 01 10:23:06 volumio volumio[1149]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Improved translations
- Fix for Wizard not showing on second start
- Fix for Playback Queue not advancing
NEW ADDITIONS
- Add DSD Quirk for Marantz SA-12 on Rivo and Primo v2
","title":"Update v3.757","updateavailable":true}
Oct 01 10:23:06 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Oct 01 10:23:06 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:23:06 volumio volumio[1149]: info: CorePlayQueue::getTrack 0
Oct 01 10:23:08 volumio volumio[1149]: info: Volumio called home
Oct 01 10:23:12 volumio volumio[1149]: info: Preload queue cleared
Oct 01 10:23:12 volumio volumio[1149]: info: CoreCommandRouter::volumioReplaceandPlayItems
Oct 01 10:23:12 volumio volumio[1149]: info: CoreStateMachine::ClearQueue
Oct 01 10:23:12 volumio volumio[1149]: info: CoreStateMachine::stop
Oct 01 10:23:12 volumio volumio[1149]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 01 10:23:12 volumio volumio[1149]: info: CorePlayQueue::clearPlayQueue
Oct 01 10:23:12 volumio volumio[1149]: info: CorePlayQueue::saveQueue
Oct 01 10:23:12 volumio volumio[1149]: info: CoreCommandRouter::volumioPushQueue
Oct 01 10:23:12 volumio volumio[1149]: info: CoreStateMachine::addQueueItems
Oct 01 10:23:12 volumio volumio[1149]: info: CorePlayQueue::addQueueItems
Oct 01 10:23:12 volumio volumio[1149]: info: Preload queue cleared
Oct 01 10:23:12 volumio volumio[1149]: info: Adding Item to queue: music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Manha De Carnival.flac
Oct 01 10:23:12 volumio volumio[1149]: info: Exploding uri music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Manha De Carnival.flac in service mpd
Oct 01 10:23:13 volumio volumio[1149]: info: Adding Item to queue: music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Always In My Heart.flac
Oct 01 10:23:13 volumio volumio[1149]: info: Exploding uri music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Always In My Heart.flac in service mpd
Oct 01 10:23:13 volumio volumio[1149]: info: Adding Item to queue: music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Maria Elena.flac
Oct 01 10:23:13 volumio volumio[1149]: info: Exploding uri music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Maria Elena.flac in service mpd
Oct 01 10:23:13 volumio volumio[1149]: info: Adding Item to queue: music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Historia De Un Amor.flac
Oct 01 10:23:13 volumio volumio[1149]: info: Exploding uri music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Historia De Un Amor.flac in service mpd
Oct 01 10:23:13 volumio volumio[1149]: info: Adding Item to queue: music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - La Mer.flac
Oct 01 10:23:13 volumio volumio[1149]: info: Exploding uri music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - La Mer.flac in service mpd
Oct 01 10:23:13 volumio volumio[1149]: info: Adding Item to queue: music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - More.flac
Oct 01 10:23:13 volumio volumio[1149]: info: Exploding uri music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - More.flac in service mpd
Oct 01 10:23:13 volumio volumio[1149]: info: Adding Item to queue: music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Autumn Leaves.flac
Oct 01 10:23:13 volumio volumio[1149]: info: Exploding uri music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Autumn Leaves.flac in service mpd
Oct 01 10:23:13 volumio volumio[1149]: info: Adding Item to queue: music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Softly As I Leave You.flac
Oct 01 10:23:13 volumio volumio[1149]: info: Exploding uri music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Softly As I Leave You.flac in service mpd
Oct 01 10:23:13 volumio volumio[1149]: info: Adding Item to queue: music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - One Note Samba.flac
Oct 01 10:23:13 volumio volumio[1149]: info: Exploding uri music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - One Note Samba.flac in service mpd
Oct 01 10:23:13 volumio volumio[1149]: info: Adding Item to queue: music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Guantanamera.flac
Oct 01 10:23:13 volumio volumio[1149]: info: Exploding uri music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Guantanamera.flac in service mpd
Oct 01 10:23:13 volumio volumio[1149]: info: Adding Item to queue: music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - La Cumparsita.flac
Oct 01 10:23:13 volumio volumio[1149]: info: Exploding uri music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - La Cumparsita.flac in service mpd
Oct 01 10:23:13 volumio volumio[1149]: info: Adding Item to queue: music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - The Girl From Ipanema.flac
Oct 01 10:23:13 volumio volumio[1149]: info: Exploding uri music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - The Girl From Ipanema.flac in service mpd
Oct 01 10:23:13 volumio volumio[1149]: info: Adding Item to queue: music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Night And Day.flac
Oct 01 10:23:13 volumio volumio[1149]: info: Exploding uri music-library/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Night And Day.flac in service mpd
Oct 01 10:23:13 volumio volumio[1149]: info: ALBUMART /albumart?cacheid=851&web=Various%20Artists/Greatest%20LATIN%20Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F18FFC09BE3834CA6%2Fmusic%2F%E5%8F%91%E7%83%A7%E8%AF%95%E9%9F%B3%2FGreatest%20LATIN%20Love%20Songs%2FVarious%20Artists%20-%20Manha%20De%20Carnival.flac&metadata=false
Oct 01 10:23:13 volumio volumio[1149]: info: URI /mnt/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Manha De Carnival.flac
Oct 01 10:23:13 volumio volumio[1149]: info: ALBUMART /albumart?cacheid=851&web=Various%20Artists/Greatest%20LATIN%20Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F18FFC09BE3834CA6%2Fmusic%2F%E5%8F%91%E7%83%A7%E8%AF%95%E9%9F%B3%2FGreatest%20LATIN%20Love%20Songs%2FVarious%20Artists%20-%20Always%20In%20My%20Heart.flac&metadata=false
Oct 01 10:23:13 volumio volumio[1149]: info: URI /mnt/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Always In My Heart.flac
Oct 01 10:23:13 volumio volumio[1149]: info: ALBUMART /albumart?cacheid=851&web=Various%20Artists/Greatest%20LATIN%20Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F18FFC09BE3834CA6%2Fmusic%2F%E5%8F%91%E7%83%A7%E8%AF%95%E9%9F%B3%2FGreatest%20LATIN%20Love%20Songs%2FVarious%20Artists%20-%20Maria%20Elena.flac&metadata=false
Oct 01 10:23:13 volumio volumio[1149]: info: URI /mnt/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Maria Elena.flac
Oct 01 10:23:13 volumio volumio[1149]: info: ALBUMART /albumart?cacheid=851&web=Various%20Artists/Greatest%20LATIN%20Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F18FFC09BE3834CA6%2Fmusic%2F%E5%8F%91%E7%83%A7%E8%AF%95%E9%9F%B3%2FGreatest%20LATIN%20Love%20Songs%2FVarious%20Artists%20-%20Historia%20De%20Un%20Amor.flac&metadata=false
Oct 01 10:23:13 volumio volumio[1149]: info: URI /mnt/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Historia De Un Amor.flac
Oct 01 10:23:13 volumio volumio[1149]: info: ALBUMART /albumart?cacheid=851&web=Various%20Artists/Greatest%20LATIN%20Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F18FFC09BE3834CA6%2Fmusic%2F%E5%8F%91%E7%83%A7%E8%AF%95%E9%9F%B3%2FGreatest%20LATIN%20Love%20Songs%2FVarious%20Artists%20-%20La%20Mer.flac&metadata=false
Oct 01 10:23:13 volumio volumio[1149]: info: URI /mnt/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - La Mer.flac
Oct 01 10:23:13 volumio volumio[1149]: info: ALBUMART /albumart?cacheid=851&web=Various%20Artists/Greatest%20LATIN%20Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F18FFC09BE3834CA6%2Fmusic%2F%E5%8F%91%E7%83%A7%E8%AF%95%E9%9F%B3%2FGreatest%20LATIN%20Love%20Songs%2FVarious%20Artists%20-%20More.flac&metadata=false
Oct 01 10:23:13 volumio volumio[1149]: info: URI /mnt/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - More.flac
Oct 01 10:23:13 volumio volumio[1149]: info: ALBUMART /albumart?cacheid=851&web=Various%20Artists/Greatest%20LATIN%20Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F18FFC09BE3834CA6%2Fmusic%2F%E5%8F%91%E7%83%A7%E8%AF%95%E9%9F%B3%2FGreatest%20LATIN%20Love%20Songs%2FVarious%20Artists%20-%20Autumn%20Leaves.flac&metadata=false
Oct 01 10:23:13 volumio volumio[1149]: info: URI /mnt/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Autumn Leaves.flac
Oct 01 10:23:13 volumio volumio[1149]: info: ALBUMART /albumart?cacheid=851&web=Various%20Artists/Greatest%20LATIN%20Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F18FFC09BE3834CA6%2Fmusic%2F%E5%8F%91%E7%83%A7%E8%AF%95%E9%9F%B3%2FGreatest%20LATIN%20Love%20Songs%2FVarious%20Artists%20-%20Softly%20As%20I%20Leave%20You.flac&metadata=false
Oct 01 10:23:13 volumio volumio[1149]: info: URI /mnt/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Softly As I Leave You.flac
Oct 01 10:23:13 volumio volumio[1149]: info: ALBUMART /albumart?cacheid=851&web=Various%20Artists/Greatest%20LATIN%20Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F18FFC09BE3834CA6%2Fmusic%2F%E5%8F%91%E7%83%A7%E8%AF%95%E9%9F%B3%2FGreatest%20LATIN%20Love%20Songs%2FVarious%20Artists%20-%20One%20Note%20Samba.flac&metadata=false
Oct 01 10:23:13 volumio volumio[1149]: info: URI /mnt/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - One Note Samba.flac
Oct 01 10:23:13 volumio volumio[1149]: info: ALBUMART /albumart?cacheid=851&web=Various%20Artists/Greatest%20LATIN%20Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F18FFC09BE3834CA6%2Fmusic%2F%E5%8F%91%E7%83%A7%E8%AF%95%E9%9F%B3%2FGreatest%20LATIN%20Love%20Songs%2FVarious%20Artists%20-%20Guantanamera.flac&metadata=false
Oct 01 10:23:13 volumio volumio[1149]: info: URI /mnt/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Guantanamera.flac
Oct 01 10:23:13 volumio volumio[1149]: info: ALBUMART /albumart?cacheid=851&web=Various%20Artists/Greatest%20LATIN%20Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F18FFC09BE3834CA6%2Fmusic%2F%E5%8F%91%E7%83%A7%E8%AF%95%E9%9F%B3%2FGreatest%20LATIN%20Love%20Songs%2FVarious%20Artists%20-%20La%20Cumparsita.flac&metadata=false
Oct 01 10:23:13 volumio volumio[1149]: info: URI /mnt/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - La Cumparsita.flac
Oct 01 10:23:13 volumio volumio[1149]: info: ALBUMART /albumart?cacheid=851&web=Various%20Artists/Greatest%20LATIN%20Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F18FFC09BE3834CA6%2Fmusic%2F%E5%8F%91%E7%83%A7%E8%AF%95%E9%9F%B3%2FGreatest%20LATIN%20Love%20Songs%2FVarious%20Artists%20-%20The%20Girl%20From%20Ipanema.flac&metadata=false
Oct 01 10:23:13 volumio volumio[1149]: info: URI /mnt/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - The Girl From Ipanema.flac
Oct 01 10:23:13 volumio volumio[1149]: info: ALBUMART /albumart?cacheid=851&web=Various%20Artists/Greatest%20LATIN%20Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F18FFC09BE3834CA6%2Fmusic%2F%E5%8F%91%E7%83%A7%E8%AF%95%E9%9F%B3%2FGreatest%20LATIN%20Love%20Songs%2FVarious%20Artists%20-%20Night%20And%20Day.flac&metadata=false
Oct 01 10:23:13 volumio volumio[1149]: info: URI /mnt/USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Night And Day.flac
Oct 01 10:23:13 volumio volumio[1149]: info: CoreCommandRouter::volumioPushQueue
Oct 01 10:23:13 volumio volumio[1149]: info: CorePlayQueue::saveQueue
Oct 01 10:23:13 volumio volumio[1149]: info: CoreStateMachine::updateTrackBlock
Oct 01 10:23:13 volumio volumio[1149]: info: CorePlayQueue::getTrackBlock
Oct 01 10:23:13 volumio volumio[1149]: info: CoreCommandRouter::volumioPlay
Oct 01 10:23:13 volumio volumio[1149]: info: CoreStateMachine::play index 7
Oct 01 10:23:13 volumio volumio[1149]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 01 10:23:13 volumio volumio[1149]: info: CoreStateMachine::stop
Oct 01 10:23:13 volumio volumio[1149]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 01 10:23:13 volumio volumio[1149]: info: CoreStateMachine::play index undefined
Oct 01 10:23:13 volumio volumio[1149]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 01 10:23:13 volumio volumio[1149]: info: CorePlayQueue::getTrack 7
Oct 01 10:23:13 volumio volumio[1149]: info: CoreStateMachine::startPlaybackTimer
Oct 01 10:23:13 volumio volumio[1149]: info: CorePlayQueue::getTrack 7
Oct 01 10:23:13 volumio volumio[1149]: verbose: ControllerMpd::clearAddPlayTracks USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Softly As I Leave You.flac
Oct 01 10:23:13 volumio volumio[1149]: verbose: ControllerMpd::sendMpdCommand stop
Oct 01 10:23:13 volumio volumio[1149]: info: sendMpdCommand stop took 16 milliseconds
Oct 01 10:23:13 volumio volumio[1149]: verbose: ControllerMpd::sendMpdCommand clear
Oct 01 10:23:13 volumio volumio[1149]: info:
Oct 01 10:23:13 volumio volumio[1149]: ---------------------------- MPD announces system playlist update
Oct 01 10:23:13 volumio volumio[1149]: info: Ignoring MPD Status Update
Oct 01 10:23:13 volumio volumio[1149]: info: sendMpdCommand clear took 8 milliseconds
Oct 01 10:23:13 volumio volumio[1149]: verbose: ControllerMpd::sendMpdCommand add "USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Softly As I Leave You.flac"
Oct 01 10:23:13 volumio volumio[1149]: error: updateQueue error: null
Oct 01 10:23:13 volumio volumio[1149]: info:
Oct 01 10:23:13 volumio volumio[1149]: ---------------------------- MPD announces system playlist update
Oct 01 10:23:13 volumio volumio[1149]: info: Ignoring MPD Status Update
Oct 01 10:23:13 volumio volumio[1149]: info: ------------------------------ 8ms
Oct 01 10:23:13 volumio volumio[1149]: info: sendMpdCommand add "USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Softly As I Leave You.flac" took 7 milliseconds
Oct 01 10:23:13 volumio volumio[1149]: verbose: ControllerMpd::sendMpdCommand play
Oct 01 10:23:13 volumio volumio[1149]: info: ------------------------------ 6ms
Oct 01 10:23:13 volumio volumio[1149]: info:
Oct 01 10:23:13 volumio volumio[1149]: ---------------------------- MPD announces state update: player
Oct 01 10:23:13 volumio volumio[1149]: info: sendMpdCommand play took 14 milliseconds
Oct 01 10:23:13 volumio volumio[1149]: info: ControllerMpd::getState
Oct 01 10:23:13 volumio volumio[1149]: verbose: ControllerMpd::sendMpdCommand status
Oct 01 10:23:13 volumio volumio[1149]: info:
Oct 01 10:23:13 volumio volumio[1149]: ---------------------------- MPD announces state update: player
Oct 01 10:23:13 volumio volumio[1149]: info: sendMpdCommand status took 444 milliseconds
Oct 01 10:23:13 volumio volumio[1149]: info: ControllerMpd::getState
Oct 01 10:23:13 volumio volumio[1149]: verbose: ControllerMpd::sendMpdCommand status
Oct 01 10:23:13 volumio volumio[1149]: verbose: ControllerMpd::parseState
Oct 01 10:23:13 volumio volumio[1149]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 01 10:23:13 volumio volumio[1149]: info: sendMpdCommand status took 5 milliseconds
Oct 01 10:23:13 volumio volumio[1149]: info: sendMpdCommand playlistinfo took 4 milliseconds
Oct 01 10:23:13 volumio volumio[1149]: verbose: ControllerMpd::parseState
Oct 01 10:23:13 volumio volumio[1149]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 01 10:23:13 volumio volumio[1149]: verbose: ControllerMpd::parseTrackInfo
Oct 01 10:23:13 volumio volumio[1149]: info: ControllerMpd::pushState
Oct 01 10:23:13 volumio volumio[1149]: info: CoreCommandRouter::servicePushState
Oct 01 10:23:13 volumio volumio[1149]: info: CorePlayQueue::getTrack 7
Oct 01 10:23:13 volumio volumio[1149]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":343,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Softly As I Leave You","artist":"Various Artists","album":"Greatest LATIN Love Songs","uri":"USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Softly As I Leave You.flac","trackType":"flac"}
Oct 01 10:23:13 volumio volumio[1149]: verbose: CURRENT POSITION 7
Oct 01 10:23:13 volumio volumio[1149]: info: CoreStateMachine::syncState stateService play
Oct 01 10:23:13 volumio volumio[1149]: info: CoreStateMachine::syncState currentStatus stop
Oct 01 10:23:13 volumio volumio[1149]: info: ------------------------------ 471ms
Oct 01 10:23:13 volumio volumio[1149]: info: sendMpdCommand playlistinfo took 13 milliseconds
Oct 01 10:23:13 volumio volumio[1149]: verbose: ControllerMpd::parseTrackInfo
Oct 01 10:23:13 volumio volumio[1149]: info: ControllerMpd::pushState
Oct 01 10:23:13 volumio volumio[1149]: info: CoreCommandRouter::servicePushState
Oct 01 10:23:13 volumio volumio[1149]: info: CorePlayQueue::getTrack 7
Oct 01 10:23:13 volumio volumio[1149]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":343,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"699 Kbps","isStreaming":false,"title":"Softly As I Leave You","artist":"Various Artists","album":"Greatest LATIN Love Songs","uri":"USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Softly As I Leave You.flac","trackType":"flac"}
Oct 01 10:23:13 volumio volumio[1149]: verbose: CURRENT POSITION 7
Oct 01 10:23:13 volumio volumio[1149]: info: CoreStateMachine::syncState stateService play
Oct 01 10:23:13 volumio volumio[1149]: info: CoreStateMachine::syncState currentStatus play
Oct 01 10:23:13 volumio volumio[1149]: info: Received an update from plugin. extracting info from payload
Oct 01 10:23:13 volumio volumio[1149]: info: CoreStateMachine::pushState
Oct 01 10:23:13 volumio volumio[1149]: info: CorePlayQueue::getTrack 7
Oct 01 10:23:13 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 01 10:23:13 volumio volumio[1149]: info: CoreCommandRouter::volumioPushState
Oct 01 10:23:13 volumio volumio[1149]: info: CoreStateMachine::pushState
Oct 01 10:23:13 volumio volumio[1149]: info: CorePlayQueue::getTrack 7
Oct 01 10:23:13 volumio volumio[1149]: info: CoreCommandRouter::volumioPushState
Oct 01 10:23:13 volumio volumio[1149]: info: ------------------------------ 60ms
Oct 01 10:23:14 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 01 10:23:14 volumio volumio[1149]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7
Oct 01 10:23:14 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:23:14 volumio volumio[1149]: info: CorePlayQueue::getTrack 7
Oct 01 10:23:17 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:23:17 volumio volumio[1149]: info: CorePlayQueue::getTrack 7
Oct 01 10:23:17 volumio sudo[2131]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py
Oct 01 10:23:17 volumio sudo[2131]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 01 10:23:19 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:23:19 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Oct 01 10:23:19 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Oct 01 10:23:19 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Oct 01 10:23:19 volumio volumio[1149]: info: CoreCommandRouter::volumioGetBrowseSources
Oct 01 10:23:19 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 01 10:23:20 volumio volumio[1149]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7
Oct 01 10:23:20 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:23:20 volumio volumio[1149]: info: CorePlayQueue::getTrack 7
Oct 01 10:23:20 volumio volumio[1149]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Oct 01 10:23:20 volumio volumio[1149]: info: Completed starting MyVolumio Plugin
Oct 01 10:23:20 volumio volumio[1149]: info: BOOT COMPLETED
Oct 01 10:23:20 volumio volumio[1149]: [Metrics] CommandRouter: 65s 215.47ms
Oct 01 10:23:20 volumio volumio[1149]: info: CoreCommandRouter::volumiosetStartupVolume
Oct 01 10:23:20 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 01 10:23:20 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 01 10:23:20 volumio volumio[1149]: info: CoreCommandRouter::Close All Modals sent
Oct 01 10:23:20 volumio volumio[1149]: info: CoreCommandRouter::Close All Modals sent
Oct 01 10:23:20 volumio volumio[1149]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav
Oct 01 10:23:20 volumio volumio[1149]: aplay: main:828: audio open error: Device or resource busy
Oct 01 10:23:21 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Oct 01 10:23:21 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Oct 01 10:23:21 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Oct 01 10:23:22 volumio volumio[1149]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8
Oct 01 10:23:22 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState
Oct 01 10:23:22 volumio volumio[1149]: info: CorePlayQueue::getTrack 7
Oct 01 10:23:30 volumio volumio[1149]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred.
Oct 01 10:23:30 volumio volumio[1149]: info: Retrying Login Due to Network Error
Oct 01 10:23:33 volumio volumio[1149]: info: CoreCommandRouter::volumioPause
Oct 01 10:23:33 volumio volumio[1149]: info: CoreStateMachine::pause
Oct 01 10:23:33 volumio volumio[1149]: info: CoreStateMachine::stPlaybackTimer
Oct 01 10:23:33 volumio volumio[1149]: info: CoreStateMachine::servicePause
Oct 01 10:23:33 volumio volumio[1149]: info: CorePlayQueue::getTrack 7
Oct 01 10:23:33 volumio volumio[1149]: info: CoreCommandRouter::servicePause
Oct 01 10:23:33 volumio volumio[1149]: info: ControllerMpd::pause
Oct 01 10:23:33 volumio volumio[1149]: verbose: ControllerMpd::sendMpdCommand pause
Oct 01 10:23:33 volumio volumio[1149]: info:
Oct 01 10:23:33 volumio volumio[1149]: ---------------------------- MPD announces state update: player
Oct 01 10:23:33 volumio volumio[1149]: info: sendMpdCommand pause took 5 milliseconds
Oct 01 10:23:33 volumio volumio[1149]: info: ControllerMpd::getState
Oct 01 10:23:33 volumio volumio[1149]: verbose: ControllerMpd::sendMpdCommand status
Oct 01 10:23:33 volumio volumio[1149]: info: sendMpdCommand status took 2 milliseconds
Oct 01 10:23:33 volumio volumio[1149]: verbose: ControllerMpd::parseState
Oct 01 10:23:33 volumio volumio[1149]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 01 10:23:33 volumio volumio[1149]: info: sendMpdCommand playlistinfo took 1 milliseconds
Oct 01 10:23:33 volumio volumio[1149]: verbose: ControllerMpd::parseTrackInfo
Oct 01 10:23:33 volumio volumio[1149]: info: ControllerMpd::pushState
Oct 01 10:23:33 volumio volumio[1149]: info: CoreCommandRouter::servicePushState
Oct 01 10:23:33 volumio volumio[1149]: info: CorePlayQueue::getTrack 7
Oct 01 10:23:33 volumio volumio[1149]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":20489,"duration":343,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"593 Kbps","isStreaming":false,"title":"Softly As I Leave You","artist":"Various Artists","album":"Greatest LATIN Love Songs","uri":"USB/18FFC09BE3834CA6/music/发烧试音/Greatest LATIN Love Songs/Various Artists - Softly As I Leave You.flac","trackType":"flac"}
Oct 01 10:23:33 volumio volumio[1149]: verbose: CURRENT POSITION 7
Oct 01 10:23:33 volumio volumio[1149]: info: CoreStateMachine::syncState stateService pause
Oct 01 10:23:33 volumio volumio[1149]: info: CoreStateMachine::syncState currentStatus pause
Oct 01 10:23:33 volumio volumio[1149]: info: CoreStateMachine::pushState
Oct 01 10:23:33 volumio volumio[1149]: info: CorePlayQueue::getTrack 7
Oct 01 10:23:33 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 01 10:23:33 volumio volumio[1149]: info: CoreCommandRouter::volumioPushState
Oct 01 10:23:33 volumio volumio[1149]: info: CoreStateMachine::stPlaybackTimer
Oct 01 10:23:33 volumio volumio[1149]: info: ------------------------------ 35ms
Oct 01 10:23:33 volumio volumio[1149]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 01 10:23:33 volumio volumio[1149]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning'
Oct 01 10:23:33 volumio volumio[1149]: at Object.readdirSync (fs.js:1048:3)
Oct 01 10:23:33 volumio volumio[1149]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11)
Oct 01 10:23:33 volumio volumio[1149]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7)
Oct 01 10:23:33 volumio volumio[1149]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62)
Oct 01 10:23:33 volumio volumio[1149]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17)
Oct 01 10:23:33 volumio volumio[1149]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48)
Oct 01 10:23:33 volumio volumio[1149]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
Oct 01 10:23:33 volumio volumio[1149]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10)
Oct 01 10:23:33 volumio volumio[1149]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12)
Oct 01 10:23:33 volumio volumio[1149]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15)
Oct 01 10:23:33 volumio volumio[1149]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
Oct 01 10:23:33 volumio volumio[1149]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8)
Oct 01 10:23:33 volumio volumio[1149]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15)
Oct 01 10:23:33 volumio volumio[1149]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
Oct 01 10:23:33 volumio volumio[1149]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12)
Oct 01 10:23:33 volumio volumio[1149]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) {
Oct 01 10:23:33 volumio volumio[1149]: errno: -20,
Oct 01 10:23:33 volumio volumio[1149]: syscall: 'scandir',
Oct 01 10:23:33 volumio volumio[1149]: code: 'ENOTDIR',
Oct 01 10:23:33 volumio volumio[1149]: path: '/tmp/peppyrunning'
Oct 01 10:23:33 volumio volumio[1149]: }
Oct 01 10:23:33 volumio volumio[1149]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 01 10:23:34 volumio sudo[2189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-01 10:22
Oct 01 10:23:34 volumio sudo[2189]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 10 (buster)"
NAME="Debian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST"
VOLUMIO_VERSION="3.742"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"