-- Logs begin at Sat 2025-01-18 18:44:40 UTC, end at Sat 2025-01-18 19:01:45 UTC. -- Jan 18 19:00:08 volumionuc volumiossh-tunnel[1952]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Jan 18 19:00:29 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 19:00:29 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 19:00:29 volumionuc volumio[909]: info: Discovery: Getting this device information Jan 18 19:00:29 volumionuc volumio[909]: info: CoreCommandRouter::volumioGetState Jan 18 19:00:29 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Jan 18 19:00:29 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jan 18 19:00:29 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jan 18 19:00:35 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 18 19:00:35 volumionuc volumio[909]: info: CURURI: albums://Badi%20Assad/Wonderland Jan 18 19:00:35 volumionuc volumio[909]: info: Executing endpoint metavolumio Jan 18 19:00:35 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 18 19:00:35 volumionuc volumio[909]: info: Executing endpoint metavolumio Jan 18 19:00:35 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 18 19:00:36 volumionuc volumio[909]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 18 19:00:36 volumionuc volumio[909]: info: CoreStateMachine::ClearQueue Jan 18 19:00:36 volumionuc volumio[909]: info: CoreStateMachine::stop Jan 18 19:00:36 volumionuc volumio[909]: info: CoreStateMachine::serviceStop Jan 18 19:00:36 volumionuc volumio[909]: info: CoreCommandRouter::serviceStop Jan 18 19:00:36 volumionuc vtcs[1460]: [2025-01-18 19:00:36.157] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 Jan 18 19:00:36 volumionuc volumio[909]: info: CorePlayQueue::clearPlayQueue Jan 18 19:00:36 volumionuc volumio[909]: info: CorePlayQueue::saveQueue Jan 18 19:00:36 volumionuc volumio[909]: info: CoreCommandRouter::volumioPushState Jan 18 19:00:36 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 18 19:00:36 volumionuc volumio[909]: info: CoreCommandRouter::volumioPushQueue Jan 18 19:00:36 volumionuc volumio[909]: info: CoreStateMachine::addQueueItems Jan 18 19:00:36 volumionuc volumio[909]: info: CorePlayQueue::addQueueItems Jan 18 19:00:36 volumionuc volumio[909]: info: Adding Item to queue: albums://Badi%20Assad/Wonderland Jan 18 19:00:36 volumionuc volumio[909]: info: Exploding uri albums://Badi%20Assad/Wonderland in service mpd Jan 18 19:00:36 volumionuc volumio[909]: info: PlaybackInactive Jan 18 19:00:36 volumionuc volumio[909]: info: Device Session is_active: true Jan 18 19:00:36 volumionuc volumio[909]: info: DeviceInactive Jan 18 19:00:36 volumionuc volumio[909]: info: Relinquishing Volumio State Jan 18 19:00:36 volumionuc volumio[909]: verbose: UNSET VOLATILE Jan 18 19:00:36 volumionuc volumio[909]: info: unSetVolatile called Jan 18 19:00:36 volumionuc volumio[909]: info: Relinquishing Volumio State to another service Jan 18 19:00:36 volumionuc vtcs[1460]: [2025-01-18 19:00:36.226] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Jan 18 19:00:36 volumionuc volumio[909]: info: CoreStateMachine::resetVolumioState Jan 18 19:00:36 volumionuc volumio[909]: info: CoreStateMachine::getcurrentVolume Jan 18 19:00:36 volumionuc volumio[909]: info: CoreCommandRouter::volumioRetrievevolume Jan 18 19:00:36 volumionuc volumio[909]: info: CoreStateMachine::pushState Jan 18 19:00:36 volumionuc volumio[909]: info: CorePlayQueue::getTrack 0 Jan 18 19:00:36 volumionuc volumio[909]: info: CoreCommandRouter::volumioPushState Jan 18 19:00:36 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 18 19:00:36 volumionuc volumio[909]: info: CorePlayQueue::saveQueue Jan 18 19:00:36 volumionuc volumio[909]: info: CoreCommandRouter::volumioPushQueue Jan 18 19:00:36 volumionuc volumio[909]: info: CoreStateMachine::updateTrackBlock Jan 18 19:00:36 volumionuc volumio[909]: info: CorePlayQueue::getTrackBlock Jan 18 19:00:36 volumionuc volumio[909]: info: CoreCommandRouter::volumioPlay Jan 18 19:00:36 volumionuc volumio[909]: verbose: UNSET VOLATILE Jan 18 19:00:36 volumionuc volumio[909]: info: CoreStateMachine::play index 0 Jan 18 19:00:36 volumionuc volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 18 19:00:36 volumionuc volumio[909]: info: CoreStateMachine::stop Jan 18 19:00:36 volumionuc volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 18 19:00:36 volumionuc volumio[909]: verbose: UNSET VOLATILE Jan 18 19:00:36 volumionuc volumio[909]: info: CoreStateMachine::play index undefined Jan 18 19:00:36 volumionuc volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 18 19:00:36 volumionuc volumio[909]: info: CorePlayQueue::getTrack 0 Jan 18 19:00:36 volumionuc volumio[909]: info: CoreStateMachine::startPlaybackTimer Jan 18 19:00:36 volumionuc volumio[909]: info: CorePlayQueue::getTrack 0 Jan 18 19:00:36 volumionuc volumio[909]: verbose: ControllerMpd::clearAddPlayTracks USB/Seagate Bac/USB Copy_2021-04-27_065730/B ok/Badi Assad/Wonderland/Badi Assad Wonderland Acredite Ou Não 928kbps.flac Jan 18 19:00:36 volumionuc volumio[909]: verbose: ControllerMpd::sendMpdCommand stop Jan 18 19:00:36 volumionuc volumio[909]: info: sendMpdCommand stop took 13 milliseconds Jan 18 19:00:36 volumionuc volumio[909]: verbose: ControllerMpd::sendMpdCommand clear Jan 18 19:00:36 volumionuc volumio[909]: info: Jan 18 19:00:36 volumionuc volumio[909]: ---------------------------- MPD announces system playlist update Jan 18 19:00:36 volumionuc volumio[909]: info: Ignoring MPD Status Update Jan 18 19:00:36 volumionuc volumio[909]: info: sendMpdCommand clear took 38 milliseconds Jan 18 19:00:36 volumionuc volumio[909]: verbose: ControllerMpd::sendMpdCommand add "USB/Seagate Bac/USB Copy_2021-04-27_065730/B ok/Badi Assad/Wonderland/Badi Assad Wonderland Acredite Ou Não 928kbps.flac" Jan 18 19:00:36 volumionuc volumio[909]: error: updateQueue error: null Jan 18 19:00:36 volumionuc volumio[909]: info: Jan 18 19:00:36 volumionuc volumio[909]: ---------------------------- MPD announces system playlist update Jan 18 19:00:36 volumionuc volumio[909]: info: Ignoring MPD Status Update Jan 18 19:00:36 volumionuc volumio[909]: info: ------------------------------ 34ms Jan 18 19:00:36 volumionuc volumio[909]: info: sendMpdCommand add "USB/Seagate Bac/USB Copy_2021-04-27_065730/B ok/Badi Assad/Wonderland/Badi Assad Wonderland Acredite Ou Não 928kbps.flac" took 25 milliseconds Jan 18 19:00:36 volumionuc volumio[909]: verbose: ControllerMpd::sendMpdCommand play Jan 18 19:00:36 volumionuc volumio[909]: info: ------------------------------ 38ms Jan 18 19:00:36 volumionuc volumio[909]: info: sendMpdCommand play took 29 milliseconds Jan 18 19:00:37 volumionuc volumio[909]: info: Jan 18 19:00:37 volumionuc volumio[909]: ---------------------------- MPD announces state update: player Jan 18 19:00:37 volumionuc volumio[909]: info: ControllerMpd::getState Jan 18 19:00:37 volumionuc volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 18 19:00:37 volumionuc volumio[909]: info: Jan 18 19:00:37 volumionuc volumio[909]: ---------------------------- MPD announces state update: player Jan 18 19:00:37 volumionuc volumio[909]: info: ControllerMpd::getState Jan 18 19:00:37 volumionuc volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 18 19:00:37 volumionuc volumio[909]: info: sendMpdCommand status took 53 milliseconds Jan 18 19:00:37 volumionuc volumio[909]: verbose: ControllerMpd::parseState Jan 18 19:00:37 volumionuc volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 19:00:37 volumionuc volumio[909]: info: sendMpdCommand status took 36 milliseconds Jan 18 19:00:37 volumionuc volumio[909]: info: sendMpdCommand playlistinfo took 29 milliseconds Jan 18 19:00:37 volumionuc volumio[909]: verbose: ControllerMpd::parseState Jan 18 19:00:37 volumionuc volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 19:00:37 volumionuc volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 18 19:00:37 volumionuc volumio[909]: info: ControllerMpd::pushState Jan 18 19:00:37 volumionuc volumio[909]: info: CoreCommandRouter::servicePushState Jan 18 19:00:37 volumionuc volumio[909]: info: CorePlayQueue::getTrack 0 Jan 18 19:00:37 volumionuc volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":580,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"978 Kbps","isStreaming":false,"title":"acredite ou não","artist":"Badi Assad","album":"Wonderland","uri":"USB/Seagate Bac/USB Copy_2021-04-27_065730/B ok/Badi Assad/Wonderland/Badi Assad Wonderland Acredite Ou Não 928kbps.flac","trackType":"flac"} Jan 18 19:00:37 volumionuc volumio[909]: verbose: CURRENT POSITION 0 Jan 18 19:00:37 volumionuc volumio[909]: info: CoreStateMachine::syncState stateService play Jan 18 19:00:37 volumionuc volumio[909]: info: CoreStateMachine::syncState currentStatus stop Jan 18 19:00:37 volumionuc volumio[909]: info: CoreStateMachine::pushState Jan 18 19:00:37 volumionuc volumio[909]: info: CorePlayQueue::getTrack 0 Jan 18 19:00:37 volumionuc volumio[909]: info: CoreCommandRouter::volumioPushState Jan 18 19:00:37 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 18 19:00:37 volumionuc volumio[909]: info: ------------------------------ 179ms Jan 18 19:00:37 volumionuc volumio[909]: info: sendMpdCommand playlistinfo took 79 milliseconds Jan 18 19:00:37 volumionuc volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 18 19:00:37 volumionuc volumio[909]: info: ControllerMpd::pushState Jan 18 19:00:37 volumionuc volumio[909]: info: CoreCommandRouter::servicePushState Jan 18 19:00:37 volumionuc volumio[909]: info: CorePlayQueue::getTrack 0 Jan 18 19:00:37 volumionuc volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":719,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"994 Kbps","isStreaming":false,"title":"acredite ou não","artist":"Badi Assad","album":"Wonderland","uri":"USB/Seagate Bac/USB Copy_2021-04-27_065730/B ok/Badi Assad/Wonderland/Badi Assad Wonderland Acredite Ou Não 928kbps.flac","trackType":"flac"} Jan 18 19:00:37 volumionuc volumio[909]: verbose: CURRENT POSITION 0 Jan 18 19:00:37 volumionuc volumio[909]: info: CoreStateMachine::syncState stateService play Jan 18 19:00:37 volumionuc volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 18 19:00:37 volumionuc volumio[909]: info: Received an update from plugin. extracting info from payload Jan 18 19:00:37 volumionuc volumio[909]: info: CoreStateMachine::pushState Jan 18 19:00:37 volumionuc volumio[909]: info: CorePlayQueue::getTrack 0 Jan 18 19:00:37 volumionuc volumio[909]: info: CoreCommandRouter::volumioPushState Jan 18 19:00:37 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 18 19:00:37 volumionuc volumio[909]: info: CoreStateMachine::pushState Jan 18 19:00:37 volumionuc volumio[909]: info: CorePlayQueue::getTrack 0 Jan 18 19:00:37 volumionuc volumio[909]: info: CoreCommandRouter::volumioPushState Jan 18 19:00:37 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 18 19:00:37 volumionuc volumio[909]: info: ------------------------------ 209ms Jan 18 19:00:59 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 19:00:59 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 19:00:59 volumionuc volumio[909]: info: Discovery: Getting this device information Jan 18 19:00:59 volumionuc volumio[909]: info: CoreCommandRouter::volumioGetState Jan 18 19:00:59 volumionuc volumio[909]: info: CorePlayQueue::getTrack 0 Jan 18 19:00:59 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Jan 18 19:00:59 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jan 18 19:00:59 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jan 18 19:01:29 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 19:01:29 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 19:01:29 volumionuc volumio[909]: info: Discovery: Getting this device information Jan 18 19:01:29 volumionuc volumio[909]: info: CoreCommandRouter::volumioGetState Jan 18 19:01:29 volumionuc volumio[909]: info: CorePlayQueue::getTrack 0 Jan 18 19:01:29 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Jan 18 19:01:29 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jan 18 19:01:29 volumionuc volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jan 18 19:01:44 volumionuc volumio[909]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 18 19:01:44 volumionuc volumio[909]: { Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net lastfm.freetls.fastly.net:443 Jan 18 19:01:44 volumionuc volumio[909]: at errnoException (dns.js:50:10) Jan 18 19:01:44 volumionuc volumio[909]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:92:26) Jan 18 19:01:44 volumionuc volumio[909]: code: 'ENOTFOUND', Jan 18 19:01:44 volumionuc volumio[909]: errno: 'ENOTFOUND', Jan 18 19:01:44 volumionuc volumio[909]: syscall: 'getaddrinfo', Jan 18 19:01:44 volumionuc volumio[909]: hostname: 'lastfm.freetls.fastly.net', Jan 18 19:01:44 volumionuc volumio[909]: host: 'lastfm.freetls.fastly.net', Jan 18 19:01:44 volumionuc volumio[909]: port: 443 } Jan 18 19:01:44 volumionuc volumio[909]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 18 19:01:45 volumionuc sudo[1993]: PAM unable to dlopen(pam_systemd.so): /lib/security/pam_systemd.so: cannot open shared object file: No such file or directory Jan 18 19:01:45 volumionuc sudo[1993]: PAM adding faulty module: pam_systemd.so Jan 18 19:01:45 volumionuc sudo[1993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-18 19:00 Jan 18 19:01:45 volumionuc sudo[1993]: 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"