-- Logs begin at Mon 2022-05-09 15:24:52 UTC, end at Tue 2025-06-10 07:15:15 UTC. -- Jun 10 07:14:07 volumiowerk volumio[23000]: info: Jun 10 07:14:07 volumiowerk volumio[23000]: ---------------------------- MPD announces system playlist update Jun 10 07:14:07 volumiowerk volumio[23000]: info: Ignoring MPD Status Update Jun 10 07:14:07 volumiowerk volumio[23000]: info: sendMpdCommand load "https://playerservices.streamtheworld.com/pls/KINK.pls" took 7794 milliseconds Jun 10 07:14:07 volumiowerk volumio[23000]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 10 07:14:07 volumiowerk volumio[23000]: verbose: ControllerMpd::sendMpdCommand play Jun 10 07:14:07 volumiowerk volumio[23000]: info: ------------------------------ 8ms Jun 10 07:14:07 volumiowerk volumio[23000]: info: sendMpdCommand play took 5 milliseconds Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 10 07:14:07 volumiowerk volumio[23000]: info: Adding plugin bluetooth to MyMusic Plugins Jun 10 07:14:07 volumiowerk volumio[23000]: info: Adding plugin cd_controller to MyMusic Plugins Jun 10 07:14:07 volumiowerk volumio[23000]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 10 07:14:07 volumiowerk volumio[23000]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 10 07:14:07 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 10 07:14:08 volumiowerk kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jun 10 07:14:08 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 10 07:14:08 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 10 07:14:08 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 07:14:08 volumiowerk volumio[23000]: info: Starting MyVolumio Remote Streaming Endpoints Jun 10 07:14:08 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 10 07:14:08 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 10 07:14:08 volumiowerk volumio[23000]: info: Streaming services startup Jun 10 07:14:08 volumiowerk volumio[23000]: info: Starting Streaming Daemon Jun 10 07:14:08 volumiowerk sudo[23276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 10 07:14:08 volumiowerk sudo[23276]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 07:14:08 volumiowerk systemd[1]: Stopping Volumio Streaming Daemon... Jun 10 07:14:08 volumiowerk systemd[1]: Starting Volumio Streaming Daemon... Jun 10 07:14:08 volumiowerk systemd[1]: Started Volumio Streaming Daemon. Jun 10 07:14:08 volumiowerk sudo[23276]: pam_unix(sudo:session): session closed for user root Jun 10 07:14:08 volumiowerk volumio[23000]: info: MyVolumio login type: Token Jun 10 07:14:08 volumiowerk volumio[23000]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 10 07:14:08 volumiowerk volumio-streaming-daemon[23286]: ############################ Jun 10 07:14:08 volumiowerk volumio-streaming-daemon[23286]: # Volumio Streaming Daemon # Jun 10 07:14:08 volumiowerk volumio-streaming-daemon[23286]: # Running on port 7777 # Jun 10 07:14:08 volumiowerk volumio-streaming-daemon[23286]: ############################ Jun 10 07:14:08 volumiowerk volumio-streaming-daemon[23286]: Environment: production Jun 10 07:14:08 volumiowerk volumio[23000]: Discovery: adding 0d0b458b-95fd-491e-b7c3-17e450230262 Jun 10 07:14:08 volumiowerk volumio[23000]: info: mDNS: Found device Volumiowerk Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreCommandRouter::volumioGetState Jun 10 07:14:08 volumiowerk volumio[23000]: info: Jun 10 07:14:08 volumiowerk volumio[23000]: ---------------------------- MPD announces state update: player Jun 10 07:14:08 volumiowerk volumio[23000]: info: ControllerMpd::getState Jun 10 07:14:08 volumiowerk volumio[23000]: verbose: ControllerMpd::sendMpdCommand status Jun 10 07:14:08 volumiowerk volumio[23000]: info: Volumio called home Jun 10 07:14:08 volumiowerk volumio[23000]: info: Jun 10 07:14:08 volumiowerk volumio[23000]: ---------------------------- MPD announces system playlist update Jun 10 07:14:08 volumiowerk volumio[23000]: info: Ignoring MPD Status Update Jun 10 07:14:08 volumiowerk volumio[23000]: info: Jun 10 07:14:08 volumiowerk volumio[23000]: ---------------------------- MPD announces state update: player Jun 10 07:14:08 volumiowerk volumio[23000]: info: ControllerMpd::getState Jun 10 07:14:08 volumiowerk volumio[23000]: verbose: ControllerMpd::sendMpdCommand status Jun 10 07:14:08 volumiowerk volumio[23000]: info: sendMpdCommand status took 11 milliseconds Jun 10 07:14:08 volumiowerk volumio[23000]: verbose: ControllerMpd::parseState Jun 10 07:14:08 volumiowerk volumio[23000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 07:14:08 volumiowerk volumio[23000]: info: ------------------------------ 23ms Jun 10 07:14:08 volumiowerk volumio[23000]: info: sendMpdCommand status took 22 milliseconds Jun 10 07:14:08 volumiowerk volumio[23000]: info: sendMpdCommand playlistinfo took 18 milliseconds Jun 10 07:14:08 volumiowerk volumio[23000]: verbose: ControllerMpd::parseState Jun 10 07:14:08 volumiowerk volumio[23000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 07:14:08 volumiowerk volumio[23000]: verbose: ControllerMpd::parseTrackInfo Jun 10 07:14:08 volumiowerk volumio[23000]: info: ControllerMpd::pushState Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreCommandRouter::servicePushState Jun 10 07:14:08 volumiowerk volumio[23000]: info: CorePlayQueue::getTrack 0 Jun 10 07:14:08 volumiowerk volumio[23000]: verbose: STATE SERVICE {"status":"play","position":0,"seek":780,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Nirvana - Where Did You Sleep Last Night (Unplugged)","artist":"KINK","album":null,"uri":"https://25243.live.streamtheworld.com:443/KINK_SC","trackType":""} Jun 10 07:14:08 volumiowerk volumio[23000]: verbose: CURRENT POSITION 0 Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreStateMachine::syncState stateService play Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreStateMachine::syncState currentStatus stop Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreStateMachine::pushState Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreCommandRouter::volumioPushState Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 07:14:08 volumiowerk volumio[23000]: info: ------------------------------ 81ms Jun 10 07:14:08 volumiowerk volumio[23000]: info: sendMpdCommand playlistinfo took 48 milliseconds Jun 10 07:14:08 volumiowerk volumio[23000]: verbose: ControllerMpd::parseTrackInfo Jun 10 07:14:08 volumiowerk volumio[23000]: info: ControllerMpd::pushState Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreCommandRouter::servicePushState Jun 10 07:14:08 volumiowerk volumio[23000]: info: CorePlayQueue::getTrack 0 Jun 10 07:14:08 volumiowerk volumio[23000]: verbose: STATE SERVICE {"status":"play","position":0,"seek":885,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Nirvana - Where Did You Sleep Last Night (Unplugged)","artist":"KINK","album":null,"uri":"https://25243.live.streamtheworld.com:443/KINK_SC","trackType":""} Jun 10 07:14:08 volumiowerk volumio[23000]: verbose: CURRENT POSITION 0 Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreStateMachine::syncState stateService play Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreStateMachine::syncState currentStatus play Jun 10 07:14:08 volumiowerk volumio[23000]: info: Received an update from plugin. extracting info from payload Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreStateMachine::pushState Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreCommandRouter::volumioPushState Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreStateMachine::pushState Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreCommandRouter::volumioPushState Jun 10 07:14:08 volumiowerk volumio[23000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 07:14:08 volumiowerk volumio[23000]: info: ------------------------------ 108ms Jun 10 07:14:08 volumiowerk volumio[23000]: error: MyVolumio FAILED LOGIN: Invalid assertion format. 3 dot separated segments required. Jun 10 07:14:11 volumiowerk volumio-streaming-daemon[23286]: Environment: production Jun 10 07:14:11 volumiowerk volumio[23000]: info: Fetching Streaming Services browse cache Jun 10 07:14:17 volumiowerk volumio[23000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 07:14:17 volumiowerk volumio[23000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 07:15:14 volumiowerk volumio[23000]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 07:15:14 volumiowerk volumio[23000]: TypeError: Cannot create property 'type' on string 'invalid server key' Jun 10 07:15:14 volumiowerk volumio[23000]: at WebSocket.onError (/volumio/node_modules/engine.io-client/node_modules/ws/lib/WebSocket.js:450:16) Jun 10 07:15:14 volumiowerk volumio[23000]: at emitOne (events.js:116:13) Jun 10 07:15:14 volumiowerk volumio[23000]: at WebSocket.emit (events.js:211:7) Jun 10 07:15:14 volumiowerk volumio[23000]: at ClientRequest.upgrade (/volumio/node_modules/engine.io-client/node_modules/ws/lib/WebSocket.js:738:12) Jun 10 07:15:14 volumiowerk volumio[23000]: at Object.onceWrapper (events.js:319:30) Jun 10 07:15:14 volumiowerk volumio[23000]: at emitThree (events.js:136:13) Jun 10 07:15:14 volumiowerk volumio[23000]: at ClientRequest.emit (events.js:217:7) Jun 10 07:15:14 volumiowerk volumio[23000]: at Socket.socketOnData (_http_client.js:472:11) Jun 10 07:15:14 volumiowerk volumio[23000]: at emitOne (events.js:116:13) Jun 10 07:15:14 volumiowerk volumio[23000]: at Socket.emit (events.js:211:7) Jun 10 07:15:14 volumiowerk volumio[23000]: at addChunk (_stream_readable.js:263:12) Jun 10 07:15:14 volumiowerk volumio[23000]: at readableAddChunk (_stream_readable.js:250:11) Jun 10 07:15:14 volumiowerk volumio[23000]: at Socket.Readable.push (_stream_readable.js:208:10) Jun 10 07:15:14 volumiowerk volumio[23000]: at TCP.onread (net.js:607:20) Jun 10 07:15:14 volumiowerk volumio[23000]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 07:15:15 volumiowerk sudo[23463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 07:14 Jun 10 07:15:15 volumiowerk sudo[23463]: 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"