-- Logs begin at Mon 2024-11-25 20:57:23 MST, end at Tue 2024-11-26 01:52:18 MST. -- Nov 26 01:51:09 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 26 01:51:09 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 01:51:09 musicbox volumio[4126]: info: Discovery: Getting this device information Nov 26 01:51:09 musicbox volumio[4126]: info: CoreCommandRouter::volumioGetState Nov 26 01:51:09 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 01:51:35 musicbox volumio[4126]: info: CorePlayQueue::getTrack 0 Nov 26 01:51:35 musicbox volumio[4126]: info: CorePlayQueue::getTrack 0 Nov 26 01:51:35 musicbox volumio[4126]: info: Prefetching next song Nov 26 01:51:35 musicbox volumio[4126]: verbose: ControllerMpd::sendMpdCommand addid "https://rr4---sn-naj7yn76.googlevideo.com/videoplayback?expire=1732632695&ei=F4xFZ679IIPDlu8PxPOfsA4&ip=67.186.248.123&id=o-AIjHfuprsuafIu7ouE-hjpK9SLBRPFF35dqw-oyvTH6M&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1732611095%2C&mh=P_&mm=31%2C26&mn=sn-naj7yn76%2Csn-n4v7snls&ms=au%2Conr&mv=m&mvi=4&pl=23&rms=au%2Cau&initcwndbps=2510000&bui=AQn3pFSiOMAZz6-rLbeT5CxY66c_jgkYhSHhyg5zuuXTq8oEm9MKetkIrrFVgtVrLhpj91gQsR9_klUm&spc=qtApAUFBRQgUHdoj-yvGDvBorQYF1Aa4DwnrL5kw8q83E04nAw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Kas7c4ca_VDBWQh7tTQ9dfIQ&rqh=1&gir=yes&clen=4053422&dur=215.161&lmt=1730710253351134&mt=1732610720&fvip=2&keepalive=yes&fexp=51326932%2C51335594&c=WEB_REMIX&sefc=1&txp=4532434&n=eTmxr5mXR001hw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAK4O9KrXlw2fApzyYd8Rgj7l2Bc58T68EXej70TLjK9iAiAHPZwdx10fKbp2rGpxcX9OpuBQKCXRqlilx2H29pKhGg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AGluJ3MwRQIhAPNIUCaTS86i6HvKy0t9we7LLWfEBw0Lf0BYSaYas6NLAiA_tOJ7otcSwUBTGmDTJKXJiBEc28dfRJDb-l_u407q3Q%3D%3D&cver=1.20211213.00.00&t.YouTube" Nov 26 01:51:35 musicbox volumio[4126]: info: Nov 26 01:51:35 musicbox volumio[4126]: ---------------------------- MPD announces system playlist update Nov 26 01:51:35 musicbox volumio[4126]: info: Ignoring MPD Status Update Nov 26 01:51:35 musicbox volumio[4126]: info: sendMpdCommand addid "https://rr4---sn-naj7yn76.googlevideo.com/videoplayback?expire=1732632695&ei=F4xFZ679IIPDlu8PxPOfsA4&ip=67.186.248.123&id=o-AIjHfuprsuafIu7ouE-hjpK9SLBRPFF35dqw-oyvTH6M&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1732611095%2C&mh=P_&mm=31%2C26&mn=sn-naj7yn76%2Csn-n4v7snls&ms=au%2Conr&mv=m&mvi=4&pl=23&rms=au%2Cau&initcwndbps=2510000&bui=AQn3pFSiOMAZz6-rLbeT5CxY66c_jgkYhSHhyg5zuuXTq8oEm9MKetkIrrFVgtVrLhpj91gQsR9_klUm&spc=qtApAUFBRQgUHdoj-yvGDvBorQYF1Aa4DwnrL5kw8q83E04nAw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Kas7c4ca_VDBWQh7tTQ9dfIQ&rqh=1&gir=yes&clen=4053422&dur=215.161&lmt=1730710253351134&mt=1732610720&fvip=2&keepalive=yes&fexp=51326932%2C51335594&c=WEB_REMIX&sefc=1&txp=4532434&n=eTmxr5mXR001hw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAK4O9KrXlw2fApzyYd8Rgj7l2Bc58T68EXej70TLjK9iAiAHPZwdx10fKbp2rGpxcX9OpuBQKCXRqlilx2H29pKhGg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AGluJ3MwRQIhAPNIUCaTS86i6HvKy0t9we7LLWfEBw0Lf0BYSaYas6NLAiA_tOJ7otcSwUBTGmDTJKXJiBEc28dfRJDb-l_u407q3Q%3D%3D&cver=1.20211213.00.00&t.YouTube" took 2 milliseconds Nov 26 01:51:35 musicbox volumio[4126]: verbose: MPD COMMAND [object Object] Nov 26 01:51:35 musicbox volumio[4126]: verbose: MPD COMMAND [object Object] Nov 26 01:51:35 musicbox volumio[4126]: info: Nov 26 01:51:35 musicbox volumio[4126]: ---------------------------- MPD announces system playlist update Nov 26 01:51:35 musicbox volumio[4126]: info: Ignoring MPD Status Update Nov 26 01:51:35 musicbox volumio[4126]: info: ------------------------------ 3ms Nov 26 01:51:35 musicbox volumio[4126]: info: [ytmusic-play] Prefetched and added track to MPD queue: Don't do Drugs Nov 26 01:51:35 musicbox volumio[4126]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 26 01:51:35 musicbox volumio[4126]: info: ------------------------------ 2ms Nov 26 01:51:35 musicbox volumio[4126]: info: sendMpdCommand consume 1 took 2 milliseconds Nov 26 01:51:38 musicbox volumio[4126]: info: Nov 26 01:51:38 musicbox volumio[4126]: ---------------------------- MPD announces system playlist update Nov 26 01:51:38 musicbox volumio[4126]: info: Ignoring MPD Status Update Nov 26 01:51:38 musicbox volumio[4126]: info: Nov 26 01:51:38 musicbox volumio[4126]: ---------------------------- MPD announces state update: player Nov 26 01:51:38 musicbox volumio[4126]: info: ControllerMpd::getState Nov 26 01:51:38 musicbox volumio[4126]: verbose: ControllerMpd::sendMpdCommand status Nov 26 01:51:38 musicbox volumio[4126]: info: ------------------------------ 2ms Nov 26 01:51:38 musicbox volumio[4126]: info: sendMpdCommand status took 2 milliseconds Nov 26 01:51:38 musicbox volumio[4126]: verbose: ControllerMpd::parseState Nov 26 01:51:38 musicbox volumio[4126]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 01:51:38 musicbox volumio[4126]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 26 01:51:38 musicbox volumio[4126]: verbose: ControllerMpd::parseTrackInfo Nov 26 01:51:38 musicbox volumio[4126]: info: ControllerMpd::pushState Nov 26 01:51:38 musicbox volumio[4126]: info: CoreCommandRouter::servicePushState Nov 26 01:51:38 musicbox volumio[4126]: info: CorePlayQueue::getTrack 0 Nov 26 01:51:38 musicbox volumio[4126]: verbose: STATE SERVICE {"status":"play","position":0,"seek":85,"duration":215,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't do Drugs","artist":"Eazy Mac & John Nonny","album":null,"uri":"https://rr4---sn-naj7yn76.googlevideo.com/videoplayback?expire=1732632695&ei=F4xFZ679IIPDlu8PxPOfsA4&ip=67.186.248.123&id=o-AIjHfuprsuafIu7ouE-hjpK9SLBRPFF35dqw-oyvTH6M&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1732611095%2C&mh=P_&mm=31%2C26&mn=sn-naj7yn76%2Csn-n4v7snls&ms=au%2Conr&mv=m&mvi=4&pl=23&rms=au%2Cau&initcwndbps=2510000&bui=AQn3pFSiOMAZz6-rLbeT5CxY66c_jgkYhSHhyg5zuuXTq8oEm9MKetkIrrFVgtVrLhpj91gQsR9_klUm&spc=qtApAUFBRQgUHdoj-yvGDvBorQYF1Aa4DwnrL5kw8q83E04nAw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Kas7c4ca_VDBWQh7tTQ9dfIQ&rqh=1&gir=yes&clen=4053422&dur=215.161&lmt=1730710253351134&mt=1732610720&fvip=2&keepalive=yes&fexp=51326932%2C51335594&c=WEB_REMIX&sefc=1&txp=4532434&n=eTmxr5mXR001hw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAK4O9KrXlw2fApzyYd8Rgj7l2Bc58T68EXej70TLjK9iAiAHPZwdx10fKbp2rGpxcX9OpuBQKCXRqlilx2H29pKhGg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AGluJ3MwRQIhAPNIUCaTS86i6HvKy0t9we7LLWfEBw0Lf0BYSaYas6NLAiA_tOJ7otcSwUBTGmDTJKXJiBEc28dfRJDb-l_u407q3Q%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Nov 26 01:51:38 musicbox volumio[4126]: verbose: CURRENT POSITION 0 Nov 26 01:51:38 musicbox volumio[4126]: info: CoreStateMachine::syncState stateService play Nov 26 01:51:38 musicbox volumio[4126]: info: CoreStateMachine::syncState currentStatus play Nov 26 01:51:38 musicbox volumio[4126]: info: Received an update from plugin. extracting info from payload Nov 26 01:51:38 musicbox volumio[4126]: info: CoreStateMachine::pushState Nov 26 01:51:38 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 01:51:38 musicbox volumio[4126]: info: CoreCommandRouter::volumioPushState Nov 26 01:51:38 musicbox volumio[4126]: info: MRS: Pushing multiroomSync output update for this device Nov 26 01:51:38 musicbox volumio[4126]: info: MRS: Pushing multiroomSync output Nov 26 01:51:38 musicbox volumio[4126]: info: CoreStateMachine::pushState Nov 26 01:51:38 musicbox volumio[4126]: info: CoreCommandRouter::volumioPushState Nov 26 01:51:38 musicbox volumio[4126]: info: MRS: Pushing multiroomSync output update for this device Nov 26 01:51:38 musicbox volumio[4126]: info: MRS: Pushing multiroomSync output Nov 26 01:51:38 musicbox volumio[4126]: info: ------------------------------ 21ms Nov 26 01:51:38 musicbox volumio[4126]: info: [ASDebug] CurState: play PrevState: play Nov 26 01:51:38 musicbox volumio[4126]: info: [ASDebug] CurState: play PrevState: play Nov 26 01:51:38 musicbox volumio[4126]: info: [GPIO_Control] Status changed: play Nov 26 01:51:38 musicbox volumio[4126]: info: [GPIO_Control] Status changed: play Nov 26 01:51:38 musicbox volumio[4126]: info: [GPIO_Control] Status changed: play Nov 26 01:51:39 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 26 01:51:39 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 01:51:39 musicbox volumio[4126]: info: Discovery: Getting this device information Nov 26 01:51:39 musicbox volumio[4126]: info: CoreCommandRouter::volumioGetState Nov 26 01:51:39 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 01:51:40 musicbox volumio[4126]: info: CoreStateMachine::startPlaybackTimer Nov 26 01:51:40 musicbox volumio[4126]: info: CorePlayQueue::getTrack 0 Nov 26 01:51:40 musicbox volumio[4126]: info: CoreCommandRouter::volumioGetVisibleSources Nov 26 01:51:40 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 01:51:40 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 26 01:51:40 musicbox volumio[4126]: info: CoreStateMachine::pushState Nov 26 01:51:40 musicbox volumio[4126]: info: CorePlayQueue::getTrack 0 Nov 26 01:51:40 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 01:51:40 musicbox volumio[4126]: info: CoreCommandRouter::volumioPushState Nov 26 01:51:40 musicbox volumio[4126]: info: MRS: Pushing multiroomSync output update for this device Nov 26 01:51:40 musicbox volumio[4126]: info: MRS: Pushing multiroomSync output Nov 26 01:51:40 musicbox volumio[4126]: info: CorePlayQueue::getTrack 0 Nov 26 01:51:40 musicbox volumio[4126]: info: [ASDebug] CurState: play PrevState: play Nov 26 01:51:40 musicbox volumio[4126]: info: [GPIO_Control] Status changed: play Nov 26 01:51:40 musicbox volumio[4126]: info: [GPIO_Control] Status changed: play Nov 26 01:52:09 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 26 01:52:09 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 01:52:09 musicbox volumio[4126]: info: Discovery: Getting this device information Nov 26 01:52:09 musicbox volumio[4126]: info: CoreCommandRouter::volumioGetState Nov 26 01:52:09 musicbox volumio[4126]: info: CorePlayQueue::getTrack 0 Nov 26 01:52:09 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 01:52:12 musicbox volumio[4126]: verbose: New Socket.io Connection to 192.168.68.51 from 192.168.68.50 UA: Mozilla/5.0 (Linux; Android 14; SM-S711U1 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.39 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Nov 26 01:52:12 musicbox volumio[4126]: info: CoreCommandRouter::volumioGetState Nov 26 01:52:12 musicbox volumio[4126]: info: CorePlayQueue::getTrack 0 Nov 26 01:52:12 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 26 01:52:12 musicbox volumio[4126]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 26 01:52:12 musicbox volumio[4126]: info: Listing playlists Nov 26 01:52:12 musicbox volumio[4126]: info: CoreCommandRouter::volumioGetVisibleSources Nov 26 01:52:12 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 01:52:12 musicbox volumio[4126]: info: CoreCommandRouter::volumioGetQueue Nov 26 01:52:12 musicbox volumio[4126]: info: CoreStateMachine::getQueue Nov 26 01:52:12 musicbox volumio[4126]: info: CorePlayQueue::getQueue Nov 26 01:52:17 musicbox volumio[4126]: verbose: New Socket.io Connection to 192.168.68.51 from 192.168.68.50 UA: Mozilla/5.0 (Linux; Android 14; SM-S711U1 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.39 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Nov 26 01:52:17 musicbox volumio[4126]: info: CoreCommandRouter::volumioGetState Nov 26 01:52:17 musicbox volumio[4126]: info: CorePlayQueue::getTrack 0 Nov 26 01:52:17 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 26 01:52:17 musicbox volumio[4126]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 26 01:52:17 musicbox volumio[4126]: info: Listing playlists Nov 26 01:52:17 musicbox volumio[4126]: info: CoreCommandRouter::volumioGetVisibleSources Nov 26 01:52:17 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 01:52:17 musicbox volumio[4126]: info: CoreCommandRouter::volumioGetQueue Nov 26 01:52:17 musicbox volumio[4126]: info: CoreStateMachine::getQueue Nov 26 01:52:17 musicbox volumio[4126]: info: CorePlayQueue::getQueue Nov 26 01:52:18 musicbox volumio[4126]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Nov 26 01:52:18 musicbox volumio[4126]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Nov 26 01:52:18 musicbox volumio[4126]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 01:52:18 musicbox volumio[4126]: TypeError: Cannot read property 'then' of undefined Nov 26 01:52:18 musicbox volumio[4126]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Nov 26 01:52:18 musicbox volumio[4126]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2274:30) Nov 26 01:52:18 musicbox volumio[4126]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1450:26) Nov 26 01:52:18 musicbox volumio[4126]: at Socket.emit (events.js:315:20) Nov 26 01:52:18 musicbox volumio[4126]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 26 01:52:18 musicbox volumio[4126]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 26 01:52:18 musicbox volumio[4126]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 01:52:18 musicbox sudo[17349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 01:51 Nov 26 01:52:18 musicbox sudo[17349]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"