-- Logs begin at Mon 2024-08-19 13:48:08 UTC, end at Mon 2024-08-19 14:39:28 UTC. -- Aug 19 14:39:00 volumio volumio[7951]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 19 14:39:02 volumio volumio[7951]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::ClearQueue Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::stop Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:39:02 volumio volumio[7951]: verbose: UNSET VOLATILE Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::stPlaybackTimer Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::updateTrackBlock Aug 19 14:39:02 volumio volumio[7951]: info: CorePlayQueue::getTrackBlock Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::pushState Aug 19 14:39:02 volumio volumio[7951]: info: CorePlayQueue::getTrack 0 Aug 19 14:39:02 volumio volumio[7951]: info: CoreCommandRouter::volumioPushState Aug 19 14:39:02 volumio volumio[7951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::serviceStop Aug 19 14:39:02 volumio volumio[7951]: info: CorePlayQueue::getTrack 0 Aug 19 14:39:02 volumio volumio[7951]: info: CoreCommandRouter::serviceStop Aug 19 14:39:02 volumio volumio[7951]: info: [1724078342160] ControllerUPNPBrowser::stop Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 14:39:02 volumio volumio[7951]: info: CorePlayQueue::clearPlayQueue Aug 19 14:39:02 volumio volumio[7951]: info: CorePlayQueue::saveQueue Aug 19 14:39:02 volumio volumio[7951]: info: CoreCommandRouter::volumioPushState Aug 19 14:39:02 volumio volumio[7951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:39:02 volumio volumio[7951]: info: CoreCommandRouter::volumioPushQueue Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::addQueueItems Aug 19 14:39:02 volumio volumio[7951]: info: CorePlayQueue::addQueueItems Aug 19 14:39:02 volumio volumio[7951]: info: Adding Item to queue: upnp/folder/http://192.168.50.78:9791/65bf2aab-f5c9-49ee-ab9e-f3bbde7550aa/upnp.org-ContentDirectory-1/control@0$albums$*a65 Aug 19 14:39:02 volumio volumio[7951]: info: Exploding uri upnp/folder/http://192.168.50.78:9791/65bf2aab-f5c9-49ee-ab9e-f3bbde7550aa/upnp.org-ContentDirectory-1/control@0$albums$*a65 in service upnp_browser Aug 19 14:39:02 volumio volumio[7951]: info: CorePlayQueue::saveQueue Aug 19 14:39:02 volumio volumio[7951]: info: CoreCommandRouter::volumioPushQueue Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::updateTrackBlock Aug 19 14:39:02 volumio volumio[7951]: info: CorePlayQueue::getTrackBlock Aug 19 14:39:02 volumio volumio[7951]: info: CoreCommandRouter::volumioPlay Aug 19 14:39:02 volumio volumio[7951]: verbose: UNSET VOLATILE Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::play index 0 Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::stop Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:39:02 volumio volumio[7951]: verbose: UNSET VOLATILE Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::play index undefined Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:39:02 volumio volumio[7951]: info: CorePlayQueue::getTrack 0 Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::startPlaybackTimer Aug 19 14:39:02 volumio volumio[7951]: info: CorePlayQueue::getTrack 0 Aug 19 14:39:02 volumio volumio[7951]: info: [1724078342323] ControllerUPNPBrowser::clearAddPlayTrack Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 14:39:02 volumio volumio[7951]: info: Aug 19 14:39:02 volumio volumio[7951]: ---------------------------- MPD announces state update: player Aug 19 14:39:02 volumio volumio[7951]: info: sendMpdCommand stop took 171 milliseconds Aug 19 14:39:02 volumio volumio[7951]: info: ControllerMpd::getState Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:39:02 volumio volumio[7951]: info: sendMpdCommand stop took 13 milliseconds Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::sendMpdCommand clear Aug 19 14:39:02 volumio volumio[7951]: info: Aug 19 14:39:02 volumio volumio[7951]: ---------------------------- MPD announces system playlist update Aug 19 14:39:02 volumio volumio[7951]: info: Ignoring MPD Status Update Aug 19 14:39:02 volumio volumio[7951]: info: sendMpdCommand status took 17 milliseconds Aug 19 14:39:02 volumio volumio[7951]: info: sendMpdCommand clear took 12 milliseconds Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::parseState Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.50.78:9790/minimserver/*/2018/*e5*8f*a4*e5*85*b8*e9*ab*98*e6*a0*bc/Murray*20Perahia*20-*20Beethoven*20Piano*20Sonatas*20(24-96,*20DG,*202018)/01.*20Beethoven*20Piano*20Sonata*20No.*2029*20In*20B*20Flat*20Major,*20Op.*20106*20-Hammerklavier-1.*20Allegro.flac" Aug 19 14:39:02 volumio volumio[7951]: error: updateQueue error: null Aug 19 14:39:02 volumio volumio[7951]: info: ------------------------------ 27ms Aug 19 14:39:02 volumio volumio[7951]: info: sendMpdCommand playlistinfo took 27 milliseconds Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.50.78:9790/minimserver/*/2018/*e5*8f*a4*e5*85*b8*e9*ab*98*e6*a0*bc/Murray*20Perahia*20-*20Beethoven*20Piano*20Sonatas*20(24-96,*20DG,*202018)/01.*20Beethoven*20Piano*20Sonata*20No.*2029*20In*20B*20Flat*20Major,*20Op.*20106*20-Hammerklavier-1.*20Allegro.flac" Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:39:02 volumio volumio[7951]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 19 14:39:02 volumio volumio[7951]: info: ------------------------------ 59ms Aug 19 14:39:02 volumio volumio[7951]: info: Aug 19 14:39:02 volumio volumio[7951]: ---------------------------- MPD announces system playlist update Aug 19 14:39:02 volumio volumio[7951]: info: Ignoring MPD Status Update Aug 19 14:39:02 volumio volumio[7951]: info: sendMpdCommand add "http://192.168.50.78:9790/minimserver/*/2018/*e5*8f*a4*e5*85*b8*e9*ab*98*e6*a0*bc/Murray*20Perahia*20-*20Beethoven*20Piano*20Sonatas*20(24-96,*20DG,*202018)/01.*20Beethoven*20Piano*20Sonata*20No.*2029*20In*20B*20Flat*20Major,*20Op.*20106*20-Hammerklavier-1.*20Allegro.flac" took 19 milliseconds Aug 19 14:39:02 volumio volumio[7951]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::sendMpdCommand play Aug 19 14:39:02 volumio volumio[7951]: info: ------------------------------ 11ms Aug 19 14:39:02 volumio volumio[7951]: info: sendMpdCommand play took 8 milliseconds Aug 19 14:39:02 volumio volumio[7951]: info: Aug 19 14:39:02 volumio volumio[7951]: ---------------------------- MPD announces state update: player Aug 19 14:39:02 volumio volumio[7951]: info: ControllerMpd::getState Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:39:02 volumio volumio[7951]: info: Aug 19 14:39:02 volumio volumio[7951]: ---------------------------- MPD announces state update: player Aug 19 14:39:02 volumio volumio[7951]: info: sendMpdCommand status took 458 milliseconds Aug 19 14:39:02 volumio volumio[7951]: info: ControllerMpd::getState Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::parseState Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:39:02 volumio volumio[7951]: info: Aug 19 14:39:02 volumio volumio[7951]: ---------------------------- MPD announces system playlist update Aug 19 14:39:02 volumio volumio[7951]: info: Ignoring MPD Status Update Aug 19 14:39:02 volumio volumio[7951]: info: sendMpdCommand status took 26 milliseconds Aug 19 14:39:02 volumio volumio[7951]: info: sendMpdCommand playlistinfo took 26 milliseconds Aug 19 14:39:02 volumio volumio[7951]: info: ------------------------------ 16ms Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::parseState Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:39:02 volumio volumio[7951]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:39:02 volumio volumio[7951]: info: ControllerMpd::pushState Aug 19 14:39:02 volumio volumio[7951]: info: CoreCommandRouter::servicePushState Aug 19 14:39:02 volumio volumio[7951]: info: CorePlayQueue::getTrack 0 Aug 19 14:39:02 volumio volumio[7951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":609,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beethoven: Piano Sonata No. 29 In B Flat Major, Op. 106 -\"Hammerklavier\"-1. Allegro","artist":"Murray Perahia","album":"Beethoven: Piano Sonatas","uri":"http://192.168.50.78:9790/minimserver/*/2018/*e5*8f*a4*e5*85*b8*e9*ab*98*e6*a0*bc/Murray*20Perahia*20-*20Beethoven*20Piano*20Sonatas*20(24-96,*20DG,*202018)/01.*20Beethoven*20Piano*20Sonata*20No.*2029*20In*20B*20Flat*20Major,*20Op.*20106*20-Hammerklavier-1.*20Allegro.flac","trackType":"flac"} Aug 19 14:39:03 volumio volumio[7951]: verbose: CURRENT POSITION 0 Aug 19 14:39:03 volumio volumio[7951]: info: CoreStateMachine::syncState stateService play Aug 19 14:39:03 volumio volumio[7951]: info: CoreStateMachine::syncState currentStatus stop Aug 19 14:39:03 volumio volumio[7951]: info: CoreStateMachine::pushState Aug 19 14:39:03 volumio volumio[7951]: info: CoreCommandRouter::volumioPushState Aug 19 14:39:03 volumio volumio[7951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:39:03 volumio volumio[7951]: info: ------------------------------ 542ms Aug 19 14:39:03 volumio volumio[7951]: info: sendMpdCommand playlistinfo took 61 milliseconds Aug 19 14:39:03 volumio volumio[7951]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:39:03 volumio volumio[7951]: info: ControllerMpd::pushState Aug 19 14:39:03 volumio volumio[7951]: info: CoreCommandRouter::servicePushState Aug 19 14:39:03 volumio volumio[7951]: info: CorePlayQueue::getTrack 0 Aug 19 14:39:03 volumio volumio[7951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":490,"duration":609,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1605 Kbps","isStreaming":false,"title":"Beethoven: Piano Sonata No. 29 In B Flat Major, Op. 106 -\"Hammerklavier\"-1. Allegro","artist":"Murray Perahia","album":"Beethoven: Piano Sonatas","uri":"http://192.168.50.78:9790/minimserver/*/2018/*e5*8f*a4*e5*85*b8*e9*ab*98*e6*a0*bc/Murray*20Perahia*20-*20Beethoven*20Piano*20Sonatas*20(24-96,*20DG,*202018)/01.*20Beethoven*20Piano*20Sonata*20No.*2029*20In*20B*20Flat*20Major,*20Op.*20106*20-Hammerklavier-1.*20Allegro.flac","trackType":"flac"} Aug 19 14:39:03 volumio volumio[7951]: verbose: CURRENT POSITION 0 Aug 19 14:39:03 volumio volumio[7951]: info: CoreStateMachine::syncState stateService play Aug 19 14:39:03 volumio volumio[7951]: info: CoreStateMachine::syncState currentStatus play Aug 19 14:39:03 volumio volumio[7951]: info: Received an update from plugin. extracting info from payload Aug 19 14:39:03 volumio volumio[7951]: info: CoreStateMachine::pushState Aug 19 14:39:03 volumio volumio[7951]: info: CoreCommandRouter::volumioPushState Aug 19 14:39:03 volumio volumio[7951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:39:03 volumio volumio[7951]: info: CoreStateMachine::pushState Aug 19 14:39:03 volumio volumio[7951]: info: CoreCommandRouter::volumioPushState Aug 19 14:39:03 volumio volumio[7951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:39:03 volumio volumio[7951]: info: ------------------------------ 126ms Aug 19 14:39:03 volumio volumio[7951]: info: CoreCommandRouter::volumioGetQueue Aug 19 14:39:03 volumio volumio[7951]: info: CoreStateMachine::getQueue Aug 19 14:39:03 volumio volumio[7951]: info: CorePlayQueue::getQueue Aug 19 14:39:03 volumio volumio[7951]: info: CoreCommandRouter::volumioGetQueue Aug 19 14:39:03 volumio volumio[7951]: info: CoreStateMachine::getQueue Aug 19 14:39:03 volumio volumio[7951]: info: CorePlayQueue::getQueue Aug 19 14:39:03 volumio volumio[7951]: info: CoreCommandRouter::volumioGetQueue Aug 19 14:39:03 volumio volumio[7951]: info: CoreStateMachine::getQueue Aug 19 14:39:03 volumio volumio[7951]: info: CorePlayQueue::getQueue Aug 19 14:39:19 volumio volumio[7951]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Murray%20Perahia/Beethoven%3A%20Piano%20Sonatas/992eef94-1a26-4572-a6dd-a85e1ed0b7a0.jpg' Aug 19 14:39:27 volumio volumio[7951]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 14:39:27 volumio volumio[7951]: { Error: connect ETIMEDOUT 31.13.95.18:443 Aug 19 14:39:27 volumio volumio[7951]: at Object._errnoException (util.js:1022:11) Aug 19 14:39:27 volumio volumio[7951]: at _exceptionWithHostPort (util.js:1044:20) Aug 19 14:39:27 volumio volumio[7951]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 19 14:39:27 volumio volumio[7951]: code: 'ETIMEDOUT', Aug 19 14:39:27 volumio volumio[7951]: errno: 'ETIMEDOUT', Aug 19 14:39:27 volumio volumio[7951]: syscall: 'connect', Aug 19 14:39:27 volumio volumio[7951]: address: '31.13.95.18', Aug 19 14:39:27 volumio volumio[7951]: port: 443 } Aug 19 14:39:27 volumio volumio[7951]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 14:39:28 volumio sudo[9540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-19 14:38 Aug 19 14:39:28 volumio sudo[9540]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"