-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Fri 2024-11-01 23:22:45 GMT. -- Nov 01 23:21:00 rivo volumio[3109]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 01 23:21:00 rivo volumio[3109]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 01 23:21:00 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 23:21:00 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 23:21:00 rivo volumio[3109]: info: Starting MyVolumio Remote Streaming Endpoints Nov 01 23:21:00 rivo volumio[3109]: info: MyVolumio login type: Token Nov 01 23:21:00 rivo volumio[3109]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 01 23:21:00 rivo volumio[3109]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Nov 01 23:21:01 rivo volumio[3109]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 01 23:21:01 rivo volumio[3109]: info: MyVolumio token set successfully Nov 01 23:21:01 rivo volumio[3109]: info: MYVOLUMIO: Adding device Nov 01 23:21:01 rivo volumio[3109]: info: MYVOLUMIO: Evaluating Server Nov 01 23:21:02 rivo volumio[3109]: info: MyVolumio Plan changed: superstar Nov 01 23:21:02 rivo volumio[3109]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Nov 01 23:21:02 rivo volumio[3109]: info: Adding audio output: Nov 01 23:21:02 rivo volumio[3109]: info: MYVOLUMIO: Adding device Nov 01 23:21:02 rivo volumio[3109]: info: MYVOLUMIO: Evaluating Server Nov 01 23:21:02 rivo volumio[3109]: info: Remote config written successfully Nov 01 23:21:02 rivo volumio[3109]: info: Starting Tunnel 1 Nov 01 23:21:02 rivo volumio[3109]: info: Starting Tunnel Connection Checker Nov 01 23:21:03 rivo volumio[3109]: info: MYVolumio Device enabled Nov 01 23:21:03 rivo volumio[3109]: info: MyVolumio status changed Nov 01 23:21:03 rivo volumio[3109]: info: Streaming services startup Nov 01 23:21:03 rivo volumio[3109]: info: Starting Streaming Daemon Nov 01 23:21:03 rivo volumio[3109]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Nov 01 23:21:03 rivo sudo[4265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 01 23:21:03 rivo sudo[4265]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 23:21:03 rivo volumio[3109]: info: Setting Geolocation for MyVolumio to eu3 Nov 01 23:21:03 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 23:21:03 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 23:21:03 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 23:21:03 rivo sudo[4265]: pam_unix(sudo:session): session closed for user root Nov 01 23:21:03 rivo volumio[3109]: error: Cannot start Volumio Streaming Daemon Nov 01 23:21:03 rivo volumio[3109]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 01 23:21:03 rivo volumio[3109]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 01 23:21:03 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 01 23:21:03 rivo volumio[3109]: info: [MyVolumio PluginManager] Plugin audio_interface/bluetooth is enabled for this plan, but could not be found on the local filesystem! Nov 01 23:21:03 rivo volumio[3109]: info: [MyVolumio PluginManager] Plugin audio_interface/multiroom is enabled for this plan, but could not be found on the local filesystem! Nov 01 23:21:03 rivo volumio[3109]: info: [MyVolumio PluginManager] Plugin miscellanea/metavolumio is enabled for this plan, but could not be found on the local filesystem! Nov 01 23:21:03 rivo volumio[3109]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! Nov 01 23:21:03 rivo volumio[3109]: info: [MyVolumio PluginManager] Plugin music_service/cd_controller is enabled for this plan, but could not be found on the local filesystem! Nov 01 23:21:03 rivo volumio[3109]: info: [MyVolumio PluginManager] Plugin music_service/smart_inputs is enabled for this plan, but could not be found on the local filesystem! Nov 01 23:21:03 rivo volumio[3109]: info: [MyVolumio PluginManager] Plugin music_service/hi_res_audio is enabled for this plan, but could not be found on the local filesystem! Nov 01 23:21:03 rivo volumio[3109]: info: [MyVolumio PluginManager] Plugin music_service/tidal is enabled for this plan, but could not be found on the local filesystem! Nov 01 23:21:03 rivo volumio[3109]: info: [MyVolumio PluginManager] Plugin music_service/qobuz is enabled for this plan, but could not be found on the local filesystem! Nov 01 23:21:03 rivo volumio[3109]: info: [MyVolumio PluginManager] Plugin music_service/tidalconnect is enabled for this plan, but could not be found on the local filesystem! Nov 01 23:21:03 rivo volumio[3109]: info: Setting Geolocation for MyVolumio to eu3 Nov 01 23:21:03 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 23:21:03 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 23:21:03 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 23:21:03 rivo volumio[3109]: info: Successfully Added MyVolumio device Nov 01 23:21:04 rivo volumio[3109]: info: Successfully Added MyVolumio device Nov 01 23:21:04 rivo volumio[3109]: info: Updating MyVolumio device info Nov 01 23:21:04 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 23:21:04 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 23:21:04 rivo volumio[3109]: info: Updating MyVolumio device info Nov 01 23:21:04 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 23:21:04 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 23:21:04 rivo volumio[3109]: info: Successfully Updated MyVolumio device Nov 01 23:21:05 rivo volumio[3109]: info: Successfully Updated MyVolumio device Nov 01 23:21:07 rivo sudo[4272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 01 23:21:07 rivo sudo[4272]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 23:21:07 rivo systemd[1]: Started MyVolumio SSH Tunnel. Nov 01 23:21:07 rivo sudo[4272]: pam_unix(sudo:session): session closed for user root Nov 01 23:21:07 rivo volumio[3109]: info: Remote SSH Started Nov 01 23:21:07 rivo autossh[4278]: port set to 0, monitoring disabled Nov 01 23:21:07 rivo autossh[4278]: starting ssh (count 1) Nov 01 23:21:07 rivo autossh[4278]: ssh child pid is 4288 Nov 01 23:21:07 rivo volumiossh-tunnel[4274]: Warning: Permanently added '[eu3.myvolumio.org]:2222,[167.71.136.233]:2222' (RSA) to the list of known hosts. Nov 01 23:21:07 rivo volumio[3109]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Nov 01 23:21:07 rivo volumio[3109]: info: CoreCommandRouter::volumioGetState Nov 01 23:21:07 rivo volumio[3109]: info: CorePlayQueue::getTrack 0 Nov 01 23:21:07 rivo volumio[3109]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.lkNoGGG8BcTuEOjVKmxPW9OLxPF3.b1b7d13aed7c0e5cae19b7aaa1a63844.state.mute' Nov 01 23:21:09 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 23:21:09 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 23:21:09 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 01 23:21:09 rivo volumio-remote-updater[2848]: No test mode Nov 01 23:21:09 rivo volumio-remote-updater[2848]: No alpha test mode Nov 01 23:21:09 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 01 23:21:09 rivo volumio[3109]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 01 23:21:09 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 01 23:21:10 rivo volumio[3109]: error: MyVolumio Plugin failed to start in a timely fashion Nov 01 23:21:10 rivo volumio[3109]: info: BOOT COMPLETED Nov 01 23:21:10 rivo volumio[3109]: [Metrics] CommandRouter: 72s 465.65ms Nov 01 23:21:10 rivo volumio[3109]: info: CoreCommandRouter::volumiosetStartupVolume Nov 01 23:21:10 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 23:21:10 rivo volumio[3109]: info: VolumeController:: Setting startup Volume 20 Nov 01 23:21:10 rivo volumio[3109]: info: VolumeController::SetAlsaVolume20 Nov 01 23:21:10 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 23:21:10 rivo volumio[3109]: info: CoreCommandRouter::Close All Modals sent Nov 01 23:21:10 rivo volumio[3109]: info: CoreCommandRouter::Close All Modals sent Nov 01 23:21:10 rivo volumio[3109]: info: CoreCommandRouter::volumioGetState Nov 01 23:21:10 rivo volumio[3109]: info: CorePlayQueue::getTrack 0 Nov 01 23:21:10 rivo volumio[3109]: info: CoreStateMachine::pushState Nov 01 23:21:10 rivo volumio[3109]: info: CorePlayQueue::getTrack 0 Nov 01 23:21:10 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 23:21:10 rivo volumio[3109]: info: CoreCommandRouter::volumioPushState Nov 01 23:21:10 rivo volumio[3109]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:21:10 rivo volumio[3109]: info: MRS: Pushing multiroomSync output Nov 01 23:21:10 rivo volumio[3109]: info: Updating RAAT Signal Path Nov 01 23:21:10 rivo volumio[3109]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 01 23:21:10 rivo volumio[3109]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 01 23:21:10 rivo volumio[3109]: info: camilladsp stopping service pid 3937... Nov 01 23:21:10 rivo volumio[3109]: info: camilladsp service terminated, instance 1 Nov 01 23:21:10 rivo volumio[3109]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 01 23:21:10 rivo volumio[3109]: info: camilladsp service started and running in background, instance 1 Nov 01 23:21:10 rivo volumio[3109]: info: camilladsp spawned new process with pid 4298, instance 1, run: true Nov 01 23:21:11 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 01 23:21:11 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 01 23:21:11 rivo volumio[3109]: xcb_connection_has_error() returned true Nov 01 23:21:11 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 01 23:21:15 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 01 23:21:15 rivo volumio[3109]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 01 23:21:15 rivo volumio[3109]: info: CoreCommandRouter::volumioGetState Nov 01 23:21:15 rivo volumio[3109]: info: CorePlayQueue::getTrack 0 Nov 01 23:21:16 rivo volumio[3109]: info: camilladsp spawned new process with pid 4307, instance 1, run: true Nov 01 23:21:20 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 23:21:20 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 23:21:20 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 01 23:21:20 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 01 23:21:20 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 01 23:21:20 rivo volumio[3109]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 23:21:20 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 23:21:40 rivo volumio[3109]: verbose: New Socket.io Connection to lknoggg8bctueojvkmxpw9olxpf3-b1b7d13aed7c0e5cae19b7aaa1a63844.eu3.myvolumio.org from 127.0.0.1 UA: Mozilla/5.0 (Linux; Android 9; KFTRPWI) AppleWebKit/537.36 (KHTML, like Gecko) Silk/128.5.6 like Chrome/128.0.6613.194 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Nov 01 23:21:40 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 01 23:21:40 rivo volumio[3109]: info: CoreCommandRouter::volumioGetVisibleSources Nov 01 23:21:40 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 23:21:40 rivo volumio[3109]: info: CoreCommandRouter::volumioGetState Nov 01 23:21:40 rivo volumio[3109]: info: CorePlayQueue::getTrack 0 Nov 01 23:21:40 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 01 23:21:40 rivo volumio[3109]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 01 23:21:40 rivo volumio[3109]: info: Received Get System Info Nov 01 23:21:40 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 23:21:40 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 23:21:40 rivo volumio[3109]: info: Discovery: Getting this device information Nov 01 23:21:40 rivo volumio[3109]: info: CoreCommandRouter::volumioGetState Nov 01 23:21:40 rivo volumio[3109]: info: CorePlayQueue::getTrack 0 Nov 01 23:21:40 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 23:21:40 rivo volumio[3109]: info: CoreCommandRouter::volumioGetState Nov 01 23:21:40 rivo volumio[3109]: info: CorePlayQueue::getTrack 0 Nov 01 23:21:40 rivo volumio[3109]: info: Listing playlists Nov 01 23:21:40 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 01 23:21:40 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 01 23:21:40 rivo volumio[3109]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 23:21:41 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 01 23:21:41 rivo volumio[3109]: info: Received Get System Info Nov 01 23:21:41 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 23:21:41 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 23:21:41 rivo volumio[3109]: info: Discovery: Getting this device information Nov 01 23:21:41 rivo volumio[3109]: info: CoreCommandRouter::volumioGetState Nov 01 23:21:41 rivo volumio[3109]: info: CorePlayQueue::getTrack 0 Nov 01 23:21:41 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 23:21:41 rivo volumio[3109]: info: CoreCommandRouter::volumioGetState Nov 01 23:21:41 rivo volumio[3109]: info: CorePlayQueue::getTrack 0 Nov 01 23:21:41 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 01 23:21:41 rivo volumio[3109]: info: Received Get System Info Nov 01 23:21:41 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 23:21:41 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 23:21:41 rivo volumio[3109]: info: Discovery: Getting this device information Nov 01 23:21:41 rivo volumio[3109]: info: CoreCommandRouter::volumioGetState Nov 01 23:21:41 rivo volumio[3109]: info: CorePlayQueue::getTrack 0 Nov 01 23:21:41 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 23:21:43 rivo volumio[3109]: info: Executing endpoint metavolumio Nov 01 23:21:43 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 23:21:43 rivo volumio[3109]: info: Executing endpoint metavolumio Nov 01 23:21:43 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 23:21:43 rivo volumio[3109]: info: Executing endpoint metavolumio Nov 01 23:21:43 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 23:21:44 rivo volumio[3109]: info: CoreCommandRouter::volumioGetQueue Nov 01 23:21:44 rivo volumio[3109]: info: CoreStateMachine::getQueue Nov 01 23:21:44 rivo volumio[3109]: info: CorePlayQueue::getQueue Nov 01 23:21:47 rivo volumio[3109]: info: CoreCommandRouter::volumioPlay Nov 01 23:21:47 rivo volumio[3109]: info: CoreStateMachine::play index 1 Nov 01 23:21:47 rivo volumio[3109]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 23:21:47 rivo volumio[3109]: info: CoreStateMachine::stop Nov 01 23:21:47 rivo volumio[3109]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 23:21:47 rivo volumio[3109]: info: CoreStateMachine::play index undefined Nov 01 23:21:47 rivo volumio[3109]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 23:21:47 rivo volumio[3109]: info: CorePlayQueue::getTrack 1 Nov 01 23:21:47 rivo volumio[3109]: info: CoreStateMachine::startPlaybackTimer Nov 01 23:21:47 rivo volumio[3109]: info: CorePlayQueue::getTrack 1 Nov 01 23:21:47 rivo volumio[3109]: info: [1730503307814] ControllerTidal::clearAddPlayTrack Nov 01 23:21:47 rivo volumio[3109]: info: Getting stream with soundQuality LOSSLESS Nov 01 23:21:47 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 23:21:47 rivo volumio[3109]: info: sendMpdCommand stop took 2 milliseconds Nov 01 23:21:47 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand clear Nov 01 23:21:47 rivo volumio[3109]: info: sendMpdCommand clear took 1 milliseconds Nov 01 23:21:47 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/13362828&soundQuality=LOSSLESS" Nov 01 23:21:47 rivo volumio[3109]: info: Nov 01 23:21:47 rivo volumio[3109]: ---------------------------- MPD announces system playlist update Nov 01 23:21:47 rivo volumio[3109]: info: Ignoring MPD Status Update Nov 01 23:21:47 rivo volumio[3109]: info: Nov 01 23:21:47 rivo volumio[3109]: ---------------------------- MPD announces system playlist update Nov 01 23:21:47 rivo volumio[3109]: info: Ignoring MPD Status Update Nov 01 23:21:47 rivo volumio[3109]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/13362828&soundQuality=LOSSLESS" took 6 milliseconds Nov 01 23:21:47 rivo volumio[3109]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 01 23:21:47 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand play Nov 01 23:21:47 rivo volumio[3109]: info: Nov 01 23:21:47 rivo volumio[3109]: ---------------------------- MPD announces system playlist update Nov 01 23:21:47 rivo volumio[3109]: info: Ignoring MPD Status Update Nov 01 23:21:47 rivo volumio[3109]: info: Nov 01 23:21:47 rivo volumio[3109]: ---------------------------- MPD announces system playlist update Nov 01 23:21:47 rivo volumio[3109]: info: Ignoring MPD Status Update Nov 01 23:21:47 rivo volumio[3109]: info: Nov 01 23:21:47 rivo volumio[3109]: ---------------------------- MPD announces system playlist update Nov 01 23:21:47 rivo volumio[3109]: info: Ignoring MPD Status Update Nov 01 23:21:47 rivo volumio[3109]: info: ------------------------------ 22ms Nov 01 23:21:47 rivo volumio[3109]: info: ------------------------------ 21ms Nov 01 23:21:47 rivo volumio[3109]: info: sendMpdCommand play took 17 milliseconds Nov 01 23:21:47 rivo volumio[3109]: info: ------------------------------ 17ms Nov 01 23:21:47 rivo volumio[3109]: info: ------------------------------ 15ms Nov 01 23:21:47 rivo volumio[3109]: info: ------------------------------ 13ms Nov 01 23:21:47 rivo volumio[3109]: info: Nov 01 23:21:47 rivo volumio[3109]: ---------------------------- MPD announces system playlist update Nov 01 23:21:47 rivo volumio[3109]: info: Ignoring MPD Status Update Nov 01 23:21:47 rivo volumio[3109]: info: Nov 01 23:21:47 rivo volumio[3109]: ---------------------------- MPD announces system playlist update Nov 01 23:21:47 rivo volumio[3109]: STREAMING PROXY: Handling url /?data=tidal://song/13362828&soundQuality=LOSSLESS Nov 01 23:21:47 rivo volumio[3109]: info: Ignoring MPD Status Update Nov 01 23:21:47 rivo volumio[3109]: info: Nov 01 23:21:47 rivo volumio[3109]: ---------------------------- MPD announces system playlist update Nov 01 23:21:47 rivo volumio[3109]: info: Ignoring MPD Status Update Nov 01 23:21:47 rivo volumio[3109]: info: ------------------------------ 11ms Nov 01 23:21:47 rivo volumio[3109]: info: ------------------------------ 8ms Nov 01 23:21:47 rivo volumio[3109]: info: ------------------------------ 6ms Nov 01 23:21:47 rivo volumio[3109]: info: Executing endpoint getStreamUrltidal Nov 01 23:21:47 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 01 23:21:48 rivo volumio[3109]: info: getStreamUrl took 807 milliseconds Nov 01 23:21:48 rivo volumio[3109]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEidiMWYwY2ZlODY0ZjE3NGEwM2FhNzViNWQxMmIxNTNkM182MS5tcDQ/0.flac?Expires=1730506908&Signature=Wc--mREOADvOdB-9xGynw-q7rlBfGq4SQU~9i2fWMhBeaocyip3DqtYVjGfLTgRO~z91WA-e36vUaYg9shGrdOwT8La8xDXX8nawiEnbm13NRrq4Q8DzVvrtYzGSPUKhtuvwTIsnZgd10a9Asxw7wX71knC3bWc-~gm3eRepPqmlkTiLxhSMuMjC86flq60B8~bxpaFRswy1xDl1g6pLMS6H~K7K8g2gfZd0ia1aX03vuLO7Sehsf6BJhGrHCQWdBcTTDSozKkWXGvicIGKHpC~PVEF-fpkePk9nB5mA9lBvUiwcr0IbKgsJgn5eKjCVtkqlFDX~IXzFNwZ2o9jjow__&Key-Pair-Id=K14LZCZ9QUI4JL Nov 01 23:21:49 rivo volumio[3109]: STREAMING PROXY: Response: 200, length: 65503272 Nov 01 23:21:49 rivo volumio[3109]: info: Nov 01 23:21:49 rivo volumio[3109]: ---------------------------- MPD announces state update: player Nov 01 23:21:49 rivo volumio[3109]: info: ControllerMpd::getState Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:21:49 rivo volumio[3109]: info: Nov 01 23:21:49 rivo volumio[3109]: ---------------------------- MPD announces state update: player Nov 01 23:21:49 rivo volumio[3109]: info: ControllerMpd::getState Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:21:49 rivo volumio[3109]: info: Nov 01 23:21:49 rivo volumio[3109]: ---------------------------- MPD announces state update: player Nov 01 23:21:49 rivo volumio[3109]: info: ControllerMpd::getState Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:21:49 rivo volumio[3109]: info: Nov 01 23:21:49 rivo volumio[3109]: ---------------------------- MPD announces state update: player Nov 01 23:21:49 rivo volumio[3109]: info: ControllerMpd::getState Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:21:49 rivo volumio[3109]: info: FusionDsp - ---- read samplerate, raw: 352800,S32_LE,2,32 Nov 01 23:21:49 rivo volumio[3109]: info: FusionDsp - ---- read samplerate from file: 352800 Nov 01 23:21:49 rivo volumio[3109]: info: camilladsp stopping service pid 4307... Nov 01 23:21:49 rivo volumio[3109]: info: camilladsp service terminated, instance 1 Nov 01 23:21:49 rivo volumio[3109]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 01 23:21:49 rivo volumio[3109]: info: camilladsp service started and running in background, instance 1 Nov 01 23:21:49 rivo volumio[3109]: info: FusionDsp - ---- read samplerate, raw: 352800,S32_LE,2,32 Nov 01 23:21:49 rivo volumio[3109]: info: FusionDsp - ---- read samplerate from file: 352800 Nov 01 23:21:49 rivo volumio[3109]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 01 23:21:49 rivo volumio[3109]: info: Nov 01 23:21:49 rivo volumio[3109]: ---------------------------- MPD announces state update: player Nov 01 23:21:49 rivo volumio[3109]: info: sendMpdCommand status took 105 milliseconds Nov 01 23:21:49 rivo volumio[3109]: info: sendMpdCommand status took 103 milliseconds Nov 01 23:21:49 rivo volumio[3109]: info: ControllerMpd::getState Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:21:49 rivo volumio[3109]: info: sendMpdCommand status took 99 milliseconds Nov 01 23:21:49 rivo volumio[3109]: info: sendMpdCommand status took 94 milliseconds Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::parseState Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::parseState Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::parseState Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::parseState Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 23:21:49 rivo volumio[3109]: info: Nov 01 23:21:49 rivo volumio[3109]: ---------------------------- MPD announces state update: player Nov 01 23:21:49 rivo volumio[3109]: info: ControllerMpd::getState Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:21:49 rivo volumio[3109]: info: Nov 01 23:21:49 rivo volumio[3109]: ---------------------------- MPD announces state update: player Nov 01 23:21:49 rivo volumio[3109]: info: ControllerMpd::getState Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:21:49 rivo volumio[3109]: info: Nov 01 23:21:49 rivo volumio[3109]: ---------------------------- MPD announces state update: player Nov 01 23:21:49 rivo volumio[3109]: info: ControllerMpd::getState Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:21:49 rivo volumio[3109]: info: sendMpdCommand status took 37 milliseconds Nov 01 23:21:49 rivo volumio[3109]: info: sendMpdCommand playlistinfo took 34 milliseconds Nov 01 23:21:49 rivo volumio[3109]: info: sendMpdCommand playlistinfo took 34 milliseconds Nov 01 23:21:49 rivo volumio[3109]: info: sendMpdCommand playlistinfo took 33 milliseconds Nov 01 23:21:49 rivo volumio[3109]: info: sendMpdCommand playlistinfo took 32 milliseconds Nov 01 23:21:49 rivo volumio[3109]: info: sendMpdCommand status took 29 milliseconds Nov 01 23:21:49 rivo volumio[3109]: info: sendMpdCommand status took 15 milliseconds Nov 01 23:21:49 rivo volumio[3109]: info: sendMpdCommand status took 13 milliseconds Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::parseState Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::parseTrackInfo Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::parseState Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::parseState Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::parseState Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 23:21:49 rivo volumio[3109]: info: ControllerMpd::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::servicePushState Nov 01 23:21:49 rivo volumio[3109]: info: CorePlayQueue::getTrack 1 Nov 01 23:21:49 rivo volumio[3109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":637,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13362828&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/13362828&soundQuality=LOSSLESS","trackType":"tidal"} Nov 01 23:21:49 rivo volumio[3109]: verbose: CURRENT POSITION 1 Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::syncState stateService play Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::syncState currentStatus stop Nov 01 23:21:49 rivo volumio[3109]: info: ControllerMpd::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::servicePushState Nov 01 23:21:49 rivo volumio[3109]: info: CorePlayQueue::getTrack 1 Nov 01 23:21:49 rivo volumio[3109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":637,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13362828&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/13362828&soundQuality=LOSSLESS","trackType":"tidal"} Nov 01 23:21:49 rivo volumio[3109]: verbose: CURRENT POSITION 1 Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::syncState stateService play Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::syncState currentStatus play Nov 01 23:21:49 rivo volumio[3109]: info: Received an update from plugin. extracting info from payload Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::volumioPushState Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::volumioPushState Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output Nov 01 23:21:49 rivo volumio[3109]: info: ControllerMpd::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::servicePushState Nov 01 23:21:49 rivo volumio[3109]: info: CorePlayQueue::getTrack 1 Nov 01 23:21:49 rivo volumio[3109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":637,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13362828&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/13362828&soundQuality=LOSSLESS","trackType":"tidal"} Nov 01 23:21:49 rivo volumio[3109]: verbose: CURRENT POSITION 1 Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::syncState stateService play Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::syncState currentStatus play Nov 01 23:21:49 rivo volumio[3109]: info: Received an update from plugin. extracting info from payload Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::volumioPushState Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::volumioPushState Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output Nov 01 23:21:49 rivo volumio[3109]: info: ControllerMpd::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::servicePushState Nov 01 23:21:49 rivo volumio[3109]: info: CorePlayQueue::getTrack 1 Nov 01 23:21:49 rivo volumio[3109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":637,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13362828&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/13362828&soundQuality=LOSSLESS","trackType":"tidal"} Nov 01 23:21:49 rivo volumio[3109]: verbose: CURRENT POSITION 1 Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::syncState stateService play Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::syncState currentStatus play Nov 01 23:21:49 rivo volumio[3109]: info: Received an update from plugin. extracting info from payload Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::volumioPushState Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::volumioPushState Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output Nov 01 23:21:49 rivo volumio[3109]: info: ------------------------------ 232ms Nov 01 23:21:49 rivo volumio[3109]: info: ------------------------------ 281ms Nov 01 23:21:49 rivo volumio[3109]: info: ------------------------------ 278ms Nov 01 23:21:49 rivo volumio[3109]: info: ------------------------------ 274ms Nov 01 23:21:49 rivo volumio[3109]: error: FusionDsp - WebSocket error: [object Object] Nov 01 23:21:49 rivo volumio[3109]: info: camilladsp spawned new process with pid 4328, instance 1, run: true Nov 01 23:21:49 rivo volumio[3109]: info: sendMpdCommand playlistinfo took 188 milliseconds Nov 01 23:21:49 rivo volumio[3109]: info: sendMpdCommand playlistinfo took 183 milliseconds Nov 01 23:21:49 rivo volumio[3109]: info: sendMpdCommand playlistinfo took 183 milliseconds Nov 01 23:21:49 rivo volumio[3109]: info: sendMpdCommand playlistinfo took 183 milliseconds Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::parseTrackInfo Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::parseTrackInfo Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::parseTrackInfo Nov 01 23:21:49 rivo volumio[3109]: verbose: ControllerMpd::parseTrackInfo Nov 01 23:21:49 rivo volumio[3109]: info: ControllerMpd::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::servicePushState Nov 01 23:21:49 rivo volumio[3109]: info: CorePlayQueue::getTrack 1 Nov 01 23:21:49 rivo volumio[3109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":637,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13362828&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/13362828&soundQuality=LOSSLESS","trackType":"tidal"} Nov 01 23:21:49 rivo volumio[3109]: verbose: CURRENT POSITION 1 Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::syncState stateService play Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::syncState currentStatus play Nov 01 23:21:49 rivo volumio[3109]: info: Received an update from plugin. extracting info from payload Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::volumioPushState Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::volumioPushState Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output Nov 01 23:21:49 rivo volumio[3109]: info: ControllerMpd::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::servicePushState Nov 01 23:21:49 rivo volumio[3109]: info: CorePlayQueue::getTrack 1 Nov 01 23:21:49 rivo volumio[3109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":637,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13362828&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/13362828&soundQuality=LOSSLESS","trackType":"tidal"} Nov 01 23:21:49 rivo volumio[3109]: verbose: CURRENT POSITION 1 Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::syncState stateService play Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::syncState currentStatus play Nov 01 23:21:49 rivo volumio[3109]: info: Received an update from plugin. extracting info from payload Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::volumioPushState Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::volumioPushState Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output Nov 01 23:21:49 rivo volumio[3109]: info: ControllerMpd::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::servicePushState Nov 01 23:21:49 rivo volumio[3109]: info: CorePlayQueue::getTrack 1 Nov 01 23:21:49 rivo volumio[3109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":637,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13362828&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/13362828&soundQuality=LOSSLESS","trackType":"tidal"} Nov 01 23:21:49 rivo volumio[3109]: verbose: CURRENT POSITION 1 Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::syncState stateService play Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::syncState currentStatus play Nov 01 23:21:49 rivo volumio[3109]: info: Received an update from plugin. extracting info from payload Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::volumioPushState Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::volumioPushState Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output Nov 01 23:21:49 rivo volumio[3109]: info: ControllerMpd::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::servicePushState Nov 01 23:21:49 rivo volumio[3109]: info: CorePlayQueue::getTrack 1 Nov 01 23:21:49 rivo volumio[3109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":637,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13362828&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/13362828&soundQuality=LOSSLESS","trackType":"tidal"} Nov 01 23:21:49 rivo volumio[3109]: verbose: CURRENT POSITION 1 Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::syncState stateService play Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::syncState currentStatus play Nov 01 23:21:49 rivo volumio[3109]: info: Received an update from plugin. extracting info from payload Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::volumioPushState Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output Nov 01 23:21:49 rivo volumio[3109]: info: CoreStateMachine::pushState Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::volumioPushState Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:21:49 rivo volumio[3109]: info: MRS: Pushing multiroomSync output Nov 01 23:21:49 rivo volumio[3109]: info: ------------------------------ 416ms Nov 01 23:21:49 rivo volumio[3109]: info: ------------------------------ 403ms Nov 01 23:21:49 rivo volumio[3109]: info: ------------------------------ 390ms Nov 01 23:21:49 rivo volumio[3109]: info: ------------------------------ 388ms Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::volumioGetState Nov 01 23:21:49 rivo volumio[3109]: info: [LastFM] Current track has sufficient metadata: title (Balance Beam) and artist (Ralph Towner) passed on explicitly Nov 01 23:21:49 rivo volumio[3109]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Nov 01 23:21:49 rivo volumio[3109]: info: Signalling Playback active due to playback status change Nov 01 23:21:49 rivo volumio[3109]: info: Signalling Playback active due to playback status change Nov 01 23:21:49 rivo volumio[3109]: info: Signalling Playback active due to playback status change Nov 01 23:21:49 rivo volumio[3109]: info: Signalling Playback active due to playback status change Nov 01 23:21:49 rivo volumio[3109]: info: Signalling Playback active due to playback status change Nov 01 23:21:49 rivo volumio[3109]: info: Signalling Playback active due to playback status change Nov 01 23:21:49 rivo volumio[3109]: info: Signalling Playback active due to playback status change Nov 01 23:21:49 rivo volumio[3109]: info: Signalling Playback active due to playback status change Nov 01 23:21:49 rivo volumio[3109]: info: Signalling Playback active due to playback status change Nov 01 23:21:49 rivo volumio[3109]: info: Signalling Playback active due to playback status change Nov 01 23:21:49 rivo volumio[3109]: info: Signalling Playback active due to playback status change Nov 01 23:21:49 rivo volumio[3109]: info: Signalling Playback active due to playback status change Nov 01 23:21:49 rivo volumio[3109]: info: Signalling Playback active due to playback status change Nov 01 23:21:49 rivo volumio[3109]: info: Signalling Playback active due to playback status change Nov 01 23:21:49 rivo volumio[3109]: info: Updating RAAT Signal Path Nov 01 23:21:49 rivo volumio[3109]: info: Updating RAAT Signal Path Nov 01 23:21:49 rivo volumio[3109]: info: Updating RAAT Signal Path Nov 01 23:21:49 rivo volumio[3109]: info: Updating RAAT Signal Path Nov 01 23:21:49 rivo volumio[3109]: info: Updating RAAT Signal Path Nov 01 23:21:49 rivo volumio[3109]: info: Updating RAAT Signal Path Nov 01 23:21:49 rivo volumio[3109]: info: Updating RAAT Signal Path Nov 01 23:21:49 rivo volumio[3109]: info: Updating RAAT Signal Path Nov 01 23:21:49 rivo volumio[3109]: info: Updating RAAT Signal Path Nov 01 23:21:49 rivo volumio[3109]: info: Updating RAAT Signal Path Nov 01 23:21:49 rivo volumio[3109]: info: Updating RAAT Signal Path Nov 01 23:21:49 rivo volumio[3109]: info: Updating RAAT Signal Path Nov 01 23:21:49 rivo volumio[3109]: info: Updating RAAT Signal Path Nov 01 23:21:49 rivo volumio[3109]: info: Updating RAAT Signal Path Nov 01 23:21:49 rivo volumio[3109]: info: CoreCommandRouter::volumioGetState Nov 01 23:21:50 rivo volumio[3109]: info: camilladsp spawned new process with pid 4335, instance 1, run: true Nov 01 23:21:50 rivo volumio[3109]: info: MCU Signalled Playback Active Nov 01 23:21:51 rivo volumio[3109]: info: CoreCommandRouter::volumioGetState Nov 01 23:21:54 rivo volumio[3109]: info: Executing endpoint metavolumio Nov 01 23:21:54 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 23:21:54 rivo volumio[3109]: info: Executing endpoint metavolumio Nov 01 23:21:54 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 23:21:54 rivo volumio[3109]: info: Executing endpoint metavolumio Nov 01 23:21:54 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 23:21:55 rivo volumio[3109]: STREAMING PROXY: Client dropped request, destroying Nov 01 23:22:44 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: tidal , explodeUri Nov 01 23:22:45 rivo volumio[3109]: info: explodeTIDALUri took 223 milliseconds Nov 01 23:22:45 rivo volumio[3109]: info: Saving Cloud item Ralph Fisher. Sound And Shadowsha Nov 01 23:22:45 rivo volumio[3109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , saveCloudItem Nov 01 23:22:45 rivo volumio[3109]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 23:22:45 rivo volumio[3109]: Error: Reference.child failed: First argument was an invalid path = "/user_data/lkNoGGG8BcTuEOjVKmxPW9OLxPF3/myPlaylists/Ralph Fisher. Sound And Shadowsha". Paths must be non-empty strings and can't contain ".", "#", "$", "[", or "]" Nov 01 23:22:45 rivo volumio[3109]: at validatePathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1667:15) Nov 01 23:22:45 rivo volumio[3109]: at validateRootPathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1679:5) Nov 01 23:22:45 rivo volumio[3109]: at Reference.child (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:13737:17) Nov 01 23:22:45 rivo volumio[3109]: at Database.ref (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:15115:48) Nov 01 23:22:45 rivo volumio[3109]: at myVolumio.syncJSONToCloud (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:28817) Nov 01 23:22:45 rivo volumio[3109]: at myVolumio.saveCloudMyPlaylist (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:23840) Nov 01 23:22:45 rivo volumio[3109]: at myVolumio.saveCloudItem (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:18337) Nov 01 23:22:45 rivo volumio[3109]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Nov 01 23:22:45 rivo volumio[3109]: at PlaylistManager.saveJSONFile (/volumio/app/playlistManager.js:613:31) Nov 01 23:22:45 rivo volumio[3109]: at /volumio/app/playlistManager.js:588:20 Nov 01 23:22:45 rivo volumio[3109]: at /volumio/node_modules/jsonfile/index.js:46:5 Nov 01 23:22:45 rivo volumio[3109]: at /volumio/node_modules/graceful-fs/graceful-fs.js:123:16 Nov 01 23:22:45 rivo volumio[3109]: at FSReqCallback.readFileAfterClose [as oncomplete] (internal/fs/read_file_context.js:71:3) Nov 01 23:22:45 rivo volumio[3109]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 23:22:45 rivo sudo[4390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-01 23:21 Nov 01 23:22:45 rivo sudo[4390]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:47:06 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e08c3e210a64867536255c2503fb09"