-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2024-10-19 21:58:16 CEST. -- Oct 19 21:57:03 volumio2 ntpd[816]: 212.45.144.3 local addr 192.168.1.35 -> Oct 19 21:57:18 volumio2 sudo[2420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 21:57:18 volumio2 sudo[2420]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 21:57:18 volumio2 sudo[2420]: pam_unix(sudo:session): session closed for user root Oct 19 21:57:18 volumio2 sudo[2423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 21:57:18 volumio2 sudo[2423]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 21:57:18 volumio2 sudo[2423]: pam_unix(sudo:session): session closed for user root Oct 19 21:57:18 volumio2 volumio[1045]: verbose: New Socket.io Connection to 192.168.1.35 from 192.168.1.28 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 19 21:57:18 volumio2 volumio[1045]: info: CoreCommandRouter::volumioGetVisibleSources Oct 19 21:57:18 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 21:57:18 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 19 21:57:18 volumio2 volumio[1045]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 19 21:57:18 volumio2 volumio[1045]: info: CoreCommandRouter::volumioGetState Oct 19 21:57:18 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 19 21:57:18 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 19 21:57:18 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 19 21:57:18 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 19 21:57:19 volumio2 sudo[2426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 21:57:19 volumio2 sudo[2426]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 21:57:19 volumio2 sudo[2426]: pam_unix(sudo:session): session closed for user root Oct 19 21:57:19 volumio2 sudo[2429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 21:57:19 volumio2 sudo[2429]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 21:57:19 volumio2 sudo[2429]: pam_unix(sudo:session): session closed for user root Oct 19 21:57:19 volumio2 volumio[1045]: verbose: New Socket.io Connection to 192.168.1.35 from 192.168.1.28 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 19 21:57:19 volumio2 volumio[1045]: info: CoreCommandRouter::volumioGetState Oct 19 21:57:19 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 19 21:57:19 volumio2 volumio[1045]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 19 21:57:19 volumio2 volumio[1045]: info: CoreCommandRouter::volumioGetVisibleSources Oct 19 21:57:19 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 21:57:19 volumio2 volumio[1045]: info: Listing playlists Oct 19 21:57:19 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 19 21:57:19 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 19 21:57:19 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 19 21:57:19 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 19 21:57:21 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 19 21:57:21 volumio2 volumio[1045]: info: Received Get System Info Oct 19 21:57:21 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 21:57:21 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 21:57:21 volumio2 volumio[1045]: info: Discovery: Getting this device information Oct 19 21:57:21 volumio2 volumio[1045]: info: CoreCommandRouter::volumioGetState Oct 19 21:57:21 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 21:57:21 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 19 21:57:21 volumio2 volumio[1045]: info: Received Get System Info Oct 19 21:57:21 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 21:57:21 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 21:57:21 volumio2 volumio[1045]: info: Discovery: Getting this device information Oct 19 21:57:21 volumio2 volumio[1045]: info: CoreCommandRouter::volumioGetState Oct 19 21:57:21 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 21:57:22 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Oct 19 21:57:22 volumio2 volumio[1045]: info: CoreCommandRouter::volumioGetQueue Oct 19 21:57:22 volumio2 volumio[1045]: info: CoreStateMachine::getQueue Oct 19 21:57:22 volumio2 volumio[1045]: info: CorePlayQueue::getQueue Oct 19 21:57:23 volumio2 volumio[1045]: info: browseTIDALUri took 191 milliseconds Oct 19 21:57:23 volumio2 volumio[1045]: info: Preload queue cleared Oct 19 21:57:23 volumio2 volumio[1045]: info: Preload queue cleared Oct 19 21:57:25 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Oct 19 21:57:25 volumio2 volumio[1045]: info: browseTIDALUri took 335 milliseconds Oct 19 21:57:25 volumio2 volumio[1045]: info: Preload queue cleared Oct 19 21:57:27 volumio2 volumio[1045]: info: Preload queue cleared Oct 19 21:57:27 volumio2 volumio[1045]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 19 21:57:27 volumio2 volumio[1045]: info: CoreStateMachine::ClearQueue Oct 19 21:57:27 volumio2 volumio[1045]: info: CoreStateMachine::stop Oct 19 21:57:27 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:57:27 volumio2 volumio[1045]: info: CorePlayQueue::clearPlayQueue Oct 19 21:57:27 volumio2 volumio[1045]: info: CorePlayQueue::saveQueue Oct 19 21:57:27 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 19 21:57:27 volumio2 volumio[1045]: info: CoreStateMachine::addQueueItems Oct 19 21:57:27 volumio2 volumio[1045]: info: CorePlayQueue::addQueueItems Oct 19 21:57:27 volumio2 volumio[1045]: info: Preload queue cleared Oct 19 21:57:27 volumio2 volumio[1045]: info: Adding Item to queue: tidal://mymusic/mixes/002e88f928e9e69e723e91da1c9b2f Oct 19 21:57:27 volumio2 volumio[1045]: info: Exploding uri tidal://mymusic/mixes/002e88f928e9e69e723e91da1c9b2f in service tidal Oct 19 21:57:28 volumio2 volumio[1045]: info: explodeTIDALUri took 535 milliseconds Oct 19 21:57:28 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 19 21:57:28 volumio2 volumio[1045]: info: CorePlayQueue::saveQueue Oct 19 21:57:28 volumio2 volumio[1045]: info: CoreStateMachine::updateTrackBlock Oct 19 21:57:28 volumio2 volumio[1045]: info: CorePlayQueue::getTrackBlock Oct 19 21:57:28 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPlay Oct 19 21:57:28 volumio2 volumio[1045]: info: CoreStateMachine::play index 0 Oct 19 21:57:28 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:57:28 volumio2 volumio[1045]: info: CoreStateMachine::stop Oct 19 21:57:28 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:57:28 volumio2 volumio[1045]: info: CoreStateMachine::play index undefined Oct 19 21:57:28 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:57:28 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:57:28 volumio2 volumio[1045]: info: CoreStateMachine::startPlaybackTimer Oct 19 21:57:28 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:57:28 volumio2 volumio[1045]: info: [1729367848554] ControllerTidal::clearAddPlayTrack Oct 19 21:57:28 volumio2 volumio[1045]: info: Getting stream with soundQuality HI_RES Oct 19 21:57:28 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 21:57:28 volumio2 volumio[1045]: info: Oct 19 21:57:28 volumio2 volumio[1045]: ---------------------------- MPD announces state update: player Oct 19 21:57:28 volumio2 volumio[1045]: info: sendMpdCommand stop took 12 milliseconds Oct 19 21:57:28 volumio2 volumio[1045]: info: ControllerMpd::getState Oct 19 21:57:28 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 19 21:57:28 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 21:57:28 volumio2 volumio[1045]: info: Oct 19 21:57:28 volumio2 volumio[1045]: ---------------------------- MPD announces system playlist update Oct 19 21:57:28 volumio2 volumio[1045]: info: Ignoring MPD Status Update Oct 19 21:57:28 volumio2 volumio[1045]: info: sendMpdCommand status took 5 milliseconds Oct 19 21:57:28 volumio2 volumio[1045]: info: sendMpdCommand clear took 5 milliseconds Oct 19 21:57:28 volumio2 volumio[1045]: verbose: ControllerMpd::parseState Oct 19 21:57:28 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 21:57:28 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24385357&soundQuality=HI_RES" Oct 19 21:57:28 volumio2 volumio[1045]: error: updateQueue error: null Oct 19 21:57:28 volumio2 volumio[1045]: info: Oct 19 21:57:28 volumio2 volumio[1045]: ---------------------------- MPD announces system playlist update Oct 19 21:57:28 volumio2 volumio[1045]: info: Ignoring MPD Status Update Oct 19 21:57:28 volumio2 volumio[1045]: info: ------------------------------ 12ms Oct 19 21:57:28 volumio2 volumio[1045]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 19 21:57:28 volumio2 volumio[1045]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24385357&soundQuality=HI_RES" took 4 milliseconds Oct 19 21:57:28 volumio2 volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 19 21:57:28 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 21:57:28 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand play Oct 19 21:57:28 volumio2 volumio[1045]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 19 21:57:28 volumio2 volumio[1045]: info: ------------------------------ 19ms Oct 19 21:57:28 volumio2 volumio[1045]: info: ------------------------------ 7ms Oct 19 21:57:28 volumio2 volumio[1045]: info: sendMpdCommand play took 3 milliseconds Oct 19 21:57:28 volumio2 volumio[1045]: STREAMING PROXY: Handling url /?data=tidal://song/24385357&soundQuality=HI_RES Oct 19 21:57:28 volumio2 volumio[1045]: info: No valid Plugin REST Endpoint: getStreamUrltidal Oct 19 21:57:28 volumio2 volumio[1045]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl Oct 19 21:57:31 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Oct 19 21:57:31 volumio2 volumio[1045]: info: browseTIDALUri took 450 milliseconds Oct 19 21:57:31 volumio2 volumio[1045]: info: Preload queue cleared Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/24385357 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/40482043 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/5693554 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/254772783 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/201312642 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/24385352 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/2933887 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/14306374 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/1375869 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/3120863 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/2334197 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/13434396 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/233059505 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/64653829 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/24385347 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/252607281 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/11343638 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/68729532 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/193361834 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/249654 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/21651813 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/166477 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/253527460 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/55172078 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/6923070 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/622359 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/3873873 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/24385397 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/107426120 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/20767339 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/8520924 Oct 19 21:57:31 volumio2 volumio[1045]: info: Preloading song: tidal://song/1757136 Oct 19 21:57:31 volumio2 volumio[1045]: info: Exploding uri tidal://song/24385357 in service tidal Oct 19 21:57:31 volumio2 volumio[1045]: info: Exploding uri tidal://song/40482043 in service tidal Oct 19 21:57:31 volumio2 volumio[1045]: info: Exploding uri tidal://song/5693554 in service tidal Oct 19 21:57:31 volumio2 volumio[1045]: info: Exploding uri tidal://song/254772783 in service tidal Oct 19 21:57:31 volumio2 volumio[1045]: info: Exploding uri tidal://song/201312642 in service tidal Oct 19 21:57:31 volumio2 volumio[1045]: info: Exploding uri tidal://song/24385352 in service tidal Oct 19 21:57:31 volumio2 volumio[1045]: info: explodeTIDALUri took 236 milliseconds Oct 19 21:57:31 volumio2 volumio[1045]: info: explodeTIDALUri took 303 milliseconds Oct 19 21:57:31 volumio2 volumio[1045]: info: Exploding uri tidal://song/2933887 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/14306374 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 285 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/1375869 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 262 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 243 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/3120863 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/2334197 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/13434396 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 258 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 250 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/233059505 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 238 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/64653829 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 211 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 436 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/24385347 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 244 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/252607281 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 248 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/11343638 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/68729532 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/193361834 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 266 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/249654 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/21651813 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 422 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 227 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/166477 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 316 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/253527460 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 269 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 423 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 237 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/55172078 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 246 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/6923070 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 235 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/622359 in service tidal Oct 19 21:57:32 volumio2 volumio[1045]: info: explodeTIDALUri took 226 milliseconds Oct 19 21:57:32 volumio2 volumio[1045]: info: Exploding uri tidal://song/3873873 in service tidal Oct 19 21:57:33 volumio2 volumio[1045]: info: Exploding uri tidal://song/24385397 in service tidal Oct 19 21:57:33 volumio2 volumio[1045]: info: explodeTIDALUri took 248 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: info: Exploding uri tidal://song/107426120 in service tidal Oct 19 21:57:33 volumio2 volumio[1045]: info: Exploding uri tidal://song/20767339 in service tidal Oct 19 21:57:33 volumio2 volumio[1045]: info: explodeTIDALUri took 256 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: info: explodeTIDALUri took 400 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: info: Exploding uri tidal://song/8520924 in service tidal Oct 19 21:57:33 volumio2 volumio[1045]: info: explodeTIDALUri took 262 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: info: explodeTIDALUri took 247 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: info: Exploding uri tidal://song/1757136 in service tidal Oct 19 21:57:33 volumio2 volumio[1045]: info: explodeTIDALUri took 239 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: info: explodeTIDALUri took 227 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: info: explodeTIDALUri took 208 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: info: explodeTIDALUri took 206 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: info: Preload queue cleared Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreStateMachine::ClearQueue Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreStateMachine::stop Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:57:33 volumio2 volumio[1045]: info: CorePlayQueue::clearPlayQueue Oct 19 21:57:33 volumio2 volumio[1045]: info: CorePlayQueue::saveQueue Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreStateMachine::addQueueItems Oct 19 21:57:33 volumio2 volumio[1045]: info: CorePlayQueue::addQueueItems Oct 19 21:57:33 volumio2 volumio[1045]: info: Preload queue cleared Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/24385357 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/24385357 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/40482043 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/40482043 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/1375858 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/1375858 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/5693554 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/5693554 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/254772783 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/254772783 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/201312642 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/201312642 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/24385352 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/24385352 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/2933887 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/2933887 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/14306374 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/14306374 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/1375869 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/1375869 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/3120863 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/3120863 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/2334197 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/2334197 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/13434396 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/13434396 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/233059505 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/233059505 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/295164 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/295164 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/64653829 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/64653829 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/24385347 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/24385347 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/252607281 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/252607281 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/11343638 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/11343638 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/68729532 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/68729532 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/193361834 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/193361834 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/1496689 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/1496689 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/249654 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/249654 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/21651813 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/21651813 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/334676 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/334676 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/166477 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/166477 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/253527460 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/253527460 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/80333237 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/80333237 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/548235 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/548235 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/55172078 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/55172078 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/6923070 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/6923070 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/622359 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/622359 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/3873873 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/3873873 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/24385397 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/24385397 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/246156 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/246156 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/107426120 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/107426120 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/20767339 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/20767339 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/8520924 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/8520924 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/21651812 Oct 19 21:57:33 volumio2 volumio[1045]: info: Using cached record of: tidal://song/21651812 Oct 19 21:57:33 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/1757136 Oct 19 21:57:33 volumio2 volumio[1045]: info: Exploding uri tidal://song/1757136 in service tidal Oct 19 21:57:33 volumio2 volumio[1045]: info: explodeTIDALUri took 300 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: info: explodeTIDALUri took 127 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 19 21:57:33 volumio2 volumio[1045]: info: CorePlayQueue::saveQueue Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreStateMachine::updateTrackBlock Oct 19 21:57:33 volumio2 volumio[1045]: info: CorePlayQueue::getTrackBlock Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPlay Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreStateMachine::play index 0 Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreStateMachine::stop Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreStateMachine::play index undefined Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:57:33 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreStateMachine::startPlaybackTimer Oct 19 21:57:33 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:57:33 volumio2 volumio[1045]: info: [1729367853685] ControllerTidal::clearAddPlayTrack Oct 19 21:57:33 volumio2 volumio[1045]: info: Getting stream with soundQuality HI_RES Oct 19 21:57:33 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 21:57:33 volumio2 volumio[1045]: info: Oct 19 21:57:33 volumio2 volumio[1045]: ---------------------------- MPD announces state update: player Oct 19 21:57:33 volumio2 volumio[1045]: info: sendMpdCommand stop took 13 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: info: ControllerMpd::getState Oct 19 21:57:33 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 19 21:57:33 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 21:57:33 volumio2 volumio[1045]: info: Oct 19 21:57:33 volumio2 volumio[1045]: ---------------------------- MPD announces system playlist update Oct 19 21:57:33 volumio2 volumio[1045]: info: Ignoring MPD Status Update Oct 19 21:57:33 volumio2 volumio[1045]: info: sendMpdCommand status took 3 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: info: sendMpdCommand clear took 4 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: verbose: ControllerMpd::parseState Oct 19 21:57:33 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 21:57:33 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24385357&soundQuality=HI_RES" Oct 19 21:57:33 volumio2 volumio[1045]: error: updateQueue error: null Oct 19 21:57:33 volumio2 volumio[1045]: info: Oct 19 21:57:33 volumio2 volumio[1045]: ---------------------------- MPD announces system playlist update Oct 19 21:57:33 volumio2 volumio[1045]: info: Ignoring MPD Status Update Oct 19 21:57:33 volumio2 volumio[1045]: info: ------------------------------ 7ms Oct 19 21:57:33 volumio2 volumio[1045]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24385357&soundQuality=HI_RES" took 4 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 19 21:57:33 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 21:57:33 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand play Oct 19 21:57:33 volumio2 volumio[1045]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 19 21:57:33 volumio2 volumio[1045]: info: ------------------------------ 16ms Oct 19 21:57:33 volumio2 volumio[1045]: info: ------------------------------ 7ms Oct 19 21:57:33 volumio2 volumio[1045]: STREAMING PROXY: Handling url /?data=tidal://song/24385357&soundQuality=HI_RES Oct 19 21:57:33 volumio2 volumio[1045]: info: sendMpdCommand play took 4 milliseconds Oct 19 21:57:33 volumio2 volumio[1045]: info: No valid Plugin REST Endpoint: getStreamUrltidal Oct 19 21:57:33 volumio2 volumio[1045]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl Oct 19 21:57:35 volumio2 volumio[1045]: info: Preload queue cleared Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreStateMachine::ClearQueue Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreStateMachine::stop Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:57:35 volumio2 volumio[1045]: info: CorePlayQueue::clearPlayQueue Oct 19 21:57:35 volumio2 volumio[1045]: info: CorePlayQueue::saveQueue Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreStateMachine::addQueueItems Oct 19 21:57:35 volumio2 volumio[1045]: info: CorePlayQueue::addQueueItems Oct 19 21:57:35 volumio2 volumio[1045]: info: Preload queue cleared Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/24385357 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/24385357 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/40482043 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/40482043 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/1375858 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/1375858 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/5693554 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/5693554 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/254772783 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/254772783 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/201312642 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/201312642 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/24385352 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/24385352 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/2933887 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/2933887 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/14306374 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/14306374 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/1375869 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/1375869 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/3120863 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/3120863 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/2334197 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/2334197 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/13434396 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/13434396 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/233059505 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/233059505 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/295164 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/295164 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/64653829 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/64653829 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/24385347 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/24385347 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/252607281 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/252607281 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/11343638 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/11343638 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/68729532 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/68729532 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/193361834 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/193361834 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/1496689 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/1496689 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/249654 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/249654 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/21651813 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/21651813 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/334676 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/334676 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/166477 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/166477 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/253527460 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/253527460 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/80333237 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/80333237 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/548235 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/548235 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/55172078 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/55172078 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/6923070 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/6923070 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/622359 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/622359 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/3873873 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/3873873 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/24385397 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/24385397 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/246156 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/246156 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/107426120 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/107426120 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/20767339 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/20767339 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/8520924 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/8520924 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/21651812 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/21651812 Oct 19 21:57:35 volumio2 volumio[1045]: info: Adding Item to queue: tidal://song/1757136 Oct 19 21:57:35 volumio2 volumio[1045]: info: Using cached record of: tidal://song/1757136 Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 19 21:57:35 volumio2 volumio[1045]: info: CorePlayQueue::saveQueue Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreStateMachine::updateTrackBlock Oct 19 21:57:35 volumio2 volumio[1045]: info: CorePlayQueue::getTrackBlock Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPlay Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreStateMachine::play index 0 Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreStateMachine::stop Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreStateMachine::play index undefined Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:57:35 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreStateMachine::startPlaybackTimer Oct 19 21:57:35 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:57:35 volumio2 volumio[1045]: info: [1729367855777] ControllerTidal::clearAddPlayTrack Oct 19 21:57:35 volumio2 volumio[1045]: info: Getting stream with soundQuality HI_RES Oct 19 21:57:35 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 21:57:35 volumio2 volumio[1045]: info: Oct 19 21:57:35 volumio2 volumio[1045]: ---------------------------- MPD announces state update: player Oct 19 21:57:35 volumio2 volumio[1045]: info: sendMpdCommand stop took 18 milliseconds Oct 19 21:57:35 volumio2 volumio[1045]: info: ControllerMpd::getState Oct 19 21:57:35 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 19 21:57:35 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 21:57:35 volumio2 volumio[1045]: info: Oct 19 21:57:35 volumio2 volumio[1045]: ---------------------------- MPD announces system playlist update Oct 19 21:57:35 volumio2 volumio[1045]: info: Ignoring MPD Status Update Oct 19 21:57:35 volumio2 volumio[1045]: info: sendMpdCommand status took 10 milliseconds Oct 19 21:57:35 volumio2 volumio[1045]: info: sendMpdCommand clear took 11 milliseconds Oct 19 21:57:35 volumio2 volumio[1045]: verbose: ControllerMpd::parseState Oct 19 21:57:35 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 21:57:35 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24385357&soundQuality=HI_RES" Oct 19 21:57:35 volumio2 volumio[1045]: error: updateQueue error: null Oct 19 21:57:35 volumio2 volumio[1045]: info: Oct 19 21:57:35 volumio2 volumio[1045]: ---------------------------- MPD announces system playlist update Oct 19 21:57:35 volumio2 volumio[1045]: info: Ignoring MPD Status Update Oct 19 21:57:35 volumio2 volumio[1045]: info: ------------------------------ 5ms Oct 19 21:57:35 volumio2 volumio[1045]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 19 21:57:35 volumio2 volumio[1045]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24385357&soundQuality=HI_RES" took 3 milliseconds Oct 19 21:57:35 volumio2 volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 19 21:57:35 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 21:57:35 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand play Oct 19 21:57:35 volumio2 volumio[1045]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 19 21:57:35 volumio2 volumio[1045]: info: ------------------------------ 19ms Oct 19 21:57:35 volumio2 volumio[1045]: info: ------------------------------ 6ms Oct 19 21:57:35 volumio2 volumio[1045]: info: sendMpdCommand play took 3 milliseconds Oct 19 21:57:35 volumio2 volumio[1045]: STREAMING PROXY: Handling url /?data=tidal://song/24385357&soundQuality=HI_RES Oct 19 21:57:35 volumio2 volumio[1045]: info: No valid Plugin REST Endpoint: getStreamUrltidal Oct 19 21:57:35 volumio2 volumio[1045]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl Oct 19 21:57:37 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 19 21:57:37 volumio2 volumio[1045]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 19 21:57:37 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 19 21:57:37 volumio2 volumio[1045]: info: Received Get System Version Oct 19 21:57:37 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 19 21:57:37 volumio2 volumio[1045]: info: Received Get System Info Oct 19 21:57:37 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 21:57:37 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 21:57:37 volumio2 volumio[1045]: info: Discovery: Getting this device information Oct 19 21:57:37 volumio2 volumio[1045]: info: CoreCommandRouter::volumioGetState Oct 19 21:57:37 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 21:57:42 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Oct 19 21:57:42 volumio2 volumio[1045]: info: Playing Remote Device: http://192.168.1.35 Oct 19 21:57:42 volumio2 volumio[1045]: info: Oct 19 21:57:42 volumio2 volumio[1045]: ---------------------------- Client requests Volumio play Oct 19 21:57:42 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPlay Oct 19 21:57:42 volumio2 volumio[1045]: info: CoreStateMachine::play index undefined Oct 19 21:57:42 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:57:42 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:57:42 volumio2 volumio[1045]: info: CoreStateMachine::startPlaybackTimer Oct 19 21:57:42 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:57:42 volumio2 volumio[1045]: info: [1729367862685] ControllerTidal::clearAddPlayTrack Oct 19 21:57:42 volumio2 volumio[1045]: info: Getting stream with soundQuality HI_RES Oct 19 21:57:42 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 21:57:42 volumio2 volumio[1045]: info: Done playing: Oct 19 21:57:42 volumio2 volumio[1045]: info: Oct 19 21:57:42 volumio2 volumio[1045]: ---------------------------- MPD announces state update: player Oct 19 21:57:42 volumio2 volumio[1045]: info: sendMpdCommand stop took 6 milliseconds Oct 19 21:57:42 volumio2 volumio[1045]: info: ControllerMpd::getState Oct 19 21:57:42 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 19 21:57:42 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 21:57:42 volumio2 volumio[1045]: info: Oct 19 21:57:42 volumio2 volumio[1045]: ---------------------------- MPD announces system playlist update Oct 19 21:57:42 volumio2 volumio[1045]: info: Ignoring MPD Status Update Oct 19 21:57:42 volumio2 volumio[1045]: info: sendMpdCommand status took 3 milliseconds Oct 19 21:57:42 volumio2 volumio[1045]: info: sendMpdCommand clear took 3 milliseconds Oct 19 21:57:42 volumio2 volumio[1045]: verbose: ControllerMpd::parseState Oct 19 21:57:42 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 21:57:42 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24385357&soundQuality=HI_RES" Oct 19 21:57:42 volumio2 volumio[1045]: error: updateQueue error: null Oct 19 21:57:42 volumio2 volumio[1045]: info: Oct 19 21:57:42 volumio2 volumio[1045]: ---------------------------- MPD announces system playlist update Oct 19 21:57:42 volumio2 volumio[1045]: info: Ignoring MPD Status Update Oct 19 21:57:42 volumio2 volumio[1045]: info: ------------------------------ 4ms Oct 19 21:57:42 volumio2 volumio[1045]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 19 21:57:42 volumio2 volumio[1045]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24385357&soundQuality=HI_RES" took 3 milliseconds Oct 19 21:57:42 volumio2 volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 19 21:57:42 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 21:57:42 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand play Oct 19 21:57:42 volumio2 volumio[1045]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 19 21:57:42 volumio2 volumio[1045]: info: ------------------------------ 11ms Oct 19 21:57:42 volumio2 volumio[1045]: info: ------------------------------ 5ms Oct 19 21:57:42 volumio2 volumio[1045]: info: sendMpdCommand play took 3 milliseconds Oct 19 21:57:42 volumio2 volumio[1045]: STREAMING PROXY: Handling url /?data=tidal://song/24385357&soundQuality=HI_RES Oct 19 21:57:42 volumio2 volumio[1045]: info: No valid Plugin REST Endpoint: getStreamUrltidal Oct 19 21:57:42 volumio2 volumio[1045]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl Oct 19 21:57:45 volumio2 volumio[1045]: verbose: New Socket.io Connection to 192.168.1.35 from 192.168.1.28 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Oct 19 21:57:45 volumio2 volumio[1045]: info: CoreCommandRouter::volumioGetState Oct 19 21:57:45 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 19 21:57:45 volumio2 volumio[1045]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 19 21:57:45 volumio2 volumio[1045]: info: CoreCommandRouter::volumioGetVisibleSources Oct 19 21:57:45 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 21:57:45 volumio2 volumio[1045]: info: Listing playlists Oct 19 21:57:45 volumio2 volumio[1045]: info: CoreCommandRouter::volumioGetQueue Oct 19 21:57:45 volumio2 volumio[1045]: info: CoreStateMachine::getQueue Oct 19 21:57:45 volumio2 volumio[1045]: info: CorePlayQueue::getQueue Oct 19 21:57:58 volumio2 ntpd[816]: 93.94.88.51 local addr 192.168.1.35 -> Oct 19 21:57:58 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPlay Oct 19 21:57:58 volumio2 volumio[1045]: info: CoreStateMachine::play index undefined Oct 19 21:57:58 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:57:58 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:57:58 volumio2 volumio[1045]: info: CoreStateMachine::startPlaybackTimer Oct 19 21:57:58 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:57:58 volumio2 volumio[1045]: info: [1729367878943] ControllerTidal::clearAddPlayTrack Oct 19 21:57:58 volumio2 volumio[1045]: info: Getting stream with soundQuality HI_RES Oct 19 21:57:58 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 21:57:58 volumio2 volumio[1045]: info: sendMpdCommand stop took 2 milliseconds Oct 19 21:57:58 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 21:57:58 volumio2 volumio[1045]: info: Oct 19 21:57:58 volumio2 volumio[1045]: ---------------------------- MPD announces state update: player Oct 19 21:57:58 volumio2 volumio[1045]: info: Oct 19 21:57:58 volumio2 volumio[1045]: ---------------------------- MPD announces system playlist update Oct 19 21:57:58 volumio2 volumio[1045]: info: Ignoring MPD Status Update Oct 19 21:57:58 volumio2 volumio[1045]: info: ControllerMpd::getState Oct 19 21:57:58 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 19 21:57:58 volumio2 volumio[1045]: info: sendMpdCommand clear took 3 milliseconds Oct 19 21:57:58 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24385357&soundQuality=HI_RES" Oct 19 21:57:58 volumio2 volumio[1045]: error: updateQueue error: null Oct 19 21:57:58 volumio2 volumio[1045]: info: Oct 19 21:57:58 volumio2 volumio[1045]: ---------------------------- MPD announces system playlist update Oct 19 21:57:58 volumio2 volumio[1045]: info: Ignoring MPD Status Update Oct 19 21:57:58 volumio2 volumio[1045]: info: sendMpdCommand status took 3 milliseconds Oct 19 21:57:58 volumio2 volumio[1045]: info: ------------------------------ 3ms Oct 19 21:57:58 volumio2 volumio[1045]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24385357&soundQuality=HI_RES" took 3 milliseconds Oct 19 21:57:58 volumio2 volumio[1045]: verbose: ControllerMpd::parseState Oct 19 21:57:58 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 21:57:58 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand play Oct 19 21:57:58 volumio2 volumio[1045]: info: ControllerMpd::pushState Oct 19 21:57:58 volumio2 volumio[1045]: info: CoreCommandRouter::servicePushState Oct 19 21:57:58 volumio2 volumio[1045]: info: CoreStateMachine::pushState Oct 19 21:57:58 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 21:57:58 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 19 21:57:58 volumio2 volumio[1045]: info: MRS: Pushing multiroomSync output update for this device Oct 19 21:57:58 volumio2 volumio[1045]: info: MRS: Pushing multiroomSync output Oct 19 21:57:58 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:57:58 volumio2 volumio[1045]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 19 21:57:58 volumio2 volumio[1045]: verbose: CURRENT POSITION 0 Oct 19 21:57:58 volumio2 volumio[1045]: info: CoreStateMachine::syncState stateService stop Oct 19 21:57:58 volumio2 volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Oct 19 21:57:58 volumio2 volumio[1045]: info: CoreStateMachine::pushState Oct 19 21:57:58 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 19 21:57:58 volumio2 volumio[1045]: info: MRS: Pushing multiroomSync output update for this device Oct 19 21:57:58 volumio2 volumio[1045]: info: MRS: Pushing multiroomSync output Oct 19 21:57:58 volumio2 volumio[1045]: info: No code Oct 19 21:57:58 volumio2 volumio[1045]: info: CoreStateMachine::pushState Oct 19 21:57:58 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 19 21:57:58 volumio2 volumio[1045]: info: MRS: Pushing multiroomSync output update for this device Oct 19 21:57:58 volumio2 volumio[1045]: info: MRS: Pushing multiroomSync output Oct 19 21:57:58 volumio2 volumio[1045]: STREAMING PROXY: Handling url /?data=tidal://song/24385357&soundQuality=HI_RES Oct 19 21:57:59 volumio2 volumio[1045]: info: ------------------------------ 72ms Oct 19 21:57:59 volumio2 volumio[1045]: info: ------------------------------ 70ms Oct 19 21:57:59 volumio2 volumio[1045]: info: sendMpdCommand play took 68 milliseconds Oct 19 21:57:59 volumio2 volumio[1045]: info: PeppySpectrum ---peppyspectrum status Oct 19 21:57:59 volumio2 volumio[1045]: info: PeppySpectrum ---peppyspectrum status undefined Oct 19 21:57:59 volumio2 volumio[1045]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.URsyrEmnftdSieCJ36TAv4aWB2u2.70cd8995b514f1af1e290ae54309f460.state.status' Oct 19 21:57:59 volumio2 volumio[1045]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.URsyrEmnftdSieCJ36TAv4aWB2u2.70cd8995b514f1af1e290ae54309f460.state.status' Oct 19 21:57:59 volumio2 volumio[1045]: info: No valid Plugin REST Endpoint: getStreamUrltidal Oct 19 21:57:59 volumio2 volumio[1045]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl Oct 19 21:58:00 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPlay Oct 19 21:58:00 volumio2 volumio[1045]: info: CoreStateMachine::play index undefined Oct 19 21:58:00 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:58:00 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:58:00 volumio2 volumio[1045]: info: CoreStateMachine::startPlaybackTimer Oct 19 21:58:00 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:58:00 volumio2 volumio[1045]: info: [1729367880569] ControllerTidal::clearAddPlayTrack Oct 19 21:58:00 volumio2 volumio[1045]: info: Getting stream with soundQuality HI_RES Oct 19 21:58:00 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 21:58:00 volumio2 volumio[1045]: info: Oct 19 21:58:00 volumio2 volumio[1045]: ---------------------------- MPD announces state update: player Oct 19 21:58:00 volumio2 volumio[1045]: info: sendMpdCommand stop took 2 milliseconds Oct 19 21:58:00 volumio2 volumio[1045]: info: ControllerMpd::getState Oct 19 21:58:00 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 19 21:58:00 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 21:58:00 volumio2 volumio[1045]: info: Oct 19 21:58:00 volumio2 volumio[1045]: ---------------------------- MPD announces system playlist update Oct 19 21:58:00 volumio2 volumio[1045]: info: Ignoring MPD Status Update Oct 19 21:58:00 volumio2 volumio[1045]: info: sendMpdCommand status took 3 milliseconds Oct 19 21:58:00 volumio2 volumio[1045]: info: sendMpdCommand clear took 3 milliseconds Oct 19 21:58:00 volumio2 volumio[1045]: verbose: ControllerMpd::parseState Oct 19 21:58:00 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 21:58:00 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24385357&soundQuality=HI_RES" Oct 19 21:58:00 volumio2 volumio[1045]: error: updateQueue error: null Oct 19 21:58:00 volumio2 volumio[1045]: info: Oct 19 21:58:00 volumio2 volumio[1045]: ---------------------------- MPD announces system playlist update Oct 19 21:58:00 volumio2 volumio[1045]: info: Ignoring MPD Status Update Oct 19 21:58:00 volumio2 volumio[1045]: info: ------------------------------ 5ms Oct 19 21:58:00 volumio2 volumio[1045]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 19 21:58:00 volumio2 volumio[1045]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24385357&soundQuality=HI_RES" took 2 milliseconds Oct 19 21:58:00 volumio2 volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 19 21:58:00 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 21:58:00 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand play Oct 19 21:58:00 volumio2 volumio[1045]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 19 21:58:00 volumio2 volumio[1045]: info: ------------------------------ 11ms Oct 19 21:58:00 volumio2 volumio[1045]: STREAMING PROXY: Handling url /?data=tidal://song/24385357&soundQuality=HI_RES Oct 19 21:58:00 volumio2 volumio[1045]: info: ------------------------------ 6ms Oct 19 21:58:00 volumio2 volumio[1045]: info: sendMpdCommand play took 4 milliseconds Oct 19 21:58:00 volumio2 volumio[1045]: info: No valid Plugin REST Endpoint: getStreamUrltidal Oct 19 21:58:00 volumio2 volumio[1045]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl Oct 19 21:58:03 volumio2 volumio[1045]: info: Preload queue cleared Oct 19 21:58:03 volumio2 volumio[1045]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 19 21:58:03 volumio2 volumio[1045]: info: CoreStateMachine::ClearQueue Oct 19 21:58:03 volumio2 volumio[1045]: info: CoreStateMachine::stop Oct 19 21:58:03 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:58:03 volumio2 volumio[1045]: info: CorePlayQueue::clearPlayQueue Oct 19 21:58:03 volumio2 volumio[1045]: info: CorePlayQueue::saveQueue Oct 19 21:58:03 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 19 21:58:03 volumio2 volumio[1045]: info: CoreStateMachine::addQueueItems Oct 19 21:58:03 volumio2 volumio[1045]: info: CorePlayQueue::addQueueItems Oct 19 21:58:03 volumio2 volumio[1045]: info: Preload queue cleared Oct 19 21:58:03 volumio2 volumio[1045]: info: Adding Item to queue: tidal://mymusic/mixes/002e88f928e9e69e723e91da1c9b2f Oct 19 21:58:03 volumio2 volumio[1045]: info: Using cached record of: tidal://mymusic/mixes/002e88f928e9e69e723e91da1c9b2f Oct 19 21:58:03 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 19 21:58:03 volumio2 volumio[1045]: info: CorePlayQueue::saveQueue Oct 19 21:58:03 volumio2 volumio[1045]: info: CoreStateMachine::updateTrackBlock Oct 19 21:58:03 volumio2 volumio[1045]: info: CorePlayQueue::getTrackBlock Oct 19 21:58:03 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPlay Oct 19 21:58:03 volumio2 volumio[1045]: info: CoreStateMachine::play index 0 Oct 19 21:58:03 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:58:03 volumio2 volumio[1045]: info: CoreStateMachine::stop Oct 19 21:58:03 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:58:03 volumio2 volumio[1045]: info: CoreStateMachine::play index undefined Oct 19 21:58:03 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:58:03 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:58:03 volumio2 volumio[1045]: info: CoreStateMachine::startPlaybackTimer Oct 19 21:58:03 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:58:03 volumio2 volumio[1045]: info: [1729367883993] ControllerTidal::clearAddPlayTrack Oct 19 21:58:03 volumio2 volumio[1045]: info: Getting stream with soundQuality HI_RES Oct 19 21:58:03 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 21:58:04 volumio2 volumio[1045]: info: Oct 19 21:58:04 volumio2 volumio[1045]: ---------------------------- MPD announces state update: player Oct 19 21:58:04 volumio2 volumio[1045]: info: sendMpdCommand stop took 16 milliseconds Oct 19 21:58:04 volumio2 volumio[1045]: info: ControllerMpd::getState Oct 19 21:58:04 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 19 21:58:04 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 21:58:04 volumio2 volumio[1045]: info: Oct 19 21:58:04 volumio2 volumio[1045]: ---------------------------- MPD announces system playlist update Oct 19 21:58:04 volumio2 volumio[1045]: info: Ignoring MPD Status Update Oct 19 21:58:04 volumio2 volumio[1045]: info: sendMpdCommand status took 3 milliseconds Oct 19 21:58:04 volumio2 volumio[1045]: info: sendMpdCommand clear took 3 milliseconds Oct 19 21:58:04 volumio2 volumio[1045]: verbose: ControllerMpd::parseState Oct 19 21:58:04 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 21:58:04 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24385357&soundQuality=HI_RES" Oct 19 21:58:04 volumio2 volumio[1045]: error: updateQueue error: null Oct 19 21:58:04 volumio2 volumio[1045]: info: Oct 19 21:58:04 volumio2 volumio[1045]: ---------------------------- MPD announces system playlist update Oct 19 21:58:04 volumio2 volumio[1045]: info: Ignoring MPD Status Update Oct 19 21:58:04 volumio2 volumio[1045]: info: ------------------------------ 4ms Oct 19 21:58:04 volumio2 volumio[1045]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 19 21:58:04 volumio2 volumio[1045]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24385357&soundQuality=HI_RES" took 3 milliseconds Oct 19 21:58:04 volumio2 volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 19 21:58:04 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 21:58:04 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand play Oct 19 21:58:04 volumio2 volumio[1045]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 19 21:58:04 volumio2 volumio[1045]: info: ------------------------------ 11ms Oct 19 21:58:04 volumio2 volumio[1045]: STREAMING PROXY: Handling url /?data=tidal://song/24385357&soundQuality=HI_RES Oct 19 21:58:04 volumio2 volumio[1045]: info: ------------------------------ 6ms Oct 19 21:58:04 volumio2 volumio[1045]: info: sendMpdCommand play took 5 milliseconds Oct 19 21:58:04 volumio2 volumio[1045]: info: No valid Plugin REST Endpoint: getStreamUrltidal Oct 19 21:58:04 volumio2 volumio[1045]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl Oct 19 21:58:10 volumio2 volumio[1045]: info: Preload queue cleared Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreStateMachine::ClearQueue Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreStateMachine::stop Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:58:10 volumio2 volumio[1045]: info: CorePlayQueue::clearPlayQueue Oct 19 21:58:10 volumio2 volumio[1045]: info: CorePlayQueue::saveQueue Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreStateMachine::addQueueItems Oct 19 21:58:10 volumio2 volumio[1045]: info: CorePlayQueue::addQueueItems Oct 19 21:58:10 volumio2 volumio[1045]: info: Preload queue cleared Oct 19 21:58:10 volumio2 volumio[1045]: info: Adding Item to queue: tidal://mymusic/mixes/002e88f928e9e69e723e91da1c9b2f Oct 19 21:58:10 volumio2 volumio[1045]: info: Using cached record of: tidal://mymusic/mixes/002e88f928e9e69e723e91da1c9b2f Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 19 21:58:10 volumio2 volumio[1045]: info: CorePlayQueue::saveQueue Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreStateMachine::updateTrackBlock Oct 19 21:58:10 volumio2 volumio[1045]: info: CorePlayQueue::getTrackBlock Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreCommandRouter::volumioPlay Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreStateMachine::play index 0 Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreStateMachine::stop Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreStateMachine::play index undefined Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 21:58:10 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreStateMachine::startPlaybackTimer Oct 19 21:58:10 volumio2 volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 19 21:58:10 volumio2 volumio[1045]: info: [1729367890306] ControllerTidal::clearAddPlayTrack Oct 19 21:58:10 volumio2 volumio[1045]: info: Getting stream with soundQuality HI_RES Oct 19 21:58:10 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 21:58:10 volumio2 volumio[1045]: info: Oct 19 21:58:10 volumio2 volumio[1045]: ---------------------------- MPD announces state update: player Oct 19 21:58:10 volumio2 volumio[1045]: info: sendMpdCommand stop took 16 milliseconds Oct 19 21:58:10 volumio2 volumio[1045]: info: ControllerMpd::getState Oct 19 21:58:10 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 19 21:58:10 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 21:58:10 volumio2 volumio[1045]: info: Oct 19 21:58:10 volumio2 volumio[1045]: ---------------------------- MPD announces system playlist update Oct 19 21:58:10 volumio2 volumio[1045]: info: Ignoring MPD Status Update Oct 19 21:58:10 volumio2 volumio[1045]: info: sendMpdCommand status took 3 milliseconds Oct 19 21:58:10 volumio2 volumio[1045]: info: sendMpdCommand clear took 4 milliseconds Oct 19 21:58:10 volumio2 volumio[1045]: verbose: ControllerMpd::parseState Oct 19 21:58:10 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 21:58:10 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24385357&soundQuality=HI_RES" Oct 19 21:58:10 volumio2 volumio[1045]: error: updateQueue error: null Oct 19 21:58:10 volumio2 volumio[1045]: info: Oct 19 21:58:10 volumio2 volumio[1045]: ---------------------------- MPD announces system playlist update Oct 19 21:58:10 volumio2 volumio[1045]: info: Ignoring MPD Status Update Oct 19 21:58:10 volumio2 volumio[1045]: info: ------------------------------ 8ms Oct 19 21:58:10 volumio2 volumio[1045]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 19 21:58:10 volumio2 volumio[1045]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24385357&soundQuality=HI_RES" took 6 milliseconds Oct 19 21:58:10 volumio2 volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 19 21:58:10 volumio2 volumio[1045]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 21:58:10 volumio2 volumio[1045]: verbose: ControllerMpd::sendMpdCommand play Oct 19 21:58:10 volumio2 volumio[1045]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 19 21:58:10 volumio2 volumio[1045]: info: ------------------------------ 18ms Oct 19 21:58:10 volumio2 volumio[1045]: STREAMING PROXY: Handling url /?data=tidal://song/24385357&soundQuality=HI_RES Oct 19 21:58:10 volumio2 volumio[1045]: info: ------------------------------ 9ms Oct 19 21:58:10 volumio2 volumio[1045]: info: sendMpdCommand play took 5 milliseconds Oct 19 21:58:10 volumio2 volumio[1045]: info: No valid Plugin REST Endpoint: getStreamUrltidal Oct 19 21:58:10 volumio2 volumio[1045]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl Oct 19 21:58:14 volumio2 ntpd[816]: 31.14.133.122 local addr 192.168.1.35 -> Oct 19 21:58:16 volumio2 volumio[1045]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Oct 19 21:58:16 volumio2 volumio[1045]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Oct 19 21:58:16 volumio2 volumio[1045]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 21:58:16 volumio2 volumio[1045]: TypeError: Cannot read property 'then' of undefined Oct 19 21:58:16 volumio2 volumio[1045]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Oct 19 21:58:16 volumio2 volumio[1045]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2260:30) Oct 19 21:58:16 volumio2 volumio[1045]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1442:26) Oct 19 21:58:16 volumio2 volumio[1045]: at Socket.emit (events.js:315:20) Oct 19 21:58:16 volumio2 volumio[1045]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 19 21:58:16 volumio2 volumio[1045]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 19 21:58:16 volumio2 volumio[1045]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 21:58:16 volumio2 sudo[2485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-19 21:57 Oct 19 21:58:16 volumio2 sudo[2485]: 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"