-- Logs begin at Thu 2019-02-14 19:12:00 KST, end at Sat 2024-10-12 13:09:55 KST. -- Oct 12 13:08:07 hswh-dx volumio[792]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Oct 12 13:08:10 hswh-dx volumio[792]: info: browseTIDALUri took 2931 milliseconds Oct 12 13:08:10 hswh-dx volumio[792]: info: Preload queue cleared Oct 12 13:08:10 hswh-dx volumio[792]: info: Preload queue cleared Oct 12 13:08:16 hswh-dx volumiologrotate[534]: ls: cannot access '/var/log/samba/log.wb-HSWH': No such file or directory Oct 12 13:08:16 hswh-dx volumiologrotate[534]: ls: cannot access 'DX': No such file or directory Oct 12 13:08:34 hswh-dx volumio[792]: info: CorePlayQueue::getTrack 42 Oct 12 13:08:34 hswh-dx volumio[792]: info: CorePlayQueue::getTrack 43 Oct 12 13:08:34 hswh-dx volumio[792]: info: Prefetching next song Oct 12 13:08:34 hswh-dx volumio[792]: info: [1728706114521] ControllerTidal::prefetch Oct 12 13:08:34 hswh-dx volumio[792]: info: Getting stream with soundQuality LOSSLESS Oct 12 13:08:34 hswh-dx volumio[792]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/112803437&soundQuality=LOSSLESS" Oct 12 13:08:34 hswh-dx volumio[792]: info: Oct 12 13:08:34 hswh-dx volumio[792]: ---------------------------- MPD announces system playlist update Oct 12 13:08:34 hswh-dx volumio[792]: info: Ignoring MPD Status Update Oct 12 13:08:34 hswh-dx volumio[792]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/112803437&soundQuality=LOSSLESS" took 8 milliseconds Oct 12 13:08:34 hswh-dx volumio[792]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 12 13:08:34 hswh-dx volumio[792]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 12 13:08:34 hswh-dx volumio[792]: info: ------------------------------ 9ms Oct 12 13:08:34 hswh-dx volumio[792]: info: sendMpdCommand consume 1 took 5 milliseconds Oct 12 13:08:38 hswh-dx volumio[792]: info: CoreStateMachine::startPlaybackTimer Oct 12 13:08:38 hswh-dx volumio[792]: info: CorePlayQueue::getTrack 43 Oct 12 13:08:39 hswh-dx volumio[792]: info: CoreStateMachine::pushState Oct 12 13:08:39 hswh-dx volumio[792]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 13:08:39 hswh-dx volumio[792]: info: CoreCommandRouter::volumioPushState Oct 12 13:08:39 hswh-dx volumio[792]: info: MRS: Pushing multiroomSync output update for this device Oct 12 13:08:39 hswh-dx volumio[792]: info: MRS: Pushing multiroomSync output Oct 12 13:08:44 hswh-dx volumio[792]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Oct 12 13:08:45 hswh-dx volumio[792]: info: browseTIDALUri took 962 milliseconds Oct 12 13:08:45 hswh-dx volumio[792]: info: Preload queue cleared Oct 12 13:08:45 hswh-dx volumio[792]: info: Preloading song: tidal://song/4325130 Oct 12 13:08:45 hswh-dx volumio[792]: info: Preloading song: tidal://song/4325131 Oct 12 13:08:45 hswh-dx volumio[792]: info: Preloading song: tidal://song/4325132 Oct 12 13:08:45 hswh-dx volumio[792]: info: Preloading song: tidal://song/4325133 Oct 12 13:08:45 hswh-dx volumio[792]: info: Preloading song: tidal://song/4325134 Oct 12 13:08:45 hswh-dx volumio[792]: info: Preloading song: tidal://song/4325135 Oct 12 13:08:45 hswh-dx volumio[792]: info: Preloading song: tidal://song/4325136 Oct 12 13:08:45 hswh-dx volumio[792]: info: Preloading song: tidal://song/4325137 Oct 12 13:08:45 hswh-dx volumio[792]: info: Exploding uri tidal://song/4325130 in service tidal Oct 12 13:08:45 hswh-dx volumio[792]: info: Exploding uri tidal://song/4325131 in service tidal Oct 12 13:08:45 hswh-dx volumio[792]: info: Exploding uri tidal://song/4325132 in service tidal Oct 12 13:08:45 hswh-dx volumio[792]: info: Exploding uri tidal://song/4325133 in service tidal Oct 12 13:08:45 hswh-dx volumio[792]: info: Exploding uri tidal://song/4325134 in service tidal Oct 12 13:08:46 hswh-dx volumio[792]: info: Exploding uri tidal://song/4325135 in service tidal Oct 12 13:08:46 hswh-dx volumio[792]: info: Exploding uri tidal://song/4325136 in service tidal Oct 12 13:08:46 hswh-dx volumio[792]: info: Exploding uri tidal://song/4325137 in service tidal Oct 12 13:08:46 hswh-dx volumio[792]: info: explodeTIDALUri took 526 milliseconds Oct 12 13:08:46 hswh-dx volumio[792]: info: explodeTIDALUri took 552 milliseconds Oct 12 13:08:46 hswh-dx volumio[792]: info: explodeTIDALUri took 544 milliseconds Oct 12 13:08:46 hswh-dx volumio[792]: info: explodeTIDALUri took 549 milliseconds Oct 12 13:08:46 hswh-dx volumio[792]: info: explodeTIDALUri took 486 milliseconds Oct 12 13:08:46 hswh-dx volumio[792]: info: explodeTIDALUri took 559 milliseconds Oct 12 13:08:46 hswh-dx volumio[792]: info: explodeTIDALUri took 565 milliseconds Oct 12 13:08:46 hswh-dx volumio[792]: info: explodeTIDALUri took 578 milliseconds Oct 12 13:08:54 hswh-dx volumio[792]: STREAMING PROXY: Handling url /?data=tidal://song/112803437&soundQuality=LOSSLESS Oct 12 13:08:54 hswh-dx volumio[792]: info: Executing endpoint getStreamUrltidal Oct 12 13:08:54 hswh-dx volumio[792]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Oct 12 13:08:55 hswh-dx volumio[792]: info: getStreamUrl took 658 milliseconds Oct 12 13:08:55 hswh-dx volumio[792]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEicwNDM0ODVkNDZkYmI2YTUyMTQ5YjYwZDY3ODliODFjMF82MS5tcDQ/0.flac?Expires=1728709735&Signature=g2qHWrcuZpDKfWx1gc8hmIPSFFgrQCddYpvMBkYgA4k8aEBTXnnp93eJqsIPoEtvFetCg8j52A7tXYzLEH~LqXPkWUhPTDTd-c79qCUXvUSW9gpU~TsscM6u5q9DCPRylXUovzMars6h4uyH409DBKDXVXqHi2xmIEWojjZ1OfbANVVdIcs7Yy9PDZH5SJTASUuQUwwr6n5rG~2aaUhPRpLVU-fz7xDEQCbzC777gu735r3kNhNNl8PhY-WyK6RktVYun1wx4cQOYVJGuw45BUiUVKYmmCrgV4h5B8MJ10VH~IoITqiAH-wCGUpr9TaI-QE~eC~fjysFaM1dkfvWJg__&Key-Pair-Id=K14LZCZ9QUI4JL Oct 12 13:08:55 hswh-dx volumio[792]: STREAMING PROXY: Response: 200, length: 10490158 Oct 12 13:08:57 hswh-dx volumio[792]: STREAMING PROXY: Client dropped request, destroying Oct 12 13:09:00 hswh-dx volumio[792]: info: Preload queue cleared Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreStateMachine::ClearQueue Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreStateMachine::stop Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreStateMachine::stPlaybackTimer Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreStateMachine::updateTrackBlock Oct 12 13:09:00 hswh-dx volumio[792]: info: CorePlayQueue::getTrackBlock Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreStateMachine::pushState Oct 12 13:09:00 hswh-dx volumio[792]: info: CorePlayQueue::getTrack 43 Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreCommandRouter::volumioPushState Oct 12 13:09:00 hswh-dx volumio[792]: info: MRS: Pushing multiroomSync output update for this device Oct 12 13:09:00 hswh-dx volumio[792]: info: MRS: Pushing multiroomSync output Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreStateMachine::serviceStop Oct 12 13:09:00 hswh-dx volumio[792]: info: CorePlayQueue::getTrack 43 Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreCommandRouter::serviceStop Oct 12 13:09:00 hswh-dx volumio[792]: info: [1728706140696] ControllerTidal::stop Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 12 13:09:00 hswh-dx volumio[792]: info: ControllerMpd::stop Oct 12 13:09:00 hswh-dx volumio[792]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 13:09:00 hswh-dx volumio[792]: info: CorePlayQueue::clearPlayQueue Oct 12 13:09:00 hswh-dx volumio[792]: info: CorePlayQueue::saveQueue Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreCommandRouter::volumioPushQueue Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreStateMachine::addQueueItems Oct 12 13:09:00 hswh-dx volumio[792]: info: CorePlayQueue::addQueueItems Oct 12 13:09:00 hswh-dx volumio[792]: info: Preload queue cleared Oct 12 13:09:00 hswh-dx volumio[792]: info: Adding Item to queue: tidal://album/4325129 Oct 12 13:09:00 hswh-dx volumio[792]: info: Exploding uri tidal://album/4325129 in service tidal Oct 12 13:09:00 hswh-dx volumio[792]: info: Oct 12 13:09:00 hswh-dx volumio[792]: ---------------------------- MPD announces state update: player Oct 12 13:09:00 hswh-dx volumio[792]: info: sendMpdCommand stop took 52 milliseconds Oct 12 13:09:00 hswh-dx volumio[792]: info: ControllerMpd::getState Oct 12 13:09:00 hswh-dx volumio[792]: verbose: ControllerMpd::sendMpdCommand status Oct 12 13:09:00 hswh-dx volumio[792]: info: sendMpdCommand status took 71 milliseconds Oct 12 13:09:00 hswh-dx volumio[792]: verbose: ControllerMpd::parseState Oct 12 13:09:00 hswh-dx volumio[792]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 13:09:00 hswh-dx volumio[792]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 13:09:00 hswh-dx volumio[792]: verbose: ControllerMpd::parseTrackInfo Oct 12 13:09:00 hswh-dx volumio[792]: info: ControllerMpd::pushState Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreCommandRouter::servicePushState Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreStateMachine::pushState Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreCommandRouter::volumioPushState Oct 12 13:09:00 hswh-dx volumio[792]: info: MRS: Pushing multiroomSync output update for this device Oct 12 13:09:00 hswh-dx volumio[792]: info: MRS: Pushing multiroomSync output Oct 12 13:09:00 hswh-dx volumio[792]: info: CorePlayQueue::getTrack 43 Oct 12 13:09:00 hswh-dx volumio[792]: 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":"112803436&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/112803436&soundQuality=LOSSLESS","trackType":"tidal"} Oct 12 13:09:00 hswh-dx volumio[792]: verbose: CURRENT POSITION 43 Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreStateMachine::syncState stateService stop Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreStateMachine::syncState currentStatus stop Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreStateMachine::pushState Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreCommandRouter::volumioPushState Oct 12 13:09:00 hswh-dx volumio[792]: info: MRS: Pushing multiroomSync output update for this device Oct 12 13:09:00 hswh-dx volumio[792]: info: MRS: Pushing multiroomSync output Oct 12 13:09:00 hswh-dx volumio[792]: info: No code Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreStateMachine::pushState Oct 12 13:09:00 hswh-dx volumio[792]: info: CoreCommandRouter::volumioPushState Oct 12 13:09:00 hswh-dx volumio[792]: info: MRS: Pushing multiroomSync output update for this device Oct 12 13:09:00 hswh-dx volumio[792]: info: MRS: Pushing multiroomSync output Oct 12 13:09:00 hswh-dx volumio[792]: info: ------------------------------ 126ms Oct 12 13:09:00 hswh-dx volumio[792]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreCommandRouter::volumioGetState Oct 12 13:09:01 hswh-dx volumio[792]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 12 13:09:01 hswh-dx volumio[792]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 12 13:09:01 hswh-dx volumio[792]: info: explodeTIDALUri took 426 milliseconds Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreCommandRouter::volumioPushQueue Oct 12 13:09:01 hswh-dx volumio[792]: info: CorePlayQueue::saveQueue Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreStateMachine::updateTrackBlock Oct 12 13:09:01 hswh-dx volumio[792]: info: CorePlayQueue::getTrackBlock Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreCommandRouter::volumioPlay Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreStateMachine::play index 0 Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreStateMachine::stop Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreStateMachine::play index undefined Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 13:09:01 hswh-dx volumio[792]: info: CorePlayQueue::getTrack 0 Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreStateMachine::startPlaybackTimer Oct 12 13:09:01 hswh-dx volumio[792]: info: CorePlayQueue::getTrack 0 Oct 12 13:09:01 hswh-dx volumio[792]: info: [1728706141170] ControllerTidal::clearAddPlayTrack Oct 12 13:09:01 hswh-dx volumio[792]: info: Getting stream with soundQuality LOSSLESS Oct 12 13:09:01 hswh-dx volumio[792]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 13:09:01 hswh-dx volumio[792]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 12 13:09:01 hswh-dx volumio[792]: info: sendMpdCommand stop took 9 milliseconds Oct 12 13:09:01 hswh-dx volumio[792]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 13:09:01 hswh-dx volumio[792]: info: Oct 12 13:09:01 hswh-dx volumio[792]: ---------------------------- MPD announces system playlist update Oct 12 13:09:01 hswh-dx volumio[792]: info: Ignoring MPD Status Update Oct 12 13:09:01 hswh-dx volumio[792]: info: sendMpdCommand clear took 8 milliseconds Oct 12 13:09:01 hswh-dx volumio[792]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/4325130&soundQuality=LOSSLESS" Oct 12 13:09:01 hswh-dx volumio[792]: error: updateQueue error: null Oct 12 13:09:01 hswh-dx volumio[792]: info: Oct 12 13:09:01 hswh-dx volumio[792]: ---------------------------- MPD announces system playlist update Oct 12 13:09:01 hswh-dx volumio[792]: info: Ignoring MPD Status Update Oct 12 13:09:01 hswh-dx volumio[792]: info: ------------------------------ 9ms Oct 12 13:09:01 hswh-dx volumio[792]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/4325130&soundQuality=LOSSLESS" took 6 milliseconds Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 12 13:09:01 hswh-dx volumio[792]: verbose: ControllerMpd::sendMpdCommand play Oct 12 13:09:01 hswh-dx volumio[792]: STREAMING PROXY: Handling url /?data=tidal://song/4325130&soundQuality=LOSSLESS Oct 12 13:09:01 hswh-dx volumio[792]: info: ------------------------------ 9ms Oct 12 13:09:01 hswh-dx volumio[792]: info: sendMpdCommand play took 6 milliseconds Oct 12 13:09:01 hswh-dx volumio[792]: info: Executing endpoint getStreamUrltidal Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Oct 12 13:09:01 hswh-dx volumio[792]: info: getStreamUrl took 544 milliseconds Oct 12 13:09:01 hswh-dx volumio[792]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic2M2YxNWM4MDk5MDlhMDUzNWU2NzAzYzM1NGU2ZDZlYV82MS5tcDQ/0.flac?Expires=1728709741&Signature=GfSic8Nryrvz6aNh9ReG~lIoNHbp~yUC-gJ~k7rPB5N9OMh-qpU0z7BcTDyWa5Dzexo7o6Mu6~fhEI7JXyUmdEZm00610CXlUZZwcwo9SObBhNo6y35klb2FXHHxc8cCB0zJGhMw5vIzTNbFELd3t-gb4fb0GH0oKn4VdsPXhgJq53~YYwUsyZAwRORB-EU3vJeh1UAGL3IHeTfa~cSLYBRoclEtUsifWaYFlCTEHeC5s3XRlr3AsEBY3PSydObX9L-dlT0sMI3X4hvreGBe0sPZd1DhVj9EPPSZkNFMCphXpPNxxP3BabHP-4myR7WAWI4mXiJplHdRn1LZoosYiA__&Key-Pair-Id=K14LZCZ9QUI4JL Oct 12 13:09:01 hswh-dx volumio[792]: STREAMING PROXY: Response: 200, length: 39314831 Oct 12 13:09:01 hswh-dx volumio[792]: info: Oct 12 13:09:01 hswh-dx volumio[792]: ---------------------------- MPD announces state update: player Oct 12 13:09:01 hswh-dx volumio[792]: info: ControllerMpd::getState Oct 12 13:09:01 hswh-dx volumio[792]: verbose: ControllerMpd::sendMpdCommand status Oct 12 13:09:01 hswh-dx volumio[792]: info: Oct 12 13:09:01 hswh-dx volumio[792]: ---------------------------- MPD announces state update: player Oct 12 13:09:01 hswh-dx volumio[792]: info: ControllerMpd::getState Oct 12 13:09:01 hswh-dx volumio[792]: verbose: ControllerMpd::sendMpdCommand status Oct 12 13:09:01 hswh-dx volumio[792]: info: sendMpdCommand status took 6 milliseconds Oct 12 13:09:01 hswh-dx volumio[792]: verbose: ControllerMpd::parseState Oct 12 13:09:01 hswh-dx volumio[792]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 13:09:01 hswh-dx volumio[792]: info: sendMpdCommand status took 8 milliseconds Oct 12 13:09:01 hswh-dx volumio[792]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 12 13:09:01 hswh-dx volumio[792]: verbose: ControllerMpd::parseState Oct 12 13:09:01 hswh-dx volumio[792]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 13:09:01 hswh-dx volumio[792]: verbose: ControllerMpd::parseTrackInfo Oct 12 13:09:01 hswh-dx volumio[792]: info: ControllerMpd::pushState Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreCommandRouter::servicePushState Oct 12 13:09:01 hswh-dx volumio[792]: info: CorePlayQueue::getTrack 0 Oct 12 13:09:01 hswh-dx volumio[792]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":626,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"150 Kbps","isStreaming":false,"title":"4325130&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/4325130&soundQuality=LOSSLESS","trackType":"tidal"} Oct 12 13:09:01 hswh-dx volumio[792]: verbose: CURRENT POSITION 0 Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreStateMachine::syncState stateService play Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreStateMachine::syncState currentStatus stop Oct 12 13:09:01 hswh-dx volumio[792]: info: ------------------------------ 34ms Oct 12 13:09:01 hswh-dx volumio[792]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 12 13:09:01 hswh-dx volumio[792]: verbose: ControllerMpd::parseTrackInfo Oct 12 13:09:01 hswh-dx volumio[792]: info: ControllerMpd::pushState Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreCommandRouter::servicePushState Oct 12 13:09:01 hswh-dx volumio[792]: info: CorePlayQueue::getTrack 0 Oct 12 13:09:01 hswh-dx volumio[792]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":626,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"286 Kbps","isStreaming":false,"title":"4325130&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/4325130&soundQuality=LOSSLESS","trackType":"tidal"} Oct 12 13:09:01 hswh-dx volumio[792]: verbose: CURRENT POSITION 0 Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreStateMachine::syncState stateService play Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreStateMachine::syncState currentStatus play Oct 12 13:09:01 hswh-dx volumio[792]: info: Received an update from plugin. extracting info from payload Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreStateMachine::pushState Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreCommandRouter::volumioPushState Oct 12 13:09:01 hswh-dx volumio[792]: info: MRS: Pushing multiroomSync output update for this device Oct 12 13:09:01 hswh-dx volumio[792]: info: MRS: Pushing multiroomSync output Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreStateMachine::pushState Oct 12 13:09:01 hswh-dx volumio[792]: info: CoreCommandRouter::volumioPushState Oct 12 13:09:01 hswh-dx volumio[792]: info: MRS: Pushing multiroomSync output update for this device Oct 12 13:09:01 hswh-dx volumio[792]: info: MRS: Pushing multiroomSync output Oct 12 13:09:01 hswh-dx volumio[792]: info: ------------------------------ 83ms Oct 12 13:09:05 hswh-dx volumio[792]: STREAMING PROXY: Client dropped request, destroying Oct 12 13:09:16 hswh-dx volumiologrotate[534]: ls: cannot access '/var/log/samba/log.wb-HSWH': No such file or directory Oct 12 13:09:16 hswh-dx volumiologrotate[534]: ls: cannot access 'DX': No such file or directory Oct 12 13:09:50 hswh-dx volumio[792]: info: Tunnel connection is inactive, restarting it Oct 12 13:09:50 hswh-dx volumio[792]: info: Starting Tunnel 1 Oct 12 13:09:50 hswh-dx volumio[792]: info: Starting Tunnel Connection Checker Oct 12 13:09:50 hswh-dx sudo[15423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 12 13:09:50 hswh-dx sudo[15423]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:09:50 hswh-dx autossh[15249]: received signal to exit (15) Oct 12 13:09:50 hswh-dx systemd[1]: Stopping MyVolumio SSH Tunnel... Oct 12 13:09:50 hswh-dx systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Oct 12 13:09:50 hswh-dx systemd[1]: sshtunnel.service: Succeeded. Oct 12 13:09:50 hswh-dx systemd[1]: Stopped MyVolumio SSH Tunnel. Oct 12 13:09:50 hswh-dx systemd[1]: Started MyVolumio SSH Tunnel. Oct 12 13:09:50 hswh-dx sudo[15423]: pam_unix(sudo:session): session closed for user root Oct 12 13:09:50 hswh-dx volumio[792]: info: Remote SSH Started Oct 12 13:09:50 hswh-dx autossh[15427]: port set to 0, monitoring disabled Oct 12 13:09:50 hswh-dx autossh[15427]: starting ssh (count 1) Oct 12 13:09:50 hswh-dx autossh[15427]: ssh child pid is 15430 Oct 12 13:09:50 hswh-dx volumio[792]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 12 13:09:50 hswh-dx volumio[792]: info: CoreCommandRouter::volumioGetState Oct 12 13:09:50 hswh-dx volumiossh-tunnel[15425]: Warning: Permanently added '[as1.myvolumio.org]:2222,[128.199.166.162]:2222' (RSA) to the list of known hosts. Oct 12 13:09:54 hswh-dx volumio[792]: info: CoreCommandRouter::volumioSaveQueueToPlaylist Oct 12 13:09:54 hswh-dx volumio[792]: info: CoreStateMachine::getQueue Oct 12 13:09:54 hswh-dx volumio[792]: info: CorePlayQueue::getQueue Oct 12 13:09:54 hswh-dx volumio[792]: info: Saving Cloud item Saint-Saëns Symphony No.3 Organ Oct 12 13:09:54 hswh-dx volumio[792]: info: CoreCommandRouter::executeOnPlugin: my_volumio , saveCloudItem Oct 12 13:09:54 hswh-dx volumio[792]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 13:09:54 hswh-dx volumio[792]: Error: Reference.child failed: First argument was an invalid path = "/user_data/Y1tqxwgnPBO41HjZ1ERZzk9xxef1/myPlaylists/Saint-Saëns Symphony No.3 Organ". Paths must be non-empty strings and can't contain ".", "#", "$", "[", or "]" Oct 12 13:09:54 hswh-dx volumio[792]: at validatePathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1667:15) Oct 12 13:09:54 hswh-dx volumio[792]: at validateRootPathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1679:5) Oct 12 13:09:54 hswh-dx volumio[792]: at Reference.child (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:13737:17) Oct 12 13:09:54 hswh-dx volumio[792]: at Database.ref (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:15115:48) Oct 12 13:09:54 hswh-dx volumio[792]: at myVolumio.syncJSONToCloud (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:28817) Oct 12 13:09:54 hswh-dx volumio[792]: at myVolumio.saveCloudMyPlaylist (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:23840) Oct 12 13:09:54 hswh-dx volumio[792]: at myVolumio.saveCloudItem (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:18337) Oct 12 13:09:54 hswh-dx volumio[792]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Oct 12 13:09:54 hswh-dx volumio[792]: at PlaylistManager.saveJSONFile (/volumio/app/playlistManager.js:613:31) Oct 12 13:09:54 hswh-dx volumio[792]: at PlaylistManager.commonAddItemsToPlaylist (/volumio/app/playlistManager.js:834:8) Oct 12 13:09:54 hswh-dx volumio[792]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 13:09:55 hswh-dx sudo[15441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-12 13:08 Oct 12 13:09:55 hswh-dx sudo[15441]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"