-- Logs begin at Thu 2019-02-14 19:12:00 JST, end at Sat 2024-10-12 06:43:39 JST. -- Oct 12 06:42:00 volumioplus kernel: hwmon hwmon1: Voltage normalised Oct 12 06:42:02 volumioplus kernel: hwmon hwmon1: Undervoltage detected! Oct 12 06:42:04 volumioplus volumio[2842]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Total Clients: 5 Oct 12 06:42:04 volumioplus volumio[2842]: info: CoreCommandRouter::volumioGetState Oct 12 06:42:04 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:42:06 volumioplus ntpd[691]: Soliciting pool server 129.250.35.250 Oct 12 06:42:08 volumioplus go-librespot[2977]: time="2024-10-12T06:42:08+09:00" level=error msg="did not receive last pong from dealer, 60s passed" Oct 12 06:42:13 volumioplus go-librespot[2977]: time="2024-10-12T06:42:13+09:00" level=error msg="failed receiving dealer message" error="failed to get reader: WebSocket closed: sent close frame: status = StatusServiceRestart and reason = \"\"" Oct 12 06:42:14 volumioplus go-librespot[2977]: time="2024-10-12T06:42:14+09:00" level=debug msg="re-established dealer connection" Oct 12 06:42:14 volumioplus go-librespot[2977]: time="2024-10-12T06:42:14+09:00" level=debug msg="received connection id: OWFmZWZhMzEtZTYyYi00NTFjLWI2OWQtNDNmYWM5ZGY4OGZjK2RlYWxlcit0Y3A6Ly8wYWNiZjk5OS5pcC5ndWUxLnNwb3RpZnkubmV0OjU3MDArNjNBQzFCQ0JBRTIxRjZEMjUzMjY3MEU4MUI2QzAyRDFEQ0IyOEQ5QzNFMjhERTE2RTEzRTc5QkM2N0MzNjlBMw==" Oct 12 06:42:14 volumioplus go-librespot[2977]: time="2024-10-12T06:42:14+09:00" level=debug msg="put connect state because NEW_DEVICE" Oct 12 06:42:19 volumioplus kernel: hwmon hwmon1: Voltage normalised Oct 12 06:42:40 volumioplus kernel: hwmon hwmon1: Undervoltage detected! Oct 12 06:42:48 volumioplus dhcpcd[788]: eth0: carrier acquired Oct 12 06:42:48 volumioplus kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 12 06:42:48 volumioplus dhcpcd[788]: eth0: IAID eb:83:a1:c2 Oct 12 06:42:48 volumioplus dhcpcd[788]: eth0: soliciting an IPv6 router Oct 12 06:42:48 volumioplus dhcpcd[788]: eth0: soliciting a DHCP lease Oct 12 06:42:49 volumioplus ifplugd(eth0)[683]: Link beat detected. Oct 12 06:42:49 volumioplus ifplugd(eth0)[683]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 12 06:42:49 volumioplus dhcpcd[3605]: sending commands to master dhcpcd process Oct 12 06:42:49 volumioplus ifplugd(eth0)[683]: client: sending commands to master dhcpcd process Oct 12 06:42:49 volumioplus dhcpcd[869]: control command: /sbin/dhcpcd eth0 Oct 12 06:42:49 volumioplus dhcpcd[869]: eth0: carrier acquired Oct 12 06:42:49 volumioplus dhcpcd[869]: eth0: IAID eb:83:a1:c2 Oct 12 06:42:49 volumioplus dhcpcd[788]: eth0: offered 192.168.1.112 from 192.168.1.1 Oct 12 06:42:49 volumioplus dhcpcd[788]: eth0: probing address 192.168.1.112/24 Oct 12 06:42:49 volumioplus ifplugd(eth0)[683]: Program executed successfully. Oct 12 06:42:49 volumioplus dhcpcd[869]: eth0: soliciting an IPv6 router Oct 12 06:42:49 volumioplus dhcpcd[869]: eth0: soliciting a DHCP lease Oct 12 06:42:51 volumioplus dhcpcd[869]: eth0: offered 192.168.1.112 from 192.168.1.1 Oct 12 06:42:51 volumioplus dhcpcd[869]: eth0: probing address 192.168.1.112/24 Oct 12 06:42:54 volumioplus dhcpcd[788]: eth0: leased 192.168.1.112 for 14400 seconds Oct 12 06:42:54 volumioplus dhcpcd[788]: eth0: adding route to 192.168.1.0/24 Oct 12 06:42:54 volumioplus avahi-daemon[509]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.112. Oct 12 06:42:54 volumioplus dhcpcd[788]: eth0: adding default route via 192.168.1.1 Oct 12 06:42:54 volumioplus avahi-daemon[509]: New relevant interface eth0.IPv4 for mDNS. Oct 12 06:42:54 volumioplus avahi-daemon[509]: Registering new address record for 192.168.1.112 on eth0.IPv4. Oct 12 06:42:55 volumioplus ntpd[691]: Listen normally on 5 eth0 192.168.1.112:123 Oct 12 06:42:55 volumioplus ntpd[691]: new interface(s) found: waking up resolver Oct 12 06:42:56 volumioplus dhcpcd[869]: eth0: leased 192.168.1.112 for 14400 seconds Oct 12 06:42:56 volumioplus dhcpcd[869]: eth0: adding route to 192.168.1.0/24 Oct 12 06:42:56 volumioplus dhcpcd[869]: eth0: adding default route via 192.168.1.1 Oct 12 06:42:58 volumioplus volumio[2842]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 06:42:58 volumioplus volumio[2842]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 06:42:58 volumioplus volumio[2842]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 06:42:58 volumioplus volumio[2842]: info: Listing playlists Oct 12 06:42:58 volumioplus volumio[2842]: info: CoreCommandRouter::volumioGetQueue Oct 12 06:42:58 volumioplus volumio[2842]: info: CoreStateMachine::getQueue Oct 12 06:42:58 volumioplus volumio[2842]: info: CorePlayQueue::getQueue Oct 12 06:42:59 volumioplus volumio[2842]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Total Clients: 5 Oct 12 06:42:59 volumioplus volumio[2842]: info: CoreCommandRouter::volumioGetState Oct 12 06:42:59 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:42:59 volumioplus volumio[2842]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 06:42:59 volumioplus volumio[2842]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 06:42:59 volumioplus volumio[2842]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 06:42:59 volumioplus volumio[2842]: info: Listing playlists Oct 12 06:42:59 volumioplus volumio[2842]: info: CoreCommandRouter::volumioGetQueue Oct 12 06:42:59 volumioplus volumio[2842]: info: CoreStateMachine::getQueue Oct 12 06:42:59 volumioplus volumio[2842]: info: CorePlayQueue::getQueue Oct 12 06:43:11 volumioplus volumio[2842]: info: Discovery: adding 3e394578-6d54-4b17-bcec-c4f6f14d7374 Oct 12 06:43:11 volumioplus volumio[2842]: info: Discovery: Found device VolumioPlus Oct 12 06:43:11 volumioplus volumio[2842]: info: CoreCommandRouter::volumioGetState Oct 12 06:43:11 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:13 volumioplus go-librespot[2977]: time="2024-10-12T06:43:13+09:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.1.112:41268->104.199.241.202:4070: read: connection reset by peer" Oct 12 06:43:13 volumioplus go-librespot[2977]: time="2024-10-12T06:43:13+09:00" level=debug msg="completed keyexchange" Oct 12 06:43:13 volumioplus go-librespot[2977]: time="2024-10-12T06:43:13+09:00" level=debug msg="completed challenge" Oct 12 06:43:13 volumioplus go-librespot[2977]: time="2024-10-12T06:43:13+09:00" level=debug msg="authenticated as ujehabij3v84w5jhzpsnaa6ae" Oct 12 06:43:13 volumioplus go-librespot[2977]: time="2024-10-12T06:43:13+09:00" level=debug msg="re-established accesspoint connection" Oct 12 06:43:13 volumioplus go-librespot[2977]: time="2024-10-12T06:43:13+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 12 06:43:13 volumioplus go-librespot[2977]: time="2024-10-12T06:43:13+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 12 06:43:13 volumioplus go-librespot[2977]: time="2024-10-12T06:43:13+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 12 06:43:13 volumioplus go-librespot[2977]: time="2024-10-12T06:43:13+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 12 06:43:13 volumioplus go-librespot[2977]: time="2024-10-12T06:43:13+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 495" Oct 12 06:43:23 volumioplus volumio[2842]: info: MPD Database updated - AlbumList cache refreshed Oct 12 06:43:23 volumioplus volumio[2842]: info: Oct 12 06:43:23 volumioplus volumio[2842]: ---------------------------- MPD announces state update: database Oct 12 06:43:23 volumioplus volumio[2842]: verbose: ControllerMpd::sendMpdCommand status Oct 12 06:43:23 volumioplus volumio[2842]: info: Oct 12 06:43:23 volumioplus volumio[2842]: ---------------------------- MPD announces state update: update Oct 12 06:43:23 volumioplus volumio[2842]: info: ControllerMpd::getState Oct 12 06:43:23 volumioplus volumio[2842]: verbose: ControllerMpd::sendMpdCommand status Oct 12 06:43:23 volumioplus volumio[2842]: info: ControllerMpd::getState Oct 12 06:43:23 volumioplus volumio[2842]: verbose: ControllerMpd::sendMpdCommand status Oct 12 06:43:23 volumioplus volumio[2842]: info: MPD Database updated - AlbumList cache refreshed Oct 12 06:43:23 volumioplus volumio[2842]: info: Oct 12 06:43:23 volumioplus volumio[2842]: ---------------------------- MPD announces state update: database Oct 12 06:43:23 volumioplus volumio[2842]: verbose: ControllerMpd::sendMpdCommand status Oct 12 06:43:23 volumioplus volumio[2842]: info: Oct 12 06:43:23 volumioplus volumio[2842]: ---------------------------- MPD announces state update: update Oct 12 06:43:23 volumioplus volumio[2842]: info: ControllerMpd::getState Oct 12 06:43:23 volumioplus volumio[2842]: verbose: ControllerMpd::sendMpdCommand status Oct 12 06:43:23 volumioplus volumio[2842]: info: ControllerMpd::getState Oct 12 06:43:23 volumioplus volumio[2842]: verbose: ControllerMpd::sendMpdCommand status Oct 12 06:43:23 volumioplus volumio[2842]: info: MPD Database updated - AlbumList cache refreshed Oct 12 06:43:23 volumioplus volumio[2842]: info: Oct 12 06:43:23 volumioplus volumio[2842]: ---------------------------- MPD announces state update: database Oct 12 06:43:23 volumioplus volumio[2842]: verbose: ControllerMpd::sendMpdCommand status Oct 12 06:43:23 volumioplus volumio[2842]: info: Oct 12 06:43:23 volumioplus volumio[2842]: ---------------------------- MPD announces state update: update Oct 12 06:43:23 volumioplus volumio[2842]: info: ControllerMpd::getState Oct 12 06:43:23 volumioplus volumio[2842]: verbose: ControllerMpd::sendMpdCommand status Oct 12 06:43:23 volumioplus volumio[2842]: info: ControllerMpd::getState Oct 12 06:43:23 volumioplus volumio[2842]: verbose: ControllerMpd::sendMpdCommand status Oct 12 06:43:26 volumioplus volumio[2842]: info: sendMpdCommand status took 3408 milliseconds Oct 12 06:43:26 volumioplus volumio[2842]: info: sendMpdCommand status took 3407 milliseconds Oct 12 06:43:26 volumioplus volumio[2842]: info: sendMpdCommand status took 3405 milliseconds Oct 12 06:43:26 volumioplus volumio[2842]: verbose: ControllerMpd::parseState Oct 12 06:43:26 volumioplus volumio[2842]: info: Command Router : Notfying DB Updatefalse Oct 12 06:43:26 volumioplus volumio[2842]: info: CoreCommandRouter::Close All Modals sent Oct 12 06:43:26 volumioplus volumio[2842]: verbose: ControllerMpd::parseState Oct 12 06:43:26 volumioplus volumio[2842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 06:43:26 volumioplus volumio[2842]: verbose: ControllerMpd::parseState Oct 12 06:43:26 volumioplus volumio[2842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 06:43:29 volumioplus volumio[2842]: info: sendMpdCommand status took 5727 milliseconds Oct 12 06:43:29 volumioplus volumio[2842]: info: sendMpdCommand status took 5724 milliseconds Oct 12 06:43:29 volumioplus volumio[2842]: info: sendMpdCommand status took 5723 milliseconds Oct 12 06:43:29 volumioplus volumio[2842]: verbose: ControllerMpd::parseState Oct 12 06:43:29 volumioplus volumio[2842]: info: Command Router : Notfying DB Updatefalse Oct 12 06:43:29 volumioplus volumio[2842]: info: CoreCommandRouter::Close All Modals sent Oct 12 06:43:29 volumioplus volumio[2842]: verbose: ControllerMpd::parseState Oct 12 06:43:29 volumioplus volumio[2842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 06:43:29 volumioplus volumio[2842]: verbose: ControllerMpd::parseState Oct 12 06:43:29 volumioplus volumio[2842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 06:43:30 volumioplus volumio[2842]: info: sendMpdCommand status took 7575 milliseconds Oct 12 06:43:30 volumioplus volumio[2842]: info: sendMpdCommand status took 7571 milliseconds Oct 12 06:43:30 volumioplus volumio[2842]: info: sendMpdCommand status took 7570 milliseconds Oct 12 06:43:30 volumioplus volumio[2842]: verbose: ControllerMpd::parseState Oct 12 06:43:31 volumioplus volumio[2842]: info: Command Router : Notfying DB Updatefalse Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::Close All Modals sent Oct 12 06:43:31 volumioplus volumio[2842]: verbose: ControllerMpd::parseState Oct 12 06:43:31 volumioplus volumio[2842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 06:43:31 volumioplus volumio[2842]: verbose: ControllerMpd::parseState Oct 12 06:43:31 volumioplus volumio[2842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 06:43:31 volumioplus volumio[2842]: info: sendMpdCommand playlistinfo took 4202 milliseconds Oct 12 06:43:31 volumioplus volumio[2842]: info: sendMpdCommand playlistinfo took 4201 milliseconds Oct 12 06:43:31 volumioplus volumio[2842]: info: sendMpdCommand playlistinfo took 1884 milliseconds Oct 12 06:43:31 volumioplus volumio[2842]: info: sendMpdCommand playlistinfo took 1884 milliseconds Oct 12 06:43:31 volumioplus volumio[2842]: info: sendMpdCommand playlistinfo took 31 milliseconds Oct 12 06:43:31 volumioplus volumio[2842]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 12 06:43:31 volumioplus volumio[2842]: verbose: ControllerMpd::parseTrackInfo Oct 12 06:43:31 volumioplus volumio[2842]: verbose: ControllerMpd::parseTrackInfo Oct 12 06:43:31 volumioplus volumio[2842]: verbose: ControllerMpd::parseTrackInfo Oct 12 06:43:31 volumioplus volumio[2842]: verbose: ControllerMpd::parseTrackInfo Oct 12 06:43:31 volumioplus volumio[2842]: verbose: ControllerMpd::parseTrackInfo Oct 12 06:43:31 volumioplus volumio[2842]: verbose: ControllerMpd::parseTrackInfo Oct 12 06:43:31 volumioplus volumio[2842]: info: ControllerMpd::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::servicePushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82324,"duration":581,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"499 Kbps","isStreaming":false,"title":"Europe Endless","artist":"Kraftwerk","album":"Trans Europa Express","uri":"NAS/Music/Kraftwerk/Trans Europe Express/01 Kraftwerk - Europe Endless.flac","trackType":"flac"} Oct 12 06:43:31 volumioplus volumio[2842]: verbose: CURRENT POSITION 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::syncState stateService play Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::syncState currentStatus play Oct 12 06:43:31 volumioplus volumio[2842]: info: Received an update from plugin. extracting info from payload Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::volumioPushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::volumioPushState Oct 12 06:43:31 volumioplus volumio[2842]: info: ControllerMpd::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::servicePushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82324,"duration":581,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"499 Kbps","isStreaming":false,"title":"Europe Endless","artist":"Kraftwerk","album":"Trans Europa Express","uri":"NAS/Music/Kraftwerk/Trans Europe Express/01 Kraftwerk - Europe Endless.flac","trackType":"flac"} Oct 12 06:43:31 volumioplus volumio[2842]: verbose: CURRENT POSITION 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::syncState stateService play Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::syncState currentStatus play Oct 12 06:43:31 volumioplus volumio[2842]: info: Received an update from plugin. extracting info from payload Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::volumioPushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::volumioPushState Oct 12 06:43:31 volumioplus volumio[2842]: info: ControllerMpd::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::servicePushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82736,"duration":581,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"578 Kbps","isStreaming":false,"title":"Europe Endless","artist":"Kraftwerk","album":"Trans Europa Express","uri":"NAS/Music/Kraftwerk/Trans Europe Express/01 Kraftwerk - Europe Endless.flac","trackType":"flac"} Oct 12 06:43:31 volumioplus volumio[2842]: verbose: CURRENT POSITION 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::syncState stateService play Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::syncState currentStatus play Oct 12 06:43:31 volumioplus volumio[2842]: info: Received an update from plugin. extracting info from payload Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::volumioPushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::volumioPushState Oct 12 06:43:31 volumioplus volumio[2842]: info: ControllerMpd::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::servicePushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82736,"duration":581,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"578 Kbps","isStreaming":false,"title":"Europe Endless","artist":"Kraftwerk","album":"Trans Europa Express","uri":"NAS/Music/Kraftwerk/Trans Europe Express/01 Kraftwerk - Europe Endless.flac","trackType":"flac"} Oct 12 06:43:31 volumioplus volumio[2842]: verbose: CURRENT POSITION 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::syncState stateService play Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::syncState currentStatus play Oct 12 06:43:31 volumioplus volumio[2842]: info: Received an update from plugin. extracting info from payload Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::volumioPushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::volumioPushState Oct 12 06:43:31 volumioplus volumio[2842]: info: ControllerMpd::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::servicePushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83126,"duration":581,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"504 Kbps","isStreaming":false,"title":"Europe Endless","artist":"Kraftwerk","album":"Trans Europa Express","uri":"NAS/Music/Kraftwerk/Trans Europe Express/01 Kraftwerk - Europe Endless.flac","trackType":"flac"} Oct 12 06:43:31 volumioplus volumio[2842]: verbose: CURRENT POSITION 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::syncState stateService play Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::syncState currentStatus play Oct 12 06:43:31 volumioplus volumio[2842]: info: Received an update from plugin. extracting info from payload Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::volumioPushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::volumioPushState Oct 12 06:43:31 volumioplus volumio[2842]: info: ControllerMpd::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::servicePushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83126,"duration":581,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"504 Kbps","isStreaming":false,"title":"Europe Endless","artist":"Kraftwerk","album":"Trans Europa Express","uri":"NAS/Music/Kraftwerk/Trans Europe Express/01 Kraftwerk - Europe Endless.flac","trackType":"flac"} Oct 12 06:43:31 volumioplus volumio[2842]: verbose: CURRENT POSITION 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::syncState stateService play Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::syncState currentStatus play Oct 12 06:43:31 volumioplus volumio[2842]: info: Received an update from plugin. extracting info from payload Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::volumioPushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreStateMachine::pushState Oct 12 06:43:31 volumioplus volumio[2842]: info: CorePlayQueue::getTrack 0 Oct 12 06:43:31 volumioplus volumio[2842]: info: CoreCommandRouter::volumioPushState Oct 12 06:43:31 volumioplus volumio[2842]: info: ------------------------------ 7970ms Oct 12 06:43:31 volumioplus volumio[2842]: info: ------------------------------ 7969ms Oct 12 06:43:31 volumioplus volumio[2842]: info: ------------------------------ 7958ms Oct 12 06:43:31 volumioplus volumio[2842]: info: ------------------------------ 7956ms Oct 12 06:43:31 volumioplus volumio[2842]: info: ------------------------------ 7948ms Oct 12 06:43:31 volumioplus volumio[2842]: info: ------------------------------ 7948ms Oct 12 06:43:31 volumioplus volumio[2842]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 12 06:43:31 volumioplus volumio[2842]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 12 06:43:31 volumioplus volumio[2842]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 12 06:43:31 volumioplus volumio[2842]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 12 06:43:31 volumioplus volumio[2842]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 12 06:43:31 volumioplus volumio[2842]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 12 06:43:31 volumioplus volumio[2842]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 12 06:43:31 volumioplus volumio[2842]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 12 06:43:31 volumioplus volumio[2842]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 12 06:43:31 volumioplus volumio[2842]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 12 06:43:31 volumioplus volumio[2842]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 12 06:43:31 volumioplus volumio[2842]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 12 06:43:38 volumioplus volumio[2842]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 06:43:38 volumioplus volumio[2842]: Error: connect ENETUNREACH 151.101.194.79:80 Oct 12 06:43:38 volumioplus volumio[2842]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 12 06:43:38 volumioplus volumio[2842]: errno: -101, Oct 12 06:43:38 volumioplus volumio[2842]: code: 'ENETUNREACH', Oct 12 06:43:38 volumioplus volumio[2842]: syscall: 'connect', Oct 12 06:43:38 volumioplus volumio[2842]: address: '151.101.194.79', Oct 12 06:43:38 volumioplus volumio[2842]: port: 80 Oct 12 06:43:38 volumioplus volumio[2842]: } Oct 12 06:43:38 volumioplus volumio[2842]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 06:43:39 volumioplus sudo[3693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-12 06:42 Oct 12 06:43:39 volumioplus sudo[3693]: 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="9552505bba4239c163ac9ee7b0b87a56e0dd0574" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="3d3fc502273a5c173ba19f72c1a952bdb2bdd3b0" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 Feb 2024 06:28:23 PM CET" VOLUMIO_VERSION="3.616" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b343c71baf05cbc21bf1a530c8e51fc1"