-- Logs begin at Tue 2024-10-29 03:52:18 MDT, end at Wed 2024-10-30 02:53:57 MDT. -- Oct 30 02:52:16 pibox volumio[971]: info: CorePlayQueue::getTrack 1199 Oct 30 02:52:16 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:16 pibox volumio[971]: info: Prefetching next song Oct 30 02:52:16 pibox volumio[971]: info: DOING PREFETCH IN MPD Oct 30 02:52:16 pibox volumio[971]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Music/Bruce Springsteen/Tunnel of Love/09 Brilliant Disguise.mp3" Oct 30 02:52:16 pibox volumio[971]: info: Oct 30 02:52:16 pibox volumio[971]: ---------------------------- MPD announces system playlist update Oct 30 02:52:16 pibox volumio[971]: info: Ignoring MPD Status Update Oct 30 02:52:16 pibox volumio[971]: info: sendMpdCommand add "INTERNAL/Music/Bruce Springsteen/Tunnel of Love/09 Brilliant Disguise.mp3" took 1 milliseconds Oct 30 02:52:16 pibox volumio[971]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 30 02:52:16 pibox volumio[971]: info: Oct 30 02:52:16 pibox volumio[971]: ---------------------------- MPD announces system playlist update Oct 30 02:52:16 pibox volumio[971]: info: Ignoring MPD Status Update Oct 30 02:52:16 pibox volumio[971]: info: Oct 30 02:52:16 pibox volumio[971]: ---------------------------- MPD announces system playlist update Oct 30 02:52:16 pibox volumio[971]: info: Ignoring MPD Status Update Oct 30 02:52:16 pibox volumio[971]: info: ------------------------------ 5ms Oct 30 02:52:16 pibox volumio[971]: info: sendMpdCommand consume 1 took 4 milliseconds Oct 30 02:52:16 pibox volumio[971]: info: ------------------------------ 3ms Oct 30 02:52:20 pibox volumio[971]: info: CoreStateMachine::startPlaybackTimer Oct 30 02:52:20 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: info: Oct 30 02:52:21 pibox volumio[971]: ---------------------------- MPD announces system playlist update Oct 30 02:52:21 pibox volumio[971]: info: Ignoring MPD Status Update Oct 30 02:52:21 pibox volumio[971]: info: Oct 30 02:52:21 pibox volumio[971]: ---------------------------- MPD announces state update: player Oct 30 02:52:21 pibox volumio[971]: info: ControllerMpd::getState Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::sendMpdCommand status Oct 30 02:52:21 pibox volumio[971]: info: Oct 30 02:52:21 pibox volumio[971]: ---------------------------- MPD announces system playlist update Oct 30 02:52:21 pibox volumio[971]: info: Ignoring MPD Status Update Oct 30 02:52:21 pibox volumio[971]: info: Oct 30 02:52:21 pibox volumio[971]: ---------------------------- MPD announces state update: player Oct 30 02:52:21 pibox volumio[971]: info: ControllerMpd::getState Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::sendMpdCommand status Oct 30 02:52:21 pibox volumio[971]: info: Oct 30 02:52:21 pibox volumio[971]: ---------------------------- MPD announces state update: player Oct 30 02:52:21 pibox volumio[971]: info: ControllerMpd::getState Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::sendMpdCommand status Oct 30 02:52:21 pibox volumio[971]: info: Oct 30 02:52:21 pibox volumio[971]: ---------------------------- MPD announces system playlist update Oct 30 02:52:21 pibox volumio[971]: info: Ignoring MPD Status Update Oct 30 02:52:21 pibox volumio[971]: info: Oct 30 02:52:21 pibox volumio[971]: ---------------------------- MPD announces state update: player Oct 30 02:52:21 pibox volumio[971]: info: ControllerMpd::getState Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::sendMpdCommand status Oct 30 02:52:21 pibox volumio[971]: info: Oct 30 02:52:21 pibox volumio[971]: ---------------------------- MPD announces state update: player Oct 30 02:52:21 pibox volumio[971]: info: ControllerMpd::getState Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::sendMpdCommand status Oct 30 02:52:21 pibox volumio[971]: info: Oct 30 02:52:21 pibox volumio[971]: ---------------------------- MPD announces state update: player Oct 30 02:52:21 pibox volumio[971]: info: ControllerMpd::getState Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::sendMpdCommand status Oct 30 02:52:21 pibox volumio[971]: info: ------------------------------ 7ms Oct 30 02:52:21 pibox volumio[971]: info: sendMpdCommand status took 6 milliseconds Oct 30 02:52:21 pibox volumio[971]: info: ------------------------------ 6ms Oct 30 02:52:21 pibox volumio[971]: info: sendMpdCommand status took 6 milliseconds Oct 30 02:52:21 pibox volumio[971]: info: sendMpdCommand status took 6 milliseconds Oct 30 02:52:21 pibox volumio[971]: info: ------------------------------ 5ms Oct 30 02:52:21 pibox volumio[971]: info: sendMpdCommand status took 5 milliseconds Oct 30 02:52:21 pibox volumio[971]: info: sendMpdCommand status took 3 milliseconds Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::parseState Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::parseState Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::parseState Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::parseState Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::parseState Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 02:52:21 pibox volumio[971]: info: sendMpdCommand status took 6 milliseconds Oct 30 02:52:21 pibox volumio[971]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 02:52:21 pibox volumio[971]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 30 02:52:21 pibox volumio[971]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 30 02:52:21 pibox volumio[971]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 02:52:21 pibox volumio[971]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::parseState Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::parseTrackInfo Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::parseTrackInfo Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::parseTrackInfo Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::parseTrackInfo Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::parseTrackInfo Oct 30 02:52:21 pibox volumio[971]: info: ControllerMpd::pushState Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::servicePushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: verbose: STATE SERVICE {"status":"play","position":0,"seek":73,"duration":257,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Brilliant Disguise","artist":"Bruce Springsteen","album":"Tunnel of Love","uri":"INTERNAL/Music/Bruce Springsteen/Tunnel of Love/09 Brilliant Disguise.mp3","trackType":"mp3"} Oct 30 02:52:21 pibox volumio[971]: verbose: CURRENT POSITION 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::syncState stateService play Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::syncState currentStatus play Oct 30 02:52:21 pibox volumio[971]: info: Received an update from plugin. extracting info from payload Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::pushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::volumioPushState Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::pushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::volumioPushState Oct 30 02:52:21 pibox volumio[971]: info: ControllerMpd::pushState Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::servicePushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: verbose: STATE SERVICE {"status":"play","position":0,"seek":73,"duration":257,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Brilliant Disguise","artist":"Bruce Springsteen","album":"Tunnel of Love","uri":"INTERNAL/Music/Bruce Springsteen/Tunnel of Love/09 Brilliant Disguise.mp3","trackType":"mp3"} Oct 30 02:52:21 pibox volumio[971]: verbose: CURRENT POSITION 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::syncState stateService play Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::syncState currentStatus play Oct 30 02:52:21 pibox volumio[971]: info: Received an update from plugin. extracting info from payload Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::pushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::volumioPushState Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::pushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::volumioPushState Oct 30 02:52:21 pibox volumio[971]: info: ControllerMpd::pushState Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::servicePushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: verbose: STATE SERVICE {"status":"play","position":0,"seek":73,"duration":257,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Brilliant Disguise","artist":"Bruce Springsteen","album":"Tunnel of Love","uri":"INTERNAL/Music/Bruce Springsteen/Tunnel of Love/09 Brilliant Disguise.mp3","trackType":"mp3"} Oct 30 02:52:21 pibox volumio[971]: verbose: CURRENT POSITION 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::syncState stateService play Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::syncState currentStatus play Oct 30 02:52:21 pibox volumio[971]: info: Received an update from plugin. extracting info from payload Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::pushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::volumioPushState Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::pushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::volumioPushState Oct 30 02:52:21 pibox volumio[971]: info: ControllerMpd::pushState Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::servicePushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: verbose: STATE SERVICE {"status":"play","position":0,"seek":73,"duration":257,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Brilliant Disguise","artist":"Bruce Springsteen","album":"Tunnel of Love","uri":"INTERNAL/Music/Bruce Springsteen/Tunnel of Love/09 Brilliant Disguise.mp3","trackType":"mp3"} Oct 30 02:52:21 pibox volumio[971]: verbose: CURRENT POSITION 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::syncState stateService play Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::syncState currentStatus play Oct 30 02:52:21 pibox volumio[971]: info: Received an update from plugin. extracting info from payload Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::pushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::volumioPushState Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::pushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::volumioPushState Oct 30 02:52:21 pibox volumio[971]: info: ControllerMpd::pushState Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::servicePushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: verbose: STATE SERVICE {"status":"play","position":0,"seek":73,"duration":257,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Brilliant Disguise","artist":"Bruce Springsteen","album":"Tunnel of Love","uri":"INTERNAL/Music/Bruce Springsteen/Tunnel of Love/09 Brilliant Disguise.mp3","trackType":"mp3"} Oct 30 02:52:21 pibox volumio[971]: verbose: CURRENT POSITION 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::syncState stateService play Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::syncState currentStatus play Oct 30 02:52:21 pibox volumio[971]: info: Received an update from plugin. extracting info from payload Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::pushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::volumioPushState Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::pushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::volumioPushState Oct 30 02:52:21 pibox volumio[971]: info: ------------------------------ 52ms Oct 30 02:52:21 pibox volumio[971]: info: ------------------------------ 52ms Oct 30 02:52:21 pibox volumio[971]: info: ------------------------------ 51ms Oct 30 02:52:21 pibox volumio[971]: info: ------------------------------ 51ms Oct 30 02:52:21 pibox volumio[971]: info: ------------------------------ 51ms Oct 30 02:52:21 pibox volumio[971]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 30 02:52:21 pibox volumio[971]: verbose: ControllerMpd::parseTrackInfo Oct 30 02:52:21 pibox volumio[971]: info: ControllerMpd::pushState Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::servicePushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: verbose: STATE SERVICE {"status":"play","position":0,"seek":73,"duration":257,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Brilliant Disguise","artist":"Bruce Springsteen","album":"Tunnel of Love","uri":"INTERNAL/Music/Bruce Springsteen/Tunnel of Love/09 Brilliant Disguise.mp3","trackType":"mp3"} Oct 30 02:52:21 pibox volumio[971]: verbose: CURRENT POSITION 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::syncState stateService play Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::syncState currentStatus play Oct 30 02:52:21 pibox volumio[971]: info: Received an update from plugin. extracting info from payload Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::pushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::volumioPushState Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::pushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::volumioPushState Oct 30 02:52:21 pibox volumio[971]: info: ------------------------------ 75ms Oct 30 02:52:21 pibox volumio[971]: info: CoreStateMachine::pushState Oct 30 02:52:21 pibox volumio[971]: info: CorePlayQueue::getTrack 233 Oct 30 02:52:21 pibox volumio[971]: info: CoreCommandRouter::volumioPushState Oct 30 02:53:56 pibox volumio[971]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 02:53:56 pibox volumio[971]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Oct 30 02:53:56 pibox volumio[971]: code: 'auth/network-request-failed', Oct 30 02:53:56 pibox volumio[971]: a: null Oct 30 02:53:56 pibox volumio[971]: } Oct 30 02:53:56 pibox volumio[971]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 02:53:56 pibox nmbd[756]: [2024/10/30 02:53:56.926331, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 02:53:56 pibox nmbd[756]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.166 for name WORKGROUP<1d>. Oct 30 02:53:56 pibox nmbd[756]: This response was from IP 192.168.0.200, reporting an IP address of 192.168.0.200. Oct 30 02:53:57 pibox sudo[7672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-30 02:52 Oct 30 02:53:57 pibox sudo[7672]: 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"