-- Logs begin at Sat 2025-05-10 16:25:43 UTC, end at Sun 2025-05-11 05:17:28 UTC. -- May 11 05:16:24 opera sudo[2102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 11 05:16:24 opera sudo[2102]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 05:16:24 opera sudo[2104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 11 05:16:24 opera sudo[2104]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 05:16:24 opera sudo[2102]: pam_unix(sudo:session): session closed for user root May 11 05:16:24 opera sudo[2104]: pam_unix(sudo:session): session closed for user root May 11 05:16:27 opera systemd[1]: Started volumio-remote-updater-push.service. May 11 05:16:27 opera systemd[1]: Starting Multi-User System. May 11 05:16:27 opera systemd[1]: Reached target Multi-User System. May 11 05:16:27 opera systemd[1]: Starting Graphical Interface. May 11 05:16:27 opera systemd[1]: Reached target Graphical Interface. May 11 05:16:27 opera systemd[1]: Starting Update UTMP about System Runlevel Changes... May 11 05:16:27 opera volumio[1390]: ------------------------------------ BT MESSAGE: BT STATUS: running May 11 05:16:27 opera systemd[1]: Started Update UTMP about System Runlevel Changes. May 11 05:16:27 opera systemd[1]: Startup finished in 11.534s (kernel) + 2min 5.246s (userspace) = 2min 16.781s. May 11 05:16:27 opera volumio[1390]: info: CoreCommandRouter::volumioGetVisibleSources May 11 05:16:27 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 05:16:27 opera volumio[1390]: info: CoreCommandRouter::volumioGetState May 11 05:16:27 opera volumio[1390]: info: CorePlayQueue::getTrack 0 May 11 05:16:27 opera volumio[1390]: info: CoreCommandRouter::volumioGetQueue May 11 05:16:27 opera volumio[1390]: info: CoreStateMachine::getQueue May 11 05:16:27 opera volumio[1390]: info: CorePlayQueue::getQueue May 11 05:16:27 opera volumio[1390]: info: Listing playlists May 11 05:16:27 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 11 05:16:27 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 11 05:16:27 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 11 05:16:27 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 11 05:16:30 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 11 05:16:30 opera volumio[1390]: info: CURURI: music-library May 11 05:16:31 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 11 05:16:31 opera volumio[1390]: info: CURURI: music-library/USB May 11 05:16:32 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 11 05:16:32 opera volumio[1390]: info: CURURI: music-library/USB/dcm-T May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 05:16:43 opera volumio[1390]: info: Retrieving Cloud Streaming UI May 11 05:16:43 opera volumio[1390]: info: Getting Tidal Cloud Configuration May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 05:16:43 opera volumio[1390]: info: Getting Qobuz Cloud Configuration May 11 05:16:43 opera volumio[1390]: info: Asking plugin for UI Config May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 05:16:43 opera volumio[1390]: info: Getting Spotify Cloud Configuration May 11 05:16:43 opera volumio[1390]: info: Asking plugin for UI Config May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 05:16:43 opera volumio[1390]: error: Could not retrieve plugin music_service spotify May 11 05:16:43 opera volumio[1390]: info: Saving Spotify Acccount May 11 05:16:43 opera volumio[1390]: info: Got it May 11 05:16:43 opera volumio[1390]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 11 05:16:43 opera volumio[1390]: info: Got Tidal Cloud Configuration May 11 05:16:43 opera volumio[1390]: info: Got it May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::volumioGetBrowseSources May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 05:16:43 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 11 05:16:47 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 05:16:49 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 11 05:16:49 opera volumio[1390]: verbose: ControllerMpd::sendMpdCommand update May 11 05:16:49 opera volumio[1390]: verbose: ControllerMpd::sendMpdCommand status May 11 05:16:49 opera volumio[1390]: info: May 11 05:16:49 opera volumio[1390]: ---------------------------- MPD announces state update: update May 11 05:16:49 opera volumio[1390]: info: sendMpdCommand update took 19 milliseconds May 11 05:16:49 opera volumio[1390]: info: ControllerMpd::getState May 11 05:16:49 opera volumio[1390]: verbose: ControllerMpd::sendMpdCommand status May 11 05:16:49 opera volumio[1390]: info: sendMpdCommand status took 14 milliseconds May 11 05:16:49 opera volumio[1390]: info: sendMpdCommand status took 8 milliseconds May 11 05:16:49 opera volumio[1390]: verbose: ControllerMpd::parseState May 11 05:16:49 opera volumio[1390]: info: Command Router : Notfying DB Updatetrue May 11 05:16:49 opera volumio[1390]: verbose: ControllerMpd::parseState May 11 05:16:49 opera volumio[1390]: info: ControllerMpd::pushState May 11 05:16:49 opera volumio[1390]: info: CoreCommandRouter::servicePushState May 11 05:16:49 opera volumio[1390]: info: CoreStateMachine::pushState May 11 05:16:49 opera volumio[1390]: info: CorePlayQueue::getTrack 0 May 11 05:16:49 opera volumio[1390]: info: CoreCommandRouter::volumioPushState May 11 05:16:49 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 05:16:49 opera volumio[1390]: info: CorePlayQueue::getTrack 0 May 11 05:16:49 opera volumio[1390]: 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} May 11 05:16:49 opera volumio[1390]: verbose: CURRENT POSITION 0 May 11 05:16:49 opera volumio[1390]: info: CoreStateMachine::syncState stateService stop May 11 05:16:49 opera volumio[1390]: info: CoreStateMachine::syncState currentStatus stop May 11 05:16:49 opera volumio[1390]: info: CoreStateMachine::pushState May 11 05:16:49 opera volumio[1390]: info: CorePlayQueue::getTrack 0 May 11 05:16:49 opera volumio[1390]: info: CoreCommandRouter::volumioPushState May 11 05:16:49 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 05:16:49 opera volumio[1390]: info: No code May 11 05:16:49 opera volumio[1390]: info: CoreStateMachine::pushState May 11 05:16:49 opera volumio[1390]: info: CorePlayQueue::getTrack 0 May 11 05:16:49 opera volumio[1390]: info: CoreCommandRouter::volumioPushState May 11 05:16:49 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 05:16:49 opera volumio[1390]: info: ------------------------------ 74ms May 11 05:16:49 opera volumio[1390]: info: Updating RAAT Signal Path May 11 05:16:49 opera volumio[1390]: info: Updating RAAT Signal Path May 11 05:16:49 opera volumio[1390]: info: Updating RAAT Signal Path May 11 05:16:51 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 05:16:52 opera volumio[1390]: info: CoreCommandRouter::Close All Modals sent May 11 05:16:55 opera volumio[1390]: verbose: ControllerMpd::sendMpdCommand status May 11 05:16:55 opera volumio[1390]: info: May 11 05:16:55 opera volumio[1390]: ---------------------------- MPD announces state update: update May 11 05:16:55 opera volumio[1390]: info: ControllerMpd::getState May 11 05:16:55 opera volumio[1390]: verbose: ControllerMpd::sendMpdCommand status May 11 05:16:55 opera volumio[1390]: info: sendMpdCommand status took 2 milliseconds May 11 05:16:55 opera volumio[1390]: info: sendMpdCommand status took 1 milliseconds May 11 05:16:55 opera volumio[1390]: verbose: ControllerMpd::parseState May 11 05:16:55 opera volumio[1390]: info: Command Router : Notfying DB Updatefalse May 11 05:16:55 opera volumio[1390]: info: CoreCommandRouter::Close All Modals sent May 11 05:16:55 opera volumio[1390]: verbose: ControllerMpd::parseState May 11 05:16:55 opera volumio[1390]: info: ControllerMpd::pushState May 11 05:16:55 opera volumio[1390]: info: CoreCommandRouter::servicePushState May 11 05:16:55 opera volumio[1390]: info: CoreStateMachine::pushState May 11 05:16:55 opera volumio[1390]: info: CorePlayQueue::getTrack 0 May 11 05:16:55 opera volumio[1390]: info: CoreCommandRouter::volumioPushState May 11 05:16:55 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 05:16:55 opera volumio[1390]: info: CorePlayQueue::getTrack 0 May 11 05:16:55 opera volumio[1390]: 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} May 11 05:16:55 opera volumio[1390]: verbose: CURRENT POSITION 0 May 11 05:16:55 opera volumio[1390]: info: CoreStateMachine::syncState stateService stop May 11 05:16:55 opera volumio[1390]: info: CoreStateMachine::syncState currentStatus stop May 11 05:16:55 opera volumio[1390]: info: CoreStateMachine::pushState May 11 05:16:55 opera volumio[1390]: info: CorePlayQueue::getTrack 0 May 11 05:16:55 opera volumio[1390]: info: CoreCommandRouter::volumioPushState May 11 05:16:55 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 05:16:55 opera volumio[1390]: info: No code May 11 05:16:55 opera volumio[1390]: info: CoreStateMachine::pushState May 11 05:16:55 opera volumio[1390]: info: CorePlayQueue::getTrack 0 May 11 05:16:55 opera volumio[1390]: info: CoreCommandRouter::volumioPushState May 11 05:16:55 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 05:16:55 opera volumio[1390]: info: ------------------------------ 40ms May 11 05:16:55 opera volumio[1390]: info: Updating RAAT Signal Path May 11 05:16:55 opera volumio[1390]: info: Updating RAAT Signal Path May 11 05:16:55 opera volumio[1390]: info: Updating RAAT Signal Path May 11 05:16:55 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 05:16:59 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 05:17:02 opera dhcpd[1424]: DHCPDISCOVER from 48:e7:da:4e:f7:b3 (opera) via wlan0 May 11 05:17:02 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 11 05:17:02 opera volumio[1390]: verbose: ControllerMpd::sendMpdCommand rescan May 11 05:17:02 opera volumio[1390]: verbose: ControllerMpd::sendMpdCommand status May 11 05:17:02 opera volumio[1390]: info: May 11 05:17:02 opera volumio[1390]: ---------------------------- MPD announces state update: update May 11 05:17:02 opera volumio[1390]: info: sendMpdCommand rescan took 4 milliseconds May 11 05:17:02 opera volumio[1390]: info: ControllerMpd::getState May 11 05:17:02 opera volumio[1390]: verbose: ControllerMpd::sendMpdCommand status May 11 05:17:02 opera volumio[1390]: info: sendMpdCommand status took 3 milliseconds May 11 05:17:02 opera volumio[1390]: info: sendMpdCommand status took 2 milliseconds May 11 05:17:02 opera volumio[1390]: verbose: ControllerMpd::parseState May 11 05:17:02 opera volumio[1390]: info: Command Router : Notfying DB Updatetrue May 11 05:17:02 opera volumio[1390]: info: CoreCommandRouter::Close All Modals sent May 11 05:17:02 opera volumio[1390]: verbose: ControllerMpd::parseState May 11 05:17:02 opera volumio[1390]: info: ControllerMpd::pushState May 11 05:17:02 opera volumio[1390]: info: CoreCommandRouter::servicePushState May 11 05:17:02 opera volumio[1390]: info: CoreStateMachine::pushState May 11 05:17:02 opera volumio[1390]: info: CorePlayQueue::getTrack 0 May 11 05:17:02 opera volumio[1390]: info: CoreCommandRouter::volumioPushState May 11 05:17:02 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 05:17:02 opera volumio[1390]: info: CorePlayQueue::getTrack 0 May 11 05:17:02 opera volumio[1390]: 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} May 11 05:17:02 opera volumio[1390]: verbose: CURRENT POSITION 0 May 11 05:17:02 opera volumio[1390]: info: CoreStateMachine::syncState stateService stop May 11 05:17:02 opera volumio[1390]: info: CoreStateMachine::syncState currentStatus stop May 11 05:17:02 opera volumio[1390]: info: CoreStateMachine::pushState May 11 05:17:02 opera volumio[1390]: info: CorePlayQueue::getTrack 0 May 11 05:17:02 opera volumio[1390]: info: CoreCommandRouter::volumioPushState May 11 05:17:02 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 05:17:02 opera volumio[1390]: info: No code May 11 05:17:02 opera volumio[1390]: info: CoreStateMachine::pushState May 11 05:17:02 opera volumio[1390]: info: CorePlayQueue::getTrack 0 May 11 05:17:02 opera volumio[1390]: info: CoreCommandRouter::volumioPushState May 11 05:17:02 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 05:17:02 opera volumio[1390]: info: ------------------------------ 56ms May 11 05:17:02 opera volumio[1390]: info: Updating RAAT Signal Path May 11 05:17:02 opera volumio[1390]: info: Updating RAAT Signal Path May 11 05:17:02 opera volumio[1390]: info: Updating RAAT Signal Path May 11 05:17:03 opera dhcpd[1424]: DHCPOFFER on 192.168.211.4 to 48:e7:da:4e:f7:b3 (opera) via wlan0 May 11 05:17:03 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 05:17:07 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 05:17:11 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 05:17:15 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 05:17:18 opera volumio[1390]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%94%A1%E7%90%B4/%E6%A9%9F%E9%81%87/e32f7827-a26e-4bae-9b5f-6520516f0122.jpg' May 11 05:17:19 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 05:17:23 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 05:17:27 opera volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 05:17:28 opera volumio[1390]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 05:17:28 opera volumio[1390]: { Error: connect ETIMEDOUT 118.107.180.216:443 May 11 05:17:28 opera volumio[1390]: at Object._errnoException (util.js:1022:11) May 11 05:17:28 opera volumio[1390]: at _exceptionWithHostPort (util.js:1044:20) May 11 05:17:28 opera volumio[1390]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) May 11 05:17:28 opera volumio[1390]: code: 'ETIMEDOUT', May 11 05:17:28 opera volumio[1390]: errno: 'ETIMEDOUT', May 11 05:17:28 opera volumio[1390]: syscall: 'connect', May 11 05:17:28 opera volumio[1390]: address: '118.107.180.216', May 11 05:17:28 opera volumio[1390]: port: 443 } May 11 05:17:28 opera volumio[1390]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 05:17:28 opera sudo[2342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-11 05:16 May 11 05:17:28 opera sudo[2342]: 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"