-- Logs begin at Mon 2024-09-09 17:49:53 CST, end at Mon 2024-09-09 18:03:13 CST. -- Sep 09 18:02:27 volumio volumio[1116]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Sep 09 18:02:27 volumio volumio[1116]: info: Retrying Login Due to Network Error Sep 09 18:02:31 volumio dhcpcd[738]: eth0: carrier lost Sep 09 18:02:31 volumio kernel: r8169 0000:02:00.0 eth0: Link is Down Sep 09 18:02:31 volumio ifplugd(eth0)[888]: Link beat lost. Sep 09 18:02:31 volumio avahi-daemon[697]: Withdrawing address record for 192.168.0.103 on eth0. Sep 09 18:02:31 volumio avahi-daemon[697]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.103. Sep 09 18:02:31 volumio avahi-daemon[697]: Interface eth0.IPv4 no longer relevant for mDNS. Sep 09 18:02:31 volumio volumio[1116]: info: Discovery: A device disappeared from network Sep 09 18:02:31 volumio volumio[1116]: info: Discovery: Device volumio disappeared from network Sep 09 18:02:31 volumio dhcpcd[738]: eth0: deleting route to 192.168.0.0/24 Sep 09 18:02:31 volumio dhcpcd[738]: eth0: deleting default route via 192.168.0.1 Sep 09 18:02:33 volumio ntpd[2335]: Deleting interface #3 eth0, 192.168.0.103#123, interface stats: received=71, sent=73, dropped=0, active_time=645 secs Sep 09 18:02:33 volumio ntpd[2335]: 95.111.202.5 local addr 192.168.0.103 -> Sep 09 18:02:33 volumio ntpd[2335]: 117.80.231.60 local addr 192.168.0.103 -> Sep 09 18:02:33 volumio ntpd[2335]: 162.159.200.123 local addr 192.168.0.103 -> Sep 09 18:02:33 volumio ntpd[2335]: 193.182.111.141 local addr 192.168.0.103 -> Sep 09 18:02:33 volumio ntpd[2335]: 119.28.206.193 local addr 192.168.0.103 -> Sep 09 18:02:33 volumio ntpd[2335]: 84.16.67.12 local addr 192.168.0.103 -> Sep 09 18:02:35 volumio dhcpcd[738]: eth0: carrier acquired Sep 09 18:02:35 volumio kernel: r8169 0000:02:00.0 eth0: Link is Up - 100Mbps/Full - flow control rx/tx Sep 09 18:02:35 volumio dhcpcd[738]: eth0: IAID 4b:b3:fa:57 Sep 09 18:02:35 volumio dhcpcd[738]: eth0: rebinding lease of 192.168.0.103 Sep 09 18:02:35 volumio dhcpcd[738]: eth0: probing address 192.168.0.103/24 Sep 09 18:02:35 volumio dhcpcd[738]: eth0: soliciting an IPv6 router Sep 09 18:02:35 volumio ifplugd(eth0)[888]: Link beat detected. Sep 09 18:02:37 volumio volumio[1116]: info: MyVolumio login type: Token Sep 09 18:02:37 volumio volumio[1116]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Sep 09 18:02:37 volumio volumio[1116]: info: Retrying Login Due to Network Error Sep 09 18:02:39 volumio dhcpcd[738]: eth0: leased 192.168.0.103 for 7200 seconds Sep 09 18:02:39 volumio dhcpcd[738]: eth0: adding route to 192.168.0.0/24 Sep 09 18:02:39 volumio dhcpcd[738]: eth0: adding default route via 192.168.0.1 Sep 09 18:02:39 volumio avahi-daemon[697]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.103. Sep 09 18:02:39 volumio avahi-daemon[697]: New relevant interface eth0.IPv4 for mDNS. Sep 09 18:02:39 volumio avahi-daemon[697]: Registering new address record for 192.168.0.103 on eth0.IPv4. Sep 09 18:02:40 volumio ntpd[2335]: ntpd exiting on signal 15 (Terminated) Sep 09 18:02:40 volumio systemd[1]: Stopping Network Time Service... Sep 09 18:02:40 volumio systemd[1]: ntp.service: Succeeded. Sep 09 18:02:40 volumio systemd[1]: Stopped Network Time Service. Sep 09 18:02:40 volumio systemd[1]: Starting Network Time Service... Sep 09 18:02:40 volumio ntpd[4496]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 09 18:02:40 volumio ntpd[4496]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 09 18:02:40 volumio systemd[1]: Started Network Time Service. Sep 09 18:02:40 volumio ntpd[4503]: proto: precision = 0.315 usec (-22) Sep 09 18:02:40 volumio ntpd[4503]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 09 18:02:40 volumio ntpd[4503]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 09 18:02:40 volumio ntpd[4503]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 622 days ago Sep 09 18:02:40 volumio ntpd[4503]: Listen and drop on 0 v6wildcard [::]:123 Sep 09 18:02:40 volumio ntpd[4503]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 09 18:02:40 volumio ntpd[4503]: Listen normally on 2 lo 127.0.0.1:123 Sep 09 18:02:40 volumio ntpd[4503]: Listen normally on 3 eth0 192.168.0.103:123 Sep 09 18:02:40 volumio ntpd[4503]: Listening on routing socket on fd #20 for interface updates Sep 09 18:02:40 volumio ntpd[4503]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 09 18:02:40 volumio ntpd[4503]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 09 18:02:40 volumio volumio[1116]: info: Discovery: adding 492524ff-0d18-4e9b-b0b7-e00a3046faf7 Sep 09 18:02:40 volumio volumio[1116]: info: Discovery: Found device Volumio Sep 09 18:02:40 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState Sep 09 18:02:40 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:02:41 volumio ntpd[4503]: Soliciting pool server 117.80.231.60 Sep 09 18:02:42 volumio ntpd[4503]: Soliciting pool server 162.159.200.123 Sep 09 18:02:43 volumio ntpd[4503]: Soliciting pool server 193.182.111.141 Sep 09 18:02:44 volumio ntpd[4503]: Soliciting pool server 202.112.31.197 Sep 09 18:02:44 volumio ntpd[4503]: Soliciting pool server 95.111.202.5 Sep 09 18:02:45 volumio ntpd[4503]: Soliciting pool server 202.112.29.82 Sep 09 18:02:46 volumio ntpd[4503]: Soliciting pool server 84.16.73.33 Sep 09 18:02:46 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 18:02:46 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 18:02:46 volumio volumio[1116]: info: Discovery: Getting this device information Sep 09 18:02:46 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState Sep 09 18:02:46 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:02:46 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 18:02:47 volumio ntpd[4503]: Soliciting pool server 2001:253:207::123 Sep 09 18:02:47 volumio volumio[1116]: info: MyVolumio login type: Token Sep 09 18:02:49 volumio volumio[1116]: info: CALLMETHOD: system_controller system saveHDMISettings [object Object] Sep 09 18:02:49 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , saveHDMISettings Sep 09 18:02:49 volumio sudo[4513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Sep 09 18:02:49 volumio sudo[4513]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 18:02:49 volumio systemd[1]: Stopping Start Volumio Kiosk... Sep 09 18:02:49 volumio startx[3779]: xinit: connection to X server lost Sep 09 18:02:49 volumio startx[3779]: [1B blob data] Sep 09 18:02:49 volumio screensaver.sh[688]: XIO: fatal IO error 11 (Resource temporarily unavailable) on X server ":0" Sep 09 18:02:49 volumio screensaver.sh[688]: after 16802 requests (16802 known processed) with 0 events remaining. Sep 09 18:02:49 volumio sudo[3959]: pam_unix(sudo:session): session closed for user root Sep 09 18:02:50 volumio startx[3779]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Sep 09 18:02:50 volumio startx[3779]: xinit: unexpected signal 15 Sep 09 18:02:50 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Sep 09 18:02:50 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Sep 09 18:02:50 volumio systemd[1]: Stopped Start Volumio Kiosk. Sep 09 18:02:50 volumio systemd[1]: Started Start Volumio Kiosk. Sep 09 18:02:50 volumio sudo[4513]: pam_unix(sudo:session): session closed for user root Sep 09 18:02:50 volumio sudo[4525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable volumio-kiosk.service Sep 09 18:02:50 volumio sudo[4525]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 18:02:50 volumio systemd[1]: Reloading. Sep 09 18:02:51 volumio startx[4522]: X.Org X Server 1.20.4 Sep 09 18:02:51 volumio startx[4522]: X Protocol Version 11, Revision 0 Sep 09 18:02:51 volumio startx[4522]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Sep 09 18:02:51 volumio startx[4522]: Current Operating System: Linux volumio 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Sep 09 18:02:51 volumio startx[4522]: 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 initrd=volumio.initrd Sep 09 18:02:51 volumio startx[4522]: Build Date: 18 December 2021 09:05:36AM Sep 09 18:02:51 volumio startx[4522]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Sep 09 18:02:51 volumio startx[4522]: Current version of pixman: 0.36.0 Sep 09 18:02:51 volumio startx[4522]: Before reporting problems, check http://wiki.x.org Sep 09 18:02:51 volumio startx[4522]: to make sure that you have the latest version. Sep 09 18:02:51 volumio startx[4522]: Markers: (--) probed, (**) from config file, (==) default setting, Sep 09 18:02:51 volumio startx[4522]: (++) from command line, (!!) notice, (II) informational, Sep 09 18:02:51 volumio startx[4522]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Sep 09 18:02:51 volumio startx[4522]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Sep 9 18:02:51 2024 Sep 09 18:02:51 volumio startx[4522]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Sep 09 18:02:51 volumio startx[4522]: (II) [KMS] Kernel modesetting enabled. Sep 09 18:02:51 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. Sep 09 18:02:52 volumio sudo[4525]: pam_unix(sudo:session): session closed for user root Sep 09 18:02:52 volumio volumio[1116]: info: enable volumio-kiosk service success Sep 09 18:02:54 volumio dhcpcd[738]: eth0: carrier lost Sep 09 18:02:54 volumio kernel: r8169 0000:02:00.0 eth0: Link is Down Sep 09 18:02:55 volumio avahi-daemon[697]: Withdrawing address record for 192.168.0.103 on eth0. Sep 09 18:02:55 volumio avahi-daemon[697]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.103. Sep 09 18:02:55 volumio avahi-daemon[697]: Interface eth0.IPv4 no longer relevant for mDNS. Sep 09 18:02:55 volumio volumio[1116]: info: Discovery: A device disappeared from network Sep 09 18:02:55 volumio volumio[1116]: info: Discovery: Device volumio disappeared from network Sep 09 18:02:55 volumio dhcpcd[738]: eth0: deleting route to 192.168.0.0/24 Sep 09 18:02:55 volumio dhcpcd[738]: eth0: deleting default route via 192.168.0.1 Sep 09 18:02:55 volumio ifplugd(eth0)[888]: Link beat lost. Sep 09 18:02:56 volumio ntpd[4503]: Deleting interface #3 eth0, 192.168.0.103#123, interface stats: received=39, sent=40, dropped=5, active_time=16 secs Sep 09 18:02:56 volumio ntpd[4503]: 84.16.73.33 local addr 192.168.0.103 -> Sep 09 18:02:56 volumio ntpd[4503]: 202.112.29.82 local addr 192.168.0.103 -> Sep 09 18:02:56 volumio ntpd[4503]: 95.111.202.5 local addr 192.168.0.103 -> Sep 09 18:02:56 volumio ntpd[4503]: 202.112.31.197 local addr 192.168.0.103 -> Sep 09 18:02:56 volumio ntpd[4503]: 193.182.111.141 local addr 192.168.0.103 -> Sep 09 18:02:56 volumio ntpd[4503]: 162.159.200.123 local addr 192.168.0.103 -> Sep 09 18:02:56 volumio ntpd[4503]: 117.80.231.60 local addr 192.168.0.103 -> Sep 09 18:02:56 volumio kernel: r8169 0000:02:00.0 eth0: Link is Up - 100Mbps/Full - flow control rx/tx Sep 09 18:02:56 volumio dhcpcd[738]: eth0: carrier acquired Sep 09 18:02:56 volumio dhcpcd[738]: eth0: IAID 4b:b3:fa:57 Sep 09 18:02:56 volumio dhcpcd[738]: eth0: soliciting an IPv6 router Sep 09 18:02:56 volumio dhcpcd[738]: eth0: rebinding lease of 192.168.0.103 Sep 09 18:02:56 volumio dhcpcd[738]: eth0: probing address 192.168.0.103/24 Sep 09 18:02:57 volumio ifplugd(eth0)[888]: Link beat detected. Sep 09 18:03:01 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState Sep 09 18:03:01 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:01 volumio volumio[1116]: 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: 7 Sep 09 18:03:01 volumio sudo[4773]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Sep 09 18:03:01 volumio sudo[4773]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 18:03:02 volumio dhcpcd[738]: eth0: leased 192.168.0.103 for 7200 seconds Sep 09 18:03:02 volumio avahi-daemon[697]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.103. Sep 09 18:03:02 volumio avahi-daemon[697]: New relevant interface eth0.IPv4 for mDNS. Sep 09 18:03:02 volumio avahi-daemon[697]: Registering new address record for 192.168.0.103 on eth0.IPv4. Sep 09 18:03:02 volumio dhcpcd[738]: eth0: adding route to 192.168.0.0/24 Sep 09 18:03:02 volumio dhcpcd[738]: eth0: adding default route via 192.168.0.1 Sep 09 18:03:03 volumio ntpd[4503]: ntpd exiting on signal 15 (Terminated) Sep 09 18:03:03 volumio systemd[1]: Stopping Network Time Service... Sep 09 18:03:03 volumio systemd[1]: ntp.service: Succeeded. Sep 09 18:03:03 volumio systemd[1]: Stopped Network Time Service. Sep 09 18:03:03 volumio systemd[1]: Starting Network Time Service... Sep 09 18:03:03 volumio ntpd[4808]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 09 18:03:03 volumio ntpd[4808]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 09 18:03:03 volumio systemd[1]: Started Network Time Service. Sep 09 18:03:03 volumio ntpd[4814]: proto: precision = 0.315 usec (-22) Sep 09 18:03:03 volumio ntpd[4814]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 09 18:03:03 volumio ntpd[4814]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 09 18:03:03 volumio ntpd[4814]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 622 days ago Sep 09 18:03:03 volumio ntpd[4814]: Listen and drop on 0 v6wildcard [::]:123 Sep 09 18:03:03 volumio ntpd[4814]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 09 18:03:03 volumio ntpd[4814]: Listen normally on 2 lo 127.0.0.1:123 Sep 09 18:03:03 volumio ntpd[4814]: Listen normally on 3 eth0 192.168.0.103:123 Sep 09 18:03:03 volumio ntpd[4814]: Listening on routing socket on fd #20 for interface updates Sep 09 18:03:03 volumio ntpd[4814]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 09 18:03:03 volumio ntpd[4814]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 09 18:03:03 volumio volumio[1116]: info: Discovery: adding 492524ff-0d18-4e9b-b0b7-e00a3046faf7 Sep 09 18:03:03 volumio volumio[1116]: info: Discovery: Found device Volumio Sep 09 18:03:03 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState Sep 09 18:03:03 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:04 volumio ntpd[4814]: Soliciting pool server 117.80.231.60 Sep 09 18:03:05 volumio ntpd[4814]: Soliciting pool server 162.159.200.123 Sep 09 18:03:05 volumio volumio[1116]: 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 Sep 09 18:03:05 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState Sep 09 18:03:05 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:06 volumio ntpd[4814]: Soliciting pool server 193.182.111.141 Sep 09 18:03:06 volumio screensaver.sh[688]: libpng warning: iCCP: known incorrect sRGB profile Sep 09 18:03:06 volumio screensaver.sh[688]: libpng warning: Interlace handling should be turned on when using png_read_image Sep 09 18:03:07 volumio volumio[1116]: 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: 9 Sep 09 18:03:07 volumio ntpd[4814]: Soliciting pool server 2001:253:207::123 Sep 09 18:03:07 volumio ntpd[4814]: Soliciting pool server 95.111.202.5 Sep 09 18:03:08 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 09 18:03:08 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 09 18:03:08 volumio volumio[1116]: info: CoreCommandRouter::volumioGetVisibleSources Sep 09 18:03:08 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 18:03:08 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState Sep 09 18:03:08 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:08 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 09 18:03:08 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 09 18:03:08 volumio volumio[1116]: info: Received Get System Info Sep 09 18:03:08 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 18:03:08 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 18:03:08 volumio volumio[1116]: info: Discovery: Getting this device information Sep 09 18:03:08 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState Sep 09 18:03:08 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:08 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 18:03:08 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState Sep 09 18:03:08 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:08 volumio volumio[1116]: info: Listing playlists Sep 09 18:03:08 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState Sep 09 18:03:08 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:08 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 09 18:03:08 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 09 18:03:09 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 09 18:03:09 volumio volumio[1116]: info: Received Get System Info Sep 09 18:03:09 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 18:03:09 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 18:03:09 volumio volumio[1116]: info: Discovery: Getting this device information Sep 09 18:03:09 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState Sep 09 18:03:09 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:09 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 18:03:09 volumio screensaver.sh[688]: libpng warning: iCCP: known incorrect sRGB profile Sep 09 18:03:09 volumio screensaver.sh[688]: libpng warning: Interlace handling should be turned on when using png_read_image Sep 09 18:03:10 volumio volumio[1116]: 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: 10 Sep 09 18:03:11 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState Sep 09 18:03:11 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:11 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 09 18:03:12 volumio volumio[1116]: info: CoreCommandRouter::volumioPause Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::pause Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::stPlaybackTimer Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::servicePause Sep 09 18:03:12 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:12 volumio volumio[1116]: info: CoreCommandRouter::servicePause Sep 09 18:03:12 volumio volumio[1116]: info: ControllerMpd::pause Sep 09 18:03:12 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand pause Sep 09 18:03:12 volumio volumio[1116]: info: Sep 09 18:03:12 volumio volumio[1116]: ---------------------------- MPD announces state update: player Sep 09 18:03:12 volumio volumio[1116]: info: sendMpdCommand pause took 5 milliseconds Sep 09 18:03:12 volumio volumio[1116]: info: ControllerMpd::getState Sep 09 18:03:12 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Sep 09 18:03:12 volumio volumio[1116]: info: Sep 09 18:03:12 volumio volumio[1116]: ---------------------------- MPD announces state update: player Sep 09 18:03:12 volumio volumio[1116]: info: ControllerMpd::getState Sep 09 18:03:12 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Sep 09 18:03:12 volumio volumio[1116]: info: Sep 09 18:03:12 volumio volumio[1116]: ---------------------------- MPD announces state update: player Sep 09 18:03:12 volumio volumio[1116]: info: ControllerMpd::getState Sep 09 18:03:12 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Sep 09 18:03:12 volumio volumio[1116]: info: Sep 09 18:03:12 volumio volumio[1116]: ---------------------------- MPD announces state update: player Sep 09 18:03:12 volumio volumio[1116]: info: ControllerMpd::getState Sep 09 18:03:12 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Sep 09 18:03:12 volumio volumio[1116]: info: sendMpdCommand status took 17 milliseconds Sep 09 18:03:12 volumio volumio[1116]: info: sendMpdCommand status took 15 milliseconds Sep 09 18:03:12 volumio volumio[1116]: info: sendMpdCommand status took 8 milliseconds Sep 09 18:03:12 volumio volumio[1116]: info: sendMpdCommand status took 4 milliseconds Sep 09 18:03:12 volumio volumio[1116]: verbose: ControllerMpd::parseState Sep 09 18:03:12 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 18:03:12 volumio volumio[1116]: verbose: ControllerMpd::parseState Sep 09 18:03:12 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 18:03:12 volumio volumio[1116]: verbose: ControllerMpd::parseState Sep 09 18:03:12 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 18:03:12 volumio volumio[1116]: verbose: ControllerMpd::parseState Sep 09 18:03:12 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 18:03:12 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 09 18:03:12 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo Sep 09 18:03:12 volumio volumio[1116]: info: ControllerMpd::pushState Sep 09 18:03:12 volumio volumio[1116]: info: CoreCommandRouter::servicePushState Sep 09 18:03:12 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:12 volumio volumio[1116]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":164417,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"1392 Kbps","isStreaming":false,"title":"ロード","artist":"THE虎舞竜","album":"ロード~ドキュメント","uri":"USB/THE虎舞竜 - ロード.flac","trackType":"flac"} Sep 09 18:03:12 volumio volumio[1116]: verbose: CURRENT POSITION 3 Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::syncState stateService pause Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus pause Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::pushState Sep 09 18:03:12 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:12 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 18:03:12 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::stPlaybackTimer Sep 09 18:03:12 volumio volumio[1116]: info: ------------------------------ 71ms Sep 09 18:03:12 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 52 milliseconds Sep 09 18:03:12 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 52 milliseconds Sep 09 18:03:12 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 52 milliseconds Sep 09 18:03:12 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo Sep 09 18:03:12 volumio volumio[1116]: info: ControllerMpd::pushState Sep 09 18:03:12 volumio volumio[1116]: info: CoreCommandRouter::servicePushState Sep 09 18:03:12 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:12 volumio volumio[1116]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":164417,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"1392 Kbps","isStreaming":false,"title":"ロード","artist":"THE虎舞竜","album":"ロード~ドキュメント","uri":"USB/THE虎舞竜 - ロード.flac","trackType":"flac"} Sep 09 18:03:12 volumio volumio[1116]: verbose: CURRENT POSITION 3 Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::syncState stateService pause Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus pause Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::pushState Sep 09 18:03:12 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:12 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::stPlaybackTimer Sep 09 18:03:12 volumio volumio[1116]: info: ControllerMpd::pushState Sep 09 18:03:12 volumio volumio[1116]: info: CoreCommandRouter::servicePushState Sep 09 18:03:12 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:12 volumio volumio[1116]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":164417,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"1392 Kbps","isStreaming":false,"title":"ロード","artist":"THE虎舞竜","album":"ロード~ドキュメント","uri":"USB/THE虎舞竜 - ロード.flac","trackType":"flac"} Sep 09 18:03:12 volumio volumio[1116]: verbose: CURRENT POSITION 3 Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::syncState stateService pause Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus pause Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::pushState Sep 09 18:03:12 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:12 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::stPlaybackTimer Sep 09 18:03:12 volumio volumio[1116]: info: ControllerMpd::pushState Sep 09 18:03:12 volumio volumio[1116]: info: CoreCommandRouter::servicePushState Sep 09 18:03:12 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:12 volumio volumio[1116]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":164417,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"1392 Kbps","isStreaming":false,"title":"ロード","artist":"THE虎舞竜","album":"ロード~ドキュメント","uri":"USB/THE虎舞竜 - ロード.flac","trackType":"flac"} Sep 09 18:03:12 volumio volumio[1116]: verbose: CURRENT POSITION 3 Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::syncState stateService pause Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus pause Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::pushState Sep 09 18:03:12 volumio volumio[1116]: info: CorePlayQueue::getTrack 3 Sep 09 18:03:12 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState Sep 09 18:03:12 volumio volumio[1116]: info: CoreStateMachine::stPlaybackTimer Sep 09 18:03:12 volumio volumio[1116]: info: ------------------------------ 260ms Sep 09 18:03:12 volumio volumio[1116]: info: ------------------------------ 253ms Sep 09 18:03:12 volumio volumio[1116]: info: ------------------------------ 251ms Sep 09 18:03:12 volumio volumio[1116]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 18:03:12 volumio volumio[1116]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Sep 09 18:03:12 volumio volumio[1116]: at Object.readdirSync (fs.js:1048:3) Sep 09 18:03:12 volumio volumio[1116]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Sep 09 18:03:12 volumio volumio[1116]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Sep 09 18:03:12 volumio volumio[1116]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Sep 09 18:03:12 volumio volumio[1116]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Sep 09 18:03:12 volumio volumio[1116]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Sep 09 18:03:12 volumio volumio[1116]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Sep 09 18:03:12 volumio volumio[1116]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Sep 09 18:03:12 volumio volumio[1116]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Sep 09 18:03:12 volumio volumio[1116]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Sep 09 18:03:12 volumio volumio[1116]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Sep 09 18:03:12 volumio volumio[1116]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Sep 09 18:03:12 volumio volumio[1116]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Sep 09 18:03:12 volumio volumio[1116]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Sep 09 18:03:12 volumio volumio[1116]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Sep 09 18:03:12 volumio volumio[1116]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Sep 09 18:03:12 volumio volumio[1116]: errno: -20, Sep 09 18:03:12 volumio volumio[1116]: syscall: 'scandir', Sep 09 18:03:12 volumio volumio[1116]: code: 'ENOTDIR', Sep 09 18:03:12 volumio volumio[1116]: path: '/tmp/peppyrunning' Sep 09 18:03:12 volumio volumio[1116]: } Sep 09 18:03:12 volumio volumio[1116]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 18:03:13 volumio sudo[5056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-09 18:02 Sep 09 18:03:13 volumio sudo[5056]: 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"