-- Logs begin at Sat 2024-02-03 10:58:39 UTC, end at Sat 2024-10-26 04:23:59 UTC. -- Oct 26 04:22:02 opera volumio[1561]: 90 Oct 26 04:22:02 opera volumio[1561]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 26 04:22:02 opera volumio[1561]: Dload Upload Total Spent Left Speed Oct 26 04:22:02 opera volumio[1081]: info: Oct 26 04:22:02 opera volumio[1081]: ---------------------------- Client requests Volume 90 Oct 26 04:22:02 opera volumio[1081]: info: VolumeController::SetAlsaVolume90 Oct 26 04:22:02 opera volumio[1081]: info: CoreStateMachine::pushState Oct 26 04:22:02 opera volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 26 04:22:02 opera volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 04:22:02 opera volumio[1561]: [155B blob data] Oct 26 04:22:02 opera volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 26 04:22:02 opera volumio[1081]: info: CoreStateMachine::getQueue Oct 26 04:22:02 opera volumio[1081]: info: CorePlayQueue::getQueue Oct 26 04:22:02 opera volumio[1081]: info: Updating RAAT Signal Path Oct 26 04:22:02 opera volumio[1561]: {"time":1729916522258,"response":"volume Success"}90 Oct 26 04:22:02 opera volumio[1081]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 26 04:22:02 opera volumio[1081]: xcb_connection_has_error() returned true Oct 26 04:22:02 opera volumio[1561]: 80 Oct 26 04:22:02 opera volumio[1561]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 26 04:22:02 opera volumio[1561]: Dload Upload Total Spent Left Speed Oct 26 04:22:02 opera volumio[1081]: info: Oct 26 04:22:02 opera volumio[1081]: ---------------------------- Client requests Volume 80 Oct 26 04:22:02 opera volumio[1081]: info: VolumeController::SetAlsaVolume80 Oct 26 04:22:02 opera volumio[1081]: info: CoreStateMachine::pushState Oct 26 04:22:02 opera volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 26 04:22:02 opera volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 04:22:02 opera volumio[1561]: [155B blob data] Oct 26 04:22:02 opera volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 26 04:22:02 opera volumio[1081]: info: CoreStateMachine::getQueue Oct 26 04:22:02 opera volumio[1081]: info: CorePlayQueue::getQueue Oct 26 04:22:02 opera volumio[1081]: info: Updating RAAT Signal Path Oct 26 04:22:02 opera volumio[1561]: {"time":1729916522654,"response":"volume Success"}80 Oct 26 04:22:03 opera volumio[1561]: 90 Oct 26 04:22:03 opera volumio[1561]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 26 04:22:03 opera volumio[1561]: Dload Upload Total Spent Left Speed Oct 26 04:22:03 opera volumio[1081]: info: Oct 26 04:22:03 opera volumio[1081]: ---------------------------- Client requests Volume 90 Oct 26 04:22:03 opera volumio[1081]: info: VolumeController::SetAlsaVolume90 Oct 26 04:22:03 opera volumio[1081]: info: CoreStateMachine::pushState Oct 26 04:22:03 opera volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 26 04:22:03 opera volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 04:22:03 opera volumio[1561]: [155B blob data] Oct 26 04:22:03 opera volumio[1561]: {"time":1729916523173,"response":"volume Success"}90 Oct 26 04:22:03 opera volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 26 04:22:03 opera volumio[1081]: info: CoreStateMachine::getQueue Oct 26 04:22:03 opera volumio[1081]: info: CorePlayQueue::getQueue Oct 26 04:22:03 opera volumio[1081]: info: Updating RAAT Signal Path Oct 26 04:22:03 opera volumio[1081]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 26 04:22:03 opera volumio[1081]: xcb_connection_has_error() returned true Oct 26 04:22:03 opera volumio[1561]: 100 Oct 26 04:22:03 opera volumio[1561]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 26 04:22:03 opera volumio[1561]: Dload Upload Total Spent Left Speed Oct 26 04:22:03 opera volumio[1081]: info: Oct 26 04:22:03 opera volumio[1081]: ---------------------------- Client requests Volume 100 Oct 26 04:22:03 opera volumio[1081]: info: VolumeController::SetAlsaVolume100 Oct 26 04:22:03 opera volumio[1081]: info: CoreStateMachine::pushState Oct 26 04:22:03 opera volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 26 04:22:03 opera volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 04:22:03 opera volumio[1561]: [155B blob data] Oct 26 04:22:03 opera volumio[1561]: {"time":1729916523473,"response":"volume Success"}100 Oct 26 04:22:03 opera volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 26 04:22:03 opera volumio[1081]: info: CoreStateMachine::getQueue Oct 26 04:22:03 opera volumio[1081]: info: CorePlayQueue::getQueue Oct 26 04:22:03 opera volumio[1081]: info: Updating RAAT Signal Path Oct 26 04:22:03 opera volumio[1081]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 26 04:22:03 opera volumio[1081]: xcb_connection_has_error() returned true Oct 26 04:22:05 opera volumio[1081]: info: Oct 26 04:22:05 opera volumio[1081]: ---------------------------- MPD announces state update: player Oct 26 04:22:05 opera volumio[1081]: info: ControllerMpd::getState Oct 26 04:22:05 opera volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 26 04:22:05 opera volumio[1081]: info: sendMpdCommand status took 1 milliseconds Oct 26 04:22:05 opera volumio[1081]: verbose: ControllerMpd::parseState Oct 26 04:22:05 opera volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 04:22:05 opera volumio[1081]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 26 04:22:05 opera volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 26 04:22:05 opera volumio[1081]: info: ControllerMpd::pushState Oct 26 04:22:05 opera volumio[1081]: info: CoreCommandRouter::servicePushState Oct 26 04:22:05 opera volumio[1081]: info: CoreStateMachine::pushState Oct 26 04:22:05 opera volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 26 04:22:05 opera volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 04:22:05 opera volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 26 04:22:05 opera volumio[1081]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Broken Heart Heals with Time","artist":"Dennis Kuo","album":"Study Music Project 10: Music for the Soul","uri":"http://sjy6.stream.qqmusic.qq.com/F000000sswD548qecX.flac?guid=ffffffff98221cb200000188eafbcf87&vkey=2B1C7B2C0DCE6ADD35FCCC4DA1C5BA05DE95EB63CE4C69623B8AD72F8DDBD1F1C56E60F2F58E5F936C2DB24360943DE70B1F6C3EC62AA54A&uin=1152921504744265956&redirect=1&fromtag=111114","trackType":""} Oct 26 04:22:05 opera volumio[1081]: verbose: CURRENT POSITION 0 Oct 26 04:22:05 opera volumio[1081]: info: CoreStateMachine::syncState stateService stop Oct 26 04:22:05 opera volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 26 04:22:05 opera volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 26 04:22:05 opera volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 04:22:05 opera volumio[1081]: info: CoreStateMachine::stPlaybackTimer Oct 26 04:22:05 opera volumio[1081]: info: ------------------------------ 19ms Oct 26 04:22:05 opera volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 26 04:22:05 opera volumio[1081]: info: CoreStateMachine::getQueue Oct 26 04:22:05 opera volumio[1081]: info: CorePlayQueue::getQueue Oct 26 04:22:05 opera volumio[1081]: info: Updating RAAT Signal Path Oct 26 04:22:05 opera volumio[1081]: info: Updating RAAT Signal Path Oct 26 04:22:05 opera volumio[1081]: info: Clearing queue after UPNP request Oct 26 04:22:05 opera volumio[1081]: info: CoreStateMachine::ClearQueue Oct 26 04:22:05 opera volumio[1081]: info: CoreStateMachine::stop Oct 26 04:22:05 opera volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 04:22:05 opera volumio[1081]: verbose: UNSET VOLATILE Oct 26 04:22:05 opera volumio[1081]: info: CorePlayQueue::clearPlayQueue Oct 26 04:22:05 opera volumio[1081]: info: CorePlayQueue::saveQueue Oct 26 04:22:05 opera volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 26 04:22:05 opera volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 04:22:05 opera volumio[1081]: info: CoreCommandRouter::volumioPushQueue Oct 26 04:22:05 opera volumio[1081]: info: Updating RAAT Signal Path Oct 26 04:22:05 opera volumio[1081]: info: Oct 26 04:22:05 opera volumio[1081]: ---------------------------- MPD announces system playlist update Oct 26 04:22:05 opera volumio[1081]: info: Ignoring MPD Status Update Oct 26 04:22:05 opera volumio[1081]: error: updateQueue error: null Oct 26 04:22:05 opera volumio[1081]: info: ------------------------------ 3ms Oct 26 04:22:05 opera volumio[1081]: info: Starting UPNP Playback Oct 26 04:22:05 opera volumio[1081]: info: Preparing playback through UPNP Oct 26 04:22:05 opera volumio[1081]: info: CoreCommandRouter::volumioGetState Oct 26 04:22:05 opera volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 26 04:22:05 opera volumio[1081]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 26 04:22:06 opera volumio[1081]: info: Oct 26 04:22:06 opera volumio[1081]: ---------------------------- MPD announces system playlist update Oct 26 04:22:06 opera volumio[1081]: info: Ignoring MPD Status Update Oct 26 04:22:06 opera volumio[1081]: info: Oct 26 04:22:06 opera volumio[1081]: ---------------------------- MPD announces system playlist update Oct 26 04:22:06 opera volumio[1081]: info: Ignoring MPD Status Update Oct 26 04:22:06 opera volumio[1081]: info: ------------------------------ 2ms Oct 26 04:22:06 opera volumio[1081]: info: ------------------------------ 5ms Oct 26 04:22:06 opera volumio[1081]: info: Oct 26 04:22:06 opera volumio[1081]: ---------------------------- MPD announces system playlist update Oct 26 04:22:06 opera volumio[1081]: info: Ignoring MPD Status Update Oct 26 04:22:06 opera volumio[1081]: info: Oct 26 04:22:06 opera volumio[1081]: ---------------------------- MPD announces system playlist update Oct 26 04:22:06 opera volumio[1081]: info: Ignoring MPD Status Update Oct 26 04:22:06 opera volumio[1081]: info: ------------------------------ 2ms Oct 26 04:22:06 opera volumio[1081]: info: ------------------------------ 1ms Oct 26 04:22:06 opera volumio[1081]: info: Oct 26 04:22:06 opera volumio[1081]: ---------------------------- MPD announces system playlist update Oct 26 04:22:06 opera volumio[1081]: info: Ignoring MPD Status Update Oct 26 04:22:06 opera volumio[1081]: info: Oct 26 04:22:06 opera volumio[1081]: ---------------------------- MPD announces system playlist update Oct 26 04:22:06 opera volumio[1081]: info: Ignoring MPD Status Update Oct 26 04:22:06 opera volumio[1081]: info: ------------------------------ 1ms Oct 26 04:22:06 opera volumio[1081]: info: ------------------------------ 1ms Oct 26 04:22:07 opera volumio[1081]: info: Oct 26 04:22:07 opera volumio[1081]: ---------------------------- MPD announces state update: player Oct 26 04:22:07 opera volumio[1081]: info: ControllerMpd::getState Oct 26 04:22:07 opera volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 26 04:22:07 opera volumio[1081]: info: Oct 26 04:22:07 opera volumio[1081]: ---------------------------- MPD announces state update: player Oct 26 04:22:07 opera volumio[1081]: info: sendMpdCommand status took 13 milliseconds Oct 26 04:22:07 opera volumio[1081]: info: ControllerMpd::getState Oct 26 04:22:07 opera volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 26 04:22:07 opera volumio[1081]: verbose: ControllerMpd::parseState Oct 26 04:22:07 opera volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 04:22:07 opera volumio[1081]: info: sendMpdCommand status took 3 milliseconds Oct 26 04:22:07 opera volumio[1081]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 26 04:22:07 opera volumio[1081]: verbose: ControllerMpd::parseState Oct 26 04:22:07 opera volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 04:22:07 opera volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 26 04:22:07 opera volumio[1081]: info: ControllerMpd::pushState Oct 26 04:22:07 opera volumio[1081]: info: CoreCommandRouter::servicePushState Oct 26 04:22:07 opera volumio[1081]: verbose: In UPNP mode Oct 26 04:22:07 opera volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cross the Line","artist":"Audiomachine/Harry Lightfoot","album":"Requiem","uri":"http://sjy6.stream.qqmusic.qq.com/F0000021kXhh0RjQBI.flac?guid=ffffffff98221cb200000188eafbcf87&vkey=76712097D084E1C5B04DC7D6A0FF7C45AA61C910CA56B0C950DA5CC05F21F2D6D7C96187CFC00B30EB9FD908BD0D115C9E4D76CA1F755A5D&uin=1152921504744265956&src=F000001rYMXh0W5iLH.flac&redirect=1&fromtag=111114","trackType":""} Oct 26 04:22:07 opera volumio[1081]: verbose: CURRENT POSITION 0 Oct 26 04:22:07 opera volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 26 04:22:07 opera volumio[1081]: info: CoreStateMachine::syncState currentStatus stop Oct 26 04:22:07 opera volumio[1081]: info: CoreStateMachine::pushState Oct 26 04:22:07 opera volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 26 04:22:07 opera volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 04:22:07 opera volumio[1081]: info: ------------------------------ 33ms Oct 26 04:22:07 opera volumio[1081]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 26 04:22:07 opera volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 26 04:22:07 opera volumio[1081]: info: ControllerMpd::pushState Oct 26 04:22:07 opera volumio[1081]: info: CoreCommandRouter::servicePushState Oct 26 04:22:07 opera volumio[1081]: verbose: In UPNP mode Oct 26 04:22:07 opera volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":200,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cross the Line","artist":"Audiomachine/Harry Lightfoot","album":"Requiem","uri":"http://sjy6.stream.qqmusic.qq.com/F0000021kXhh0RjQBI.flac?guid=ffffffff98221cb200000188eafbcf87&vkey=76712097D084E1C5B04DC7D6A0FF7C45AA61C910CA56B0C950DA5CC05F21F2D6D7C96187CFC00B30EB9FD908BD0D115C9E4D76CA1F755A5D&uin=1152921504744265956&src=F000001rYMXh0W5iLH.flac&redirect=1&fromtag=111114","trackType":""} Oct 26 04:22:07 opera volumio[1081]: verbose: CURRENT POSITION 0 Oct 26 04:22:07 opera volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 26 04:22:07 opera volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 26 04:22:07 opera volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 26 04:22:07 opera volumio[1081]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 26 04:22:07 opera volumio[1081]: info: CoreStateMachine::pushState Oct 26 04:22:07 opera volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 26 04:22:07 opera volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 04:22:07 opera volumio[1081]: info: CoreStateMachine::pushState Oct 26 04:22:07 opera volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 26 04:22:07 opera volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 04:22:07 opera volumio[1081]: info: ------------------------------ 42ms Oct 26 04:22:07 opera volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 26 04:22:07 opera volumio[1081]: info: CoreStateMachine::getQueue Oct 26 04:22:07 opera volumio[1081]: info: CorePlayQueue::getQueue Oct 26 04:22:07 opera volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 26 04:22:07 opera volumio[1081]: info: CoreStateMachine::getQueue Oct 26 04:22:07 opera volumio[1081]: info: CorePlayQueue::getQueue Oct 26 04:22:07 opera volumio[1081]: info: Updating RAAT Signal Path Oct 26 04:22:07 opera volumio[1081]: info: Updating RAAT Signal Path Oct 26 04:22:07 opera volumio[1081]: info: Updating RAAT Signal Path Oct 26 04:22:07 opera volumio[1081]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dennis%20Kuo/Study%20Music%20Project%2010%3A%20Music%20for%20the%20Soul/8632594f-27d3-4583-a409-0830138344cf.jpg' Oct 26 04:22:51 opera systemd[1]: Started volumio-remote-updater-push.service. Oct 26 04:22:51 opera systemd[1]: Starting Multi-User System. Oct 26 04:22:51 opera systemd[1]: Reached target Multi-User System. Oct 26 04:22:51 opera systemd[1]: Starting Graphical Interface. Oct 26 04:22:51 opera systemd[1]: Reached target Graphical Interface. Oct 26 04:22:51 opera systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 26 04:22:51 opera volumio[1081]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 04:22:51 opera systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 26 04:22:51 opera systemd[1]: Startup finished in 11.469s (kernel) + 2min 4.891s (userspace) = 2min 16.360s. Oct 26 04:23:59 opera volumio[1081]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 04:23:59 opera volumio[1081]: { Error: connect ETIMEDOUT 45.114.11.25:443 Oct 26 04:23:59 opera volumio[1081]: at Object._errnoException (util.js:1022:11) Oct 26 04:23:59 opera volumio[1081]: at _exceptionWithHostPort (util.js:1044:20) Oct 26 04:23:59 opera volumio[1081]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Oct 26 04:23:59 opera volumio[1081]: code: 'ETIMEDOUT', Oct 26 04:23:59 opera volumio[1081]: errno: 'ETIMEDOUT', Oct 26 04:23:59 opera volumio[1081]: syscall: 'connect', Oct 26 04:23:59 opera volumio[1081]: address: '45.114.11.25', Oct 26 04:23:59 opera volumio[1081]: port: 443 } Oct 26 04:23:59 opera volumio[1081]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 04:23:59 opera sudo[2181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-26 04:22 Oct 26 04:23:59 opera sudo[2181]: 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"