-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sun 2024-10-06 18:30:36 CEST. -- Oct 06 18:29:15 volumio-mano volumio[876]: info: Tunnel connection is inactive, restarting it Oct 06 18:29:15 volumio-mano volumio[876]: info: Starting Tunnel 1 Oct 06 18:29:15 volumio-mano volumio[876]: info: Starting Tunnel Connection Checker Oct 06 18:29:15 volumio-mano sudo[7168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 06 18:29:15 volumio-mano sudo[7168]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 18:29:15 volumio-mano autossh[7023]: received signal to exit (15) Oct 06 18:29:15 volumio-mano systemd[1]: Stopping MyVolumio SSH Tunnel... Oct 06 18:29:15 volumio-mano systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Oct 06 18:29:15 volumio-mano systemd[1]: sshtunnel.service: Succeeded. Oct 06 18:29:15 volumio-mano systemd[1]: Stopped MyVolumio SSH Tunnel. Oct 06 18:29:15 volumio-mano systemd[1]: Started MyVolumio SSH Tunnel. Oct 06 18:29:15 volumio-mano sudo[7168]: pam_unix(sudo:session): session closed for user root Oct 06 18:29:15 volumio-mano volumio[876]: info: Remote SSH Started Oct 06 18:29:15 volumio-mano autossh[7176]: port set to 0, monitoring disabled Oct 06 18:29:15 volumio-mano autossh[7176]: starting ssh (count 1) Oct 06 18:29:15 volumio-mano autossh[7176]: ssh child pid is 7179 Oct 06 18:29:15 volumio-mano volumio[876]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 06 18:29:15 volumio-mano volumio[876]: info: CoreCommandRouter::volumioGetState Oct 06 18:29:29 volumio-mano volumiossh-tunnel[7170]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Oct 06 18:29:35 volumio-mano volumio[876]: info: CoreCommandRouter::volumioGetState Oct 06 18:29:37 volumio-mano volumio[876]: info: Executing endpoint metavolumio Oct 06 18:29:37 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 06 18:29:37 volumio-mano volumio[876]: info: Executing endpoint metavolumio Oct 06 18:29:37 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 06 18:29:37 volumio-mano volumio[876]: info: Executing endpoint metavolumio Oct 06 18:29:37 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 06 18:29:46 volumio-mano volumio[876]: info: Executing endpoint metavolumio Oct 06 18:29:46 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 06 18:29:46 volumio-mano volumio[876]: info: Executing endpoint metavolumio Oct 06 18:29:46 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 06 18:29:47 volumio-mano volumio[876]: info: CorePlayQueue::getTrack 8 Oct 06 18:29:47 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: tidal , goto Oct 06 18:29:47 volumio-mano volumio[876]: info: explodeTIDALUri took 342 milliseconds Oct 06 18:29:47 volumio-mano volumio[876]: info: browseTIDALUri took 257 milliseconds Oct 06 18:29:48 volumio-mano volumio[876]: info: Executing endpoint getSimilarAlbums Oct 06 18:29:48 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 06 18:29:48 volumio-mano volumio[876]: info: Executing endpoint getSimilarAlbums Oct 06 18:29:48 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 06 18:29:48 volumio-mano volumio[876]: info: Executing endpoint getSimilarAlbums Oct 06 18:29:48 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 06 18:29:48 volumio-mano volumio[876]: info: Executing endpoint getSimilarAlbums Oct 06 18:29:48 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 06 18:29:48 volumio-mano volumio[876]: info: Executing endpoint getSimilarAlbums Oct 06 18:29:48 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 06 18:29:48 volumio-mano volumio[876]: info: Executing endpoint metavolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: Executing endpoint metavolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: Executing endpoint metavolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: Executing endpoint metavolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: Executing endpoint metavolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: Executing endpoint metavolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: Executing endpoint metavolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: Executing endpoint metavolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: Executing endpoint metavolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: Executing endpoint metavolumio Oct 06 18:29:48 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 06 18:29:55 volumio-mano volumiologrotate[597]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 06 18:29:55 volumio-mano volumiologrotate[597]: ls: cannot access 'MANO': No such file or directory Oct 06 18:30:23 volumio-mano volumio[876]: info: Listing playlists Oct 06 18:30:24 volumio-mano volumio[876]: info: CorePlayQueue::getTrack 8 Oct 06 18:30:24 volumio-mano volumio[876]: info: CorePlayQueue::getTrack 9 Oct 06 18:30:24 volumio-mano volumio[876]: info: Prefetching next song Oct 06 18:30:24 volumio-mano volumio[876]: info: [1728232224203] ControllerTidal::prefetch Oct 06 18:30:24 volumio-mano volumio[876]: info: Getting stream with soundQuality LOSSLESS Oct 06 18:30:24 volumio-mano volumio[876]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/133928793&soundQuality=LOSSLESS" Oct 06 18:30:24 volumio-mano volumio[876]: info: Oct 06 18:30:24 volumio-mano volumio[876]: ---------------------------- MPD announces system playlist update Oct 06 18:30:24 volumio-mano volumio[876]: info: Ignoring MPD Status Update Oct 06 18:30:24 volumio-mano volumio[876]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/133928793&soundQuality=LOSSLESS" took 2 milliseconds Oct 06 18:30:24 volumio-mano volumio[876]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 18:30:24 volumio-mano volumio[876]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 06 18:30:24 volumio-mano volumio[876]: STREAMING PROXY: Handling url /?data=tidal://song/133928793&soundQuality=LOSSLESS Oct 06 18:30:24 volumio-mano volumio[876]: info: ------------------------------ 3ms Oct 06 18:30:24 volumio-mano volumio[876]: info: sendMpdCommand consume 1 took 2 milliseconds Oct 06 18:30:24 volumio-mano volumio[876]: info: Executing endpoint getStreamUrltidal Oct 06 18:30:24 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Oct 06 18:30:24 volumio-mano volumio[876]: info: getStreamUrl took 241 milliseconds Oct 06 18:30:24 volumio-mano volumio[876]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic3YjljODQxODUyYzY1NjY5YjEzODkzYzVkODkyZmJlMV82MS5tcDQ/0.flac?Expires=1728235824&Signature=rVfkjByN9G9hmg7Du3ESHyGJGX2SwXc~AqW3iUfCkOnGMzew6IL04-RZTI8k~LCqB69vROgsUG2tudklxqgoPQuzawIT8Ck-FGdlxK5pjnr3UXO2PW7vWp~KvYSnlm~zMQ~iu~P4xGrOHTLh88VcSsw4Fj0Mmyp9v33-W09v2QGLI7KCaXM~ZwEpXuw2~HDGf0Ic8OGF4MOh8NbaUHbGnXaOFFApyP4~N8T5CWv1phzB~DGUIhLpN6-8QlCQav5o7gy0F5or~WPbSyc~Y9mcS3OtX0DEEIg3isqPIscMLC8jZkr7M~lyusTX3YvAthW8aOgixnczh5dTNAykCsHEbQ__&Key-Pair-Id=K14LZCZ9QUI4JL Oct 06 18:30:24 volumio-mano volumio[876]: STREAMING PROXY: Response: 200, length: 29327614 Oct 06 18:30:27 volumio-mano volumio[876]: STREAMING PROXY: Client dropped request, destroying Oct 06 18:30:27 volumio-mano volumio[876]: info: Oct 06 18:30:27 volumio-mano volumio[876]: ---------------------------- MPD announces system playlist update Oct 06 18:30:27 volumio-mano volumio[876]: info: Ignoring MPD Status Update Oct 06 18:30:27 volumio-mano volumio[876]: info: Oct 06 18:30:27 volumio-mano volumio[876]: ---------------------------- MPD announces state update: player Oct 06 18:30:27 volumio-mano volumio[876]: info: ControllerMpd::getState Oct 06 18:30:27 volumio-mano volumio[876]: verbose: ControllerMpd::sendMpdCommand status Oct 06 18:30:27 volumio-mano volumio[876]: info: ------------------------------ 4ms Oct 06 18:30:27 volumio-mano volumio[876]: info: sendMpdCommand status took 1 milliseconds Oct 06 18:30:27 volumio-mano volumio[876]: verbose: ControllerMpd::parseState Oct 06 18:30:27 volumio-mano volumio[876]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 18:30:27 volumio-mano volumio[876]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 06 18:30:27 volumio-mano volumio[876]: verbose: ControllerMpd::parseTrackInfo Oct 06 18:30:27 volumio-mano volumio[876]: info: ControllerMpd::pushState Oct 06 18:30:27 volumio-mano volumio[876]: info: CoreCommandRouter::servicePushState Oct 06 18:30:27 volumio-mano volumio[876]: info: CorePlayQueue::getTrack 8 Oct 06 18:30:27 volumio-mano volumio[876]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":355,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"459 Kbps","isStreaming":false,"title":"133928793&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/133928793&soundQuality=LOSSLESS","trackType":"tidal"} Oct 06 18:30:27 volumio-mano volumio[876]: verbose: CURRENT POSITION 8 Oct 06 18:30:27 volumio-mano volumio[876]: info: CoreStateMachine::syncState stateService play Oct 06 18:30:27 volumio-mano volumio[876]: info: CoreStateMachine::syncState currentStatus play Oct 06 18:30:27 volumio-mano volumio[876]: info: Received an update from plugin. extracting info from payload Oct 06 18:30:27 volumio-mano volumio[876]: info: CoreStateMachine::pushState Oct 06 18:30:27 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 18:30:27 volumio-mano volumio[876]: info: CoreCommandRouter::volumioPushState Oct 06 18:30:27 volumio-mano volumio[876]: info: MRS: Pushing multiroomSync output update for this device Oct 06 18:30:27 volumio-mano volumio[876]: info: MRS: Pushing multiroomSync output Oct 06 18:30:27 volumio-mano volumio[876]: info: CoreStateMachine::pushState Oct 06 18:30:28 volumio-mano volumio[876]: info: CoreCommandRouter::volumioPushState Oct 06 18:30:28 volumio-mano volumio[876]: info: MRS: Pushing multiroomSync output update for this device Oct 06 18:30:28 volumio-mano volumio[876]: info: MRS: Pushing multiroomSync output Oct 06 18:30:28 volumio-mano volumio[876]: info: ------------------------------ 49ms Oct 06 18:30:28 volumio-mano volumio[876]: info: CoreStateMachine::startPlaybackTimer Oct 06 18:30:28 volumio-mano volumio[876]: info: CorePlayQueue::getTrack 9 Oct 06 18:30:29 volumio-mano volumio[876]: info: CoreStateMachine::pushState Oct 06 18:30:29 volumio-mano volumio[876]: info: CorePlayQueue::getTrack 9 Oct 06 18:30:29 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 18:30:29 volumio-mano volumio[876]: info: CoreCommandRouter::volumioPushState Oct 06 18:30:29 volumio-mano volumio[876]: info: MRS: Pushing multiroomSync output update for this device Oct 06 18:30:29 volumio-mano volumio[876]: info: MRS: Pushing multiroomSync output Oct 06 18:30:35 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: tidal , explodeUri Oct 06 18:30:35 volumio-mano volumio[876]: info: explodeTIDALUri took 260 milliseconds Oct 06 18:30:35 volumio-mano volumio[876]: info: Saving Cloud item blues gitarre americana etc. Oct 06 18:30:35 volumio-mano volumio[876]: info: CoreCommandRouter::executeOnPlugin: my_volumio , saveCloudItem Oct 06 18:30:35 volumio-mano volumio[876]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 18:30:35 volumio-mano volumio[876]: Error: Reference.child failed: First argument was an invalid path = "/user_data/KxmOxJrr6WddhEWHzrjoG5eZOHl2/myPlaylists/blues gitarre americana etc.". Paths must be non-empty strings and can't contain ".", "#", "$", "[", or "]" Oct 06 18:30:35 volumio-mano volumio[876]: at validatePathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1667:15) Oct 06 18:30:35 volumio-mano volumio[876]: at validateRootPathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1679:5) Oct 06 18:30:35 volumio-mano volumio[876]: at Reference.child (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:13737:17) Oct 06 18:30:35 volumio-mano volumio[876]: at Database.ref (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:15115:48) Oct 06 18:30:35 volumio-mano volumio[876]: at myVolumio.syncJSONToCloud (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:28817) Oct 06 18:30:35 volumio-mano volumio[876]: at myVolumio.saveCloudMyPlaylist (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:23840) Oct 06 18:30:35 volumio-mano volumio[876]: at myVolumio.saveCloudItem (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:18337) Oct 06 18:30:35 volumio-mano volumio[876]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Oct 06 18:30:35 volumio-mano volumio[876]: at PlaylistManager.saveJSONFile (/volumio/app/playlistManager.js:613:31) Oct 06 18:30:35 volumio-mano volumio[876]: at /volumio/app/playlistManager.js:588:20 Oct 06 18:30:35 volumio-mano volumio[876]: at /volumio/node_modules/jsonfile/index.js:46:5 Oct 06 18:30:35 volumio-mano volumio[876]: at /volumio/node_modules/graceful-fs/graceful-fs.js:123:16 Oct 06 18:30:35 volumio-mano volumio[876]: at FSReqCallback.readFileAfterClose [as oncomplete] (internal/fs/read_file_context.js:63:3) Oct 06 18:30:35 volumio-mano volumio[876]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 18:30:36 volumio-mano sudo[7236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-06 18:29 Oct 06 18:30:36 volumio-mano sudo[7236]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"