-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Mon 2024-10-28 00:51:54 CST. -- Oct 28 00:50:00 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-DISCONNECTED bssid=8c:8e:0d:4f:57:fc reason=6 Oct 28 00:50:00 volumio dhcpcd[3747]: wlan0: carrier lost Oct 28 00:50:00 volumio wpa_supplicant[3735]: wlan0: Trying to associate with SSID 'Faber' Oct 28 00:50:00 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Oct 28 00:50:00 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Oct 28 00:50:00 volumio avahi-daemon[624]: Withdrawing address record for 192.168.1.38 on wlan0. Oct 28 00:50:00 volumio avahi-daemon[624]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.38. Oct 28 00:50:00 volumio avahi-daemon[624]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 28 00:50:00 volumio dhcpcd[3747]: wlan0: deleting route to 192.168.1.0/24 Oct 28 00:50:00 volumio dhcpcd[3747]: wlan0: deleting default route via 192.168.1.1 Oct 28 00:50:00 volumio volumio[871]: info: Discovery: A device disappeared from network Oct 28 00:50:00 volumio volumio[871]: info: Discovery: Device volumio disappeared from network Oct 28 00:50:00 volumio dnsmasq[862]: reading /etc/resolv.conf Oct 28 00:50:00 volumio dnsmasq[862]: using nameserver 208.67.222.222#53 Oct 28 00:50:00 volumio dnsmasq[862]: using nameserver 208.67.220.220#53 Oct 28 00:50:00 volumio ntpd[775]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 28 00:50:01 volumio wpa_supplicant[3735]: wlan0: Associated with 8c:8e:0d:4f:57:fc Oct 28 00:50:01 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-CONNECTED - Connection to 8c:8e:0d:4f:57:fc completed [id=0 id_str=] Oct 28 00:50:01 volumio dhcpcd[3747]: wlan0: carrier acquired Oct 28 00:50:01 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 28 00:50:01 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Oct 28 00:50:01 volumio dhcpcd[3747]: wlan0: IAID eb:78:c8:ac Oct 28 00:50:01 volumio dhcpcd[3747]: wlan0: rebinding lease of 192.168.1.38 Oct 28 00:50:02 volumio dhcpcd[3747]: wlan0: probing address 192.168.1.38/24 Oct 28 00:50:02 volumio dhcpcd[3747]: wlan0: soliciting an IPv6 router Oct 28 00:50:02 volumio ntpd[775]: Deleting interface #37 wlan0, 192.168.1.38#123, interface stats: received=23, sent=24, dropped=0, active_time=26 secs Oct 28 00:50:02 volumio ntpd[775]: 95.111.202.5 local addr 192.168.1.38 -> Oct 28 00:50:02 volumio ntpd[775]: 84.16.73.33 local addr 192.168.1.38 -> Oct 28 00:50:02 volumio ntpd[775]: 108.59.2.24 local addr 192.168.1.38 -> Oct 28 00:50:02 volumio ntpd[775]: 162.159.200.123 local addr 192.168.1.38 -> Oct 28 00:50:02 volumio ntpd[775]: 78.46.102.180 local addr 192.168.1.38 -> Oct 28 00:50:02 volumio ntpd[775]: 119.28.206.193 local addr 192.168.1.38 -> Oct 28 00:50:02 volumio ntpd[775]: 193.182.111.12 local addr 192.168.1.38 -> Oct 28 00:50:02 volumio ntpd[775]: 219.216.128.25 local addr 192.168.1.38 -> Oct 28 00:50:02 volumio ntpd[775]: 202.112.31.197 local addr 192.168.1.38 -> Oct 28 00:50:02 volumio ntpd[775]: 162.159.200.1 local addr 192.168.1.38 -> Oct 28 00:50:02 volumio ntpd[775]: 84.16.67.12 local addr 192.168.1.38 -> Oct 28 00:50:06 volumio dhcpcd[3747]: wlan0: leased 192.168.1.38 for 86400 seconds Oct 28 00:50:06 volumio avahi-daemon[624]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.38. Oct 28 00:50:06 volumio avahi-daemon[624]: New relevant interface wlan0.IPv4 for mDNS. Oct 28 00:50:06 volumio avahi-daemon[624]: Registering new address record for 192.168.1.38 on wlan0.IPv4. Oct 28 00:50:06 volumio dhcpcd[3747]: wlan0: adding route to 192.168.1.0/24 Oct 28 00:50:06 volumio dhcpcd[3747]: wlan0: adding default route via 192.168.1.1 Oct 28 00:50:06 volumio dnsmasq[862]: reading /etc/resolv.conf Oct 28 00:50:06 volumio dnsmasq[862]: using nameserver 192.168.1.1#53 Oct 28 00:50:06 volumio dnsmasq[862]: using nameserver 208.67.222.222#53 Oct 28 00:50:06 volumio dnsmasq[862]: using nameserver 208.67.220.220#53 Oct 28 00:50:06 volumio volumio[871]: info: Preload queue cleared Oct 28 00:50:06 volumio volumio[871]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 28 00:50:06 volumio volumio[871]: info: CoreStateMachine::ClearQueue Oct 28 00:50:06 volumio volumio[871]: info: CoreStateMachine::stop Oct 28 00:50:06 volumio volumio[871]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 00:50:06 volumio volumio[871]: info: CoreStateMachine::stPlaybackTimer Oct 28 00:50:06 volumio volumio[871]: info: CoreStateMachine::updateTrackBlock Oct 28 00:50:06 volumio volumio[871]: info: CorePlayQueue::getTrackBlock Oct 28 00:50:06 volumio volumio[871]: info: CoreStateMachine::pushState Oct 28 00:50:06 volumio volumio[871]: info: CorePlayQueue::getTrack 4 Oct 28 00:50:06 volumio volumio[871]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 00:50:06 volumio volumio[871]: info: CoreCommandRouter::volumioPushState Oct 28 00:50:06 volumio volumio[871]: info: CoreStateMachine::serviceStop Oct 28 00:50:06 volumio volumio[871]: info: CorePlayQueue::getTrack 4 Oct 28 00:50:06 volumio volumio[871]: info: CoreCommandRouter::serviceStop Oct 28 00:50:06 volumio volumio[871]: info: ControllerMpd::stop Oct 28 00:50:06 volumio volumio[871]: verbose: ControllerMpd::sendMpdCommand stop Oct 28 00:50:06 volumio volumio[871]: info: CorePlayQueue::clearPlayQueue Oct 28 00:50:06 volumio volumio[871]: info: CorePlayQueue::saveQueue Oct 28 00:50:06 volumio volumio[871]: info: CoreCommandRouter::volumioPushQueue Oct 28 00:50:06 volumio volumio[871]: info: CoreStateMachine::addQueueItems Oct 28 00:50:06 volumio volumio[871]: info: CorePlayQueue::addQueueItems Oct 28 00:50:06 volumio volumio[871]: info: Preload queue cleared Oct 28 00:50:06 volumio volumio[871]: info: Adding Item to queue: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/01 - One of These Days.flac Oct 28 00:50:06 volumio volumio[871]: info: Using cached record of: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/01 - One of These Days.flac Oct 28 00:50:06 volumio volumio[871]: info: Adding Item to queue: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/02 - A Pillow of Winds.flac Oct 28 00:50:06 volumio volumio[871]: info: Using cached record of: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/02 - A Pillow of Winds.flac Oct 28 00:50:06 volumio volumio[871]: info: Adding Item to queue: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/03 - Fearless.flac Oct 28 00:50:06 volumio volumio[871]: info: Using cached record of: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/03 - Fearless.flac Oct 28 00:50:06 volumio volumio[871]: info: Adding Item to queue: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/04 - San Tropez.flac Oct 28 00:50:06 volumio volumio[871]: info: Using cached record of: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/04 - San Tropez.flac Oct 28 00:50:06 volumio volumio[871]: info: Adding Item to queue: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/05 - Seamus.flac Oct 28 00:50:06 volumio volumio[871]: info: Using cached record of: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/05 - Seamus.flac Oct 28 00:50:06 volumio volumio[871]: info: Adding Item to queue: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/06 - Echoes.flac Oct 28 00:50:06 volumio volumio[871]: info: Using cached record of: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/06 - Echoes.flac Oct 28 00:50:06 volumio volumio[871]: info: CoreCommandRouter::volumioPushQueue Oct 28 00:50:06 volumio volumio[871]: info: CorePlayQueue::saveQueue Oct 28 00:50:06 volumio volumio[871]: info: CoreStateMachine::updateTrackBlock Oct 28 00:50:06 volumio volumio[871]: info: CorePlayQueue::getTrackBlock Oct 28 00:50:06 volumio volumio[871]: info: CoreCommandRouter::volumioPlay Oct 28 00:50:06 volumio volumio[871]: info: CoreStateMachine::play index 5 Oct 28 00:50:06 volumio volumio[871]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 00:50:06 volumio volumio[871]: info: CoreStateMachine::stop Oct 28 00:50:06 volumio volumio[871]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 00:50:06 volumio volumio[871]: info: CoreStateMachine::play index undefined Oct 28 00:50:06 volumio volumio[871]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 00:50:06 volumio volumio[871]: info: CorePlayQueue::getTrack 5 Oct 28 00:50:06 volumio volumio[871]: info: CoreStateMachine::startPlaybackTimer Oct 28 00:50:06 volumio volumio[871]: info: CorePlayQueue::getTrack 5 Oct 28 00:50:06 volumio volumio[871]: verbose: ControllerMpd::clearAddPlayTracks USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/06 - Echoes.flac Oct 28 00:50:06 volumio volumio[871]: verbose: ControllerMpd::sendMpdCommand stop Oct 28 00:50:07 volumio volumio[871]: info: Preload queue cleared Oct 28 00:50:07 volumio volumio[871]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::ClearQueue Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::stop Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 00:50:07 volumio volumio[871]: info: CorePlayQueue::clearPlayQueue Oct 28 00:50:07 volumio volumio[871]: info: CorePlayQueue::saveQueue Oct 28 00:50:07 volumio volumio[871]: info: CoreCommandRouter::volumioPushQueue Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::addQueueItems Oct 28 00:50:07 volumio volumio[871]: info: CorePlayQueue::addQueueItems Oct 28 00:50:07 volumio volumio[871]: info: Preload queue cleared Oct 28 00:50:07 volumio volumio[871]: info: Adding Item to queue: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/01 - One of These Days.flac Oct 28 00:50:07 volumio volumio[871]: info: Using cached record of: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/01 - One of These Days.flac Oct 28 00:50:07 volumio volumio[871]: info: Adding Item to queue: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/02 - A Pillow of Winds.flac Oct 28 00:50:07 volumio volumio[871]: info: Using cached record of: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/02 - A Pillow of Winds.flac Oct 28 00:50:07 volumio volumio[871]: info: Adding Item to queue: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/03 - Fearless.flac Oct 28 00:50:07 volumio volumio[871]: info: Using cached record of: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/03 - Fearless.flac Oct 28 00:50:07 volumio volumio[871]: info: Adding Item to queue: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/04 - San Tropez.flac Oct 28 00:50:07 volumio volumio[871]: info: Using cached record of: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/04 - San Tropez.flac Oct 28 00:50:07 volumio volumio[871]: info: Adding Item to queue: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/05 - Seamus.flac Oct 28 00:50:07 volumio volumio[871]: info: Using cached record of: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/05 - Seamus.flac Oct 28 00:50:07 volumio volumio[871]: info: Adding Item to queue: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/06 - Echoes.flac Oct 28 00:50:07 volumio volumio[871]: info: Using cached record of: music-library/USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/06 - Echoes.flac Oct 28 00:50:07 volumio volumio[871]: info: CoreCommandRouter::volumioPushQueue Oct 28 00:50:07 volumio volumio[871]: info: CorePlayQueue::saveQueue Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::updateTrackBlock Oct 28 00:50:07 volumio volumio[871]: info: CorePlayQueue::getTrackBlock Oct 28 00:50:07 volumio volumio[871]: info: CoreCommandRouter::volumioPlay Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::play index 5 Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::stop Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::play index undefined Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 00:50:07 volumio volumio[871]: info: CorePlayQueue::getTrack 5 Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::startPlaybackTimer Oct 28 00:50:07 volumio volumio[871]: info: CorePlayQueue::getTrack 5 Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::clearAddPlayTracks USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/06 - Echoes.flac Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::sendMpdCommand stop Oct 28 00:50:07 volumio volumio[871]: info: Oct 28 00:50:07 volumio volumio[871]: ---------------------------- MPD announces state update: player Oct 28 00:50:07 volumio volumio[871]: info: sendMpdCommand stop took 127 milliseconds Oct 28 00:50:07 volumio volumio[871]: info: ControllerMpd::getState Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::sendMpdCommand status Oct 28 00:50:07 volumio volumio[871]: info: sendMpdCommand stop took 71 milliseconds Oct 28 00:50:07 volumio volumio[871]: info: sendMpdCommand stop took 11 milliseconds Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::sendMpdCommand clear Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::sendMpdCommand clear Oct 28 00:50:07 volumio volumio[871]: info: Oct 28 00:50:07 volumio volumio[871]: ---------------------------- MPD announces system playlist update Oct 28 00:50:07 volumio volumio[871]: info: Ignoring MPD Status Update Oct 28 00:50:07 volumio volumio[871]: info: Oct 28 00:50:07 volumio volumio[871]: ---------------------------- MPD announces system playlist update Oct 28 00:50:07 volumio volumio[871]: info: Ignoring MPD Status Update Oct 28 00:50:07 volumio volumio[871]: info: sendMpdCommand status took 10 milliseconds Oct 28 00:50:07 volumio volumio[871]: info: sendMpdCommand clear took 8 milliseconds Oct 28 00:50:07 volumio volumio[871]: info: sendMpdCommand clear took 8 milliseconds Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::parseState Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::sendMpdCommand add "USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/06 - Echoes.flac" Oct 28 00:50:07 volumio volumio[871]: error: updateQueue error: null Oct 28 00:50:07 volumio volumio[871]: error: updateQueue error: null Oct 28 00:50:07 volumio volumio[871]: info: Oct 28 00:50:07 volumio volumio[871]: ---------------------------- MPD announces system playlist update Oct 28 00:50:07 volumio volumio[871]: info: Ignoring MPD Status Update Oct 28 00:50:07 volumio volumio[871]: info: Oct 28 00:50:07 volumio volumio[871]: ---------------------------- MPD announces system playlist update Oct 28 00:50:07 volumio volumio[871]: info: Ignoring MPD Status Update Oct 28 00:50:07 volumio volumio[871]: info: ------------------------------ 26ms Oct 28 00:50:07 volumio volumio[871]: info: ------------------------------ 24ms Oct 28 00:50:07 volumio volumio[871]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 28 00:50:07 volumio volumio[871]: info: sendMpdCommand add "USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/06 - Echoes.flac" took 17 milliseconds Oct 28 00:50:07 volumio volumio[871]: info: sendMpdCommand add "USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/06 - Echoes.flac" took 14 milliseconds Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::parseTrackInfo Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::sendMpdCommand play Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::sendMpdCommand play Oct 28 00:50:07 volumio volumio[871]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 28 00:50:07 volumio volumio[871]: info: ------------------------------ 43ms Oct 28 00:50:07 volumio volumio[871]: info: Oct 28 00:50:07 volumio volumio[871]: ---------------------------- MPD announces state update: player Oct 28 00:50:07 volumio volumio[871]: info: ------------------------------ 33ms Oct 28 00:50:07 volumio volumio[871]: info: ------------------------------ 33ms Oct 28 00:50:07 volumio volumio[871]: info: sendMpdCommand play took 28 milliseconds Oct 28 00:50:07 volumio volumio[871]: info: ControllerMpd::getState Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::sendMpdCommand status Oct 28 00:50:07 volumio volumio[871]: info: Oct 28 00:50:07 volumio volumio[871]: ---------------------------- MPD announces state update: player Oct 28 00:50:07 volumio volumio[871]: info: ControllerMpd::getState Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::sendMpdCommand status Oct 28 00:50:07 volumio volumio[871]: info: sendMpdCommand status took 10 milliseconds Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::parseState Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 00:50:07 volumio volumio[871]: info: sendMpdCommand status took 14 milliseconds Oct 28 00:50:07 volumio volumio[871]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::parseState Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::parseTrackInfo Oct 28 00:50:07 volumio volumio[871]: info: ControllerMpd::pushState Oct 28 00:50:07 volumio volumio[871]: info: CoreCommandRouter::servicePushState Oct 28 00:50:07 volumio volumio[871]: info: CorePlayQueue::getTrack 5 Oct 28 00:50:07 volumio volumio[871]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1413,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Echoes","artist":"Pink Floyd","album":"Meddle","uri":"USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/06 - Echoes.flac","trackType":"flac"} Oct 28 00:50:07 volumio volumio[871]: verbose: CURRENT POSITION 5 Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::syncState stateService play Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::syncState currentStatus stop Oct 28 00:50:07 volumio volumio[871]: info: ------------------------------ 33ms Oct 28 00:50:07 volumio volumio[871]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 28 00:50:07 volumio volumio[871]: verbose: ControllerMpd::parseTrackInfo Oct 28 00:50:07 volumio volumio[871]: info: ControllerMpd::pushState Oct 28 00:50:07 volumio volumio[871]: info: CoreCommandRouter::servicePushState Oct 28 00:50:07 volumio volumio[871]: info: CorePlayQueue::getTrack 5 Oct 28 00:50:07 volumio volumio[871]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1413,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Echoes","artist":"Pink Floyd","album":"Meddle","uri":"USB/Musica_Fab/[0489] Pet Sounds [HDCD] [40th Anniversary Edition 2006]/Pink - Floyd - Meddle [192kHz · 24bit]/06 - Echoes.flac","trackType":"flac"} Oct 28 00:50:07 volumio volumio[871]: verbose: CURRENT POSITION 5 Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::syncState stateService play Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::syncState currentStatus play Oct 28 00:50:07 volumio volumio[871]: info: Received an update from plugin. extracting info from payload Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::pushState Oct 28 00:50:07 volumio volumio[871]: info: CorePlayQueue::getTrack 5 Oct 28 00:50:07 volumio volumio[871]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 00:50:07 volumio volumio[871]: info: CoreCommandRouter::volumioPushState Oct 28 00:50:07 volumio volumio[871]: info: CoreStateMachine::pushState Oct 28 00:50:07 volumio volumio[871]: info: CorePlayQueue::getTrack 5 Oct 28 00:50:07 volumio volumio[871]: info: CoreCommandRouter::volumioPushState Oct 28 00:50:07 volumio volumio[871]: info: ------------------------------ 68ms Oct 28 00:50:07 volumio volumio[871]: info: Discovery: adding 1f5ddf30-f610-485e-8b94-f1cf9695e317 Oct 28 00:50:07 volumio volumio[871]: info: Discovery: Found device Volumio Oct 28 00:50:07 volumio volumio[871]: info: CoreCommandRouter::volumioGetState Oct 28 00:50:07 volumio volumio[871]: info: CorePlayQueue::getTrack 5 Oct 28 00:50:07 volumio ntpd[775]: Listen normally on 38 wlan0 192.168.1.38:123 Oct 28 00:50:07 volumio ntpd[775]: new interface(s) found: waking up resolver Oct 28 00:50:30 volumio wpa_supplicant[3735]: RRM: Ignoring radio measurement request: Not RRM network Oct 28 00:50:32 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-DISCONNECTED bssid=8c:8e:0d:4f:57:fc reason=6 Oct 28 00:50:32 volumio wpa_supplicant[3735]: wlan0: Trying to associate with SSID 'Faber' Oct 28 00:50:32 volumio dhcpcd[3747]: wlan0: carrier lost Oct 28 00:50:32 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Oct 28 00:50:32 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Oct 28 00:50:32 volumio avahi-daemon[624]: Withdrawing address record for 192.168.1.38 on wlan0. Oct 28 00:50:32 volumio avahi-daemon[624]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.38. Oct 28 00:50:32 volumio avahi-daemon[624]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 28 00:50:32 volumio dhcpcd[3747]: wlan0: deleting route to 192.168.1.0/24 Oct 28 00:50:32 volumio dhcpcd[3747]: wlan0: deleting default route via 192.168.1.1 Oct 28 00:50:32 volumio volumio[871]: info: Discovery: A device disappeared from network Oct 28 00:50:32 volumio volumio[871]: info: Discovery: Device volumio disappeared from network Oct 28 00:50:32 volumio dnsmasq[862]: reading /etc/resolv.conf Oct 28 00:50:32 volumio dnsmasq[862]: using nameserver 208.67.222.222#53 Oct 28 00:50:32 volumio dnsmasq[862]: using nameserver 208.67.220.220#53 Oct 28 00:50:33 volumio wpa_supplicant[3735]: wlan0: Associated with 8c:8e:0d:4f:57:fc Oct 28 00:50:33 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-CONNECTED - Connection to 8c:8e:0d:4f:57:fc completed [id=0 id_str=] Oct 28 00:50:33 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 28 00:50:33 volumio dhcpcd[3747]: wlan0: carrier acquired Oct 28 00:50:33 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Oct 28 00:50:33 volumio dhcpcd[3747]: wlan0: IAID eb:78:c8:ac Oct 28 00:50:33 volumio dhcpcd[3747]: wlan0: rebinding lease of 192.168.1.38 Oct 28 00:50:33 volumio dhcpcd[3747]: wlan0: probing address 192.168.1.38/24 Oct 28 00:50:33 volumio dhcpcd[3747]: wlan0: soliciting an IPv6 router Oct 28 00:50:34 volumio ntpd[775]: Deleting interface #38 wlan0, 192.168.1.38#123, interface stats: received=19, sent=20, dropped=0, active_time=27 secs Oct 28 00:50:34 volumio ntpd[775]: 162.159.200.123 local addr 192.168.1.38 -> Oct 28 00:50:34 volumio ntpd[775]: 78.46.102.180 local addr 192.168.1.38 -> Oct 28 00:50:34 volumio ntpd[775]: 119.28.206.193 local addr 192.168.1.38 -> Oct 28 00:50:34 volumio ntpd[775]: 193.182.111.12 local addr 192.168.1.38 -> Oct 28 00:50:34 volumio ntpd[775]: 219.216.128.25 local addr 192.168.1.38 -> Oct 28 00:50:34 volumio ntpd[775]: 202.112.31.197 local addr 192.168.1.38 -> Oct 28 00:50:34 volumio ntpd[775]: 162.159.200.1 local addr 192.168.1.38 -> Oct 28 00:50:34 volumio ntpd[775]: 84.16.67.12 local addr 192.168.1.38 -> Oct 28 00:50:34 volumio ntpd[775]: 108.59.2.24 local addr 192.168.1.38 -> Oct 28 00:50:34 volumio ntpd[775]: 84.16.73.33 local addr 192.168.1.38 -> Oct 28 00:50:34 volumio ntpd[775]: 95.111.202.5 local addr 192.168.1.38 -> Oct 28 00:50:38 volumio dhcpcd[3747]: wlan0: leased 192.168.1.38 for 86400 seconds Oct 28 00:50:38 volumio avahi-daemon[624]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.38. Oct 28 00:50:38 volumio avahi-daemon[624]: New relevant interface wlan0.IPv4 for mDNS. Oct 28 00:50:38 volumio dhcpcd[3747]: wlan0: adding route to 192.168.1.0/24 Oct 28 00:50:38 volumio avahi-daemon[624]: Registering new address record for 192.168.1.38 on wlan0.IPv4. Oct 28 00:50:38 volumio dhcpcd[3747]: wlan0: adding default route via 192.168.1.1 Oct 28 00:50:38 volumio dnsmasq[862]: reading /etc/resolv.conf Oct 28 00:50:38 volumio dnsmasq[862]: using nameserver 192.168.1.1#53 Oct 28 00:50:38 volumio dnsmasq[862]: using nameserver 208.67.222.222#53 Oct 28 00:50:38 volumio dnsmasq[862]: using nameserver 208.67.220.220#53 Oct 28 00:50:39 volumio volumio[871]: info: Discovery: adding 1f5ddf30-f610-485e-8b94-f1cf9695e317 Oct 28 00:50:39 volumio volumio[871]: info: Discovery: Found device Volumio Oct 28 00:50:39 volumio volumio[871]: info: CoreCommandRouter::volumioGetState Oct 28 00:50:39 volumio volumio[871]: info: CorePlayQueue::getTrack 5 Oct 28 00:50:39 volumio ntpd[775]: Listen normally on 39 wlan0 192.168.1.38:123 Oct 28 00:50:39 volumio ntpd[775]: new interface(s) found: waking up resolver Oct 28 00:51:02 volumio wpa_supplicant[3735]: RRM: Ignoring radio measurement request: Not RRM network Oct 28 00:51:03 volumio ntpd[775]: Soliciting pool server 2606:4700:f1::1 Oct 28 00:51:07 volumio ntpd[775]: Soliciting pool server 202.112.29.82 Oct 28 00:51:29 volumio volumio[871]: Searching plugin music_service/mpd Oct 28 00:51:29 volumio volumio[871]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 28 00:51:30 volumio volumio[871]: info: All search sources collected, pushing search results Oct 28 00:51:34 volumio wpa_supplicant[3735]: RRM: Ignoring radio measurement request: Not RRM network Oct 28 00:51:35 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-DISCONNECTED bssid=8c:8e:0d:4f:57:fc reason=6 Oct 28 00:51:35 volumio dhcpcd[3747]: wlan0: carrier lost Oct 28 00:51:35 volumio wpa_supplicant[3735]: wlan0: Trying to associate with SSID 'Faber' Oct 28 00:51:35 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Oct 28 00:51:35 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Oct 28 00:51:35 volumio avahi-daemon[624]: Withdrawing address record for 192.168.1.38 on wlan0. Oct 28 00:51:35 volumio avahi-daemon[624]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.38. Oct 28 00:51:35 volumio avahi-daemon[624]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 28 00:51:35 volumio dhcpcd[3747]: wlan0: deleting route to 192.168.1.0/24 Oct 28 00:51:35 volumio volumio[871]: info: Discovery: A device disappeared from network Oct 28 00:51:35 volumio volumio[871]: info: Discovery: Device volumio disappeared from network Oct 28 00:51:35 volumio dhcpcd[3747]: wlan0: deleting default route via 192.168.1.1 Oct 28 00:51:35 volumio dnsmasq[862]: reading /etc/resolv.conf Oct 28 00:51:35 volumio dnsmasq[862]: using nameserver 208.67.222.222#53 Oct 28 00:51:35 volumio dnsmasq[862]: using nameserver 208.67.220.220#53 Oct 28 00:51:36 volumio wpa_supplicant[3735]: wlan0: Associated with 8c:8e:0d:4f:57:fc Oct 28 00:51:36 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-CONNECTED - Connection to 8c:8e:0d:4f:57:fc completed [id=0 id_str=] Oct 28 00:51:36 volumio dhcpcd[3747]: wlan0: carrier acquired Oct 28 00:51:36 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 28 00:51:36 volumio wpa_supplicant[3735]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Oct 28 00:51:36 volumio dhcpcd[3747]: wlan0: IAID eb:78:c8:ac Oct 28 00:51:36 volumio dhcpcd[3747]: wlan0: rebinding lease of 192.168.1.38 Oct 28 00:51:36 volumio dhcpcd[3747]: wlan0: soliciting an IPv6 router Oct 28 00:51:36 volumio dhcpcd[3747]: wlan0: probing address 192.168.1.38/24 Oct 28 00:51:37 volumio ntpd[775]: Deleting interface #39 wlan0, 192.168.1.38#123, interface stats: received=19, sent=20, dropped=0, active_time=58 secs Oct 28 00:51:37 volumio ntpd[775]: 202.112.29.82 local addr 192.168.1.38 -> Oct 28 00:51:37 volumio ntpd[775]: 162.159.200.123 local addr 192.168.1.38 -> Oct 28 00:51:37 volumio ntpd[775]: 78.46.102.180 local addr 192.168.1.38 -> Oct 28 00:51:37 volumio ntpd[775]: 119.28.206.193 local addr 192.168.1.38 -> Oct 28 00:51:37 volumio ntpd[775]: 193.182.111.12 local addr 192.168.1.38 -> Oct 28 00:51:37 volumio ntpd[775]: 219.216.128.25 local addr 192.168.1.38 -> Oct 28 00:51:37 volumio ntpd[775]: 202.112.31.197 local addr 192.168.1.38 -> Oct 28 00:51:37 volumio ntpd[775]: 162.159.200.1 local addr 192.168.1.38 -> Oct 28 00:51:37 volumio ntpd[775]: 84.16.67.12 local addr 192.168.1.38 -> Oct 28 00:51:37 volumio ntpd[775]: 108.59.2.24 local addr 192.168.1.38 -> Oct 28 00:51:37 volumio ntpd[775]: 84.16.73.33 local addr 192.168.1.38 -> Oct 28 00:51:37 volumio ntpd[775]: 95.111.202.5 local addr 192.168.1.38 -> Oct 28 00:51:41 volumio dhcpcd[3747]: wlan0: leased 192.168.1.38 for 86400 seconds Oct 28 00:51:41 volumio avahi-daemon[624]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.38. Oct 28 00:51:41 volumio avahi-daemon[624]: New relevant interface wlan0.IPv4 for mDNS. Oct 28 00:51:41 volumio dhcpcd[3747]: wlan0: adding route to 192.168.1.0/24 Oct 28 00:51:41 volumio avahi-daemon[624]: Registering new address record for 192.168.1.38 on wlan0.IPv4. Oct 28 00:51:41 volumio dhcpcd[3747]: wlan0: adding default route via 192.168.1.1 Oct 28 00:51:41 volumio dnsmasq[862]: reading /etc/resolv.conf Oct 28 00:51:41 volumio dnsmasq[862]: using nameserver 192.168.1.1#53 Oct 28 00:51:41 volumio dnsmasq[862]: using nameserver 208.67.222.222#53 Oct 28 00:51:41 volumio dnsmasq[862]: using nameserver 208.67.220.220#53 Oct 28 00:51:42 volumio volumio[871]: info: Discovery: adding 1f5ddf30-f610-485e-8b94-f1cf9695e317 Oct 28 00:51:42 volumio volumio[871]: info: Discovery: Found device Volumio Oct 28 00:51:42 volumio volumio[871]: info: CoreCommandRouter::volumioGetState Oct 28 00:51:42 volumio volumio[871]: info: CorePlayQueue::getTrack 5 Oct 28 00:51:42 volumio ntpd[775]: Listen normally on 40 wlan0 192.168.1.38:123 Oct 28 00:51:42 volumio ntpd[775]: new interface(s) found: waking up resolver Oct 28 00:51:44 volumio ntpd[775]: 78.46.102.180 local addr 192.168.1.38 -> Oct 28 00:51:45 volumio ntpd[775]: 162.159.200.1 local addr 192.168.1.38 -> Oct 28 00:51:46 volumio ntpd[775]: 84.16.67.12 local addr 192.168.1.38 -> Oct 28 00:51:46 volumio ntpd[775]: 202.112.31.197 local addr 192.168.1.38 -> Oct 28 00:51:47 volumio volumio[871]: Searching plugin music_service/mpd Oct 28 00:51:47 volumio volumio[871]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 28 00:51:47 volumio volumio[871]: Searching plugin music_service/mpd Oct 28 00:51:47 volumio volumio[871]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 28 00:51:47 volumio volumio[871]: Searching plugin music_service/mpd Oct 28 00:51:47 volumio volumio[871]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 28 00:51:47 volumio volumio[871]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Pink%20Floyd/020e3241-c578-413d-9dc5-e1f48bd099e0.jpg' Oct 28 00:51:47 volumio volumio[871]: info: All search sources collected, pushing search results Oct 28 00:51:47 volumio volumio[871]: info: All search sources collected, pushing search results Oct 28 00:51:47 volumio volumio[871]: info: All search sources collected, pushing search results Oct 28 00:51:52 volumio volumio[871]: Searching plugin music_service/mpd Oct 28 00:51:52 volumio volumio[871]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 28 00:51:53 volumio volumio[871]: info: All search sources collected, pushing search results Oct 28 00:51:53 volumio volumio[871]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 00:51:53 volumio volumio[871]: Error: connect ETIMEDOUT 162.125.83.1:80 Oct 28 00:51:53 volumio volumio[871]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 28 00:51:53 volumio volumio[871]: errno: -110, Oct 28 00:51:53 volumio volumio[871]: code: 'ETIMEDOUT', Oct 28 00:51:53 volumio volumio[871]: syscall: 'connect', Oct 28 00:51:53 volumio volumio[871]: address: '162.125.83.1', Oct 28 00:51:53 volumio volumio[871]: port: 80 Oct 28 00:51:53 volumio volumio[871]: } Oct 28 00:51:53 volumio volumio[871]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 00:51:54 volumio sudo[6631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-28 00:50 Oct 28 00:51:54 volumio sudo[6631]: 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"