-- Logs begin at Sun 2024-07-21 19:33:13 HKT, end at Sun 2024-07-21 19:35:07 HKT. -- Jul 21 19:34:07 volumio dhcpcd[827]: eth0: carrier acquired Jul 21 19:34:07 volumio kernel: r8169 0000:01:00.0 eth0: Link is Up - 100Mbps/Full - flow control rx/tx Jul 21 19:34:07 volumio dhcpcd[827]: eth0: IAID 84:a2:a6:b4 Jul 21 19:34:08 volumio ifplugd(eth0)[937]: Link beat detected. Jul 21 19:34:08 volumio dhcpcd[827]: eth0: soliciting a DHCP lease Jul 21 19:34:08 volumio ifplugd(eth0)[937]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jul 21 19:34:08 volumio ifplugd(eth0)[937]: client: ifup: interface eth0 already configured Jul 21 19:34:08 volumio ifplugd(eth0)[937]: Program executed successfully. Jul 21 19:34:08 volumio dhcpcd[827]: eth0: soliciting an IPv6 router Jul 21 19:34:13 volumio dhcpcd[827]: eth0: probing for an IPv4LL address Jul 21 19:34:18 volumio dhcpcd[827]: eth0: using IPv4LL address 169.254.206.69 Jul 21 19:34:18 volumio dhcpcd[827]: eth0: adding route to 169.254.0.0/16 Jul 21 19:34:18 volumio dhcpcd[827]: eth0: adding default route Jul 21 19:34:18 volumio avahi-daemon[713]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.206.69. Jul 21 19:34:18 volumio avahi-daemon[713]: New relevant interface eth0.IPv4 for mDNS. Jul 21 19:34:18 volumio avahi-daemon[713]: Registering new address record for 169.254.206.69 on eth0.IPv4. Jul 21 19:34:18 volumio nmbd[902]: [2024/07/21 19:34:18.230078, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 21 19:34:18 volumio systemd[1]: Started Samba NMB Daemon. Jul 21 19:34:18 volumio nmbd[902]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 21 19:34:18 volumio systemd[1]: Starting Samba Winbind Daemon... Jul 21 19:34:18 volumio winbindd[1924]: [2024/07/21 19:34:18.412706, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 21 19:34:18 volumio winbindd[1924]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 21 19:34:18 volumio winbindd[1924]: [2024/07/21 19:34:18.416452, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 21 19:34:18 volumio systemd[1]: Started Samba Winbind Daemon. Jul 21 19:34:18 volumio winbindd[1924]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 21 19:34:18 volumio systemd[1]: Starting Samba SMB Daemon... Jul 21 19:34:18 volumio smbd[1930]: [2024/07/21 19:34:18.770884, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 21 19:34:18 volumio systemd[1]: Started Samba SMB Daemon. Jul 21 19:34:18 volumio smbd[1930]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 21 19:34:18 volumio systemd[1]: Reached target Multi-User System. Jul 21 19:34:18 volumio systemd[1]: Reached target Graphical Interface. Jul 21 19:34:18 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 21 19:34:18 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 21 19:34:18 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 21 19:34:18 volumio systemd[1]: Startup finished in 12.994s (kernel) + 1min 5.676s (userspace) = 1min 18.670s. Jul 21 19:34:19 volumio ntpd[921]: Listen normally on 3 eth0 169.254.206.69:123 Jul 21 19:34:19 volumio ntpd[921]: new interface(s) found: waking up resolver Jul 21 19:34:20 volumio dhcpcd[827]: eth0: offered 192.168.255.9 from 192.168.255.1 Jul 21 19:34:20 volumio dhcpcd[827]: eth0: probing address 192.168.255.9/24 Jul 21 19:34:21 volumio volumio[1549]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 21 19:34:22 volumio volumio[1549]: ..........................................................++++ Jul 21 19:34:24 volumio volumio[1549]: ..........................................................................................................++++ Jul 21 19:34:24 volumio volumio[1549]: e is 65537 (0x010001) Jul 21 19:34:24 volumio volumio[1549]: writing RSA key Jul 21 19:34:25 volumio dhcpcd[827]: eth0: leased 192.168.255.9 for 7200 seconds Jul 21 19:34:25 volumio dhcpcd[827]: eth0: adding route to 192.168.255.0/24 Jul 21 19:34:25 volumio dhcpcd[827]: eth0: changing default route via 192.168.255.1 Jul 21 19:34:25 volumio avahi-daemon[713]: Registering new address record for 192.168.255.9 on eth0.IPv4. Jul 21 19:34:25 volumio ntpd[921]: ntpd exiting on signal 15 (Terminated) Jul 21 19:34:25 volumio systemd[1]: Stopping Network Time Service... Jul 21 19:34:25 volumio systemd[1]: ntp.service: Succeeded. Jul 21 19:34:25 volumio systemd[1]: Stopped Network Time Service. Jul 21 19:34:25 volumio systemd[1]: Starting Network Time Service... Jul 21 19:34:25 volumio ntpd[2007]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 21 19:34:25 volumio ntpd[2007]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 21 19:34:25 volumio systemd[1]: Started Network Time Service. Jul 21 19:34:25 volumio avahi-daemon[713]: Withdrawing address record for 169.254.206.69 on eth0. Jul 21 19:34:25 volumio avahi-daemon[713]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.206.69. Jul 21 19:34:25 volumio avahi-daemon[713]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.255.9. Jul 21 19:34:25 volumio ntpd[2015]: proto: precision = 0.162 usec (-22) Jul 21 19:34:25 volumio dhcpcd[827]: eth0: deleting route to 169.254.0.0/16 Jul 21 19:34:25 volumio ntpd[2015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 21 19:34:25 volumio ntpd[2015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 21 19:34:25 volumio ntpd[2015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 572 days ago Jul 21 19:34:25 volumio ntpd[2015]: Listen and drop on 0 v6wildcard [::]:123 Jul 21 19:34:25 volumio ntpd[2015]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 21 19:34:25 volumio ntpd[2015]: Listen normally on 2 lo 127.0.0.1:123 Jul 21 19:34:25 volumio ntpd[2015]: Listen normally on 3 eth0 192.168.255.9:123 Jul 21 19:34:25 volumio ntpd[2015]: Listening on routing socket on fd #20 for interface updates Jul 21 19:34:25 volumio ntpd[2015]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 21 19:34:25 volumio ntpd[2015]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 21 19:34:26 volumio ntpd[2015]: Soliciting pool server 116.203.151.74 Jul 21 19:34:27 volumio ntpd[2015]: Soliciting pool server 84.16.67.12 Jul 21 19:34:28 volumio ntpd[2015]: Soliciting pool server 193.182.111.14 Jul 21 19:34:29 volumio volumio[971]: info: Discovery: adding 2ba6174e-b93b-47e9-9bc3-f2295759ba1c Jul 21 19:34:29 volumio volumio[971]: info: Discovery: Found device Volumio Jul 21 19:34:29 volumio volumio[971]: info: CoreCommandRouter::volumioGetState Jul 21 19:34:29 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Jul 21 19:34:29 volumio ntpd[2015]: Soliciting pool server 116.203.151.74 Jul 21 19:34:29 volumio ntpd[2015]: Soliciting pool server 193.182.111.143 Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 21 19:34:30 volumio volumio[971]: info: Adding plugin bluetooth to MyMusic Plugins Jul 21 19:34:30 volumio volumio[971]: info: Adding plugin multiroom to MyMusic Plugins Jul 21 19:34:30 volumio volumio[971]: info: Adding plugin metavolumio to MyMusic Plugins Jul 21 19:34:30 volumio volumio[971]: info: Adding plugin cd_controller to MyMusic Plugins Jul 21 19:34:30 volumio volumio[971]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 21 19:34:30 volumio volumio[971]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 21 19:34:30 volumio volumio[971]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 21 19:34:30 volumio ntpd[2015]: Soliciting pool server 202.112.29.82 Jul 21 19:34:31 volumio volumio[971]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 21 19:34:31 volumio volumio[971]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 21 19:34:31 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 19:34:31 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 19:34:31 volumio volumio[971]: info: Starting MyVolumio Remote Streaming Endpoints Jul 21 19:34:31 volumio volumio[971]: info: MyVolumio not started Jul 21 19:34:31 volumio volumio[971]: info: Initializing device activation check Jul 21 19:34:31 volumio volumio[971]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 21 19:34:31 volumio volumio[971]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 21 19:34:31 volumio ntpd[2015]: Soliciting pool server 95.111.202.5 Jul 21 19:34:32 volumio volumio[971]: info: Starting Streaming Service Transparent Proxy Jul 21 19:34:32 volumio volumio[971]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 21 19:34:32 volumio volumio[971]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 21 19:34:32 volumio volumio[971]: info: Streaming services startup Jul 21 19:34:32 volumio volumio[971]: info: Starting Streaming Daemon Jul 21 19:34:32 volumio sudo[2077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 21 19:34:32 volumio sudo[2077]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:34:32 volumio sudo[2077]: pam_unix(sudo:session): session closed for user root Jul 21 19:34:32 volumio volumio[971]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 21 19:34:32 volumio volumio[971]: error: Cannot start Volumio Streaming Daemon Jul 21 19:34:32 volumio volumio[971]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 21 19:34:32 volumio volumio[971]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 21 19:34:33 volumio volumio[971]: STREAMING PROXY: Starting server on port 3245 Jul 21 19:34:33 volumio volumio[971]: Node JS runtime: 14 Jul 21 19:34:59 volumio sudo[2112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 21 19:34:59 volumio sudo[2112]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:34:59 volumio sudo[2112]: pam_unix(sudo:session): session closed for user root Jul 21 19:34:59 volumio sudo[2115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 21 19:34:59 volumio sudo[2115]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:34:59 volumio sudo[2115]: pam_unix(sudo:session): session closed for user root Jul 21 19:34:59 volumio volumio[971]: verbose: New Socket.io Connection to 192.168.255.9 from 192.168.255.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Edg/126.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Jul 21 19:35:00 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 21 19:35:00 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 21 19:35:01 volumio sudo[2122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 21 19:35:01 volumio sudo[2122]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:35:01 volumio sudo[2122]: pam_unix(sudo:session): session closed for user root Jul 21 19:35:01 volumio sudo[2125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 21 19:35:01 volumio sudo[2125]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:35:01 volumio sudo[2125]: pam_unix(sudo:session): session closed for user root Jul 21 19:35:01 volumio volumio[971]: verbose: New Socket.io Connection to 192.168.255.9 from 192.168.255.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Edg/126.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Jul 21 19:35:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 21 19:35:01 volumio volumio[971]: info: CoreCommandRouter::volumioGetVisibleSources Jul 21 19:35:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 21 19:35:01 volumio volumio[971]: info: CoreCommandRouter::volumioGetState Jul 21 19:35:01 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Jul 21 19:35:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 21 19:35:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 21 19:35:01 volumio volumio[971]: info: Received Get System Info Jul 21 19:35:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 21 19:35:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 21 19:35:01 volumio volumio[971]: info: Discovery: Getting this device information Jul 21 19:35:01 volumio volumio[971]: info: CoreCommandRouter::volumioGetState Jul 21 19:35:01 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Jul 21 19:35:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 21 19:35:01 volumio volumio[971]: info: CoreCommandRouter::volumioGetState Jul 21 19:35:01 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Jul 21 19:35:01 volumio volumio[971]: info: Listing playlists Jul 21 19:35:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 21 19:35:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 21 19:35:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 21 19:35:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 21 19:35:01 volumio volumio[971]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 21 19:35:01 volumio volumio[971]: info: CoreCommandRouter::volumioGetState Jul 21 19:35:01 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Jul 21 19:35:02 volumio volumio[971]: info: CoreCommandRouter::volumioGetState Jul 21 19:35:02 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Jul 21 19:35:03 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 21 19:35:03 volumio volumio[971]: info: Received Get System Info Jul 21 19:35:03 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 21 19:35:03 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 21 19:35:03 volumio volumio[971]: info: Discovery: Getting this device information Jul 21 19:35:03 volumio volumio[971]: info: CoreCommandRouter::volumioGetState Jul 21 19:35:03 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Jul 21 19:35:03 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 21 19:35:03 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 21 19:35:05 volumio volumio[971]: info: CoreCommandRouter::volumioNext Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::next Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::stop Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::stPlaybackTimer Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::updateTrackBlock Jul 21 19:35:05 volumio volumio[971]: info: CorePlayQueue::getTrackBlock Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::pushState Jul 21 19:35:05 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Jul 21 19:35:05 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 19:35:05 volumio volumio[971]: info: CoreCommandRouter::volumioPushState Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::serviceStop Jul 21 19:35:05 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Jul 21 19:35:05 volumio volumio[971]: info: CoreCommandRouter::serviceStop Jul 21 19:35:05 volumio volumio[971]: info: ControllerMpd::stop Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand stop Jul 21 19:35:05 volumio volumio[971]: info: Jul 21 19:35:05 volumio volumio[971]: ---------------------------- MPD announces state update: player Jul 21 19:35:05 volumio volumio[971]: info: sendMpdCommand stop took 21 milliseconds Jul 21 19:35:05 volumio volumio[971]: info: ControllerMpd::getState Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::play index undefined Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 19:35:05 volumio volumio[971]: info: CorePlayQueue::getTrack 1 Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::startPlaybackTimer Jul 21 19:35:05 volumio volumio[971]: info: CorePlayQueue::getTrack 1 Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::clearAddPlayTracks USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand stop Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::updateTrackBlock Jul 21 19:35:05 volumio volumio[971]: info: CorePlayQueue::getTrackBlock Jul 21 19:35:05 volumio volumio[971]: info: sendMpdCommand status took 6 milliseconds Jul 21 19:35:05 volumio volumio[971]: info: sendMpdCommand stop took 2 milliseconds Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::parseState Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand clear Jul 21 19:35:05 volumio volumio[971]: info: Jul 21 19:35:05 volumio volumio[971]: ---------------------------- MPD announces system playlist update Jul 21 19:35:05 volumio volumio[971]: info: Ignoring MPD Status Update Jul 21 19:35:05 volumio volumio[971]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 21 19:35:05 volumio volumio[971]: info: sendMpdCommand clear took 7 milliseconds Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::parseTrackInfo Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand add "USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav" Jul 21 19:35:05 volumio volumio[971]: info: ControllerMpd::pushState Jul 21 19:35:05 volumio volumio[971]: info: CoreCommandRouter::servicePushState Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::pushState Jul 21 19:35:05 volumio volumio[971]: info: CorePlayQueue::getTrack 1 Jul 21 19:35:05 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 19:35:05 volumio volumio[971]: info: CoreCommandRouter::volumioPushState Jul 21 19:35:05 volumio volumio[971]: info: CorePlayQueue::getTrack 1 Jul 21 19:35:05 volumio volumio[971]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.月光小夜曲.wav","artist":null,"album":null,"uri":"USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav","trackType":"wav"} Jul 21 19:35:05 volumio volumio[971]: verbose: CURRENT POSITION 1 Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::syncState stateService stop Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::syncState currentStatus stop Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::pushState Jul 21 19:35:05 volumio volumio[971]: info: CorePlayQueue::getTrack 1 Jul 21 19:35:05 volumio volumio[971]: info: CoreCommandRouter::volumioPushState Jul 21 19:35:05 volumio volumio[971]: info: No code Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::pushState Jul 21 19:35:05 volumio volumio[971]: info: CorePlayQueue::getTrack 1 Jul 21 19:35:05 volumio volumio[971]: info: CoreCommandRouter::volumioPushState Jul 21 19:35:05 volumio volumio[971]: info: ------------------------------ 63ms Jul 21 19:35:05 volumio volumio[971]: error: updateQueue error: null Jul 21 19:35:05 volumio volumio[971]: info: Jul 21 19:35:05 volumio volumio[971]: ---------------------------- MPD announces system playlist update Jul 21 19:35:05 volumio volumio[971]: info: Ignoring MPD Status Update Jul 21 19:35:05 volumio volumio[971]: info: ------------------------------ 53ms Jul 21 19:35:05 volumio volumio[971]: info: sendMpdCommand add "USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav" took 48 milliseconds Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand play Jul 21 19:35:05 volumio volumio[971]: info: Jul 21 19:35:05 volumio volumio[971]: ---------------------------- MPD announces state update: player Jul 21 19:35:05 volumio volumio[971]: info: ------------------------------ 19ms Jul 21 19:35:05 volumio volumio[971]: info: sendMpdCommand play took 19 milliseconds Jul 21 19:35:05 volumio volumio[971]: info: ControllerMpd::getState Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:35:05 volumio volumio[971]: info: Jul 21 19:35:05 volumio volumio[971]: ---------------------------- MPD announces state update: player Jul 21 19:35:05 volumio volumio[971]: info: ControllerMpd::getState Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:35:05 volumio volumio[971]: info: sendMpdCommand status took 6 milliseconds Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::parseState Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 19:35:05 volumio volumio[971]: info: sendMpdCommand status took 6 milliseconds Jul 21 19:35:05 volumio volumio[971]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::parseState Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::parseTrackInfo Jul 21 19:35:05 volumio volumio[971]: info: ControllerMpd::pushState Jul 21 19:35:05 volumio volumio[971]: info: CoreCommandRouter::servicePushState Jul 21 19:35:05 volumio volumio[971]: info: CorePlayQueue::getTrack 1 Jul 21 19:35:05 volumio volumio[971]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.初次尝到寂寞.wav","artist":null,"album":null,"uri":"USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav","trackType":"wav"} Jul 21 19:35:05 volumio volumio[971]: verbose: CURRENT POSITION 1 Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::syncState stateService play Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::syncState currentStatus stop Jul 21 19:35:05 volumio volumio[971]: info: ------------------------------ 18ms Jul 21 19:35:05 volumio volumio[971]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 21 19:35:05 volumio volumio[971]: verbose: ControllerMpd::parseTrackInfo Jul 21 19:35:05 volumio volumio[971]: info: ControllerMpd::pushState Jul 21 19:35:05 volumio volumio[971]: info: CoreCommandRouter::servicePushState Jul 21 19:35:05 volumio volumio[971]: info: CorePlayQueue::getTrack 1 Jul 21 19:35:05 volumio volumio[971]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.初次尝到寂寞.wav","artist":null,"album":null,"uri":"USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav","trackType":"wav"} Jul 21 19:35:05 volumio volumio[971]: verbose: CURRENT POSITION 1 Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::syncState stateService play Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::syncState currentStatus play Jul 21 19:35:05 volumio volumio[971]: info: Received an update from plugin. extracting info from payload Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::pushState Jul 21 19:35:05 volumio volumio[971]: info: CorePlayQueue::getTrack 1 Jul 21 19:35:05 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 19:35:05 volumio volumio[971]: info: CoreCommandRouter::volumioPushState Jul 21 19:35:05 volumio volumio[971]: info: CoreStateMachine::pushState Jul 21 19:35:05 volumio volumio[971]: info: CorePlayQueue::getTrack 1 Jul 21 19:35:05 volumio volumio[971]: info: CoreCommandRouter::volumioPushState Jul 21 19:35:05 volumio volumio[971]: info: ------------------------------ 42ms Jul 21 19:35:06 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:35:06 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:35:06 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 21 19:35:06 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 21 19:35:06 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 21 19:35:06 volumio volumio[971]: info: CoreCommandRouter::volumioGetBrowseSources Jul 21 19:35:06 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 21 19:35:07 volumio volumio[971]: info: CoreCommandRouter::volumioPause Jul 21 19:35:07 volumio volumio[971]: info: CoreStateMachine::pause Jul 21 19:35:07 volumio volumio[971]: info: CoreStateMachine::stPlaybackTimer Jul 21 19:35:07 volumio volumio[971]: info: CoreStateMachine::servicePause Jul 21 19:35:07 volumio volumio[971]: info: CorePlayQueue::getTrack 1 Jul 21 19:35:07 volumio volumio[971]: info: CoreCommandRouter::servicePause Jul 21 19:35:07 volumio volumio[971]: info: ControllerMpd::pause Jul 21 19:35:07 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand pause Jul 21 19:35:07 volumio volumio[971]: info: Jul 21 19:35:07 volumio volumio[971]: ---------------------------- MPD announces state update: player Jul 21 19:35:07 volumio volumio[971]: info: sendMpdCommand pause took 2 milliseconds Jul 21 19:35:07 volumio volumio[971]: info: ControllerMpd::getState Jul 21 19:35:07 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:35:07 volumio volumio[971]: info: sendMpdCommand status took 1 milliseconds Jul 21 19:35:07 volumio volumio[971]: verbose: ControllerMpd::parseState Jul 21 19:35:07 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 19:35:07 volumio volumio[971]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 21 19:35:07 volumio volumio[971]: verbose: ControllerMpd::parseTrackInfo Jul 21 19:35:07 volumio volumio[971]: info: ControllerMpd::pushState Jul 21 19:35:07 volumio volumio[971]: info: CoreCommandRouter::servicePushState Jul 21 19:35:07 volumio volumio[971]: info: CorePlayQueue::getTrack 1 Jul 21 19:35:07 volumio volumio[971]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2276,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.初次尝到寂寞.wav","artist":null,"album":null,"uri":"USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav","trackType":"wav"} Jul 21 19:35:07 volumio volumio[971]: verbose: CURRENT POSITION 1 Jul 21 19:35:07 volumio volumio[971]: info: CoreStateMachine::syncState stateService pause Jul 21 19:35:07 volumio volumio[971]: info: CoreStateMachine::syncState currentStatus pause Jul 21 19:35:07 volumio volumio[971]: info: CoreStateMachine::pushState Jul 21 19:35:07 volumio volumio[971]: info: CorePlayQueue::getTrack 1 Jul 21 19:35:07 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 19:35:07 volumio volumio[971]: info: CoreCommandRouter::volumioPushState Jul 21 19:35:07 volumio volumio[971]: info: CoreStateMachine::stPlaybackTimer Jul 21 19:35:07 volumio volumio[971]: info: ------------------------------ 16ms Jul 21 19:35:07 volumio volumio[971]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 21 19:35:07 volumio volumio[971]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Jul 21 19:35:07 volumio volumio[971]: at Object.readdirSync (fs.js:1048:3) Jul 21 19:35:07 volumio volumio[971]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Jul 21 19:35:07 volumio volumio[971]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Jul 21 19:35:07 volumio volumio[971]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Jul 21 19:35:07 volumio volumio[971]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Jul 21 19:35:07 volumio volumio[971]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Jul 21 19:35:07 volumio volumio[971]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jul 21 19:35:07 volumio volumio[971]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Jul 21 19:35:07 volumio volumio[971]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Jul 21 19:35:07 volumio volumio[971]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Jul 21 19:35:07 volumio volumio[971]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jul 21 19:35:07 volumio volumio[971]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Jul 21 19:35:07 volumio volumio[971]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Jul 21 19:35:07 volumio volumio[971]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jul 21 19:35:07 volumio volumio[971]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Jul 21 19:35:07 volumio volumio[971]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Jul 21 19:35:07 volumio volumio[971]: errno: -20, Jul 21 19:35:07 volumio volumio[971]: syscall: 'scandir', Jul 21 19:35:07 volumio volumio[971]: code: 'ENOTDIR', Jul 21 19:35:07 volumio volumio[971]: path: '/tmp/peppyrunning' Jul 21 19:35:07 volumio volumio[971]: } Jul 21 19:35:07 volumio volumio[971]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 21 19:35:07 volumio sudo[2183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-21 19:34 Jul 21 19:35:07 volumio sudo[2183]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"