-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2026-01-10 15:56:30 CET. -- Jan 10 15:55:00 volumioroland volumio[937]: STREAMING PROXY: Response: 200, length: 99236221 Jan 10 15:55:00 volumioroland volumio[937]: STREAMING PROXY: Client dropped request, destroying Jan 10 15:55:00 volumioroland volumio[937]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/21413801" Jan 10 15:55:00 volumioroland volumio[937]: info: Jan 10 15:55:00 volumioroland volumio[937]: ---------------------------- MPD announces system playlist update Jan 10 15:55:00 volumioroland volumio[937]: info: Ignoring MPD Status Update Jan 10 15:55:00 volumioroland volumio[937]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/21413801" took 0 milliseconds Jan 10 15:55:00 volumioroland volumio[937]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 10 15:55:00 volumioroland volumio[937]: verbose: ControllerMpd::sendMpdCommand play Jan 10 15:55:00 volumioroland volumio[937]: info: ------------------------------ 1ms Jan 10 15:55:00 volumioroland volumio[937]: info: sendMpdCommand play took 1 milliseconds Jan 10 15:55:00 volumioroland volumio[937]: STREAMING PROXY: Handling url /?data=qobuz://song/21413801 Jan 10 15:55:00 volumioroland volumio[937]: info: Executing endpoint getStreamUrlqobuz Jan 10 15:55:00 volumioroland volumio[937]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 10 15:55:00 volumioroland volumio[937]: info: getStreamUrl took 195 milliseconds Jan 10 15:55:00 volumioroland volumio[937]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1653657&eid=21413801&fmt=7&profile=raw&app_id=539451548&cid=3487637&etsp=1768060500&hmac=qB9GV3HEA_0I7OTUk1XWFaqvlGc Jan 10 15:55:00 volumioroland volumio[937]: STREAMING PROXY: Response: 200, length: 99236221 Jan 10 15:55:00 volumioroland volumio[937]: info: Jan 10 15:55:00 volumioroland volumio[937]: ---------------------------- MPD announces state update: player Jan 10 15:55:00 volumioroland volumio[937]: info: ControllerMpd::getState Jan 10 15:55:00 volumioroland volumio[937]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:55:00 volumioroland volumio[937]: info: Jan 10 15:55:00 volumioroland volumio[937]: ---------------------------- MPD announces state update: player Jan 10 15:55:00 volumioroland volumio[937]: info: sendMpdCommand status took 2 milliseconds Jan 10 15:55:00 volumioroland volumio[937]: info: ControllerMpd::getState Jan 10 15:55:00 volumioroland volumio[937]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:55:00 volumioroland volumio[937]: verbose: ControllerMpd::parseState Jan 10 15:55:00 volumioroland volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:55:00 volumioroland volumio[937]: info: sendMpdCommand status took 1 milliseconds Jan 10 15:55:00 volumioroland volumio[937]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 10 15:55:00 volumioroland volumio[937]: verbose: ControllerMpd::parseState Jan 10 15:55:00 volumioroland volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:55:00 volumioroland volumio[937]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:55:00 volumioroland volumio[937]: info: ControllerMpd::pushState Jan 10 15:55:00 volumioroland volumio[937]: info: CoreCommandRouter::servicePushState Jan 10 15:55:00 volumioroland volumio[937]: info: CorePlayQueue::getTrack 0 Jan 10 15:55:00 volumioroland volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":305,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"21413801","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/21413801","trackType":"qobuz"} Jan 10 15:55:00 volumioroland volumio[937]: verbose: CURRENT POSITION 0 Jan 10 15:55:00 volumioroland volumio[937]: info: CoreStateMachine::syncState stateService play Jan 10 15:55:00 volumioroland volumio[937]: info: CoreStateMachine::syncState currentStatus stop Jan 10 15:55:00 volumioroland volumio[937]: info: ------------------------------ 4ms Jan 10 15:55:00 volumioroland volumio[937]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 10 15:55:00 volumioroland volumio[937]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:55:00 volumioroland volumio[937]: info: ControllerMpd::pushState Jan 10 15:55:00 volumioroland volumio[937]: info: CoreCommandRouter::servicePushState Jan 10 15:55:00 volumioroland volumio[937]: info: CorePlayQueue::getTrack 0 Jan 10 15:55:00 volumioroland volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":305,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"21413801","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/21413801","trackType":"qobuz"} Jan 10 15:55:00 volumioroland volumio[937]: verbose: CURRENT POSITION 0 Jan 10 15:55:00 volumioroland volumio[937]: info: CoreStateMachine::syncState stateService play Jan 10 15:55:00 volumioroland volumio[937]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:55:00 volumioroland volumio[937]: info: Received an update from plugin. extracting info from payload Jan 10 15:55:00 volumioroland volumio[937]: info: CoreStateMachine::pushState Jan 10 15:55:00 volumioroland volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:55:00 volumioroland volumio[937]: info: CoreCommandRouter::volumioPushState Jan 10 15:55:00 volumioroland volumio[937]: info: MRS: Pushing multiroomSync output update for this device Jan 10 15:55:00 volumioroland volumio[937]: info: MRS: Pushing multiroomSync output Jan 10 15:55:00 volumioroland volumio[937]: info: CoreCommandRouter::volumioGetState Jan 10 15:55:00 volumioroland volumio[937]: info: CoreStateMachine::pushState Jan 10 15:55:00 volumioroland volumio[937]: info: CoreCommandRouter::volumioPushState Jan 10 15:55:00 volumioroland volumio[937]: info: MRS: Pushing multiroomSync output update for this device Jan 10 15:55:00 volumioroland volumio[937]: info: MRS: Pushing multiroomSync output Jan 10 15:55:00 volumioroland volumio[937]: info: CoreCommandRouter::volumioGetState Jan 10 15:55:00 volumioroland volumio[937]: info: ------------------------------ 18ms Jan 10 15:55:12 volumioroland volumio[937]: STREAMING PROXY: Client dropped request, destroying Jan 10 15:55:22 volumioroland ntpd[1224]: 62.108.36.235 local addr 192.168.178.113 -> Jan 10 15:55:41 volumioroland volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 15:55:41 volumioroland volumio[937]: info: Received Get System Info Jan 10 15:55:41 volumioroland volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 15:55:41 volumioroland volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 15:55:41 volumioroland volumio[937]: info: Discovery: Getting this device information Jan 10 15:55:41 volumioroland volumio[937]: info: CoreCommandRouter::volumioGetState Jan 10 15:55:41 volumioroland volumio[937]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 15:55:42 volumioroland volumio[937]: info: Executing endpoint metavolumio Jan 10 15:55:42 volumioroland volumio[937]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 10 15:55:42 volumioroland volumio[937]: info: Executing endpoint metavolumio Jan 10 15:55:42 volumioroland volumio[937]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 10 15:56:22 volumioroland volumio[937]: info: CoreCommandRouter::volumioSeek Jan 10 15:56:22 volumioroland volumio[937]: info: CoreStateMachine::seek Jan 10 15:56:22 volumioroland volumio[937]: info: CorePlayQueue::getTrack 0 Jan 10 15:56:22 volumioroland volumio[937]: info: TRACKBLOCK {"album":"Live ad Alcatraz","albumUri":"qobuz://album/8012871013654","albumart":"https://static.qobuz.com/images/covers/54/36/8012871013654_600.jpg","artist":"Fausto Mesolella","artistUri":"qobuz://artist/54588","audioQuality":"HI_RES","duration":305,"explicit":false,"name":"Sonatina improvvisata d'inizio estate","service":"qobuz","title":"Sonatina improvvisata d'inizio estate","tracknumber":1,"type":"track","uri":"qobuz://song/21413801","volumeNumber":1,"tagImage":"/albumart?sectionimage=music_service/qobuz/icons/qobuzhires.png"} Jan 10 15:56:22 volumioroland volumio[937]: info: CoreStateMachine::startPlaybackTimer Jan 10 15:56:22 volumioroland volumio[937]: info: CorePlayQueue::getTrack 0 Jan 10 15:56:22 volumioroland volumio[937]: info: [1768056982446] ControllerQobuz::seek Jan 10 15:56:22 volumioroland volumio[937]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 10 15:56:22 volumioroland volumio[937]: info: ControllerMpd::seek Jan 10 15:56:22 volumioroland volumio[937]: info: CoreStateMachine::pushState Jan 10 15:56:22 volumioroland volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:56:22 volumioroland volumio[937]: info: CoreCommandRouter::volumioPushState Jan 10 15:56:22 volumioroland volumio[937]: info: MRS: Pushing multiroomSync output update for this device Jan 10 15:56:22 volumioroland volumio[937]: info: MRS: Pushing multiroomSync output Jan 10 15:56:22 volumioroland volumio[937]: info: CoreCommandRouter::volumioGetState Jan 10 15:56:22 volumioroland volumio[937]: error: null Jan 10 15:56:22 volumioroland volumio[937]: info: Jan 10 15:56:22 volumioroland volumio[937]: ---------------------------- MPD announces state update: player Jan 10 15:56:22 volumioroland volumio[937]: info: ControllerMpd::getState Jan 10 15:56:22 volumioroland volumio[937]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:56:22 volumioroland volumio[937]: info: sendMpdCommand status took 3 milliseconds Jan 10 15:56:22 volumioroland volumio[937]: verbose: ControllerMpd::parseState Jan 10 15:56:22 volumioroland volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:56:22 volumioroland volumio[937]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 10 15:56:22 volumioroland volumio[937]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:56:22 volumioroland volumio[937]: info: ControllerMpd::pushState Jan 10 15:56:22 volumioroland volumio[937]: info: CoreCommandRouter::servicePushState Jan 10 15:56:22 volumioroland volumio[937]: info: CorePlayQueue::getTrack 0 Jan 10 15:56:22 volumioroland volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63257,"duration":305,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2436 Kbps","isStreaming":false,"title":"21413801","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/21413801","trackType":"qobuz"} Jan 10 15:56:22 volumioroland volumio[937]: verbose: CURRENT POSITION 0 Jan 10 15:56:22 volumioroland volumio[937]: info: CoreStateMachine::syncState stateService play Jan 10 15:56:22 volumioroland volumio[937]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:56:22 volumioroland volumio[937]: info: Received an update from plugin. extracting info from payload Jan 10 15:56:22 volumioroland volumio[937]: info: CoreStateMachine::pushState Jan 10 15:56:22 volumioroland volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:56:22 volumioroland volumio[937]: info: CoreCommandRouter::volumioPushState Jan 10 15:56:22 volumioroland volumio[937]: info: MRS: Pushing multiroomSync output update for this device Jan 10 15:56:22 volumioroland volumio[937]: info: MRS: Pushing multiroomSync output Jan 10 15:56:22 volumioroland volumio[937]: info: CoreCommandRouter::volumioGetState Jan 10 15:56:22 volumioroland volumio[937]: info: CoreStateMachine::pushState Jan 10 15:56:22 volumioroland volumio[937]: info: CoreCommandRouter::volumioPushState Jan 10 15:56:22 volumioroland volumio[937]: info: MRS: Pushing multiroomSync output update for this device Jan 10 15:56:22 volumioroland volumio[937]: info: MRS: Pushing multiroomSync output Jan 10 15:56:22 volumioroland volumio[937]: info: CoreCommandRouter::volumioGetState Jan 10 15:56:22 volumioroland volumio[937]: info: ------------------------------ 17ms Jan 10 15:56:30 volumioroland volumio[937]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Jan 10 15:56:30 volumioroland volumio[937]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Jan 10 15:56:30 volumioroland volumio[937]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 15:56:30 volumioroland volumio[937]: TypeError: Cannot read property 'then' of undefined Jan 10 15:56:30 volumioroland volumio[937]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Jan 10 15:56:30 volumioroland volumio[937]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Jan 10 15:56:30 volumioroland volumio[937]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) Jan 10 15:56:30 volumioroland volumio[937]: at Socket.emit (events.js:315:20) Jan 10 15:56:30 volumioroland volumio[937]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 10 15:56:30 volumioroland volumio[937]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jan 10 15:56:30 volumioroland volumio[937]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 15:56:30 volumioroland sudo[3655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-10 15:55 Jan 10 15:56:30 volumioroland sudo[3655]: 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="5d50cd80bebce60dddccd7f5e813d656e28ca4cd" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="7840f283c310c38bfe939a4c485727a6ff6a0ce1" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 21 Oct 2025 03:46:47 PM CEST" VOLUMIO_VERSION="3.866" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e2f3a8aca3147ba51b5da8167dd6ff8"