-- Logs begin at Tue 2025-06-03 09:15:26 UTC, end at Tue 2025-06-03 11:20:09 UTC. -- Jun 03 11:19:01 volumiowerk volumio-remote-updater[608]: [2025-06-03 11:19:01] [connect] Successful connection Jun 03 11:19:01 volumiowerk volumio-remote-updater[608]: [2025-06-03 11:19:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1748949541 101 Jun 03 11:19:01 volumiowerk volumio[25706]: info: CoreCommandRouter::volumioGetState Jun 03 11:19:01 volumiowerk volumio[25706]: info: CorePlayQueue::getTrack 0 Jun 03 11:19:01 volumiowerk volumio[25706]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 03 11:19:01 volumiowerk volumio[25706]: info: Listing playlists Jun 03 11:19:04 volumiowerk volumio[25706]: info: ControllerAutoPlay - getting queue Jun 03 11:19:04 volumiowerk volumio[25706]: info: CoreCommandRouter::volumioGetQueue Jun 03 11:19:04 volumiowerk volumio[25706]: info: CoreStateMachine::getQueue Jun 03 11:19:04 volumiowerk volumio[25706]: info: CorePlayQueue::getQueue Jun 03 11:19:04 volumiowerk volumio[25706]: info: ControllerAutoPlay - start playing -> queue is not empty Jun 03 11:19:04 volumiowerk volumio[25706]: info: CoreCommandRouter::volumioPlay Jun 03 11:19:04 volumiowerk volumio[25706]: verbose: UNSET VOLATILE Jun 03 11:19:04 volumiowerk volumio[25706]: info: CoreStateMachine::play index undefined Jun 03 11:19:04 volumiowerk volumio[25706]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 11:19:04 volumiowerk volumio[25706]: info: CorePlayQueue::getTrack 0 Jun 03 11:19:04 volumiowerk volumio[25706]: info: CoreStateMachine::startPlaybackTimer Jun 03 11:19:04 volumiowerk volumio[25706]: info: CorePlayQueue::getTrack 0 Jun 03 11:19:04 volumiowerk volumio[25706]: info: [1748949544287] ControllerWebradio::clearAddPlayTrack Jun 03 11:19:04 volumiowerk volumio[25706]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 11:19:04 volumiowerk volumio[25706]: info: sendMpdCommand stop took 15 milliseconds Jun 03 11:19:04 volumiowerk volumio[25706]: verbose: ControllerMpd::sendMpdCommand clear Jun 03 11:19:04 volumiowerk volumio[25706]: info: Jun 03 11:19:04 volumiowerk volumio[25706]: ---------------------------- MPD announces system playlist update Jun 03 11:19:04 volumiowerk volumio[25706]: info: Ignoring MPD Status Update Jun 03 11:19:04 volumiowerk volumio[25706]: info: sendMpdCommand clear took 4 milliseconds Jun 03 11:19:04 volumiowerk volumio[25706]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/pls/KINK.pls" Jun 03 11:19:04 volumiowerk volumio[25706]: error: updateQueue error: null Jun 03 11:19:04 volumiowerk volumio[25706]: info: ------------------------------ 6ms Jun 03 11:19:14 volumiowerk volumio[25706]: verbose: ControllerMpd::sendMpdCommand add "https://playerservices.streamtheworld.com/pls/KINK.pls" Jun 03 11:19:14 volumiowerk volumio[25706]: info: Jun 03 11:19:14 volumiowerk volumio[25706]: ---------------------------- MPD announces system playlist update Jun 03 11:19:14 volumiowerk volumio[25706]: info: Ignoring MPD Status Update Jun 03 11:19:14 volumiowerk volumio[25706]: info: sendMpdCommand add "https://playerservices.streamtheworld.com/pls/KINK.pls" took 5 milliseconds Jun 03 11:19:14 volumiowerk volumio[25706]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 03 11:19:14 volumiowerk volumio[25706]: verbose: ControllerMpd::sendMpdCommand play Jun 03 11:19:14 volumiowerk volumio[25706]: info: ------------------------------ 8ms Jun 03 11:19:14 volumiowerk volumio[25706]: info: sendMpdCommand play took 5 milliseconds Jun 03 11:19:22 volumiowerk volumio[25706]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:19:22 volumiowerk volumio[25706]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:19:24 volumiowerk volumio[25706]: info: Jun 03 11:19:24 volumiowerk volumio[25706]: ---------------------------- MPD announces state update: player Jun 03 11:19:24 volumiowerk volumio[25706]: info: ControllerMpd::getState Jun 03 11:19:24 volumiowerk volumio[25706]: verbose: ControllerMpd::sendMpdCommand status Jun 03 11:19:24 volumiowerk volumio[25706]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 03 11:19:24 volumiowerk volumio[25706]: info: sendMpdCommand status took 9 milliseconds Jun 03 11:19:24 volumiowerk volumio[25706]: verbose: ControllerMpd::parseState Jun 03 11:19:24 volumiowerk volumio[25706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 11:19:24 volumiowerk volumio[25706]: info: sendMpdCommand clearerror took 7 milliseconds Jun 03 11:19:24 volumiowerk volumio[25706]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 03 11:19:24 volumiowerk volumio[25706]: verbose: ControllerMpd::parseTrackInfo Jun 03 11:19:24 volumiowerk volumio[25706]: info: ControllerMpd::pushState Jun 03 11:19:24 volumiowerk volumio[25706]: info: CoreCommandRouter::servicePushState Jun 03 11:19:24 volumiowerk volumio[25706]: info: CoreStateMachine::pushState Jun 03 11:19:24 volumiowerk volumio[25706]: info: CoreCommandRouter::volumioPushState Jun 03 11:19:24 volumiowerk volumio[25706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 11:19:24 volumiowerk volumio[25706]: info: CorePlayQueue::getTrack 0 Jun 03 11:19:24 volumiowerk volumio[25706]: 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":"KINK.pls","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/pls/KINK.pls","trackType":"pls"} Jun 03 11:19:24 volumiowerk volumio[25706]: verbose: CURRENT POSITION 0 Jun 03 11:19:24 volumiowerk volumio[25706]: info: CoreStateMachine::syncState stateService stop Jun 03 11:19:24 volumiowerk volumio[25706]: info: CoreStateMachine::syncState currentStatus stop Jun 03 11:19:24 volumiowerk volumio[25706]: info: CoreStateMachine::pushState Jun 03 11:19:24 volumiowerk volumio[25706]: info: CoreCommandRouter::volumioPushState Jun 03 11:19:24 volumiowerk volumio[25706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 11:19:24 volumiowerk volumio[25706]: info: No code Jun 03 11:19:24 volumiowerk volumio[25706]: info: CoreStateMachine::pushState Jun 03 11:19:24 volumiowerk volumio[25706]: info: CoreCommandRouter::volumioPushState Jun 03 11:19:24 volumiowerk volumio[25706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 11:19:24 volumiowerk volumio[25706]: info: ------------------------------ 55ms Jun 03 11:19:27 volumiowerk volumio[25706]: Discovery: adding 0d0b458b-95fd-491e-b7c3-17e450230262 Jun 03 11:19:27 volumiowerk volumio[25706]: info: mDNS: Found device Volumiowerk Jun 03 11:19:27 volumiowerk volumio[25706]: info: CoreCommandRouter::volumioGetState Jun 03 11:20:04 volumiowerk volumio[25706]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.917&uuid=49c0e8e97e0fc77a1a81abcac877378f" http://updates.volumio.org/downloader-v1/track-device Jun 03 11:20:04 volumiowerk volumio[25706]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 03 11:20:04 volumiowerk volumio[25706]: Dload Upload Total Spent Left Speed Jun 03 11:20:04 volumiowerk volumio[25706]: [1.9K blob data] Jun 03 11:20:04 volumiowerk volumio[25706]: [1.9K blob data] Jun 03 11:20:04 volumiowerk volumio[25706]: [460B blob data] Jun 03 11:20:04 volumiowerk volumio[25706]: retrying in 5 seconds, trial 0 Jun 03 11:20:04 volumiowerk volumio[25706]: Volumio Calling Home Jun 03 11:20:08 volumiowerk volumio[25706]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 11:20:08 volumiowerk volumio[25706]: TypeError: Cannot create property 'type' on string 'invalid server key' Jun 03 11:20:08 volumiowerk volumio[25706]: at WebSocket.onError (/volumio/node_modules/engine.io-client/node_modules/ws/lib/WebSocket.js:450:16) Jun 03 11:20:08 volumiowerk volumio[25706]: at emitOne (events.js:116:13) Jun 03 11:20:08 volumiowerk volumio[25706]: at WebSocket.emit (events.js:211:7) Jun 03 11:20:08 volumiowerk volumio[25706]: at ClientRequest.upgrade (/volumio/node_modules/engine.io-client/node_modules/ws/lib/WebSocket.js:738:12) Jun 03 11:20:08 volumiowerk volumio[25706]: at Object.onceWrapper (events.js:319:30) Jun 03 11:20:08 volumiowerk volumio[25706]: at emitThree (events.js:136:13) Jun 03 11:20:08 volumiowerk volumio[25706]: at ClientRequest.emit (events.js:217:7) Jun 03 11:20:08 volumiowerk volumio[25706]: at Socket.socketOnData (_http_client.js:472:11) Jun 03 11:20:08 volumiowerk volumio[25706]: at emitOne (events.js:116:13) Jun 03 11:20:08 volumiowerk volumio[25706]: at Socket.emit (events.js:211:7) Jun 03 11:20:08 volumiowerk volumio[25706]: at addChunk (_stream_readable.js:263:12) Jun 03 11:20:08 volumiowerk volumio[25706]: at readableAddChunk (_stream_readable.js:250:11) Jun 03 11:20:08 volumiowerk volumio[25706]: at Socket.Readable.push (_stream_readable.js:208:10) Jun 03 11:20:08 volumiowerk volumio[25706]: at TCP.onread (net.js:607:20) Jun 03 11:20:08 volumiowerk volumio[25706]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 11:20:09 volumiowerk sudo[26107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-03 11:19 Jun 03 11:20:09 volumiowerk sudo[26107]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"