-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2024-09-07 01:20:49 UTC. -- Sep 07 01:19:00 volumio ntpd[637]: Deleting interface #18 eth0, 192.168.1.32#123, interface stats: received=0, sent=0, dropped=0, active_time=140 secs Sep 07 01:19:00 volumio ntpd[637]: Deleting interface #19 eth0, 169.254.89.0#123, interface stats: received=0, sent=0, dropped=0, active_time=140 secs Sep 07 01:19:09 volumio ifplugd(eth0)[634]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Sep 07 01:19:09 volumio ifplugd(eth0)[634]: client: sending commands to master dhcpcd process Sep 07 01:19:09 volumio dhcpcd[4583]: sending commands to master dhcpcd process Sep 07 01:19:09 volumio dhcpcd[860]: control command: /sbin/dhcpcd -k eth0 Sep 07 01:19:09 volumio dhcpcd[860]: eth0: removing interface Sep 07 01:19:09 volumio ifplugd(eth0)[634]: Program executed successfully. Sep 07 01:19:09 volumio kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Sep 07 01:19:09 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Sep 07 01:19:11 volumio dhcpcd[805]: eth0: carrier acquired Sep 07 01:19:11 volumio dhcpcd[805]: eth0: IAID 32:ac:ef:a3 Sep 07 01:19:11 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Sep 07 01:19:11 volumio dhcpcd[805]: eth0: soliciting an IPv6 router Sep 07 01:19:11 volumio dhcpcd[805]: eth0: rebinding lease of 192.168.1.32 Sep 07 01:19:12 volumio ifplugd(eth0)[634]: Link beat detected. Sep 07 01:19:12 volumio ifplugd(eth0)[634]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Sep 07 01:19:12 volumio ifplugd(eth0)[634]: client: sending commands to master dhcpcd process Sep 07 01:19:12 volumio dhcpcd[4639]: sending commands to master dhcpcd process Sep 07 01:19:12 volumio dhcpcd[860]: control command: /sbin/dhcpcd eth0 Sep 07 01:19:12 volumio dhcpcd[860]: eth0: carrier acquired Sep 07 01:19:12 volumio dhcpcd[860]: eth0: IAID 32:ac:ef:a3 Sep 07 01:19:12 volumio systemd[1]: Starting Cleanup of Temporary Directories... Sep 07 01:19:12 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Sep 07 01:19:12 volumio systemd[1]: Started Cleanup of Temporary Directories. Sep 07 01:19:12 volumio dhcpcd[860]: eth0: rebinding lease of 192.168.1.32 Sep 07 01:19:12 volumio ifplugd(eth0)[634]: Program executed successfully. Sep 07 01:19:12 volumio dhcpcd[860]: eth0: soliciting an IPv6 router Sep 07 01:19:16 volumio dhcpcd[805]: eth0: probing for an IPv4LL address Sep 07 01:19:16 volumio dhcpcd[805]: eth0: DHCP lease expired Sep 07 01:19:16 volumio dhcpcd[805]: eth0: soliciting a DHCP lease Sep 07 01:19:17 volumio dhcpcd[860]: eth0: probing for an IPv4LL address Sep 07 01:19:17 volumio dhcpcd[860]: eth0: DHCP lease expired Sep 07 01:19:17 volumio dhcpcd[860]: eth0: soliciting a DHCP lease Sep 07 01:19:20 volumio dhcpcd[860]: eth0: carrier lost Sep 07 01:19:20 volumio dhcpcd[805]: eth0: carrier lost Sep 07 01:19:20 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Sep 07 01:19:21 volumio ifplugd(eth0)[634]: Link beat lost. Sep 07 01:19:22 volumio dhcpcd[860]: eth0: carrier acquired Sep 07 01:19:22 volumio dhcpcd[805]: eth0: carrier acquired Sep 07 01:19:22 volumio dhcpcd[805]: eth0: IAID 32:ac:ef:a3 Sep 07 01:19:22 volumio dhcpcd[860]: eth0: IAID 32:ac:ef:a3 Sep 07 01:19:22 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Sep 07 01:19:22 volumio dhcpcd[860]: eth0: soliciting a DHCP lease Sep 07 01:19:22 volumio dhcpcd[860]: eth0: soliciting an IPv6 router Sep 07 01:19:22 volumio dhcpcd[805]: eth0: soliciting a DHCP lease Sep 07 01:19:23 volumio dhcpcd[805]: eth0: soliciting an IPv6 router Sep 07 01:19:23 volumio ifplugd(eth0)[634]: Link beat detected. Sep 07 01:19:23 volumio dhcpcd[805]: eth0: carrier lost Sep 07 01:19:23 volumio dhcpcd[860]: eth0: carrier lost Sep 07 01:19:23 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Sep 07 01:19:24 volumio ifplugd(eth0)[634]: Link beat lost. Sep 07 01:19:26 volumio dhcpcd[860]: eth0: carrier acquired Sep 07 01:19:26 volumio dhcpcd[805]: eth0: carrier acquired Sep 07 01:19:26 volumio dhcpcd[860]: eth0: IAID 32:ac:ef:a3 Sep 07 01:19:26 volumio dhcpcd[805]: eth0: IAID 32:ac:ef:a3 Sep 07 01:19:26 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Sep 07 01:19:26 volumio ifplugd(eth0)[634]: Link beat detected. Sep 07 01:19:27 volumio dhcpcd[860]: eth0: soliciting a DHCP lease Sep 07 01:19:27 volumio dhcpcd[860]: eth0: soliciting an IPv6 router Sep 07 01:19:27 volumio dhcpcd[805]: eth0: soliciting an IPv6 router Sep 07 01:19:27 volumio dhcpcd[805]: eth0: soliciting a DHCP lease Sep 07 01:19:32 volumio dhcpcd[860]: eth0: probing for an IPv4LL address Sep 07 01:19:32 volumio dhcpcd[805]: eth0: probing for an IPv4LL address Sep 07 01:19:34 volumio ntpd[637]: 208.85.20.220 local addr 192.168.1.26 -> Sep 07 01:19:34 volumio nmbd[620]: [2024/09/07 01:19:34.885132, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 07 01:19:34 volumio nmbd[620]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Sep 07 01:19:36 volumio dhcpcd[860]: eth0: using IPv4LL address 169.254.79.161 Sep 07 01:19:36 volumio avahi-daemon[480]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.79.161. Sep 07 01:19:36 volumio avahi-daemon[480]: New relevant interface eth0.IPv4 for mDNS. Sep 07 01:19:36 volumio avahi-daemon[480]: Registering new address record for 169.254.79.161 on eth0.IPv4. Sep 07 01:19:36 volumio dhcpcd[860]: eth0: adding route to 169.254.0.0/16 Sep 07 01:19:37 volumio dhcpcd[805]: eth0: using IPv4LL address 169.254.89.0 Sep 07 01:19:37 volumio dhcpcd[805]: eth0: adding route to 169.254.0.0/16 Sep 07 01:19:37 volumio dhcpcd[805]: eth0: adding default route Sep 07 01:19:37 volumio avahi-daemon[480]: Registering new address record for 169.254.89.0 on eth0.IPv4. Sep 07 01:19:38 volumio ntpd[637]: Listen normally on 20 eth0 169.254.79.161:123 Sep 07 01:19:38 volumio ntpd[637]: Listen normally on 21 eth0 169.254.89.0:123 Sep 07 01:19:38 volumio ntpd[637]: new interface(s) found: waking up resolver Sep 07 01:19:39 volumio ntpd[637]: 178.32.88.247 local addr 192.168.1.26 -> Sep 07 01:19:52 volumio dhcpcd[805]: eth0: carrier lost Sep 07 01:19:52 volumio dhcpcd[860]: eth0: carrier lost Sep 07 01:19:52 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Sep 07 01:19:52 volumio ifplugd(eth0)[634]: Link beat lost. Sep 07 01:19:53 volumio avahi-daemon[480]: Withdrawing address record for 169.254.79.161 on eth0. Sep 07 01:19:53 volumio avahi-daemon[480]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.79.161. Sep 07 01:19:53 volumio avahi-daemon[480]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.89.0. Sep 07 01:19:53 volumio avahi-daemon[480]: Withdrawing address record for 169.254.89.0 on eth0. Sep 07 01:19:53 volumio avahi-daemon[480]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.89.0. Sep 07 01:19:53 volumio avahi-daemon[480]: Interface eth0.IPv4 no longer relevant for mDNS. Sep 07 01:19:53 volumio dhcpcd[805]: eth0: deleting route to 169.254.0.0/16 Sep 07 01:19:53 volumio dhcpcd[805]: eth0: deleting default route Sep 07 01:19:53 volumio dhcpcd[860]: eth0: deleting route to 169.254.0.0/16 Sep 07 01:19:53 volumio volumio[908]: info: Discovery: A device disappeared from network Sep 07 01:19:53 volumio volumio[908]: info: Discovery: adding 5f7a0aef-e557-432c-9d86-8f23a08f4409 Sep 07 01:19:53 volumio volumio[908]: info: Discovery: Found device Volumio Sep 07 01:19:53 volumio volumio[908]: info: CoreCommandRouter::volumioGetState Sep 07 01:19:54 volumio ntpd[637]: Deleting interface #20 eth0, 169.254.79.161#123, interface stats: received=0, sent=0, dropped=0, active_time=16 secs Sep 07 01:19:54 volumio ntpd[637]: Deleting interface #21 eth0, 169.254.89.0#123, interface stats: received=0, sent=0, dropped=0, active_time=16 secs Sep 07 01:20:01 volumio dhcpcd[805]: eth0: carrier acquired Sep 07 01:20:01 volumio dhcpcd[860]: eth0: carrier acquired Sep 07 01:20:01 volumio dhcpcd[805]: eth0: IAID 32:ac:ef:a3 Sep 07 01:20:01 volumio dhcpcd[860]: eth0: IAID 32:ac:ef:a3 Sep 07 01:20:01 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Sep 07 01:20:01 volumio dhcpcd[805]: eth0: soliciting a DHCP lease Sep 07 01:20:01 volumio dhcpcd[860]: eth0: soliciting an IPv6 router Sep 07 01:20:01 volumio dhcpcd[805]: eth0: soliciting an IPv6 router Sep 07 01:20:01 volumio dhcpcd[860]: eth0: soliciting a DHCP lease Sep 07 01:20:02 volumio ifplugd(eth0)[634]: Link beat detected. Sep 07 01:20:05 volumio dhcpcd[805]: eth0: offered 192.168.1.32 from 192.168.1.1 Sep 07 01:20:05 volumio dhcpcd[805]: eth0: probing address 192.168.1.32/24 Sep 07 01:20:06 volumio dhcpcd[860]: eth0: probing for an IPv4LL address Sep 07 01:20:10 volumio dhcpcd[805]: eth0: leased 192.168.1.32 for 86400 seconds Sep 07 01:20:10 volumio dhcpcd[805]: eth0: adding route to 192.168.1.0/24 Sep 07 01:20:10 volumio dhcpcd[805]: eth0: adding default route via 192.168.1.1 Sep 07 01:20:10 volumio avahi-daemon[480]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.32. Sep 07 01:20:10 volumio avahi-daemon[480]: New relevant interface eth0.IPv4 for mDNS. Sep 07 01:20:10 volumio avahi-daemon[480]: Registering new address record for 192.168.1.32 on eth0.IPv4. Sep 07 01:20:11 volumio volumio[908]: info: Discovery: this is already registered, 5f7a0aef-e557-432c-9d86-8f23a08f4409 Sep 07 01:20:11 volumio volumio[908]: info: Discovery: Found device Volumio Sep 07 01:20:11 volumio volumio[908]: info: CoreCommandRouter::volumioGetState Sep 07 01:20:12 volumio dhcpcd[860]: eth0: using IPv4LL address 169.254.79.161 Sep 07 01:20:12 volumio dhcpcd[860]: eth0: adding route to 169.254.0.0/16 Sep 07 01:20:12 volumio avahi-daemon[480]: Registering new address record for 169.254.79.161 on eth0.IPv4. Sep 07 01:20:13 volumio ntpd[637]: Listen normally on 22 eth0 192.168.1.32:123 Sep 07 01:20:13 volumio ntpd[637]: Listen normally on 23 eth0 169.254.79.161:123 Sep 07 01:20:13 volumio ntpd[637]: new interface(s) found: waking up resolver Sep 07 01:20:14 volumio dhcpcd[860]: eth0: offered 192.168.1.32 from 192.168.1.1 Sep 07 01:20:14 volumio dhcpcd[860]: eth0: leased 192.168.1.32 for 86400 seconds Sep 07 01:20:14 volumio dhcpcd[860]: eth0: adding route to 192.168.1.0/24 Sep 07 01:20:14 volumio dhcpcd[860]: eth0: adding default route via 192.168.1.1 Sep 07 01:20:25 volumio volumio[908]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.30 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 9 Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::volumioGetState Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 07 01:20:25 volumio volumio[908]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 07 01:20:25 volumio volumio[908]: info: Listing playlists Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::volumioGetVisibleSources Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::volumioGetQueue Sep 07 01:20:25 volumio volumio[908]: info: CoreStateMachine::getQueue Sep 07 01:20:25 volumio volumio[908]: info: CorePlayQueue::getQueue Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::volumioGetState Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 07 01:20:25 volumio volumio[908]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 07 01:20:25 volumio volumio[908]: info: Listing playlists Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::volumioGetVisibleSources Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::volumioGetQueue Sep 07 01:20:25 volumio volumio[908]: info: CoreStateMachine::getQueue Sep 07 01:20:25 volumio volumio[908]: info: CorePlayQueue::getQueue Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::volumioGetState Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 07 01:20:25 volumio volumio[908]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 07 01:20:25 volumio volumio[908]: info: Listing playlists Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::volumioGetVisibleSources Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::volumioGetQueue Sep 07 01:20:25 volumio volumio[908]: info: CoreStateMachine::getQueue Sep 07 01:20:25 volumio volumio[908]: info: CorePlayQueue::getQueue Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::volumioGetState Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 07 01:20:25 volumio volumio[908]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 07 01:20:25 volumio volumio[908]: info: Listing playlists Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::volumioGetVisibleSources Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 01:20:25 volumio volumio[908]: info: CoreCommandRouter::volumioGetQueue Sep 07 01:20:25 volumio volumio[908]: info: CoreStateMachine::getQueue Sep 07 01:20:25 volumio volumio[908]: info: CorePlayQueue::getQueue Sep 07 01:20:26 volumio volumio[908]: info: CoreCommandRouter::volumioGetState Sep 07 01:20:26 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 07 01:20:26 volumio volumio[908]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 07 01:20:26 volumio volumio[908]: info: Listing playlists Sep 07 01:20:26 volumio volumio[908]: info: CoreCommandRouter::volumioGetVisibleSources Sep 07 01:20:26 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 01:20:26 volumio volumio[908]: info: CoreCommandRouter::volumioGetQueue Sep 07 01:20:26 volumio volumio[908]: info: CoreStateMachine::getQueue Sep 07 01:20:26 volumio volumio[908]: info: CorePlayQueue::getQueue Sep 07 01:20:28 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 07 01:20:32 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 07 01:20:36 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 07 01:20:40 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 07 01:20:42 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Sep 07 01:20:42 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Sep 07 01:20:43 volumio volumio[908]: info: Error browsing device nas for shares : Error: Command failed: echo volumio | smbclient -L nas.local. Sep 07 01:20:43 volumio volumio[908]: Unable to initialize messaging context Sep 07 01:20:44 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 07 01:20:44 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 07 01:20:44 volumio volumio[908]: verbose: ControllerMpd::sendMpdCommand update Sep 07 01:20:44 volumio volumio[908]: verbose: ControllerMpd::sendMpdCommand status Sep 07 01:20:44 volumio volumio[908]: info: Sep 07 01:20:44 volumio volumio[908]: ---------------------------- MPD announces state update: update Sep 07 01:20:44 volumio volumio[908]: info: sendMpdCommand update took 7 milliseconds Sep 07 01:20:44 volumio volumio[908]: info: ControllerMpd::getState Sep 07 01:20:44 volumio volumio[908]: verbose: ControllerMpd::sendMpdCommand status Sep 07 01:20:44 volumio volumio[908]: verbose: ControllerMpd::sendMpdCommand status Sep 07 01:20:44 volumio volumio[908]: info: Sep 07 01:20:44 volumio volumio[908]: ---------------------------- MPD announces state update: update Sep 07 01:20:44 volumio volumio[908]: info: ControllerMpd::getState Sep 07 01:20:44 volumio volumio[908]: verbose: ControllerMpd::sendMpdCommand status Sep 07 01:20:44 volumio volumio[908]: info: sendMpdCommand status took 8 milliseconds Sep 07 01:20:44 volumio volumio[908]: info: sendMpdCommand status took 4 milliseconds Sep 07 01:20:44 volumio volumio[908]: info: sendMpdCommand status took 4 milliseconds Sep 07 01:20:44 volumio volumio[908]: info: sendMpdCommand status took 3 milliseconds Sep 07 01:20:44 volumio volumio[908]: verbose: ControllerMpd::parseState Sep 07 01:20:47 volumio volumio[908]: info: Command Router : Notfying DB Updatetrue Sep 07 01:20:47 volumio volumio[908]: verbose: ControllerMpd::parseState Sep 07 01:20:47 volumio volumio[908]: verbose: ControllerMpd::parseState Sep 07 01:20:47 volumio volumio[908]: info: Command Router : Notfying DB Updatetrue Sep 07 01:20:47 volumio volumio[908]: info: CoreCommandRouter::Close All Modals sent Sep 07 01:20:47 volumio volumio[908]: verbose: ControllerMpd::parseState Sep 07 01:20:47 volumio volumio[908]: info: ControllerMpd::pushState Sep 07 01:20:47 volumio volumio[908]: info: CoreCommandRouter::servicePushState Sep 07 01:20:47 volumio volumio[908]: info: CoreStateMachine::pushState Sep 07 01:20:47 volumio volumio[908]: info: CoreCommandRouter::volumioPushState Sep 07 01:20:47 volumio volumio[908]: info: CorePlayQueue::getTrack 0 Sep 07 01:20:47 volumio volumio[908]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 01:20:47 volumio volumio[908]: verbose: CURRENT POSITION 0 Sep 07 01:20:47 volumio volumio[908]: info: CoreStateMachine::syncState stateService stop Sep 07 01:20:47 volumio volumio[908]: info: CoreStateMachine::syncState currentStatus stop Sep 07 01:20:47 volumio volumio[908]: info: CoreStateMachine::pushState Sep 07 01:20:47 volumio volumio[908]: info: CoreCommandRouter::volumioPushState Sep 07 01:20:47 volumio volumio[908]: info: No code Sep 07 01:20:47 volumio volumio[908]: info: CoreStateMachine::pushState Sep 07 01:20:47 volumio volumio[908]: info: CoreCommandRouter::volumioPushState Sep 07 01:20:47 volumio volumio[908]: info: ControllerMpd::pushState Sep 07 01:20:47 volumio volumio[908]: info: CoreCommandRouter::servicePushState Sep 07 01:20:47 volumio volumio[908]: info: CoreStateMachine::pushState Sep 07 01:20:47 volumio volumio[908]: info: CoreCommandRouter::volumioPushState Sep 07 01:20:47 volumio volumio[908]: info: CorePlayQueue::getTrack 0 Sep 07 01:20:47 volumio volumio[908]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 01:20:47 volumio volumio[908]: verbose: CURRENT POSITION 0 Sep 07 01:20:47 volumio volumio[908]: info: CoreStateMachine::syncState stateService stop Sep 07 01:20:47 volumio volumio[908]: info: CoreStateMachine::syncState currentStatus stop Sep 07 01:20:47 volumio volumio[908]: info: CoreStateMachine::pushState Sep 07 01:20:47 volumio volumio[908]: info: CoreCommandRouter::volumioPushState Sep 07 01:20:47 volumio volumio[908]: info: No code Sep 07 01:20:47 volumio volumio[908]: info: CoreStateMachine::pushState Sep 07 01:20:47 volumio volumio[908]: info: CoreCommandRouter::volumioPushState Sep 07 01:20:47 volumio volumio[908]: info: ------------------------------ 2879ms Sep 07 01:20:47 volumio volumio[908]: info: ------------------------------ 2876ms Sep 07 01:20:47 volumio volumio[908]: verbose: ControllerMpd::sendMpdCommand status Sep 07 01:20:47 volumio volumio[908]: info: Sep 07 01:20:47 volumio volumio[908]: ---------------------------- MPD announces state update: update Sep 07 01:20:47 volumio volumio[908]: info: ControllerMpd::getState Sep 07 01:20:47 volumio volumio[908]: verbose: ControllerMpd::sendMpdCommand status Sep 07 01:20:47 volumio volumio[908]: verbose: ControllerMpd::sendMpdCommand status Sep 07 01:20:47 volumio volumio[908]: info: Sep 07 01:20:47 volumio volumio[908]: ---------------------------- MPD announces state update: update Sep 07 01:20:47 volumio volumio[908]: info: ControllerMpd::getState Sep 07 01:20:47 volumio volumio[908]: verbose: ControllerMpd::sendMpdCommand status Sep 07 01:20:47 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 07 01:20:47 volumio volumio[908]: verbose: ControllerMpd::sendMpdCommand rescan Sep 07 01:20:47 volumio volumio[908]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.7xqkcadrJ7XeHXlpxacRWly81rG3.024ede094d6cd43d7ac7aafd756ba8ea.state.status' Sep 07 01:20:47 volumio volumio[908]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.7xqkcadrJ7XeHXlpxacRWly81rG3.024ede094d6cd43d7ac7aafd756ba8ea.state.status' Sep 07 01:20:47 volumio volumio[908]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.7xqkcadrJ7XeHXlpxacRWly81rG3.024ede094d6cd43d7ac7aafd756ba8ea.state.status' Sep 07 01:20:47 volumio volumio[908]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.7xqkcadrJ7XeHXlpxacRWly81rG3.024ede094d6cd43d7ac7aafd756ba8ea.state.status' Sep 07 01:20:47 volumio volumio[908]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.7xqkcadrJ7XeHXlpxacRWly81rG3.024ede094d6cd43d7ac7aafd756ba8ea.state.status' Sep 07 01:20:47 volumio volumio[908]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.7xqkcadrJ7XeHXlpxacRWly81rG3.024ede094d6cd43d7ac7aafd756ba8ea.state.status' Sep 07 01:20:47 volumio volumio[908]: verbose: ControllerMpd::sendMpdCommand status Sep 07 01:20:47 volumio volumio[908]: info: Sep 07 01:20:47 volumio volumio[908]: ---------------------------- MPD announces state update: update Sep 07 01:20:47 volumio volumio[908]: info: sendMpdCommand status took 86 milliseconds Sep 07 01:20:47 volumio volumio[908]: info: sendMpdCommand status took 83 milliseconds Sep 07 01:20:47 volumio volumio[908]: info: sendMpdCommand status took 83 milliseconds Sep 07 01:20:47 volumio volumio[908]: info: sendMpdCommand status took 81 milliseconds Sep 07 01:20:47 volumio volumio[908]: info: sendMpdCommand rescan took 66 milliseconds Sep 07 01:20:47 volumio volumio[908]: info: ControllerMpd::getState Sep 07 01:20:47 volumio volumio[908]: verbose: ControllerMpd::sendMpdCommand status Sep 07 01:20:47 volumio volumio[908]: verbose: ControllerMpd::parseState Sep 07 01:20:47 volumio volumio[908]: info: Command Router : Notfying DB Updatefalse Sep 07 01:20:47 volumio volumio[908]: info: CoreCommandRouter::Close All Modals sent Sep 07 01:20:47 volumio volumio[908]: verbose: ControllerMpd::parseState Sep 07 01:20:47 volumio volumio[908]: verbose: ControllerMpd::parseState Sep 07 01:20:48 volumio volumio[908]: info: Command Router : Notfying DB Updatefalse Sep 07 01:20:48 volumio volumio[908]: info: CoreCommandRouter::Close All Modals sent Sep 07 01:20:48 volumio volumio[908]: verbose: ControllerMpd::parseState Sep 07 01:20:48 volumio volumio[908]: info: ControllerMpd::pushState Sep 07 01:20:48 volumio volumio[908]: info: CoreCommandRouter::servicePushState Sep 07 01:20:48 volumio volumio[908]: info: CoreStateMachine::pushState Sep 07 01:20:48 volumio volumio[908]: info: CoreCommandRouter::volumioPushState Sep 07 01:20:48 volumio volumio[908]: info: CorePlayQueue::getTrack 0 Sep 07 01:20:48 volumio volumio[908]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 01:20:48 volumio volumio[908]: verbose: CURRENT POSITION 0 Sep 07 01:20:48 volumio volumio[908]: info: CoreStateMachine::syncState stateService stop Sep 07 01:20:48 volumio volumio[908]: info: CoreStateMachine::syncState currentStatus stop Sep 07 01:20:48 volumio volumio[908]: info: CoreStateMachine::pushState Sep 07 01:20:48 volumio volumio[908]: info: CoreCommandRouter::volumioPushState Sep 07 01:20:48 volumio volumio[908]: info: No code Sep 07 01:20:48 volumio volumio[908]: info: CoreStateMachine::pushState Sep 07 01:20:48 volumio volumio[908]: info: CoreCommandRouter::volumioPushState Sep 07 01:20:49 volumio volumio[908]: info: ControllerMpd::pushState Sep 07 01:20:49 volumio volumio[908]: info: CoreCommandRouter::servicePushState Sep 07 01:20:49 volumio volumio[908]: info: CoreStateMachine::pushState Sep 07 01:20:49 volumio volumio[908]: info: CoreCommandRouter::volumioPushState Sep 07 01:20:49 volumio volumio[908]: info: CorePlayQueue::getTrack 0 Sep 07 01:20:49 volumio volumio[908]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 01:20:49 volumio volumio[908]: verbose: CURRENT POSITION 0 Sep 07 01:20:49 volumio volumio[908]: info: CoreStateMachine::syncState stateService stop Sep 07 01:20:49 volumio volumio[908]: info: CoreStateMachine::syncState currentStatus stop Sep 07 01:20:49 volumio volumio[908]: info: CoreStateMachine::pushState Sep 07 01:20:49 volumio volumio[908]: info: CoreCommandRouter::volumioPushState Sep 07 01:20:49 volumio volumio[908]: info: No code Sep 07 01:20:49 volumio volumio[908]: info: CoreStateMachine::pushState Sep 07 01:20:49 volumio volumio[908]: info: CoreCommandRouter::volumioPushState Sep 07 01:20:49 volumio volumio[908]: info: ------------------------------ 1277ms Sep 07 01:20:49 volumio volumio[908]: info: ------------------------------ 1275ms Sep 07 01:20:49 volumio volumio[908]: verbose: ControllerMpd::sendMpdCommand status Sep 07 01:20:49 volumio volumio[908]: info: Sep 07 01:20:49 volumio volumio[908]: ---------------------------- MPD announces state update: update Sep 07 01:20:49 volumio volumio[908]: info: ControllerMpd::getState Sep 07 01:20:49 volumio volumio[908]: verbose: ControllerMpd::sendMpdCommand status Sep 07 01:20:49 volumio volumio[908]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 01:20:49 volumio volumio[908]: Error: dns service error: unknown Sep 07 01:20:49 volumio volumio[908]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Sep 07 01:20:49 volumio volumio[908]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Sep 07 01:20:49 volumio volumio[908]: errorCode: -65537 Sep 07 01:20:49 volumio volumio[908]: } Sep 07 01:20:49 volumio volumio[908]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 01:20:49 volumio sudo[5151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-07 01:19 Sep 07 01:20:49 volumio sudo[5151]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="8e6823482d6af342172ec9fdc1c15b0dd02653d2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 04 Dec 2022 08:41:29 PM CET" VOLUMIO_VERSION="3.396" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d460c75d04818d0f55c67627fa362668"