-- Logs begin at Wed 2024-10-23 23:31:26 CST, end at Thu 2024-10-24 20:01:23 CST. -- Oct 24 20:00:00 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Oct 24 20:00:00 volumio dhcpcd[1059]: eth0: soliciting an IPv6 router Oct 24 20:00:00 volumio ifplugd(eth0)[814]: Link beat detected. Oct 24 20:00:00 volumio dhcpcd[1059]: eth0: rebinding lease of 192.168.1.79 Oct 24 20:00:00 volumio dhcpcd[1059]: eth0: probing address 192.168.1.79/24 Oct 24 20:00:05 volumio dhcpcd[1059]: eth0: leased 192.168.1.79 for 86400 seconds Oct 24 20:00:05 volumio avahi-daemon[680]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.79. Oct 24 20:00:05 volumio dhcpcd[1059]: eth0: adding route to 192.168.1.0/24 Oct 24 20:00:05 volumio avahi-daemon[680]: New relevant interface eth0.IPv4 for mDNS. Oct 24 20:00:05 volumio dhcpcd[1059]: eth0: adding default route via 192.168.1.1 Oct 24 20:00:05 volumio avahi-daemon[680]: Registering new address record for 192.168.1.79 on eth0.IPv4. Oct 24 20:00:06 volumio volumio[1414]: info: Discovery: adding 6d8dc8c5-bcf1-4e9e-925a-73c9efb43d60 Oct 24 20:00:06 volumio volumio[1414]: info: Discovery: Found device Volumio Oct 24 20:00:06 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 24 20:00:07 volumio shairport-sync[1609]: {"time":1729768618048,"response":"startAirplayPlayback Success"}Very large sync error: 157881 frames, with delay: 246081, td_in_frames: 56713, rt: 8547196014, nt: 8547137478, current_delay: 130832, seqno: 27618, given timestamp: 4252170182. Oct 24 20:00:07 volumio ntpd[818]: Listen normally on 4 eth0 192.168.1.79:123 Oct 24 20:00:07 volumio ntpd[818]: new interface(s) found: waking up resolver Oct 24 20:00:07 volumio shairport-sync[1609]: Very large sync error: 157877 frames, with delay: 246077, td_in_frames: 57222, rt: 8547196014, nt: 8547137830, current_delay: 130671, seqno: 27619, given timestamp: 4252170534. Oct 24 20:00:07 volumio shairport-sync[1609]: Very large sync error: 157879 frames, with delay: 246079, td_in_frames: 57241, rt: 8547196014, nt: 8547138182, current_delay: 131006, seqno: 27620, given timestamp: 4252170886. Oct 24 20:00:07 volumio shairport-sync[1609]: Very large sync error: 157874 frames, with delay: 246074, td_in_frames: 58245, rt: 8547196014, nt: 8547139238, current_delay: 131053, seqno: 27623, given timestamp: 4252171942. Oct 24 20:00:07 volumio shairport-sync[1609]: Shome mhistake shurely: very large number of frames to drop: 157874 -- setting it to 132300. Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:08 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:11 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:11 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:11 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:00:11 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:11 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:11 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:11 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:11 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:11 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:11 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:12 volumio shairport-sync[1609]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 24 20:00:12 volumio shairport-sync[1609]: Dload Upload Total Spent Left Speed Oct 24 20:00:12 volumio volumio[1414]: info: Oct 24 20:00:12 volumio volumio[1414]: ---------------------------- Client requests Stop Airplay Playback Oct 24 20:00:12 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Oct 24 20:00:12 volumio volumio[1414]: info: Airplay playback stop Oct 24 20:00:12 volumio volumio[1414]: info: Airplay Playback Stopped, clearing state Oct 24 20:00:12 volumio volumio[1414]: info: CoreStateMachine::resetVolumioState Oct 24 20:00:12 volumio volumio[1414]: info: CoreStateMachine::getcurrentVolume Oct 24 20:00:12 volumio volumio[1414]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 20:00:12 volumio shairport-sync[1609]: [158B blob data] Oct 24 20:00:12 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:12 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:14 volumio volumio[1414]: info: Airplay started streaming, receiving metadatas Oct 24 20:00:14 volumio volumio[1414]: info: Airplay started streaming, receiving metadatas Oct 24 20:00:14 volumio shairport-sync[1609]: {"time":1729771212007,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Oct 24 20:00:14 volumio shairport-sync[1609]: Dload Upload Total Spent Left Speed Oct 24 20:00:14 volumio volumio[1414]: info: Oct 24 20:00:14 volumio volumio[1414]: ---------------------------- Client requests Start Airplay PlaybackRoutine Oct 24 20:00:14 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Oct 24 20:00:14 volumio volumio[1414]: info: Airplay playback start Oct 24 20:00:14 volumio shairport-sync[1609]: [158B blob data] Oct 24 20:00:15 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:15 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:15 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:00:15 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:15 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:15 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:15 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:15 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:15 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:15 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:15 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:15 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:15 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:00:16 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:00:16 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:16 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:26 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:30 volumio volumio[1414]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Music%20therapy/4fecdcd4-836e-4483-99d7-0cd21a6ec88e.jpg' Oct 24 20:00:32 volumio volumio[1414]: verbose: New Socket.io Connection to 192.168.1.79 from 192.168.1.205 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 4 Oct 24 20:00:32 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 24 20:00:32 volumio volumio[1414]: info: CoreCommandRouter::volumioGetVisibleSources Oct 24 20:00:32 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 20:00:32 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 24 20:00:32 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 24 20:00:32 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 24 20:00:32 volumio volumio[1414]: info: Received Get System Info Oct 24 20:00:32 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 20:00:32 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 20:00:32 volumio volumio[1414]: info: Discovery: Getting this device information Oct 24 20:00:32 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 24 20:00:32 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 20:00:32 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 24 20:00:32 volumio volumio[1414]: info: Listing playlists Oct 24 20:00:32 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 24 20:00:43 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:00:43 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:00:43 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:43 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:45 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:45 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:45 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:00:45 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:45 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:45 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:45 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:45 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:45 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:45 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:45 volumio shairport-sync[1609]: {"time":1729771214704,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Oct 24 20:00:45 volumio shairport-sync[1609]: Dload Upload Total Spent Left Speed Oct 24 20:00:45 volumio volumio[1414]: info: Oct 24 20:00:45 volumio volumio[1414]: ---------------------------- Client requests Stop Airplay Playback Oct 24 20:00:45 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Oct 24 20:00:45 volumio volumio[1414]: info: Airplay playback stop Oct 24 20:00:45 volumio volumio[1414]: info: Airplay Playback Stopped, clearing state Oct 24 20:00:45 volumio volumio[1414]: info: CoreStateMachine::resetVolumioState Oct 24 20:00:45 volumio volumio[1414]: info: CoreStateMachine::getcurrentVolume Oct 24 20:00:45 volumio volumio[1414]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 20:00:45 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:45 volumio shairport-sync[1609]: [158B blob data] Oct 24 20:00:45 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:46 volumio volumio[1414]: info: Airplay started streaming, receiving metadatas Oct 24 20:00:46 volumio volumio[1414]: info: Airplay started streaming, receiving metadatas Oct 24 20:00:47 volumio shairport-sync[1609]: {"time":1729771245156,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Oct 24 20:00:47 volumio shairport-sync[1609]: Dload Upload Total Spent Left Speed Oct 24 20:00:47 volumio volumio[1414]: info: Oct 24 20:00:47 volumio volumio[1414]: ---------------------------- Client requests Start Airplay PlaybackRoutine Oct 24 20:00:47 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Oct 24 20:00:47 volumio volumio[1414]: info: Airplay playback start Oct 24 20:00:47 volumio shairport-sync[1609]: [158B blob data] Oct 24 20:00:47 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:47 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:47 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:00:47 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:47 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:47 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:47 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:00:48 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:00:59 volumio volumio[1414]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%B0%AD%E5%92%8F%E9%BA%9F/ff2131dd-a2f7-4de8-a259-3c58a95b62c7.jpg' Oct 24 20:01:00 volumio volumio[1414]: verbose: New Socket.io Connection to 192.168.1.79 from 192.168.1.205 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 4 Oct 24 20:01:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 24 20:01:00 volumio volumio[1414]: info: CoreCommandRouter::volumioGetVisibleSources Oct 24 20:01:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 20:01:00 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 24 20:01:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 24 20:01:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 24 20:01:00 volumio volumio[1414]: info: Received Get System Info Oct 24 20:01:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 20:01:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 20:01:00 volumio volumio[1414]: info: Discovery: Getting this device information Oct 24 20:01:00 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 24 20:01:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 20:01:00 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 24 20:01:00 volumio volumio[1414]: info: Listing playlists Oct 24 20:01:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 24 20:01:21 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:01:21 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:01:21 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:01:21 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:01:21 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:01:21 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:01:21 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:01:21 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:01:21 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:01:21 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::servicePushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreStateMachine::pushState Oct 24 20:01:22 volumio volumio[1414]: info: CoreCommandRouter::volumioPushState Oct 24 20:01:23 volumio volumio[1414]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 20:01:23 volumio volumio[1414]: Error: connect ECONNREFUSED 162.220.12.226:80 Oct 24 20:01:23 volumio volumio[1414]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 24 20:01:23 volumio volumio[1414]: errno: -111, Oct 24 20:01:23 volumio volumio[1414]: code: 'ECONNREFUSED', Oct 24 20:01:23 volumio volumio[1414]: syscall: 'connect', Oct 24 20:01:23 volumio volumio[1414]: address: '162.220.12.226', Oct 24 20:01:23 volumio volumio[1414]: port: 80 Oct 24 20:01:23 volumio volumio[1414]: } Oct 24 20:01:23 volumio volumio[1414]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 20:01:23 volumio sudo[4059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-24 20:00 Oct 24 20:01:23 volumio sudo[4059]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"