-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Fri 2026-02-13 06:35:53 EET. -- Feb 13 06:34:08 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: smart_inputs , handleBrowseUri Feb 13 06:34:08 media volumio[878]: info: CoreCommandRouter::volumioStop Feb 13 06:34:08 media volumio[878]: info: CoreStateMachine::stop Feb 13 06:34:08 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:08 media volumio[878]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Feb 13 06:34:08 media volumio[878]: info: CoreCommandRouter::volumioGetQueue Feb 13 06:34:08 media volumio[878]: info: CoreStateMachine::getQueue Feb 13 06:34:08 media volumio[878]: info: CorePlayQueue::getQueue Feb 13 06:34:08 media volumio[878]: info: Listing playlists Feb 13 06:34:08 media volumio[878]: info: Enabling Audio Input 5 Feb 13 06:34:08 media sudo[27570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart audioinput.service Feb 13 06:34:08 media sudo[27570]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 13 06:34:08 media systemd[1]: Started Audio Input Service. Feb 13 06:34:08 media sudo[27570]: pam_unix(sudo:session): session closed for user root Feb 13 06:34:08 media volumio[878]: info: Successfully Started Audio Input Feb 13 06:34:08 media volumio[878]: info: Notifying AUDIO INPUT Feb 13 06:34:08 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:08 media volumio[878]: info: CoreCommandRouter::servicePushState Feb 13 06:34:08 media volumio[878]: info: CoreStateMachine::pushState Feb 13 06:34:08 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 06:34:08 media volumio[878]: info: CoreCommandRouter::volumioPushState Feb 13 06:34:08 media volumio[878]: info: MRS: Pushing multiroomSync output update for this device Feb 13 06:34:08 media volumio[878]: info: MRS: Pushing multiroomSync output Feb 13 06:34:08 media volumio[878]: info: CoreCommandRouter::volumioGetState Feb 13 06:34:08 media volumio[878]: info: CoreCommandRouter::volumiosetSourceActiveaudio_input/5 Feb 13 06:34:08 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 06:34:08 media volumio[878]: Cannot find translation for source YouTube2 Feb 13 06:34:08 media volumio[878]: Cannot find translation for source 80s80s Radio Feb 13 06:34:08 media volumio[878]: Cannot find translation for source Volusonic Feb 13 06:34:08 media volumio[878]: Cannot find translation for source AB13X USB Audio Feb 13 06:34:09 media volumio[878]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Feb 13 06:34:09 media volumio[878]: info: FusionDsp - ---- read samplerate from file: 48000 Feb 13 06:34:09 media volumio[878]: info: camilladsp service terminated, instance 1 Feb 13 06:34:09 media volumio[878]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 13 06:34:09 media volumio[878]: info: camilladsp service started and running in background, instance 1 Feb 13 06:34:09 media volumio[878]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 13 06:34:09 media volumio[878]: info: CoreCommandRouter::servicePushState Feb 13 06:34:09 media volumio[878]: info: CoreStateMachine::pushState Feb 13 06:34:09 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 06:34:09 media volumio[878]: info: CoreCommandRouter::volumioPushState Feb 13 06:34:09 media volumio[878]: info: MRS: Pushing multiroomSync output update for this device Feb 13 06:34:09 media volumio[878]: info: MRS: Pushing multiroomSync output Feb 13 06:34:09 media volumio[878]: info: CoreCommandRouter::volumioGetState Feb 13 06:34:09 media volumio[878]: info: CoreCommandRouter::servicePushState Feb 13 06:34:09 media volumio[878]: info: CoreStateMachine::pushState Feb 13 06:34:09 media volumio[878]: info: CoreCommandRouter::volumioPushState Feb 13 06:34:09 media volumio[878]: info: MRS: Pushing multiroomSync output update for this device Feb 13 06:34:09 media volumio[878]: info: MRS: Pushing multiroomSync output Feb 13 06:34:09 media volumio[878]: info: CoreCommandRouter::volumioGetState Feb 13 06:34:10 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Feb 13 06:34:10 media volumio[878]: info: [youtube2-browse] browseUri: youtube2 Feb 13 06:34:10 media volumio[878]: info: [youtube2] InnertubeLoader: creating Innertube instance... Feb 13 06:34:21 media volumio[878]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Feb 13 06:34:21 media volumio[878]: info: [youtube2] InnertubeLoader: obtaining po_token by visitorData... Feb 13 06:34:22 media volumio[878]: info: [youtube2] InnertubeLoader: obtained po_token (expires in 43200 seconds) Feb 13 06:34:22 media volumio[878]: info: [youtube2] InnertubeLoader: re-create Innertube instance with po_token Feb 13 06:34:22 media volumio[878]: info: [youtube2] InnertubeLoader: creating Innertube instance with po_token... Feb 13 06:34:23 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: smart_inputs , handleBrowseUri Feb 13 06:34:23 media volumio[878]: info: Disabiling Audio Input 5 Feb 13 06:34:23 media volumio[878]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Feb 13 06:34:23 media sudo[28825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop audioinput.service Feb 13 06:34:23 media sudo[28825]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 13 06:34:23 media systemd[1]: Stopping Audio Input Service... Feb 13 06:34:23 media systemd[1]: audioinput.service: Main process exited, code=killed, status=15/TERM Feb 13 06:34:23 media volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 13 06:34:26 media volumio[878]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Feb 13 06:34:26 media volumio[878]: info: [youtube2] InnertubeLoader: going to refresh po_token in 43100 seconds Feb 13 06:34:26 media volumio[878]: info: Preload queue cleared Feb 13 06:34:26 media volumio[878]: info: Preload queue cleared Feb 13 06:34:29 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Feb 13 06:34:29 media volumio[878]: info: [youtube2-browse] browseUri: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC-9-kyTW8ZkZNDHQJ6FgpwQ%22%7D%7D Feb 13 06:34:30 media volumio[878]: info: Preload queue cleared Feb 13 06:34:30 media volumio[878]: info: Preload queue cleared Feb 13 06:34:30 media volumio[878]: info: Preload queue cleared Feb 13 06:34:30 media volumio[878]: info: Preload queue cleared Feb 13 06:34:30 media volumio[878]: info: Preload queue cleared Feb 13 06:34:30 media volumio[878]: info: Preload queue cleared Feb 13 06:34:30 media volumio[878]: info: Preload queue cleared Feb 13 06:34:30 media volumio[878]: info: Preload queue cleared Feb 13 06:34:32 media volumio[878]: info: Preload queue cleared Feb 13 06:34:32 media volumio[878]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 13 06:34:32 media volumio[878]: info: CoreStateMachine::ClearQueue Feb 13 06:34:32 media volumio[878]: info: CoreStateMachine::stop Feb 13 06:34:32 media volumio[878]: info: CoreStateMachine::serviceStop Feb 13 06:34:32 media volumio[878]: info: CoreCommandRouter::serviceStop Feb 13 06:34:32 media volumio[878]: info: INPUTS, stopping input: audio_input Feb 13 06:34:32 media volumio[878]: info: Disabiling Audio Input 5 Feb 13 06:34:32 media volumio[878]: info: CorePlayQueue::clearPlayQueue Feb 13 06:34:32 media volumio[878]: info: CorePlayQueue::saveQueue Feb 13 06:34:32 media volumio[878]: info: CoreCommandRouter::volumioPushQueue Feb 13 06:34:32 media volumio[878]: info: CoreStateMachine::addQueueItems Feb 13 06:34:32 media volumio[878]: info: CorePlayQueue::addQueueItems Feb 13 06:34:32 media volumio[878]: info: Preload queue cleared Feb 13 06:34:32 media volumio[878]: info: Adding Item to queue: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC-9-kyTW8ZkZNDHQJ6FgpwQ%22%7D%7D/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_k5n4srrEB1wgvIjPNTXS9G1ufE9WQxhnA%22%7D%7D%7D Feb 13 06:34:32 media volumio[878]: info: Exploding uri youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC-9-kyTW8ZkZNDHQJ6FgpwQ%22%7D%7D/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_k5n4srrEB1wgvIjPNTXS9G1ufE9WQxhnA%22%7D%7D%7D in service youtube2 Feb 13 06:34:32 media volumio[878]: info: [youtube2-browse] explodeUri: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC-9-kyTW8ZkZNDHQJ6FgpwQ%22%7D%7D/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_k5n4srrEB1wgvIjPNTXS9G1ufE9WQxhnA%22%7D%7D%7D Feb 13 06:34:32 media sudo[29533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop audioinput.service Feb 13 06:34:32 media sudo[29533]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 13 06:34:32 media volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 13 06:34:33 media volumio[878]: info: CoreCommandRouter::volumioPushQueue Feb 13 06:34:33 media volumio[878]: info: CorePlayQueue::saveQueue Feb 13 06:34:33 media volumio[878]: info: CoreStateMachine::updateTrackBlock Feb 13 06:34:33 media volumio[878]: info: CorePlayQueue::getTrackBlock Feb 13 06:34:33 media volumio[878]: info: CoreCommandRouter::volumioPlay Feb 13 06:34:33 media volumio[878]: verbose: UNSET VOLATILE: Service: smart_inputs Feb 13 06:34:33 media volumio[878]: info: Disabiling Audio Input 5 Feb 13 06:34:33 media volumio[878]: info: CoreStateMachine::play index 0 Feb 13 06:34:33 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:33 media volumio[878]: info: CoreStateMachine::stop Feb 13 06:34:33 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:33 media volumio[878]: info: CoreStateMachine::stPlaybackTimer Feb 13 06:34:33 media volumio[878]: info: CoreStateMachine::updateTrackBlock Feb 13 06:34:33 media volumio[878]: info: CorePlayQueue::getTrackBlock Feb 13 06:34:33 media volumio[878]: info: CoreStateMachine::pushState Feb 13 06:34:33 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:33 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 06:34:33 media volumio[878]: info: CoreCommandRouter::volumioPushState Feb 13 06:34:33 media sudo[29641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop audioinput.service Feb 13 06:34:33 media sudo[29641]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 13 06:34:33 media volumio[878]: info: MRS: Pushing multiroomSync output update for this device Feb 13 06:34:33 media volumio[878]: info: MRS: Pushing multiroomSync output Feb 13 06:34:33 media volumio[878]: info: CoreCommandRouter::volumioGetState Feb 13 06:34:33 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:33 media volumio[878]: info: CoreStateMachine::serviceStop Feb 13 06:34:33 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:33 media volumio[878]: info: CoreCommandRouter::serviceStop Feb 13 06:34:33 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 13 06:34:33 media volumio[878]: info: ControllerMpd::stop Feb 13 06:34:33 media volumio[878]: verbose: ControllerMpd::sendMpdCommand stop Feb 13 06:34:33 media volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 13 06:34:33 media volumio[878]: info: sendMpdCommand stop took 55 milliseconds Feb 13 06:34:33 media volumio[878]: info: CoreStateMachine::play index undefined Feb 13 06:34:33 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:33 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:33 media volumio[878]: info: CoreStateMachine::startPlaybackTimer Feb 13 06:34:33 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:33 media volumio[878]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22pvf_Qv4rmLM%22%2C%22playlistId%22%3A%22RDCLAK5uy_k5n4srrEB1wgvIjPNTXS9G1ufE9WQxhnA%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22J.%20Cole%20-%20Two%20Six%20(Official%20Music%20Video)%22%2C%22artist%22%3A%22J.%20Cole%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fpvf_Qv4rmLM%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAjXQa_kfUlbAwTWdNSvQW_SZvD-A%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22pvf_Qv4rmLM%22%2C%22playlistId%22%3A%22RDCLAK5uy_k5n4srrEB1wgvIjPNTXS9G1ufE9WQxhnA%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D%7D Feb 13 06:34:33 media volumio[878]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 13 06:34:33 media volumio[878]: error: [youtube2] Error in VideoModel.getInfo(pvf_Qv4rmLM): No valid URL to decipher Error: No valid URL to decipher Feb 13 06:34:33 media volumio[878]: at _Player.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:34251:13) Feb 13 06:34:33 media volumio[878]: at _Format.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:23105:19) Feb 13 06:34:33 media volumio[878]: at VideoModel._VideoModel_chooseFormat (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:86:39) Feb 13 06:34:33 media volumio[878]: at VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:69:116) Feb 13 06:34:33 media volumio[878]: at runMicrotasks () Feb 13 06:34:33 media volumio[878]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Feb 13 06:34:33 media volumio[878]: at async Function.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:167:19) Feb 13 06:34:33 media volumio[878]: at async PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:89:49) Feb 13 06:34:37 media volumio[878]: info: CoreCommandRouter::volumioPlay Feb 13 06:34:37 media volumio[878]: info: CoreStateMachine::play index undefined Feb 13 06:34:37 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:37 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:37 media volumio[878]: info: CoreStateMachine::startPlaybackTimer Feb 13 06:34:37 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:37 media volumio[878]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22pvf_Qv4rmLM%22%2C%22playlistId%22%3A%22RDCLAK5uy_k5n4srrEB1wgvIjPNTXS9G1ufE9WQxhnA%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22J.%20Cole%20-%20Two%20Six%20(Official%20Music%20Video)%22%2C%22artist%22%3A%22J.%20Cole%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fpvf_Qv4rmLM%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAjXQa_kfUlbAwTWdNSvQW_SZvD-A%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22pvf_Qv4rmLM%22%2C%22playlistId%22%3A%22RDCLAK5uy_k5n4srrEB1wgvIjPNTXS9G1ufE9WQxhnA%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D%7D Feb 13 06:34:37 media volumio[878]: error: [youtube2] Error in VideoModel.getInfo(pvf_Qv4rmLM): No valid URL to decipher Error: No valid URL to decipher Feb 13 06:34:37 media volumio[878]: at _Player.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:34251:13) Feb 13 06:34:37 media volumio[878]: at _Format.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:23105:19) Feb 13 06:34:37 media volumio[878]: at VideoModel._VideoModel_chooseFormat (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:86:39) Feb 13 06:34:37 media volumio[878]: at VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:69:116) Feb 13 06:34:37 media volumio[878]: at runMicrotasks () Feb 13 06:34:37 media volumio[878]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Feb 13 06:34:37 media volumio[878]: at async Function.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:167:19) Feb 13 06:34:37 media volumio[878]: at async PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:89:49) Feb 13 06:34:42 media volumio[878]: info: Preload queue cleared Feb 13 06:34:42 media volumio[878]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 13 06:34:42 media volumio[878]: info: CoreStateMachine::ClearQueue Feb 13 06:34:42 media volumio[878]: info: CoreStateMachine::stop Feb 13 06:34:42 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:42 media volumio[878]: info: CorePlayQueue::clearPlayQueue Feb 13 06:34:42 media volumio[878]: info: CorePlayQueue::saveQueue Feb 13 06:34:42 media volumio[878]: info: CoreCommandRouter::volumioPushQueue Feb 13 06:34:42 media volumio[878]: info: CoreStateMachine::addQueueItems Feb 13 06:34:42 media volumio[878]: info: CorePlayQueue::addQueueItems Feb 13 06:34:42 media volumio[878]: info: Preload queue cleared Feb 13 06:34:42 media volumio[878]: info: Adding Item to queue: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC-9-kyTW8ZkZNDHQJ6FgpwQ%22%7D%7D/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_k7O9KByATGA1TqFYhyOkylpJ6fM1avtww%22%7D%7D%7D Feb 13 06:34:42 media volumio[878]: info: Exploding uri youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC-9-kyTW8ZkZNDHQJ6FgpwQ%22%7D%7D/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_k7O9KByATGA1TqFYhyOkylpJ6fM1avtww%22%7D%7D%7D in service youtube2 Feb 13 06:34:42 media volumio[878]: info: [youtube2-browse] explodeUri: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC-9-kyTW8ZkZNDHQJ6FgpwQ%22%7D%7D/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_k7O9KByATGA1TqFYhyOkylpJ6fM1avtww%22%7D%7D%7D Feb 13 06:34:43 media volumio[878]: info: CoreCommandRouter::volumioPushQueue Feb 13 06:34:43 media volumio[878]: info: CorePlayQueue::saveQueue Feb 13 06:34:43 media volumio[878]: info: CoreStateMachine::updateTrackBlock Feb 13 06:34:43 media volumio[878]: info: CorePlayQueue::getTrackBlock Feb 13 06:34:43 media volumio[878]: info: CoreCommandRouter::volumioPlay Feb 13 06:34:43 media volumio[878]: info: CoreStateMachine::play index 0 Feb 13 06:34:43 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:43 media volumio[878]: info: CoreStateMachine::stop Feb 13 06:34:43 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:43 media volumio[878]: info: CoreStateMachine::play index undefined Feb 13 06:34:43 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:43 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:43 media volumio[878]: info: CoreStateMachine::startPlaybackTimer Feb 13 06:34:43 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:43 media volumio[878]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22pBZxDWMeuUc%22%2C%22playlistId%22%3A%22RDCLAK5uy_k7O9KByATGA1TqFYhyOkylpJ6fM1avtww%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22DABABY%20-%20POP%20DAT%20THING%20(Official%20Audio)%22%2C%22artist%22%3A%22DaBaby%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FpBZxDWMeuUc%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2CNACELwBSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gmAAtAFigIMCAAQARhLIGIoZTAP%26rs%3DAOn4CLAh1ottRFwsEwZNbiNzbMyf-pP-Bw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22pBZxDWMeuUc%22%2C%22playlistId%22%3A%22RDCLAK5uy_k7O9KByATGA1TqFYhyOkylpJ6fM1avtww%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D%7D Feb 13 06:34:43 media volumio[878]: error: [youtube2] Error in VideoModel.getInfo(pBZxDWMeuUc): No valid URL to decipher Error: No valid URL to decipher Feb 13 06:34:43 media volumio[878]: at _Player.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:34251:13) Feb 13 06:34:43 media volumio[878]: at _Format.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:23105:19) Feb 13 06:34:43 media volumio[878]: at VideoModel._VideoModel_chooseFormat (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:86:39) Feb 13 06:34:43 media volumio[878]: at VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:69:116) Feb 13 06:34:43 media volumio[878]: at runMicrotasks () Feb 13 06:34:43 media volumio[878]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Feb 13 06:34:43 media volumio[878]: at async Function.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:167:19) Feb 13 06:34:43 media volumio[878]: at async PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:89:49) Feb 13 06:34:44 media volumio[878]: info: Preload queue cleared Feb 13 06:34:44 media volumio[878]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::ClearQueue Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::stop Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:44 media volumio[878]: info: CorePlayQueue::clearPlayQueue Feb 13 06:34:44 media volumio[878]: info: CorePlayQueue::saveQueue Feb 13 06:34:44 media volumio[878]: info: CoreCommandRouter::volumioPushQueue Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::addQueueItems Feb 13 06:34:44 media volumio[878]: info: CorePlayQueue::addQueueItems Feb 13 06:34:44 media volumio[878]: info: Preload queue cleared Feb 13 06:34:44 media volumio[878]: info: Adding Item to queue: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC-9-kyTW8ZkZNDHQJ6FgpwQ%22%7D%7D/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_k7O9KByATGA1TqFYhyOkylpJ6fM1avtww%22%7D%7D%7D Feb 13 06:34:44 media volumio[878]: info: Using cached record of: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC-9-kyTW8ZkZNDHQJ6FgpwQ%22%7D%7D/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_k7O9KByATGA1TqFYhyOkylpJ6fM1avtww%22%7D%7D%7D Feb 13 06:34:44 media volumio[878]: info: CoreCommandRouter::volumioPushQueue Feb 13 06:34:44 media volumio[878]: info: CorePlayQueue::saveQueue Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::updateTrackBlock Feb 13 06:34:44 media volumio[878]: info: CorePlayQueue::getTrackBlock Feb 13 06:34:44 media volumio[878]: info: CoreCommandRouter::volumioPlay Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::play index 0 Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::stop Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::play index undefined Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:44 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::startPlaybackTimer Feb 13 06:34:44 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:44 media volumio[878]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22pBZxDWMeuUc%22%2C%22playlistId%22%3A%22RDCLAK5uy_k7O9KByATGA1TqFYhyOkylpJ6fM1avtww%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22DABABY%20-%20POP%20DAT%20THING%20(Official%20Audio)%22%2C%22artist%22%3A%22DaBaby%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FpBZxDWMeuUc%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2CNACELwBSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gmAAtAFigIMCAAQARhLIGIoZTAP%26rs%3DAOn4CLAh1ottRFwsEwZNbiNzbMyf-pP-Bw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22pBZxDWMeuUc%22%2C%22playlistId%22%3A%22RDCLAK5uy_k7O9KByATGA1TqFYhyOkylpJ6fM1avtww%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D%7D Feb 13 06:34:44 media volumio[878]: info: Preload queue cleared Feb 13 06:34:44 media volumio[878]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::ClearQueue Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::stop Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:44 media volumio[878]: info: CorePlayQueue::clearPlayQueue Feb 13 06:34:44 media volumio[878]: info: CorePlayQueue::saveQueue Feb 13 06:34:44 media volumio[878]: info: CoreCommandRouter::volumioPushQueue Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::addQueueItems Feb 13 06:34:44 media volumio[878]: info: CorePlayQueue::addQueueItems Feb 13 06:34:44 media volumio[878]: info: Preload queue cleared Feb 13 06:34:44 media volumio[878]: info: Adding Item to queue: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC-9-kyTW8ZkZNDHQJ6FgpwQ%22%7D%7D/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_k7O9KByATGA1TqFYhyOkylpJ6fM1avtww%22%7D%7D%7D Feb 13 06:34:44 media volumio[878]: info: Using cached record of: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC-9-kyTW8ZkZNDHQJ6FgpwQ%22%7D%7D/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_k7O9KByATGA1TqFYhyOkylpJ6fM1avtww%22%7D%7D%7D Feb 13 06:34:44 media volumio[878]: info: CoreCommandRouter::volumioPushQueue Feb 13 06:34:44 media volumio[878]: info: CorePlayQueue::saveQueue Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::updateTrackBlock Feb 13 06:34:44 media volumio[878]: info: CorePlayQueue::getTrackBlock Feb 13 06:34:44 media volumio[878]: info: CoreCommandRouter::volumioPlay Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::play index 0 Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::stop Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::play index undefined Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:44 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:44 media volumio[878]: info: CoreStateMachine::startPlaybackTimer Feb 13 06:34:44 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:44 media volumio[878]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22pBZxDWMeuUc%22%2C%22playlistId%22%3A%22RDCLAK5uy_k7O9KByATGA1TqFYhyOkylpJ6fM1avtww%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22DABABY%20-%20POP%20DAT%20THING%20(Official%20Audio)%22%2C%22artist%22%3A%22DaBaby%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FpBZxDWMeuUc%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2CNACELwBSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gmAAtAFigIMCAAQARhLIGIoZTAP%26rs%3DAOn4CLAh1ottRFwsEwZNbiNzbMyf-pP-Bw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22pBZxDWMeuUc%22%2C%22playlistId%22%3A%22RDCLAK5uy_k7O9KByATGA1TqFYhyOkylpJ6fM1avtww%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D%7D Feb 13 06:34:45 media volumio[878]: error: [youtube2] Error in VideoModel.getInfo(pBZxDWMeuUc): No valid URL to decipher Error: No valid URL to decipher Feb 13 06:34:45 media volumio[878]: at _Player.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:34251:13) Feb 13 06:34:45 media volumio[878]: at _Format.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:23105:19) Feb 13 06:34:45 media volumio[878]: at VideoModel._VideoModel_chooseFormat (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:86:39) Feb 13 06:34:45 media volumio[878]: at VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:69:116) Feb 13 06:34:45 media volumio[878]: at runMicrotasks () Feb 13 06:34:45 media volumio[878]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Feb 13 06:34:45 media volumio[878]: at async Function.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:167:19) Feb 13 06:34:45 media volumio[878]: at async PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:89:49) Feb 13 06:34:45 media volumio[878]: error: [youtube2] Error in VideoModel.getInfo(pBZxDWMeuUc): No valid URL to decipher Error: No valid URL to decipher Feb 13 06:34:45 media volumio[878]: at _Player.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:34251:13) Feb 13 06:34:45 media volumio[878]: at _Format.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:23105:19) Feb 13 06:34:45 media volumio[878]: at VideoModel._VideoModel_chooseFormat (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:86:39) Feb 13 06:34:45 media volumio[878]: at VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:69:116) Feb 13 06:34:45 media volumio[878]: at runMicrotasks () Feb 13 06:34:45 media volumio[878]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Feb 13 06:34:45 media volumio[878]: at async Function.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:167:19) Feb 13 06:34:45 media volumio[878]: at async PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:89:49) Feb 13 06:34:46 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Feb 13 06:34:46 media volumio[878]: info: [1770957286814] [80s80s] handleBrowseUri curUri: 80s80s Feb 13 06:34:46 media volumio[878]: info: Preload queue cleared Feb 13 06:34:49 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Feb 13 06:34:49 media volumio[878]: info: [1770957289557] [80s80s] handleBrowseUri curUri: 80s80s/nineties Feb 13 06:34:49 media volumio[878]: info: [1770957289558] [80s80s] getRadioContent url: nineties Feb 13 06:34:49 media volumio[878]: info: Preload queue cleared Feb 13 06:34:51 media volumio[878]: info: Preload queue cleared Feb 13 06:34:51 media volumio[878]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 13 06:34:51 media volumio[878]: info: CoreStateMachine::ClearQueue Feb 13 06:34:51 media volumio[878]: info: CoreStateMachine::stop Feb 13 06:34:51 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:51 media volumio[878]: info: CorePlayQueue::clearPlayQueue Feb 13 06:34:51 media volumio[878]: info: CorePlayQueue::saveQueue Feb 13 06:34:51 media volumio[878]: info: CoreCommandRouter::volumioPushQueue Feb 13 06:34:51 media volumio[878]: info: CoreStateMachine::addQueueItems Feb 13 06:34:51 media volumio[878]: info: CorePlayQueue::addQueueItems Feb 13 06:34:51 media volumio[878]: info: Preload queue cleared Feb 13 06:34:51 media volumio[878]: info: Adding Item to queue: webnineties/4 Feb 13 06:34:51 media volumio[878]: info: Exploding uri webnineties/4 in service 80s80s Feb 13 06:34:51 media volumio[878]: info: [1770957291845] [80s80s] explodeUri: webnineties/4 Feb 13 06:34:51 media volumio[878]: info: CoreCommandRouter::volumioPushQueue Feb 13 06:34:51 media volumio[878]: info: CorePlayQueue::saveQueue Feb 13 06:34:51 media volumio[878]: info: CoreStateMachine::updateTrackBlock Feb 13 06:34:51 media volumio[878]: info: CorePlayQueue::getTrackBlock Feb 13 06:34:51 media volumio[878]: info: CoreCommandRouter::volumioPlay Feb 13 06:34:51 media volumio[878]: info: CoreStateMachine::play index 0 Feb 13 06:34:51 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:51 media volumio[878]: info: CoreStateMachine::stop Feb 13 06:34:51 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:51 media volumio[878]: info: CoreStateMachine::play index undefined Feb 13 06:34:51 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:51 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:51 media volumio[878]: info: CoreStateMachine::startPlaybackTimer Feb 13 06:34:51 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:51 media volumio[878]: info: CoreCommandRouter::volumioGetVisibleSources Feb 13 06:34:51 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 06:34:51 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 13 06:34:51 media volumio[878]: info: [1770957291861] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/techno/mp3-192/volumio Feb 13 06:34:51 media volumio[878]: info: [1770957291861] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=261&count=2 Feb 13 06:34:52 media volumio[878]: info: [1770957292180] [80s80s] received new event containing 2 songs. Feb 13 06:34:52 media volumio[878]: verbose: ControllerMpd::sendMpdCommand stop Feb 13 06:34:52 media volumio[878]: info: sendMpdCommand stop took 2 milliseconds Feb 13 06:34:52 media volumio[878]: verbose: ControllerMpd::sendMpdCommand clear Feb 13 06:34:52 media volumio[878]: info: Feb 13 06:34:52 media volumio[878]: ---------------------------- MPD announces system playlist update Feb 13 06:34:52 media volumio[878]: info: Ignoring MPD Status Update Feb 13 06:34:52 media volumio[878]: info: sendMpdCommand clear took 3 milliseconds Feb 13 06:34:52 media volumio[878]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 13 06:34:52 media volumio[878]: error: updateQueue error: null Feb 13 06:34:52 media volumio[878]: info: Feb 13 06:34:52 media volumio[878]: ---------------------------- MPD announces state update: options Feb 13 06:34:52 media volumio[878]: info: ------------------------------ 5ms Feb 13 06:34:52 media volumio[878]: info: sendMpdCommand consume 1 took 3 milliseconds Feb 13 06:34:52 media volumio[878]: info: ControllerMpd::getState Feb 13 06:34:52 media volumio[878]: verbose: ControllerMpd::sendMpdCommand status Feb 13 06:34:52 media volumio[878]: info: [1770957292191] [80s80s] adding url: http://streams.90s90s.de/techno/mp3-192/volumio Feb 13 06:34:52 media volumio[878]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/techno/mp3-192/volumio" Feb 13 06:34:52 media volumio[878]: info: sendMpdCommand status took 9 milliseconds Feb 13 06:34:52 media volumio[878]: verbose: ControllerMpd::parseState Feb 13 06:34:52 media volumio[878]: info: ControllerMpd::pushState Feb 13 06:34:52 media volumio[878]: info: CoreCommandRouter::servicePushState Feb 13 06:34:52 media volumio[878]: info: CoreStateMachine::pushState Feb 13 06:34:52 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:52 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 06:34:52 media volumio[878]: info: CoreCommandRouter::volumioPushState Feb 13 06:34:52 media volumio[878]: info: MRS: Pushing multiroomSync output update for this device Feb 13 06:34:52 media volumio[878]: info: MRS: Pushing multiroomSync output Feb 13 06:34:52 media volumio[878]: info: CoreCommandRouter::volumioGetState Feb 13 06:34:52 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:52 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:52 media volumio[878]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 13 06:34:52 media volumio[878]: info: ------------------------------ 47ms Feb 13 06:34:52 media volumio[878]: info: Feb 13 06:34:52 media volumio[878]: ---------------------------- MPD announces system playlist update Feb 13 06:34:52 media volumio[878]: info: Ignoring MPD Status Update Feb 13 06:34:52 media volumio[878]: info: sendMpdCommand add "http://streams.90s90s.de/techno/mp3-192/volumio" took 46 milliseconds Feb 13 06:34:52 media volumio[878]: verbose: ControllerMpd::sendMpdCommand play Feb 13 06:34:52 media volumio[878]: info: ------------------------------ 27ms Feb 13 06:34:52 media volumio[878]: info: sendMpdCommand play took 16 milliseconds Feb 13 06:34:52 media volumio[878]: info: [1770957292265] [80s80s] Pushing the next song state: Junk Project - Junkhunter Feb 13 06:34:52 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:52 media volumio[878]: info: CoreCommandRouter::servicePushState Feb 13 06:34:52 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:52 media volumio[878]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music/v4/ea/b1/97/eab19726-6cef-49a8-37d3-ba516339ac1e/source/600x600bb.jpg","name":"Junk Project - Junkhunter","title":"Junkhunter","artist":"90s90s Techno","album":"","streaming":true,"disableUiControls":true,"duration":"407","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 13 06:34:52 media volumio[878]: verbose: CURRENT POSITION 0 Feb 13 06:34:52 media volumio[878]: info: CoreStateMachine::syncState stateService play Feb 13 06:34:52 media volumio[878]: info: CoreStateMachine::syncState currentStatus stop Feb 13 06:34:52 media volumio[878]: info: [1770957292267] [80s80s] PlayNextTrack API delay: 30 Feb 13 06:34:52 media volumio[878]: info: [1770957292282] [80s80s] Setting timer to: 407000 milliseconds. Feb 13 06:34:52 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:52 media volumio[878]: info: CorePlayQueue::getTrack 1 Feb 13 06:34:54 media volumio[878]: info: Feb 13 06:34:54 media volumio[878]: ---------------------------- MPD announces state update: player Feb 13 06:34:54 media volumio[878]: info: ControllerMpd::getState Feb 13 06:34:54 media volumio[878]: verbose: ControllerMpd::sendMpdCommand status Feb 13 06:34:54 media volumio[878]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Feb 13 06:34:54 media volumio[878]: info: FusionDsp - ---- read samplerate from file: 192000 Feb 13 06:34:54 media volumio[878]: info: camilladsp stopping service pid 32012... Feb 13 06:34:54 media volumio[878]: info: camilladsp service terminated, instance 1 Feb 13 06:34:54 media volumio[878]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 13 06:34:54 media volumio[878]: info: camilladsp service started and running in background, instance 1 Feb 13 06:34:54 media volumio[878]: info: Feb 13 06:34:54 media volumio[878]: ---------------------------- MPD announces state update: player Feb 13 06:34:54 media volumio[878]: info: sendMpdCommand status took 104 milliseconds Feb 13 06:34:54 media volumio[878]: info: ControllerMpd::getState Feb 13 06:34:54 media volumio[878]: verbose: ControllerMpd::sendMpdCommand status Feb 13 06:34:54 media volumio[878]: verbose: ControllerMpd::parseState Feb 13 06:34:54 media volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 13 06:34:54 media volumio[878]: info: Feb 13 06:34:54 media volumio[878]: ---------------------------- MPD announces system playlist update Feb 13 06:34:54 media volumio[878]: info: Ignoring MPD Status Update Feb 13 06:34:54 media volumio[878]: info: Feb 13 06:34:54 media volumio[878]: ---------------------------- MPD announces state update: player Feb 13 06:34:54 media volumio[878]: info: ControllerMpd::getState Feb 13 06:34:54 media volumio[878]: verbose: ControllerMpd::sendMpdCommand status Feb 13 06:34:54 media volumio[878]: info: sendMpdCommand status took 42 milliseconds Feb 13 06:34:54 media volumio[878]: info: sendMpdCommand playlistinfo took 43 milliseconds Feb 13 06:34:54 media volumio[878]: verbose: ControllerMpd::parseState Feb 13 06:34:54 media volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 13 06:34:54 media volumio[878]: verbose: ControllerMpd::parseTrackInfo Feb 13 06:34:54 media volumio[878]: info: ControllerMpd::pushState Feb 13 06:34:54 media volumio[878]: info: CoreCommandRouter::servicePushState Feb 13 06:34:54 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:54 media volumio[878]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 13 06:34:54 media volumio[878]: info: ------------------------------ 154ms Feb 13 06:34:54 media volumio[878]: info: ------------------------------ 6ms Feb 13 06:34:54 media volumio[878]: info: sendMpdCommand status took 6 milliseconds Feb 13 06:34:54 media volumio[878]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 13 06:34:54 media volumio[878]: verbose: ControllerMpd::parseState Feb 13 06:34:54 media volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 13 06:34:54 media volumio[878]: verbose: ControllerMpd::parseTrackInfo Feb 13 06:34:54 media volumio[878]: info: ControllerMpd::pushState Feb 13 06:34:54 media volumio[878]: info: CoreCommandRouter::servicePushState Feb 13 06:34:54 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:54 media volumio[878]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 13 06:34:54 media volumio[878]: info: ------------------------------ 51ms Feb 13 06:34:54 media volumio[878]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 13 06:34:54 media volumio[878]: verbose: ControllerMpd::parseTrackInfo Feb 13 06:34:54 media volumio[878]: info: ControllerMpd::pushState Feb 13 06:34:54 media volumio[878]: info: CoreCommandRouter::servicePushState Feb 13 06:34:54 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:34:54 media volumio[878]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 13 06:34:54 media volumio[878]: info: ------------------------------ 10ms Feb 13 06:34:55 media volumio[878]: info: CoreCommandRouter::volumioPlay Feb 13 06:34:55 media volumio[878]: info: CoreStateMachine::play index undefined Feb 13 06:34:55 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:34:55 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:35:04 media volumio[878]: info: CoreCommandRouter::volumioPlay Feb 13 06:35:04 media volumio[878]: info: CoreStateMachine::play index undefined Feb 13 06:35:04 media volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 13 06:35:04 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:35:23 media volumio[878]: info: VolumeController::SetAlsaVolume80 Feb 13 06:35:23 media volumio[878]: info: CoreStateMachine::pushState Feb 13 06:35:23 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:35:23 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 06:35:23 media volumio[878]: info: CoreCommandRouter::volumioPushState Feb 13 06:35:23 media volumio[878]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 13 06:35:23 media volumio[878]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 13 06:35:24 media volumio[878]: info: VolumeController::SetAlsaVolume85 Feb 13 06:35:24 media volumio[878]: info: CoreStateMachine::pushState Feb 13 06:35:24 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:35:24 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 06:35:24 media volumio[878]: info: CoreCommandRouter::volumioPushState Feb 13 06:35:24 media volumio[878]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 13 06:35:24 media volumio[878]: info: VolumeController::SetAlsaVolume95 Feb 13 06:35:24 media volumio[878]: info: CoreStateMachine::pushState Feb 13 06:35:24 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:35:24 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 06:35:24 media volumio[878]: info: CoreCommandRouter::volumioPushState Feb 13 06:35:24 media volumio[878]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 13 06:35:25 media volumio[878]: info: VolumeController::SetAlsaVolume100 Feb 13 06:35:25 media volumio[878]: info: CoreStateMachine::pushState Feb 13 06:35:25 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:35:25 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 06:35:25 media volumio[878]: info: CoreCommandRouter::volumioPushState Feb 13 06:35:25 media volumio[878]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 13 06:35:39 media volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 13 06:35:39 media volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 13 06:35:39 media volumio[878]: info: Retrieving Cloud Streaming UI Feb 13 06:35:39 media volumio[878]: info: Getting Tidal Cloud Configuration Feb 13 06:35:39 media volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 13 06:35:39 media volumio[878]: info: Getting Qobuz Cloud Configuration Feb 13 06:35:39 media volumio[878]: info: Asking plugin for UI Config Feb 13 06:35:39 media volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 13 06:35:39 media volumio[878]: info: Getting Spotify Cloud Configuration Feb 13 06:35:39 media volumio[878]: info: Asking plugin for UI Config Feb 13 06:35:39 media volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 13 06:35:39 media volumio[878]: info: Saving Spotify Acccount Feb 13 06:35:39 media volumio[878]: info: Got it Feb 13 06:35:39 media volumio[878]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 13 06:35:39 media volumio[878]: info: Got Tidal Cloud Configuration Feb 13 06:35:39 media volumio[878]: info: Got it Feb 13 06:35:39 media volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 13 06:35:39 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 13 06:35:39 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 13 06:35:39 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 06:35:39 media volumio[878]: info: CoreCommandRouter::volumioGetBrowseSources Feb 13 06:35:39 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 13 06:35:39 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 13 06:35:43 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 13 06:35:46 media volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 13 06:35:46 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 13 06:35:46 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 06:35:46 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 13 06:35:46 media volumio[878]: info: Getting Alsa Cards List without I2S DAC Feb 13 06:35:46 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 13 06:35:46 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 06:35:46 media volumio[878]: Invalid card number. Feb 13 06:35:46 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 06:35:46 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 06:35:46 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 06:35:46 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 06:35:46 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 06:35:46 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 06:35:46 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 06:35:46 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 13 06:35:46 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 13 06:35:53 media systemd[1]: audioinput.service: State 'stop-final-sigterm' timed out. Killing. Feb 13 06:35:53 media systemd[1]: audioinput.service: Killing process 27587 (sox) with signal SIGKILL. Feb 13 06:35:53 media systemd[1]: audioinput.service: Failed with result 'timeout'. Feb 13 06:35:53 media systemd[1]: Stopped Audio Input Service. Feb 13 06:35:53 media sudo[29641]: pam_unix(sudo:session): session closed for user root Feb 13 06:35:53 media sudo[28825]: pam_unix(sudo:session): session closed for user root Feb 13 06:35:53 media sudo[29533]: pam_unix(sudo:session): session closed for user root Feb 13 06:35:53 media volumio[878]: info: Successfully Stopped Audio Input Feb 13 06:35:53 media volumio[878]: info: Successfully Stopped Audio Input Feb 13 06:35:53 media volumio[878]: info: CoreCommandRouter::servicePushState Feb 13 06:35:53 media volumio[878]: info: CoreStateMachine::pushState Feb 13 06:35:53 media volumio[878]: info: CorePlayQueue::getTrack 0 Feb 13 06:35:53 media volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 06:35:53 media volumio[878]: info: CoreCommandRouter::volumioPushState Feb 13 06:35:53 media volumio[878]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 06:35:53 media volumio[878]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Feb 13 06:35:53 media volumio[878]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Feb 13 06:35:53 media volumio[878]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:182:10) Feb 13 06:35:53 media volumio[878]: at /volumio/app/index.js:326:81 Feb 13 06:35:53 media volumio[878]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Feb 13 06:35:53 media volumio[878]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Feb 13 06:35:53 media volumio[878]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Feb 13 06:35:53 media volumio[878]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Feb 13 06:35:53 media volumio[878]: at CoreStateMachine.syncState (/volumio/app/statemachine.js:599:10) Feb 13 06:35:53 media volumio[878]: at CoreCommandRouter.servicePushState (/volumio/app/index.js:414:28) Feb 13 06:35:53 media volumio[878]: at SmartInputs.pushMeta (/myvolumio/plugins/music_service/smart_inputs/smart_inputs_real:1:2606) Feb 13 06:35:53 media volumio[878]: at SmartInputs.clearInputs (/myvolumio/plugins/music_service/smart_inputs/smart_inputs_real:1:2527) Feb 13 06:35:53 media volumio[878]: at /myvolumio/plugins/music_service/smart_inputs/smart_inputs_real:1:7926 Feb 13 06:35:53 media volumio[878]: at ChildProcess.exithandler (child_process.js:299:7) Feb 13 06:35:53 media volumio[878]: at ChildProcess.emit (events.js:315:20) Feb 13 06:35:53 media volumio[878]: at maybeClose (internal/child_process.js:1048:16) Feb 13 06:35:53 media volumio[878]: at Socket. (internal/child_process.js:439:11) { Feb 13 06:35:53 media volumio[878]: code: 'NumberExpected' Feb 13 06:35:53 media volumio[878]: } Feb 13 06:35:53 media volumio[878]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 06:35:53 media sudo[7808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-13 06:34 Feb 13 06:35:53 media sudo[7808]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Feb 2026 06:35:23 PM CET" VOLUMIO_VERSION="3.909" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="517103fc19cd9a99450248ca5eb596b2"