-- 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"