-- Logs begin at Wed 2023-09-13 02:38:43 UTC, end at Thu 2024-04-25 03:13:40 UTC. -- Apr 25 03:12:05 opera volumio[947]: Searching plugin music_service/tidal Apr 25 03:12:05 opera volumio[947]: info: CoreCommandRouter::executeOnPlugin: tidal , search Apr 25 03:12:12 opera volumio[947]: info: searchTIDALUri took 6650 milliseconds Apr 25 03:12:12 opera volumio[947]: info: search took 6650 milliseconds Apr 25 03:12:12 opera volumio[947]: info: All search sources collected, pushing search results Apr 25 03:12:50 opera volumio[947]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Apr 25 03:12:56 opera volumio[947]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Apr 25 03:12:57 opera volumio[947]: info: browseTIDALUri took 6553 milliseconds Apr 25 03:12:57 opera volumio[947]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 25 03:12:57 opera volumio[947]: info: CoreStateMachine::ClearQueue Apr 25 03:12:57 opera volumio[947]: info: CoreStateMachine::stop Apr 25 03:12:57 opera volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 03:12:57 opera volumio[947]: verbose: UNSET VOLATILE Apr 25 03:12:57 opera volumio[947]: info: CorePlayQueue::clearPlayQueue Apr 25 03:12:57 opera volumio[947]: info: CorePlayQueue::saveQueue Apr 25 03:12:57 opera volumio[947]: info: CoreCommandRouter::volumioPushState Apr 25 03:12:57 opera volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 03:12:57 opera volumio[947]: info: CoreCommandRouter::volumioPushQueue Apr 25 03:12:57 opera volumio[947]: info: CoreStateMachine::addQueueItems Apr 25 03:12:57 opera volumio[947]: info: CorePlayQueue::addQueueItems Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813237 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813237 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813238 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813238 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813239 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813239 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813240 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813240 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813241 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813241 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813242 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813242 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813243 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813243 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813244 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813244 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813245 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813245 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813246 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813246 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813247 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813247 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813248 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813248 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813249 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813249 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813250 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813250 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813251 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813251 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813252 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813252 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813253 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813253 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813254 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813254 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813255 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813255 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813256 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813256 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813257 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813257 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813258 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813258 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813259 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813259 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Adding Item to queue: tidal://song/266813260 Apr 25 03:12:57 opera volumio[947]: info: Exploding uri tidal://song/266813260 in service tidal Apr 25 03:12:57 opera volumio[947]: info: Updating RAAT Signal Path Apr 25 03:12:57 opera volumio[947]: info: browseTIDALUri took 1283 milliseconds Apr 25 03:12:58 opera volumio[947]: info: explodeTIDALUri took 1251 milliseconds Apr 25 03:12:58 opera volumio[947]: info: explodeTIDALUri took 1350 milliseconds Apr 25 03:12:59 opera volumio[947]: info: explodeTIDALUri took 1438 milliseconds Apr 25 03:12:59 opera volumio[947]: info: explodeTIDALUri took 1432 milliseconds Apr 25 03:12:59 opera volumio[947]: info: explodeTIDALUri took 1439 milliseconds Apr 25 03:12:59 opera volumio[947]: info: explodeTIDALUri took 1485 milliseconds Apr 25 03:12:59 opera volumio[947]: info: explodeTIDALUri took 1518 milliseconds Apr 25 03:12:59 opera volumio[947]: info: explodeTIDALUri took 1546 milliseconds Apr 25 03:12:59 opera volumio[947]: info: explodeTIDALUri took 1547 milliseconds Apr 25 03:12:59 opera volumio[947]: info: explodeTIDALUri took 1617 milliseconds Apr 25 03:13:03 opera volumio[947]: info: explodeTIDALUri took 6286 milliseconds Apr 25 03:13:04 opera volumio[947]: info: explodeTIDALUri took 6598 milliseconds Apr 25 03:13:04 opera volumio[947]: info: explodeTIDALUri took 6618 milliseconds Apr 25 03:13:04 opera volumio[947]: info: explodeTIDALUri took 6653 milliseconds Apr 25 03:13:07 opera volumio[947]: info: explodeTIDALUri took 9617 milliseconds Apr 25 03:13:07 opera volumio[947]: info: explodeTIDALUri took 9666 milliseconds Apr 25 03:13:07 opera volumio[947]: info: explodeTIDALUri took 9729 milliseconds Apr 25 03:13:07 opera volumio[947]: info: explodeTIDALUri took 9806 milliseconds Apr 25 03:13:07 opera volumio[947]: info: explodeTIDALUri took 9844 milliseconds Apr 25 03:13:07 opera volumio[947]: info: explodeTIDALUri took 9928 milliseconds Apr 25 03:13:07 opera volumio[947]: info: explodeTIDALUri took 9979 milliseconds Apr 25 03:13:09 opera volumio[947]: info: explodeTIDALUri took 11727 milliseconds Apr 25 03:13:09 opera volumio[947]: info: explodeTIDALUri took 11867 milliseconds Apr 25 03:13:15 opera volumio[947]: info: explodeTIDALUri took 18221 milliseconds Apr 25 03:13:15 opera volumio[947]: info: CorePlayQueue::saveQueue Apr 25 03:13:15 opera volumio[947]: info: CoreCommandRouter::volumioPushQueue Apr 25 03:13:15 opera volumio[947]: info: CoreStateMachine::updateTrackBlock Apr 25 03:13:15 opera volumio[947]: info: CorePlayQueue::getTrackBlock Apr 25 03:13:15 opera volumio[947]: info: CoreCommandRouter::volumioPlay Apr 25 03:13:15 opera volumio[947]: verbose: UNSET VOLATILE Apr 25 03:13:15 opera volumio[947]: info: CoreStateMachine::play index 6 Apr 25 03:13:15 opera volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 03:13:15 opera volumio[947]: info: CoreStateMachine::stop Apr 25 03:13:15 opera volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 03:13:15 opera volumio[947]: verbose: UNSET VOLATILE Apr 25 03:13:15 opera volumio[947]: info: CoreStateMachine::play index undefined Apr 25 03:13:15 opera volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 03:13:15 opera volumio[947]: info: CorePlayQueue::getTrack 6 Apr 25 03:13:15 opera volumio[947]: info: CoreStateMachine::startPlaybackTimer Apr 25 03:13:15 opera volumio[947]: info: CorePlayQueue::getTrack 6 Apr 25 03:13:15 opera volumio[947]: info: [1714014795896] ControllerTidal::clearAddPlayTrack Apr 25 03:13:15 opera volumio[947]: info: Getting stream with soundQuality LOSSLESS Apr 25 03:13:17 opera volumio[947]: info: getStreamUrl took 1332 milliseconds Apr 25 03:13:17 opera volumio[947]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 03:13:17 opera volumio[947]: info: sendMpdCommand stop took 2 milliseconds Apr 25 03:13:17 opera volumio[947]: verbose: ControllerMpd::sendMpdCommand clear Apr 25 03:13:17 opera volumio[947]: info: Apr 25 03:13:17 opera volumio[947]: ---------------------------- MPD announces system playlist update Apr 25 03:13:17 opera volumio[947]: info: Ignoring MPD Status Update Apr 25 03:13:17 opera volumio[947]: info: sendMpdCommand clear took 2 milliseconds Apr 25 03:13:17 opera volumio[947]: verbose: ControllerMpd::sendMpdCommand add "http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInY2RlYzJkNTVjNDZlMWQ2OGU4NDY2OWZmNmYzODg4MGJfNjEubXA0/0.flac?token=1714018397~MTU4NjA2NmYzMTNkYWE2YTQ1MTg5M2YwZWRjNmIyMjg2MWM5MzZhYw==" Apr 25 03:13:17 opera volumio[947]: error: updateQueue error: null Apr 25 03:13:17 opera volumio[947]: info: Apr 25 03:13:17 opera volumio[947]: ---------------------------- MPD announces system playlist update Apr 25 03:13:17 opera volumio[947]: info: Ignoring MPD Status Update Apr 25 03:13:17 opera volumio[947]: info: ------------------------------ 3ms Apr 25 03:13:17 opera volumio[947]: info: sendMpdCommand add "http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInY2RlYzJkNTVjNDZlMWQ2OGU4NDY2OWZmNmYzODg4MGJfNjEubXA0/0.flac?token=1714018397~MTU4NjA2NmYzMTNkYWE2YTQ1MTg5M2YwZWRjNmIyMjg2MWM5MzZhYw==" took 2 milliseconds Apr 25 03:13:17 opera volumio[947]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 25 03:13:17 opera volumio[947]: verbose: ControllerMpd::sendMpdCommand play Apr 25 03:13:17 opera volumio[947]: info: ------------------------------ 2ms Apr 25 03:13:17 opera volumio[947]: info: sendMpdCommand play took 2 milliseconds Apr 25 03:13:29 opera volumio[947]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Apr 25 03:13:29 opera volumio[947]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Apr 25 03:13:31 opera volumio[947]: info: browseTIDALUri took 1488 milliseconds Apr 25 03:13:31 opera volumio[947]: info: browseTIDALUri took 1243 milliseconds Apr 25 03:13:33 opera volumio[947]: info: Executing endpoint metavolumio Apr 25 03:13:33 opera volumio[947]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 03:13:34 opera volumio[947]: info: Executing endpoint metavolumio Apr 25 03:13:34 opera volumio[947]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 03:13:35 opera volumio[947]: info: Executing endpoint metavolumio Apr 25 03:13:35 opera volumio[947]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 03:13:35 opera volumio[947]: info: Executing endpoint metavolumio Apr 25 03:13:35 opera volumio[947]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 03:13:38 opera volumio[947]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alan%20O%27Bryant/e69b3a64-2ece-4dfb-9b98-39c60c0a22f8.jpg' Apr 25 03:13:40 opera volumio[947]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 03:13:40 opera volumio[947]: { Error: connect ETIMEDOUT 104.244.46.244:80 Apr 25 03:13:40 opera volumio[947]: at Object._errnoException (util.js:1022:11) Apr 25 03:13:40 opera volumio[947]: at _exceptionWithHostPort (util.js:1044:20) Apr 25 03:13:40 opera volumio[947]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 25 03:13:40 opera volumio[947]: code: 'ETIMEDOUT', Apr 25 03:13:40 opera volumio[947]: errno: 'ETIMEDOUT', Apr 25 03:13:40 opera volumio[947]: syscall: 'connect', Apr 25 03:13:40 opera volumio[947]: address: '104.244.46.244', Apr 25 03:13:40 opera volumio[947]: port: 80 } Apr 25 03:13:40 opera volumio[947]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 03:13:40 opera sudo[6887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-25 03:12 Apr 25 03:13:40 opera sudo[6887]: 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="047a38c6e11e7058b10d3038ac9dcbff94f92828" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Apr 8 17:29:57 CEST 2022" VOLUMIO_VERSION="1.033" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="77fa3b0bf86939c934741dd9bb62adcc"