-- Logs begin at Thu 2024-08-22 18:49:33 CEST, end at Thu 2024-08-22 22:31:59 CEST. -- Aug 22 22:30:00 dac-volumio-manu volumio[13259]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.23:8895/resource/2832/MEDIA_ITEM/MP3-0/ORIGINAL" Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: Aug 22 22:30:00 dac-volumio-manu volumio[13259]: ---------------------------- MPD announces system playlist update Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: Ignoring MPD Status Update Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: sendMpdCommand add "http://192.168.1.23:8895/resource/2832/MEDIA_ITEM/MP3-0/ORIGINAL" took 2 milliseconds Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 22 22:30:00 dac-volumio-manu volumio[13259]: verbose: ControllerMpd::sendMpdCommand play Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: ------------------------------ 5ms Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: sendMpdCommand play took 3 milliseconds Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: Aug 22 22:30:00 dac-volumio-manu volumio[13259]: ---------------------------- MPD announces state update: player Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: ControllerMpd::getState Aug 22 22:30:00 dac-volumio-manu volumio[13259]: verbose: ControllerMpd::sendMpdCommand status Aug 22 22:30:00 dac-volumio-manu kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: sendMpdCommand status took 54 milliseconds Aug 22 22:30:00 dac-volumio-manu volumio[13259]: verbose: ControllerMpd::parseState Aug 22 22:30:00 dac-volumio-manu volumio[13259]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: Aug 22 22:30:00 dac-volumio-manu volumio[13259]: ---------------------------- MPD announces system playlist update Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: Ignoring MPD Status Update Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: Aug 22 22:30:00 dac-volumio-manu volumio[13259]: ---------------------------- MPD announces state update: player Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: ControllerMpd::getState Aug 22 22:30:00 dac-volumio-manu volumio[13259]: verbose: ControllerMpd::sendMpdCommand status Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 22 22:30:00 dac-volumio-manu volumio[13259]: verbose: ControllerMpd::parseTrackInfo Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: ControllerMpd::pushState Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::servicePushState Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CorePlayQueue::getTrack 498 Aug 22 22:30:00 dac-volumio-manu volumio[13259]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Xam-Xam","artist":"Kora Jazz Trio","album":"Part III","uri":"http://192.168.1.23:8895/resource/2832/MEDIA_ITEM/MP3-0/ORIGINAL","trackType":""} Aug 22 22:30:00 dac-volumio-manu volumio[13259]: verbose: CURRENT POSITION 498 Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CoreStateMachine::syncState stateService play Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CoreStateMachine::syncState currentStatus stop Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: ------------------------------ 74ms Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: ------------------------------ 16ms Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: sendMpdCommand status took 13 milliseconds Aug 22 22:30:00 dac-volumio-manu volumio[13259]: verbose: ControllerMpd::parseState Aug 22 22:30:00 dac-volumio-manu volumio[13259]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 22 22:30:00 dac-volumio-manu volumio[13259]: verbose: ControllerMpd::parseTrackInfo Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: ControllerMpd::pushState Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::servicePushState Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CorePlayQueue::getTrack 498 Aug 22 22:30:00 dac-volumio-manu volumio[13259]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":255,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Xam-Xam","artist":"Kora Jazz Trio","album":"Part III","uri":"http://192.168.1.23:8895/resource/2832/MEDIA_ITEM/MP3-0/ORIGINAL","trackType":""} Aug 22 22:30:00 dac-volumio-manu volumio[13259]: verbose: CURRENT POSITION 498 Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CoreStateMachine::syncState stateService play Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CoreStateMachine::syncState currentStatus play Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: Received an update from plugin. extracting info from payload Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CoreStateMachine::pushState Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioPushState Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: MRS: Pushing multiroomSync output update for this device Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: MRS: Pushing multiroomSync output Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CoreStateMachine::pushState Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioPushState Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: MRS: Pushing multiroomSync output update for this device Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: MRS: Pushing multiroomSync output Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: ------------------------------ 73ms Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioGetQueue Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CoreStateMachine::getQueue Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CorePlayQueue::getQueue Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioGetQueue Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CoreStateMachine::getQueue Aug 22 22:30:00 dac-volumio-manu volumio[13259]: info: CorePlayQueue::getQueue Aug 22 22:30:00 dac-volumio-manu volumio[13259]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Aug 22 22:30:00 dac-volumio-manu volumio[13259]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Aug 22 22:30:03 dac-volumio-manu volumio[13259]: info: VolumeController::SetAlsaVolume13 Aug 22 22:30:03 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioGetState Aug 22 22:30:03 dac-volumio-manu volumio[13259]: info: CoreStateMachine::pushState Aug 22 22:30:03 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 22:30:03 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioPushState Aug 22 22:30:03 dac-volumio-manu volumio[13259]: info: MRS: Pushing multiroomSync output update for this device Aug 22 22:30:03 dac-volumio-manu volumio[13259]: info: MRS: Pushing multiroomSync output Aug 22 22:30:03 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioGetQueue Aug 22 22:30:03 dac-volumio-manu volumio[13259]: info: CoreStateMachine::getQueue Aug 22 22:30:03 dac-volumio-manu volumio[13259]: info: CorePlayQueue::getQueue Aug 22 22:30:03 dac-volumio-manu volumio[13259]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Aug 22 22:30:03 dac-volumio-manu volumio[13259]: SPOTIFY: SPOTIFY VOLUME 50 Aug 22 22:30:03 dac-volumio-manu volumio[13259]: SPOTIFY: VOLUMIO VOLUME 13 Aug 22 22:30:03 dac-volumio-manu volumio[13259]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 22 22:30:03 dac-volumio-manu volumio[13259]: info: Setting Spotify Volume from Volumio: 13 Aug 22 22:30:04 dac-volumio-manu volumio[13259]: info: VolumeController::SetAlsaVolume3 Aug 22 22:30:04 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioGetState Aug 22 22:30:04 dac-volumio-manu volumio[13259]: info: CoreStateMachine::pushState Aug 22 22:30:04 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 22:30:04 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioPushState Aug 22 22:30:04 dac-volumio-manu volumio[13259]: info: MRS: Pushing multiroomSync output update for this device Aug 22 22:30:04 dac-volumio-manu volumio[13259]: info: MRS: Pushing multiroomSync output Aug 22 22:30:04 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioGetQueue Aug 22 22:30:04 dac-volumio-manu volumio[13259]: info: CoreStateMachine::getQueue Aug 22 22:30:04 dac-volumio-manu volumio[13259]: info: CorePlayQueue::getQueue Aug 22 22:30:04 dac-volumio-manu volumio[13259]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Aug 22 22:30:04 dac-volumio-manu volumio[13259]: SPOTIFY: SPOTIFY VOLUME 13 Aug 22 22:30:04 dac-volumio-manu volumio[13259]: SPOTIFY: VOLUMIO VOLUME 3 Aug 22 22:30:04 dac-volumio-manu volumio[13259]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 22 22:30:04 dac-volumio-manu volumio[13259]: info: Setting Spotify Volume from Volumio: 3 Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: VolumeController::SetAlsaVolume8 Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioGetState Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: CoreStateMachine::pushState Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioPushState Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: MRS: Pushing multiroomSync output update for this device Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: MRS: Pushing multiroomSync output Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioGetQueue Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: CoreStateMachine::getQueue Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: CorePlayQueue::getQueue Aug 22 22:30:05 dac-volumio-manu volumio[13259]: SPOTIFY: RECEIVED VOLUMIO VOLUME 8 Aug 22 22:30:05 dac-volumio-manu volumio[13259]: SPOTIFY: SPOTIFY VOLUME 3 Aug 22 22:30:05 dac-volumio-manu volumio[13259]: SPOTIFY: VOLUMIO VOLUME 8 Aug 22 22:30:05 dac-volumio-manu volumio[13259]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: Setting Spotify Volume from Volumio: 8 Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: VolumeController::SetAlsaVolume10 Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioGetState Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: CoreStateMachine::pushState Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioPushState Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: MRS: Pushing multiroomSync output update for this device Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: MRS: Pushing multiroomSync output Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioGetQueue Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: CoreStateMachine::getQueue Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: CorePlayQueue::getQueue Aug 22 22:30:05 dac-volumio-manu volumio[13259]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Aug 22 22:30:05 dac-volumio-manu volumio[13259]: SPOTIFY: SPOTIFY VOLUME 8 Aug 22 22:30:05 dac-volumio-manu volumio[13259]: SPOTIFY: VOLUMIO VOLUME 10 Aug 22 22:30:05 dac-volumio-manu volumio[13259]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 22 22:30:05 dac-volumio-manu volumio[13259]: info: Setting Spotify Volume from Volumio: 10 Aug 22 22:30:07 dac-volumio-manu volumio[13259]: SPOTIFY: SETTING SPOTIFY VOLUME 10 Aug 22 22:30:07 dac-volumio-manu volumio[13259]: info: Sending Spotify command with payload to local API: /player/volume Aug 22 22:30:15 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioGetState Aug 22 22:30:17 dac-volumio-manu volumio[13259]: info: Executing endpoint metavolumio Aug 22 22:30:17 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 22 22:30:18 dac-volumio-manu volumio[13259]: info: Executing endpoint metavolumio Aug 22 22:30:18 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 22 22:30:18 dac-volumio-manu volumio[13259]: info: Executing endpoint metavolumio Aug 22 22:30:18 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 22 22:30:34 dac-volumio-manu volumiologrotate[633]: ls: cannot access '/var/log/samba/log.wb-DAC': No such file or directory Aug 22 22:30:34 dac-volumio-manu volumiologrotate[633]: ls: cannot access 'VOLUMIO': No such file or directory Aug 22 22:30:34 dac-volumio-manu volumiologrotate[633]: ls: cannot access 'MANU': No such file or directory Aug 22 22:30:52 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::volumioGetState Aug 22 22:30:54 dac-volumio-manu volumio[13259]: info: Executing endpoint metavolumio Aug 22 22:30:54 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 22 22:30:54 dac-volumio-manu volumio[13259]: info: Executing endpoint metavolumio Aug 22 22:30:54 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 22 22:30:54 dac-volumio-manu volumio[13259]: info: Executing endpoint metavolumio Aug 22 22:30:54 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 22 22:30:58 dac-volumio-manu volumio[13259]: info: Executing endpoint metavolumio Aug 22 22:30:58 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 22 22:30:58 dac-volumio-manu volumio[13259]: info: Executing endpoint metavolumio Aug 22 22:30:58 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 22 22:31:01 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 22 22:31:01 dac-volumio-manu volumio[13259]: info: Preload queue cleared Aug 22 22:31:03 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 22 22:31:03 dac-volumio-manu volumio[13259]: info: Preload queue cleared Aug 22 22:31:07 dac-volumio-manu volumio[13259]: Searching plugin music_service/upnp_browser Aug 22 22:31:07 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Aug 22 22:31:07 dac-volumio-manu volumio[13259]: info: All search sources collected, pushing search results Aug 22 22:31:21 dac-volumio-manu volumio[13259]: Searching plugin music_service/upnp_browser Aug 22 22:31:21 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Aug 22 22:31:21 dac-volumio-manu volumio[13259]: info: All search sources collected, pushing search results Aug 22 22:31:32 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 22 22:31:32 dac-volumio-manu volumio[13259]: info: Preload queue cleared Aug 22 22:31:33 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 22 22:31:33 dac-volumio-manu volumio[13259]: info: Preload queue cleared Aug 22 22:31:34 dac-volumio-manu volumiologrotate[633]: ls: cannot access '/var/log/samba/log.wb-DAC': No such file or directory Aug 22 22:31:34 dac-volumio-manu volumiologrotate[633]: ls: cannot access 'VOLUMIO': No such file or directory Aug 22 22:31:34 dac-volumio-manu volumiologrotate[633]: ls: cannot access 'MANU': No such file or directory Aug 22 22:31:44 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 22 22:31:44 dac-volumio-manu volumio[13259]: info: Preload queue cleared Aug 22 22:31:47 dac-volumio-manu volumio[13259]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 22 22:31:48 dac-volumio-manu volumio[13259]: info: Preload queue cleared Aug 22 22:31:58 dac-volumio-manu volumio[13259]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 22 22:31:58 dac-volumio-manu volumio[13259]: Error: connect ETIMEDOUT 151.101.122.79:443 Aug 22 22:31:58 dac-volumio-manu volumio[13259]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 22 22:31:58 dac-volumio-manu volumio[13259]: errno: -110, Aug 22 22:31:58 dac-volumio-manu volumio[13259]: code: 'ETIMEDOUT', Aug 22 22:31:58 dac-volumio-manu volumio[13259]: syscall: 'connect', Aug 22 22:31:58 dac-volumio-manu volumio[13259]: address: '151.101.122.79', Aug 22 22:31:58 dac-volumio-manu volumio[13259]: port: 443 Aug 22 22:31:58 dac-volumio-manu volumio[13259]: } Aug 22 22:31:58 dac-volumio-manu volumio[13259]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 22 22:31:59 dac-volumio-manu sudo[15365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-22 22:30 Aug 22 22:31:59 dac-volumio-manu sudo[15365]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"