-- Logs begin at Mon 2024-10-28 18:54:46 CET, end at Tue 2024-10-29 21:00:17 CET. -- Oct 29 20:59:08 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:08 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:08 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:13 volumio sudo[1452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 20:59:13 volumio sudo[1452]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 20:59:13 volumio sudo[1452]: pam_unix(sudo:session): session closed for user root Oct 29 20:59:13 volumio sudo[1454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:59:13 volumio sudo[1454]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 20:59:13 volumio sudo[1454]: pam_unix(sudo:session): session closed for user root Oct 29 20:59:13 volumio volumio[735]: verbose: New Socket.io Connection to 192.168.87.5 from 192.168.87.78 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: 7 Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:13 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:13 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:13 volumio volumio[735]: info: Received Get System Info Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 20:59:13 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:13 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:13 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:13 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:13 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::volumioGetVisibleSources Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:13 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:13 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:13 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:13 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 29 20:59:13 volumio volumio[735]: info: Received Get System Info Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 20:59:13 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:13 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:13 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:13 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:13 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:13 volumio volumio[735]: info: Listing playlists Oct 29 20:59:13 volumio volumio[735]: info: Received Get System Info Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 20:59:13 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:13 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:13 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:13 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:13 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 29 20:59:14 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:14 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:14 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:18 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:18 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:18 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:18 volumio volumio[735]: info: Listing playlists Oct 29 20:59:18 volumio volumio[735]: info: Listing playlists Oct 29 20:59:20 volumio sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 20:59:20 volumio sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 20:59:20 volumio sudo[1485]: pam_unix(sudo:session): session closed for user root Oct 29 20:59:20 volumio sudo[1487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:59:20 volumio sudo[1487]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 20:59:20 volumio sudo[1487]: pam_unix(sudo:session): session closed for user root Oct 29 20:59:20 volumio volumio[735]: verbose: New Socket.io Connection to 192.168.87.5 from 192.168.87.78 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: 7 Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:20 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:20 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:20 volumio volumio[735]: info: Received Get System Info Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 20:59:20 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:20 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:20 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:20 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:20 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:20 volumio volumio[735]: info: Received Get System Info Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 20:59:20 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:20 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:20 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:20 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:20 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::volumioGetVisibleSources Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:20 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:20 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:20 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:20 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 29 20:59:20 volumio volumio[735]: info: Received Get System Info Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 20:59:20 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:20 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:20 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:20 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:20 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:20 volumio volumio[735]: info: Listing playlists Oct 29 20:59:20 volumio volumio[735]: info: Received Get System Info Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 20:59:20 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:20 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:20 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:20 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:20 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:20 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 29 20:59:22 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 29 20:59:22 volumio volumio[735]: info: Received Get System Info Oct 29 20:59:22 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 20:59:22 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 20:59:22 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 20:59:22 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:22 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:22 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:22 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 20:59:23 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 29 20:59:25 volumio volumio[735]: debug: API:emitFavourites Oct 29 20:59:25 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , myVolumioLogout Oct 29 20:59:25 volumio volumio[735]: info: [MyVolumio PluginManager] Subscribed plan changed to free Oct 29 20:59:25 volumio volumio[735]: info: MYVOLUMIO LOGGED OUT Oct 29 20:59:25 volumio volumio[735]: info: Removing browser output: myVolumio user plan is not superstar Oct 29 20:59:25 volumio volumio[735]: info: Removing audio output: Oct 29 20:59:25 volumio volumio[735]: info: Stoppping Tunnel 1 Oct 29 20:59:25 volumio sudo[1503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 29 20:59:25 volumio sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 20:59:25 volumio sudo[1503]: pam_unix(sudo:session): session closed for user root Oct 29 20:59:25 volumio volumio[735]: info: Remote SSH Stopped Oct 29 20:59:27 volumio volumio[735]: info: MyVolumio not started Oct 29 20:59:27 volumio volumio[735]: info: Initializing device activation check Oct 29 20:59:27 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 29 20:59:27 volumio volumio[735]: info: Received Get System Info Oct 29 20:59:27 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 20:59:27 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 20:59:27 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 20:59:27 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:27 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:27 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:27 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 20:59:27 volumio volumio[735]: info: MyVolumio not started Oct 29 20:59:27 volumio volumio[735]: info: Initializing device activation check Oct 29 20:59:28 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:28 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:28 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:30 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 29 20:59:38 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:38 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:38 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:38 volumio volumio[735]: info: Listing playlists Oct 29 20:59:38 volumio volumio[735]: info: Listing playlists Oct 29 20:59:46 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:46 volumio volumio[735]: info: CorePlayQueue::getTrack 5 Oct 29 20:59:46 volumio volumio[735]: info: Prefetching next song Oct 29 20:59:48 volumio volumio[735]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-5aupcvm-j2ie.googlevideo.com/videoplayback?expire=1730253586&ei=sj4hZ-CRM6SRv_IP0paH0Qs&ip=188.228.55.16&id=o-AOQL29dEiKQ9lv689hE_AKo75A_cSZ75rS8QW8hWR5jp&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1730231986%2C&mh=pK&mm=31%2C29&mn=sn-5aupcvm-j2ie%2Csn-5goeenez&ms=au%2Crdu&mv=m&mvi=1&pl=22&rms=au%2Cau&gcr=dk&initcwndbps=2708750&siu=1&bui=AQn3pFRS1Zq6Y6UNDX7LmHlk0Z4ezzXBYzPWfrE_wlSn_AMiq8PYZELeynoanECxufjEGnThJQ&spc=qtApAUAaZGnhP28pR72iScBBI_MlFEF_EaOgXERiuItueB39SLWU8RoyWC5ZxNw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pjcWtUI4s2SlD3dFewPts6UQ&rqh=1&gir=yes&clen=5585874&dur=347.301&lmt=1714867437590430&mt=1730231360&fvip=3&keepalive=yes&fexp=51312688%2C51326932&c=WEB_REMIX&sefc=1&txp=1432434&n=b5NZKvKkmrDPSw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRgIhAM49tM6rRXlEwL1G0JtwHPb0tBSabebcg-TG3dcy9h27AiEA1H-9Fr0sBWt2nD2MSRz0pyrWkFlN_JpKRXI7fjDOHSA%3D&sig=AJfQdSswRAIgVUIZdRk4uxE7mL7nNQ9eH55ZP9kk5mUyOUcus_Xo28YCIDseHBJBhMwsLl36QIk6xYgEGHMAk5l1Jupw-c_HjNqu&cver=1.20211213.00.00&t.YouTube" Oct 29 20:59:48 volumio volumio[735]: info: Oct 29 20:59:48 volumio volumio[735]: ---------------------------- MPD announces system playlist update Oct 29 20:59:48 volumio volumio[735]: info: Ignoring MPD Status Update Oct 29 20:59:48 volumio volumio[735]: info: sendMpdCommand addid "https://rr1---sn-5aupcvm-j2ie.googlevideo.com/videoplayback?expire=1730253586&ei=sj4hZ-CRM6SRv_IP0paH0Qs&ip=188.228.55.16&id=o-AOQL29dEiKQ9lv689hE_AKo75A_cSZ75rS8QW8hWR5jp&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1730231986%2C&mh=pK&mm=31%2C29&mn=sn-5aupcvm-j2ie%2Csn-5goeenez&ms=au%2Crdu&mv=m&mvi=1&pl=22&rms=au%2Cau&gcr=dk&initcwndbps=2708750&siu=1&bui=AQn3pFRS1Zq6Y6UNDX7LmHlk0Z4ezzXBYzPWfrE_wlSn_AMiq8PYZELeynoanECxufjEGnThJQ&spc=qtApAUAaZGnhP28pR72iScBBI_MlFEF_EaOgXERiuItueB39SLWU8RoyWC5ZxNw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pjcWtUI4s2SlD3dFewPts6UQ&rqh=1&gir=yes&clen=5585874&dur=347.301&lmt=1714867437590430&mt=1730231360&fvip=3&keepalive=yes&fexp=51312688%2C51326932&c=WEB_REMIX&sefc=1&txp=1432434&n=b5NZKvKkmrDPSw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRgIhAM49tM6rRXlEwL1G0JtwHPb0tBSabebcg-TG3dcy9h27AiEA1H-9Fr0sBWt2nD2MSRz0pyrWkFlN_JpKRXI7fjDOHSA%3D&sig=AJfQdSswRAIgVUIZdRk4uxE7mL7nNQ9eH55ZP9kk5mUyOUcus_Xo28YCIDseHBJBhMwsLl36QIk6xYgEGHMAk5l1Jupw-c_HjNqu&cver=1.20211213.00.00&t.YouTube" took 5 milliseconds Oct 29 20:59:48 volumio volumio[735]: verbose: MPD COMMAND [object Object] Oct 29 20:59:48 volumio volumio[735]: info: Oct 29 20:59:48 volumio volumio[735]: ---------------------------- MPD announces system playlist update Oct 29 20:59:48 volumio volumio[735]: info: Ignoring MPD Status Update Oct 29 20:59:48 volumio volumio[735]: info: Oct 29 20:59:48 volumio volumio[735]: ---------------------------- MPD announces system playlist update Oct 29 20:59:48 volumio volumio[735]: info: Ignoring MPD Status Update Oct 29 20:59:48 volumio volumio[735]: info: Oct 29 20:59:48 volumio volumio[735]: ---------------------------- MPD announces system playlist update Oct 29 20:59:48 volumio volumio[735]: info: Ignoring MPD Status Update Oct 29 20:59:48 volumio volumio[735]: info: Oct 29 20:59:48 volumio volumio[735]: ---------------------------- MPD announces system playlist update Oct 29 20:59:48 volumio volumio[735]: info: Ignoring MPD Status Update Oct 29 20:59:48 volumio volumio[735]: info: Oct 29 20:59:48 volumio volumio[735]: ---------------------------- MPD announces system playlist update Oct 29 20:59:48 volumio volumio[735]: info: Ignoring MPD Status Update Oct 29 20:59:48 volumio volumio[735]: info: ------------------------------ 25ms Oct 29 20:59:48 volumio volumio[735]: info: ------------------------------ 21ms Oct 29 20:59:48 volumio volumio[735]: info: ------------------------------ 20ms Oct 29 20:59:48 volumio volumio[735]: info: ------------------------------ 12ms Oct 29 20:59:48 volumio volumio[735]: info: [ytmusic-play] Prefetched and added track to MPD queue: The Sea Oct 29 20:59:48 volumio volumio[735]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 29 20:59:48 volumio volumio[735]: info: ------------------------------ 15ms Oct 29 20:59:48 volumio volumio[735]: info: ------------------------------ 13ms Oct 29 20:59:48 volumio volumio[735]: info: sendMpdCommand consume 1 took 8 milliseconds Oct 29 20:59:48 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 29 20:59:48 volumio volumio[735]: info: MyVolumio login type: Token Oct 29 20:59:48 volumio volumio[735]: debug: API:emitFavourites Oct 29 20:59:48 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:48 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:48 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:49 volumio volumio[735]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 29 20:59:50 volumio volumio[735]: info: MyVolumio token set successfully Oct 29 20:59:50 volumio volumio[735]: info: MYVOLUMIO: Adding device Oct 29 20:59:50 volumio volumio[735]: info: MYVOLUMIO: Evaluating Server Oct 29 20:59:50 volumio volumio[735]: info: MyVolumio Plan changed: premium Oct 29 20:59:50 volumio volumio[735]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 29 20:59:50 volumio volumio[735]: info: Removing browser output: myVolumio user plan is not superstar Oct 29 20:59:50 volumio volumio[735]: info: Removing audio output: Oct 29 20:59:50 volumio volumio[735]: info: MYVOLUMIO: Adding device Oct 29 20:59:50 volumio volumio[735]: info: MYVOLUMIO: Evaluating Server Oct 29 20:59:50 volumio volumio[735]: info: Remote config written successfully Oct 29 20:59:50 volumio volumio[735]: info: Starting Tunnel 1 Oct 29 20:59:50 volumio volumio[735]: info: Starting Tunnel Connection Checker Oct 29 20:59:51 volumio volumio[735]: info: MYVolumio Device disabled Oct 29 20:59:51 volumio volumio[735]: info: Oct 29 20:59:51 volumio volumio[735]: ---------------------------- MPD announces system playlist update Oct 29 20:59:51 volumio volumio[735]: info: Ignoring MPD Status Update Oct 29 20:59:51 volumio volumio[735]: info: ControllerMpd::getState Oct 29 20:59:51 volumio volumio[735]: verbose: ControllerMpd::sendMpdCommand status Oct 29 20:59:51 volumio volumio[735]: info: Oct 29 20:59:51 volumio volumio[735]: ---------------------------- MPD announces state update: player Oct 29 20:59:51 volumio volumio[735]: info: ControllerMpd::getState Oct 29 20:59:51 volumio volumio[735]: verbose: ControllerMpd::sendMpdCommand status Oct 29 20:59:51 volumio volumio[735]: info: Oct 29 20:59:51 volumio volumio[735]: ---------------------------- MPD announces system playlist update Oct 29 20:59:51 volumio volumio[735]: info: Ignoring MPD Status Update Oct 29 20:59:51 volumio volumio[735]: info: Oct 29 20:59:51 volumio volumio[735]: ---------------------------- MPD announces state update: player Oct 29 20:59:51 volumio volumio[735]: info: ControllerMpd::getState Oct 29 20:59:51 volumio volumio[735]: verbose: ControllerMpd::sendMpdCommand status Oct 29 20:59:51 volumio volumio[735]: info: Oct 29 20:59:51 volumio volumio[735]: ---------------------------- MPD announces system playlist update Oct 29 20:59:51 volumio volumio[735]: info: Ignoring MPD Status Update Oct 29 20:59:51 volumio volumio[735]: info: Oct 29 20:59:51 volumio volumio[735]: ---------------------------- MPD announces state update: player Oct 29 20:59:51 volumio volumio[735]: info: ControllerMpd::getState Oct 29 20:59:51 volumio volumio[735]: verbose: ControllerMpd::sendMpdCommand status Oct 29 20:59:51 volumio volumio[735]: info: sendMpdCommand status took 16 milliseconds Oct 29 20:59:51 volumio volumio[735]: info: ------------------------------ 19ms Oct 29 20:59:51 volumio volumio[735]: info: sendMpdCommand status took 15 milliseconds Oct 29 20:59:51 volumio volumio[735]: info: ------------------------------ 14ms Oct 29 20:59:51 volumio volumio[735]: info: sendMpdCommand status took 13 milliseconds Oct 29 20:59:51 volumio volumio[735]: info: ------------------------------ 12ms Oct 29 20:59:51 volumio volumio[735]: info: sendMpdCommand status took 10 milliseconds Oct 29 20:59:51 volumio volumio[735]: verbose: ControllerMpd::parseState Oct 29 20:59:51 volumio volumio[735]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 20:59:51 volumio volumio[735]: verbose: ControllerMpd::parseState Oct 29 20:59:51 volumio volumio[735]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 20:59:51 volumio volumio[735]: verbose: ControllerMpd::parseState Oct 29 20:59:51 volumio volumio[735]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 20:59:51 volumio volumio[735]: verbose: ControllerMpd::parseState Oct 29 20:59:51 volumio volumio[735]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 20:59:51 volumio volumio[735]: info: sendMpdCommand playlistinfo took 17 milliseconds Oct 29 20:59:51 volumio volumio[735]: info: sendMpdCommand playlistinfo took 17 milliseconds Oct 29 20:59:51 volumio volumio[735]: verbose: ControllerMpd::parseTrackInfo Oct 29 20:59:51 volumio volumio[735]: verbose: ControllerMpd::parseTrackInfo Oct 29 20:59:51 volumio volumio[735]: info: ControllerMpd::pushState Oct 29 20:59:51 volumio volumio[735]: info: CoreCommandRouter::servicePushState Oct 29 20:59:51 volumio volumio[735]: debug: CoreStateMachine::syncState Oct 29 20:59:51 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:51 volumio volumio[735]: verbose: STATE SERVICE {"status":"play","position":0,"seek":431,"duration":347,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Sea","artist":"Morcheeba","album":"Big Calm","uri":"https://rr1---sn-5aupcvm-j2ie.googlevideo.com/videoplayback?expire=1730253586&ei=sj4hZ-CRM6SRv_IP0paH0Qs&ip=188.228.55.16&id=o-AOQL29dEiKQ9lv689hE_AKo75A_cSZ75rS8QW8hWR5jp&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1730231986%2C&mh=pK&mm=31%2C29&mn=sn-5aupcvm-j2ie%2Csn-5goeenez&ms=au%2Crdu&mv=m&mvi=1&pl=22&rms=au%2Cau&gcr=dk&initcwndbps=2708750&siu=1&bui=AQn3pFRS1Zq6Y6UNDX7LmHlk0Z4ezzXBYzPWfrE_wlSn_AMiq8PYZELeynoanECxufjEGnThJQ&spc=qtApAUAaZGnhP28pR72iScBBI_MlFEF_EaOgXERiuItueB39SLWU8RoyWC5ZxNw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pjcWtUI4s2SlD3dFewPts6UQ&rqh=1&gir=yes&clen=5585874&dur=347.301&lmt=1714867437590430&mt=1730231360&fvip=3&keepalive=yes&fexp=51312688%2C51326932&c=WEB_REMIX&sefc=1&txp=1432434&n=b5NZKvKkmrDPSw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRgIhAM49tM6rRXlEwL1G0JtwHPb0tBSabebcg-TG3dcy9h27AiEA1H-9Fr0sBWt2nD2MSRz0pyrWkFlN_JpKRXI7fjDOHSA%3D&sig=AJfQdSswRAIgVUIZdRk4uxE7mL7nNQ9eH55ZP9kk5mUyOUcus_Xo28YCIDseHBJBhMwsLl36QIk6xYgEGHMAk5l1Jupw-c_HjNqu&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Oct 29 20:59:51 volumio volumio[735]: verbose: CURRENT POSITION 4 Oct 29 20:59:51 volumio volumio[735]: info: CoreStateMachine::syncState stateService play Oct 29 20:59:51 volumio volumio[735]: info: CoreStateMachine::syncState currentStatus play Oct 29 20:59:51 volumio volumio[735]: info: Received an update from plugin. extracting info from payload Oct 29 20:59:51 volumio volumio[735]: info: CoreStateMachine::pushState Oct 29 20:59:51 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:51 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:51 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 20:59:51 volumio volumio[735]: info: CoreCommandRouter::volumioPushState Oct 29 20:59:51 volumio volumio[735]: debug: API:pushState Oct 29 20:59:51 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:51 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:51 volumio volumio[735]: info: CoreStateMachine::pushState Oct 29 20:59:51 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:51 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:51 volumio volumio[735]: info: CoreCommandRouter::volumioPushState Oct 29 20:59:51 volumio volumio[735]: debug: API:pushState Oct 29 20:59:51 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:51 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:51 volumio volumio[735]: info: ControllerMpd::pushState Oct 29 20:59:51 volumio volumio[735]: info: CoreCommandRouter::servicePushState Oct 29 20:59:51 volumio volumio[735]: debug: CoreStateMachine::syncState Oct 29 20:59:51 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:51 volumio volumio[735]: verbose: STATE SERVICE {"status":"play","position":0,"seek":431,"duration":347,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Sea","artist":"Morcheeba","album":"Big Calm","uri":"https://rr1---sn-5aupcvm-j2ie.googlevideo.com/videoplayback?expire=1730253586&ei=sj4hZ-CRM6SRv_IP0paH0Qs&ip=188.228.55.16&id=o-AOQL29dEiKQ9lv689hE_AKo75A_cSZ75rS8QW8hWR5jp&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1730231986%2C&mh=pK&mm=31%2C29&mn=sn-5aupcvm-j2ie%2Csn-5goeenez&ms=au%2Crdu&mv=m&mvi=1&pl=22&rms=au%2Cau&gcr=dk&initcwndbps=2708750&siu=1&bui=AQn3pFRS1Zq6Y6UNDX7LmHlk0Z4ezzXBYzPWfrE_wlSn_AMiq8PYZELeynoanECxufjEGnThJQ&spc=qtApAUAaZGnhP28pR72iScBBI_MlFEF_EaOgXERiuItueB39SLWU8RoyWC5ZxNw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pjcWtUI4s2SlD3dFewPts6UQ&rqh=1&gir=yes&clen=5585874&dur=347.301&lmt=1714867437590430&mt=1730231360&fvip=3&keepalive=yes&fexp=51312688%2C51326932&c=WEB_REMIX&sefc=1&txp=1432434&n=b5NZKvKkmrDPSw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRgIhAM49tM6rRXlEwL1G0JtwHPb0tBSabebcg-TG3dcy9h27AiEA1H-9Fr0sBWt2nD2MSRz0pyrWkFlN_JpKRXI7fjDOHSA%3D&sig=AJfQdSswRAIgVUIZdRk4uxE7mL7nNQ9eH55ZP9kk5mUyOUcus_Xo28YCIDseHBJBhMwsLl36QIk6xYgEGHMAk5l1Jupw-c_HjNqu&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Oct 29 20:59:51 volumio volumio[735]: verbose: CURRENT POSITION 4 Oct 29 20:59:51 volumio volumio[735]: info: CoreStateMachine::syncState stateService play Oct 29 20:59:51 volumio volumio[735]: info: CoreStateMachine::syncState currentStatus play Oct 29 20:59:51 volumio volumio[735]: info: Received an update from plugin. extracting info from payload Oct 29 20:59:51 volumio volumio[735]: info: CoreStateMachine::pushState Oct 29 20:59:51 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:51 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:51 volumio volumio[735]: info: CoreCommandRouter::volumioPushState Oct 29 20:59:51 volumio volumio[735]: debug: API:pushState Oct 29 20:59:51 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:51 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:51 volumio volumio[735]: info: CoreStateMachine::pushState Oct 29 20:59:51 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:51 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:51 volumio volumio[735]: info: CoreCommandRouter::volumioPushState Oct 29 20:59:51 volumio volumio[735]: debug: API:pushState Oct 29 20:59:51 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:51 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:51 volumio volumio[735]: info: ControllerMpd::pushState Oct 29 20:59:51 volumio volumio[735]: info: CoreCommandRouter::servicePushState Oct 29 20:59:51 volumio volumio[735]: debug: CoreStateMachine::syncState Oct 29 20:59:51 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:51 volumio volumio[735]: verbose: STATE SERVICE {"status":"play","position":0,"seek":431,"duration":347,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Sea","artist":"Morcheeba","album":"Big Calm","uri":"https://rr1---sn-5aupcvm-j2ie.googlevideo.com/videoplayback?expire=1730253586&ei=sj4hZ-CRM6SRv_IP0paH0Qs&ip=188.228.55.16&id=o-AOQL29dEiKQ9lv689hE_AKo75A_cSZ75rS8QW8hWR5jp&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1730231986%2C&mh=pK&mm=31%2C29&mn=sn-5aupcvm-j2ie%2Csn-5goeenez&ms=au%2Crdu&mv=m&mvi=1&pl=22&rms=au%2Cau&gcr=dk&initcwndbps=2708750&siu=1&bui=AQn3pFRS1Zq6Y6UNDX7LmHlk0Z4ezzXBYzPWfrE_wlSn_AMiq8PYZELeynoanECxufjEGnThJQ&spc=qtApAUAaZGnhP28pR72iScBBI_MlFEF_EaOgXERiuItueB39SLWU8RoyWC5ZxNw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pjcWtUI4s2SlD3dFewPts6UQ&rqh=1&gir=yes&clen=5585874&dur=347.301&lmt=1714867437590430&mt=1730231360&fvip=3&keepalive=yes&fexp=51312688%2C51326932&c=WEB_REMIX&sefc=1&txp=1432434&n=b5NZKvKkmrDPSw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRgIhAM49tM6rRXlEwL1G0JtwHPb0tBSabebcg-TG3dcy9h27AiEA1H-9Fr0sBWt2nD2MSRz0pyrWkFlN_JpKRXI7fjDOHSA%3D&sig=AJfQdSswRAIgVUIZdRk4uxE7mL7nNQ9eH55ZP9kk5mUyOUcus_Xo28YCIDseHBJBhMwsLl36QIk6xYgEGHMAk5l1Jupw-c_HjNqu&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Oct 29 20:59:51 volumio volumio[735]: verbose: CURRENT POSITION 4 Oct 29 20:59:51 volumio volumio[735]: info: CoreStateMachine::syncState stateService play Oct 29 20:59:51 volumio volumio[735]: info: CoreStateMachine::syncState currentStatus play Oct 29 20:59:51 volumio volumio[735]: info: Received an update from plugin. extracting info from payload Oct 29 20:59:51 volumio volumio[735]: info: CoreStateMachine::pushState Oct 29 20:59:51 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:51 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:51 volumio volumio[735]: info: CoreCommandRouter::volumioPushState Oct 29 20:59:51 volumio volumio[735]: debug: API:pushState Oct 29 20:59:51 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:51 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:51 volumio volumio[735]: info: CoreStateMachine::pushState Oct 29 20:59:51 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:51 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:51 volumio volumio[735]: info: CoreCommandRouter::volumioPushState Oct 29 20:59:51 volumio volumio[735]: debug: API:pushState Oct 29 20:59:51 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:51 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:51 volumio volumio[735]: info: ------------------------------ 113ms Oct 29 20:59:51 volumio volumio[735]: info: ------------------------------ 110ms Oct 29 20:59:51 volumio volumio[735]: info: ------------------------------ 108ms Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - State change Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - In validConnectionOptions Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - Valid connection found Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO-CONTROL: *********** ONKYO PLUGIN STATE CHANGE ******** Oct 29 20:59:51 volumio volumio[735]: info: ONKYO-CONTROL: New state: {"status":"play","position":4,"title":"Paradise Circus (feat. Hope Sandoval)","artist":"Massive Attack","album":"Heligoland","albumart":"https://lh3.googleusercontent.com/-6rEZPBgjiIyhWubHH99Lxis_fgxF6DAXwifCXFm0IAAn6pIXttB2mkY7LtT2RH3rb8MifrmMjoxaRA=w701-h701-l90-rj","uri":"ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Paradise%20Circus%20(feat.%20Hope%20Sandoval)%22%2C%22artist%22%3A%22Massive%20Attack%22%2C%22album%22%3A%22Heligoland%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F-6rEZPBgjiIyhWubHH99Lxis_fgxF6DAXwifCXFm0IAAn6pIXttB2mkY7LtT2RH3rb8MifrmMjoxaRA%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%226hUkyKBsGtQ%22%2C%22playlistId%22%3A%22RDAMVMyRhho0qhaW0%22%2C%22params%22%3A%22OAHyAQQIAXgBkgQjUVByNEM1cERMaGh1eEo1LTZPM0tyaG5zV0FleDlWNEZlU2_qBAt5UmhobzBxaGFXMA%253D%253D%22%2C%22index%22%3A4%2C%22playlistSetVideoId%22%3A%22090796A75D153932%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watchContinuation%22%2C%22payload%22%3A%7B%22token%22%3A%22CDISigESC2VVSkdGdjdBZ2RJIhFSREFNVk15UmhobzBxaGFXMDJQd0FFQjhnRUNlQUdTQkNOUlVISTBRelZ3UkV4b2FIVjRTalV0Tms4elMzSm9ibk5YUVdWNE9WWTBSbVZUYi1vRUMzbFNhR2h2TUhGb1lWY3c4MdABAfoBEEQ2MjVBQjQwMjk0RDM4MUQYCoIBFVBUOkVndGxWVXBIUm5ZM1FXZGtTUQ%253D%253D%22%2C%22playlistId%22%3A%22RDAMVMyRhho0qhaW0%22%2C%22params%22%3A%22OAHyAQQIAXgBkgQjUVByNEM1cERMaGh1eEo1LTZPM0tyaG5zV0FleDlWNEZlU2_qBAt5UmhobzBxaGFXMA%253D%253D%22%2C%22index%22%3A49%2C%22playlistSetVideoId%22%3A%22D625AB40294D381D%22%7D%7D%7D%7D","seek":298146,"duration":298,"samplerate":"VBR 160 kbps","bitdepth":"32 bit","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":16,"dbVolume":null,"disableVolumeControl":false,"mute":false,"updatedb":false,"volatile":false,"service":"ytmusic"} connection: {"reconnect":true,"verify_commands":false,"port":80,"host":"192.168.87.57","model":"shit"} Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - State change Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - In validConnectionOptions Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - Valid connection found Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO-CONTROL: *********** ONKYO PLUGIN STATE CHANGE ******** Oct 29 20:59:51 volumio volumio[735]: info: ONKYO-CONTROL: New state: {"status":"play","position":4,"title":"Paradise Circus (feat. Hope Sandoval)","artist":"Massive Attack","album":"Heligoland","albumart":"https://lh3.googleusercontent.com/-6rEZPBgjiIyhWubHH99Lxis_fgxF6DAXwifCXFm0IAAn6pIXttB2mkY7LtT2RH3rb8MifrmMjoxaRA=w701-h701-l90-rj","uri":"ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Paradise%20Circus%20(feat.%20Hope%20Sandoval)%22%2C%22artist%22%3A%22Massive%20Attack%22%2C%22album%22%3A%22Heligoland%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F-6rEZPBgjiIyhWubHH99Lxis_fgxF6DAXwifCXFm0IAAn6pIXttB2mkY7LtT2RH3rb8MifrmMjoxaRA%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%226hUkyKBsGtQ%22%2C%22playlistId%22%3A%22RDAMVMyRhho0qhaW0%22%2C%22params%22%3A%22OAHyAQQIAXgBkgQjUVByNEM1cERMaGh1eEo1LTZPM0tyaG5zV0FleDlWNEZlU2_qBAt5UmhobzBxaGFXMA%253D%253D%22%2C%22index%22%3A4%2C%22playlistSetVideoId%22%3A%22090796A75D153932%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watchContinuation%22%2C%22payload%22%3A%7B%22token%22%3A%22CDISigESC2VVSkdGdjdBZ2RJIhFSREFNVk15UmhobzBxaGFXMDJQd0FFQjhnRUNlQUdTQkNOUlVISTBRelZ3UkV4b2FIVjRTalV0Tms4elMzSm9ibk5YUVdWNE9WWTBSbVZUYi1vRUMzbFNhR2h2TUhGb1lWY3c4MdABAfoBEEQ2MjVBQjQwMjk0RDM4MUQYCoIBFVBUOkVndGxWVXBIUm5ZM1FXZGtTUQ%253D%253D%22%2C%22playlistId%22%3A%22RDAMVMyRhho0qhaW0%22%2C%22params%22%3A%22OAHyAQQIAXgBkgQjUVByNEM1cERMaGh1eEo1LTZPM0tyaG5zV0FleDlWNEZlU2_qBAt5UmhobzBxaGFXMA%253D%253D%22%2C%22index%22%3A49%2C%22playlistSetVideoId%22%3A%22D625AB40294D381D%22%7D%7D%7D%7D","seek":298146,"duration":298,"samplerate":"VBR 160 kbps","bitdepth":"32 bit","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":16,"dbVolume":null,"disableVolumeControl":false,"mute":false,"updatedb":false,"volatile":false,"service":"ytmusic"} connection: {"reconnect":true,"verify_commands":false,"port":80,"host":"192.168.87.57","model":"shit"} Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - State change Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - In validConnectionOptions Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - Valid connection found Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO-CONTROL: *********** ONKYO PLUGIN STATE CHANGE ******** Oct 29 20:59:51 volumio volumio[735]: info: ONKYO-CONTROL: New state: {"status":"play","position":4,"title":"Paradise Circus (feat. Hope Sandoval)","artist":"Massive Attack","album":"Heligoland","albumart":"https://lh3.googleusercontent.com/-6rEZPBgjiIyhWubHH99Lxis_fgxF6DAXwifCXFm0IAAn6pIXttB2mkY7LtT2RH3rb8MifrmMjoxaRA=w701-h701-l90-rj","uri":"ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Paradise%20Circus%20(feat.%20Hope%20Sandoval)%22%2C%22artist%22%3A%22Massive%20Attack%22%2C%22album%22%3A%22Heligoland%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F-6rEZPBgjiIyhWubHH99Lxis_fgxF6DAXwifCXFm0IAAn6pIXttB2mkY7LtT2RH3rb8MifrmMjoxaRA%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%226hUkyKBsGtQ%22%2C%22playlistId%22%3A%22RDAMVMyRhho0qhaW0%22%2C%22params%22%3A%22OAHyAQQIAXgBkgQjUVByNEM1cERMaGh1eEo1LTZPM0tyaG5zV0FleDlWNEZlU2_qBAt5UmhobzBxaGFXMA%253D%253D%22%2C%22index%22%3A4%2C%22playlistSetVideoId%22%3A%22090796A75D153932%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watchContinuation%22%2C%22payload%22%3A%7B%22token%22%3A%22CDISigESC2VVSkdGdjdBZ2RJIhFSREFNVk15UmhobzBxaGFXMDJQd0FFQjhnRUNlQUdTQkNOUlVISTBRelZ3UkV4b2FIVjRTalV0Tms4elMzSm9ibk5YUVdWNE9WWTBSbVZUYi1vRUMzbFNhR2h2TUhGb1lWY3c4MdABAfoBEEQ2MjVBQjQwMjk0RDM4MUQYCoIBFVBUOkVndGxWVXBIUm5ZM1FXZGtTUQ%253D%253D%22%2C%22playlistId%22%3A%22RDAMVMyRhho0qhaW0%22%2C%22params%22%3A%22OAHyAQQIAXgBkgQjUVByNEM1cERMaGh1eEo1LTZPM0tyaG5zV0FleDlWNEZlU2_qBAt5UmhobzBxaGFXMA%253D%253D%22%2C%22index%22%3A49%2C%22playlistSetVideoId%22%3A%22D625AB40294D381D%22%7D%7D%7D%7D","seek":298146,"duration":298,"samplerate":"VBR 160 kbps","bitdepth":"32 bit","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":16,"dbVolume":null,"disableVolumeControl":false,"mute":false,"updatedb":false,"volatile":false,"service":"ytmusic"} connection: {"reconnect":true,"verify_commands":false,"port":80,"host":"192.168.87.57","model":"shit"} Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - State change Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - In validConnectionOptions Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - Valid connection found Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO-CONTROL: *********** ONKYO PLUGIN STATE CHANGE ******** Oct 29 20:59:51 volumio volumio[735]: info: ONKYO-CONTROL: New state: {"status":"play","position":4,"title":"Paradise Circus (feat. Hope Sandoval)","artist":"Massive Attack","album":"Heligoland","albumart":"https://lh3.googleusercontent.com/-6rEZPBgjiIyhWubHH99Lxis_fgxF6DAXwifCXFm0IAAn6pIXttB2mkY7LtT2RH3rb8MifrmMjoxaRA=w701-h701-l90-rj","uri":"ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Paradise%20Circus%20(feat.%20Hope%20Sandoval)%22%2C%22artist%22%3A%22Massive%20Attack%22%2C%22album%22%3A%22Heligoland%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F-6rEZPBgjiIyhWubHH99Lxis_fgxF6DAXwifCXFm0IAAn6pIXttB2mkY7LtT2RH3rb8MifrmMjoxaRA%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%226hUkyKBsGtQ%22%2C%22playlistId%22%3A%22RDAMVMyRhho0qhaW0%22%2C%22params%22%3A%22OAHyAQQIAXgBkgQjUVByNEM1cERMaGh1eEo1LTZPM0tyaG5zV0FleDlWNEZlU2_qBAt5UmhobzBxaGFXMA%253D%253D%22%2C%22index%22%3A4%2C%22playlistSetVideoId%22%3A%22090796A75D153932%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watchContinuation%22%2C%22payload%22%3A%7B%22token%22%3A%22CDISigESC2VVSkdGdjdBZ2RJIhFSREFNVk15UmhobzBxaGFXMDJQd0FFQjhnRUNlQUdTQkNOUlVISTBRelZ3UkV4b2FIVjRTalV0Tms4elMzSm9ibk5YUVdWNE9WWTBSbVZUYi1vRUMzbFNhR2h2TUhGb1lWY3c4MdABAfoBEEQ2MjVBQjQwMjk0RDM4MUQYCoIBFVBUOkVndGxWVXBIUm5ZM1FXZGtTUQ%253D%253D%22%2C%22playlistId%22%3A%22RDAMVMyRhho0qhaW0%22%2C%22params%22%3A%22OAHyAQQIAXgBkgQjUVByNEM1cERMaGh1eEo1LTZPM0tyaG5zV0FleDlWNEZlU2_qBAt5UmhobzBxaGFXMA%253D%253D%22%2C%22index%22%3A49%2C%22playlistSetVideoId%22%3A%22D625AB40294D381D%22%7D%7D%7D%7D","seek":298146,"duration":298,"samplerate":"VBR 160 kbps","bitdepth":"32 bit","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":16,"dbVolume":null,"disableVolumeControl":false,"mute":false,"updatedb":false,"volatile":false,"service":"ytmusic"} connection: {"reconnect":true,"verify_commands":false,"port":80,"host":"192.168.87.57","model":"shit"} Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - State change Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - In validConnectionOptions Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - Valid connection found Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO-CONTROL: *********** ONKYO PLUGIN STATE CHANGE ******** Oct 29 20:59:51 volumio volumio[735]: info: ONKYO-CONTROL: New state: {"status":"play","position":4,"title":"Paradise Circus (feat. Hope Sandoval)","artist":"Massive Attack","album":"Heligoland","albumart":"https://lh3.googleusercontent.com/-6rEZPBgjiIyhWubHH99Lxis_fgxF6DAXwifCXFm0IAAn6pIXttB2mkY7LtT2RH3rb8MifrmMjoxaRA=w701-h701-l90-rj","uri":"ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Paradise%20Circus%20(feat.%20Hope%20Sandoval)%22%2C%22artist%22%3A%22Massive%20Attack%22%2C%22album%22%3A%22Heligoland%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F-6rEZPBgjiIyhWubHH99Lxis_fgxF6DAXwifCXFm0IAAn6pIXttB2mkY7LtT2RH3rb8MifrmMjoxaRA%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%226hUkyKBsGtQ%22%2C%22playlistId%22%3A%22RDAMVMyRhho0qhaW0%22%2C%22params%22%3A%22OAHyAQQIAXgBkgQjUVByNEM1cERMaGh1eEo1LTZPM0tyaG5zV0FleDlWNEZlU2_qBAt5UmhobzBxaGFXMA%253D%253D%22%2C%22index%22%3A4%2C%22playlistSetVideoId%22%3A%22090796A75D153932%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watchContinuation%22%2C%22payload%22%3A%7B%22token%22%3A%22CDISigESC2VVSkdGdjdBZ2RJIhFSREFNVk15UmhobzBxaGFXMDJQd0FFQjhnRUNlQUdTQkNOUlVISTBRelZ3UkV4b2FIVjRTalV0Tms4elMzSm9ibk5YUVdWNE9WWTBSbVZUYi1vRUMzbFNhR2h2TUhGb1lWY3c4MdABAfoBEEQ2MjVBQjQwMjk0RDM4MUQYCoIBFVBUOkVndGxWVXBIUm5ZM1FXZGtTUQ%253D%253D%22%2C%22playlistId%22%3A%22RDAMVMyRhho0qhaW0%22%2C%22params%22%3A%22OAHyAQQIAXgBkgQjUVByNEM1cERMaGh1eEo1LTZPM0tyaG5zV0FleDlWNEZlU2_qBAt5UmhobzBxaGFXMA%253D%253D%22%2C%22index%22%3A49%2C%22playlistSetVideoId%22%3A%22D625AB40294D381D%22%7D%7D%7D%7D","seek":298146,"duration":298,"samplerate":"VBR 160 kbps","bitdepth":"32 bit","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":16,"dbVolume":null,"disableVolumeControl":false,"mute":false,"updatedb":false,"volatile":false,"service":"ytmusic"} connection: {"reconnect":true,"verify_commands":false,"port":80,"host":"192.168.87.57","model":"shit"} Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - State change Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - In validConnectionOptions Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO - Valid connection found Oct 29 20:59:51 volumio volumio[735]: debug: ONKYO-CONTROL: *********** ONKYO PLUGIN STATE CHANGE ******** Oct 29 20:59:51 volumio volumio[735]: info: ONKYO-CONTROL: New state: {"status":"play","position":4,"title":"Paradise Circus (feat. Hope Sandoval)","artist":"Massive Attack","album":"Heligoland","albumart":"https://lh3.googleusercontent.com/-6rEZPBgjiIyhWubHH99Lxis_fgxF6DAXwifCXFm0IAAn6pIXttB2mkY7LtT2RH3rb8MifrmMjoxaRA=w701-h701-l90-rj","uri":"ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Paradise%20Circus%20(feat.%20Hope%20Sandoval)%22%2C%22artist%22%3A%22Massive%20Attack%22%2C%22album%22%3A%22Heligoland%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F-6rEZPBgjiIyhWubHH99Lxis_fgxF6DAXwifCXFm0IAAn6pIXttB2mkY7LtT2RH3rb8MifrmMjoxaRA%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%226hUkyKBsGtQ%22%2C%22playlistId%22%3A%22RDAMVMyRhho0qhaW0%22%2C%22params%22%3A%22OAHyAQQIAXgBkgQjUVByNEM1cERMaGh1eEo1LTZPM0tyaG5zV0FleDlWNEZlU2_qBAt5UmhobzBxaGFXMA%253D%253D%22%2C%22index%22%3A4%2C%22playlistSetVideoId%22%3A%22090796A75D153932%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watchContinuation%22%2C%22payload%22%3A%7B%22token%22%3A%22CDISigESC2VVSkdGdjdBZ2RJIhFSREFNVk15UmhobzBxaGFXMDJQd0FFQjhnRUNlQUdTQkNOUlVISTBRelZ3UkV4b2FIVjRTalV0Tms4elMzSm9ibk5YUVdWNE9WWTBSbVZUYi1vRUMzbFNhR2h2TUhGb1lWY3c4MdABAfoBEEQ2MjVBQjQwMjk0RDM4MUQYCoIBFVBUOkVndGxWVXBIUm5ZM1FXZGtTUQ%253D%253D%22%2C%22playlistId%22%3A%22RDAMVMyRhho0qhaW0%22%2C%22params%22%3A%22OAHyAQQIAXgBkgQjUVByNEM1cERMaGh1eEo1LTZPM0tyaG5zV0FleDlWNEZlU2_qBAt5UmhobzBxaGFXMA%253D%253D%22%2C%22index%22%3A49%2C%22playlistSetVideoId%22%3A%22D625AB40294D381D%22%7D%7D%7D%7D","seek":298146,"duration":298,"samplerate":"VBR 160 kbps","bitdepth":"32 bit","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":16,"dbVolume":null,"disableVolumeControl":false,"mute":false,"updatedb":false,"volatile":false,"service":"ytmusic"} connection: {"reconnect":true,"verify_commands":false,"port":80,"host":"192.168.87.57","model":"shit"} Oct 29 20:59:52 volumio volumio[735]: info: Setting Geolocation for MyVolumio to eu4 Oct 29 20:59:53 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:59:53 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:59:53 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:59:53 volumio volumio[735]: info: Setting Geolocation for MyVolumio to eu8 Oct 29 20:59:53 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:59:53 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:59:53 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:59:53 volumio volumio[735]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 29 20:59:53 volumio volumio[735]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 29 20:59:54 volumio volumio[735]: info: Updating MyVolumio device info Oct 29 20:59:54 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:59:54 volumio volumio[735]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 29 20:59:54 volumio volumio[735]: info: Updating MyVolumio device info Oct 29 20:59:54 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:59:54 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:59:54 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:59:55 volumio volumio[735]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 29 20:59:55 volumio sudo[1677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 20:59:55 volumio sudo[1677]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 20:59:55 volumio sudo[1677]: pam_unix(sudo:session): session closed for user root Oct 29 20:59:55 volumio sudo[1679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:59:55 volumio sudo[1679]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 20:59:55 volumio sudo[1679]: pam_unix(sudo:session): session closed for user root Oct 29 20:59:55 volumio volumio[735]: verbose: New Socket.io Connection to 192.168.87.5 from 192.168.87.78 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: 7 Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:55 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:55 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:55 volumio volumio[735]: info: Received Get System Info Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 20:59:55 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:55 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:55 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:55 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:55 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:55 volumio volumio[735]: info: Received Get System Info Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 20:59:55 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:55 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:55 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:55 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:55 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::volumioGetVisibleSources Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:55 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:55 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:55 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:55 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 29 20:59:55 volumio volumio[735]: info: Received Get System Info Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 20:59:55 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:55 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:55 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:55 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:55 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:55 volumio volumio[735]: info: Listing playlists Oct 29 20:59:55 volumio volumio[735]: info: Received Get System Info Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 20:59:55 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:55 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:55 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:55 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:55 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:55 volumio volumio[735]: info: Received Get System Info Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 20:59:55 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:55 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:55 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:55 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:55 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:55 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 29 20:59:56 volumio sudo[1692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 29 20:59:56 volumio sudo[1692]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 20:59:56 volumio systemd[1]: Started MyVolumio SSH Tunnel. Oct 29 20:59:56 volumio sudo[1692]: pam_unix(sudo:session): session closed for user root Oct 29 20:59:56 volumio volumio[735]: info: Remote SSH Started Oct 29 20:59:56 volumio autossh[1695]: port set to 0, monitoring disabled Oct 29 20:59:56 volumio autossh[1695]: starting ssh (count 1) Oct 29 20:59:56 volumio autossh[1695]: ssh child pid is 1698 Oct 29 20:59:56 volumio volumiossh-tunnel[1694]: Warning: Permanently added '[eu8.myvolumio.org]:2222,[178.62.246.67]:2222' (RSA) to the list of known hosts. Oct 29 20:59:56 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:56 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:56 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:56 volumio volumio[735]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 29 20:59:56 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:56 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:56 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:57 volumio volumio[735]: info: MYVOLUMIO: Adding device Oct 29 20:59:57 volumio volumio[735]: info: MYVOLUMIO: Evaluating Server Oct 29 20:59:57 volumio volumio[735]: info: MYVOLUMIO: Adding device Oct 29 20:59:57 volumio volumio[735]: info: MYVOLUMIO: Evaluating Server Oct 29 20:59:59 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 20:59:59 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 20:59:59 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 20:59:59 volumio volumio[735]: info: Listing playlists Oct 29 20:59:59 volumio volumio[735]: info: Listing playlists Oct 29 21:00:00 volumio volumio[735]: info: Setting Geolocation for MyVolumio to eu6 Oct 29 21:00:00 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 21:00:00 volumio sudo[1747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 21:00:00 volumio sudo[1747]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:00 volumio sudo[1747]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:00 volumio sudo[1749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 21:00:00 volumio sudo[1749]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:00 volumio sudo[1749]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:00 volumio volumio[735]: verbose: New Socket.io Connection to 192.168.87.5 from 192.168.87.78 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 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:01 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:01 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:01 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:01 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:01 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:01 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:01 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:01 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:01 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:01 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:01 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:01 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:01 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:01 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:01 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:01 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:01 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:01 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:01 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:01 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::volumioGetVisibleSources Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:01 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:01 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:01 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:01 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 29 21:00:01 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:01 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:01 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:01 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:01 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:01 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:01 volumio volumio[735]: info: Listing playlists Oct 29 21:00:01 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:01 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:01 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:01 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:01 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:01 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:01 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:01 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:01 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:01 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:01 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:01 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:01 volumio volumio[735]: info: MYVolumio Device enabled Oct 29 21:00:01 volumio volumio[735]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 29 21:00:01 volumio volumio[735]: info: MyVolumio status changed Oct 29 21:00:01 volumio volumio[735]: info: Streaming services startup Oct 29 21:00:01 volumio volumio[735]: info: Starting Streaming Daemon Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 29 21:00:01 volumio volumio[735]: info: Setting Geolocation for MyVolumio to us3 Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 21:00:01 volumio sudo[1756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 29 21:00:01 volumio sudo[1756]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:01 volumio volumio[735]: info: Successfully Added MyVolumio device Oct 29 21:00:01 volumio sudo[1756]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:01 volumio volumio[735]: info: Updating MyVolumio device info Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 21:00:01 volumio volumio[735]: error: Cannot start Volumio Streaming Daemon Oct 29 21:00:01 volumio volumio[735]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 29 21:00:01 volumio volumio[735]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 29 21:00:01 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 29 21:00:01 volumio volumio[735]: error: [MyVolumio PluginManager] Cache data is invalid! Oct 29 21:00:02 volumio volumio[735]: info: Successfully Added MyVolumio device Oct 29 21:00:02 volumio volumio[735]: info: Successfully Updated MyVolumio device Oct 29 21:00:02 volumio volumio[735]: info: Updating MyVolumio device info Oct 29 21:00:02 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 21:00:02 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 21:00:02 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 21:00:02 volumio volumio[735]: info: Successfully Updated MyVolumio device Oct 29 21:00:02 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 29 21:00:02 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:02 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:02 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:02 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:02 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:02 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:02 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:02 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:03 volumio volumio[735]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 29 21:00:03 volumio volumio[735]: info: [1730232003740] Starting BluetoothController Oct 29 21:00:03 volumio volumio[735]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Oct 29 21:00:07 volumio volumio[735]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Oct 29 21:00:07 volumio sudo[1763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 29 21:00:07 volumio sudo[1763]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:07 volumio sudo[1763]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:07 volumio volumio[735]: info: MRS: MultiRoom plugin initialized Oct 29 21:00:07 volumio volumio[735]: info: MRS: STOPPING SNAPCLIENT Oct 29 21:00:07 volumio volumio[735]: info: MRS: Snap server stop Oct 29 21:00:07 volumio sudo[1782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 29 21:00:07 volumio sudo[1782]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:07 volumio volumio[735]: info: MRS: STOPPING volumioStreaming Oct 29 21:00:07 volumio sudo[1789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 29 21:00:07 volumio sudo[1789]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:07 volumio volumio[735]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 29 21:00:07 volumio sudo[1795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 29 21:00:08 volumio sudo[1795]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:08 volumio sudo[1799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 29 21:00:08 volumio sudo[1799]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:08 volumio sudo[1799]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:08 volumio sudo[1782]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:08 volumio volumio[735]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 29 21:00:08 volumio sudo[1789]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:08 volumio sudo[1795]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:08 volumio volumio[735]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 29 21:00:08 volumio volumio[735]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 29 21:00:08 volumio volumio[735]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 29 21:00:08 volumio volumio[735]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Oct 29 21:00:08 volumio volumio[735]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 29 21:00:08 volumio volumio[735]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 29 21:00:08 volumio volumio[735]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 29 21:00:08 volumio volumio[735]: info: Preparing to generate the ALSA configuration file Oct 29 21:00:08 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 29 21:00:08 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 29 21:00:08 volumio volumio[735]: info: MRS: Removed streaming files Oct 29 21:00:08 volumio volumio[735]: info: MRS: volumioStreaming STOPPED Oct 29 21:00:08 volumio volumio[735]: info: MRS: SNAPSERVER STOPPED Oct 29 21:00:08 volumio volumio[735]: info: MRS: SNAPCLIENT STOPPED Oct 29 21:00:08 volumio volumio[735]: Upnp client error: Error: This socket has been ended by the other party Oct 29 21:00:08 volumio volumio[735]: Upnp client error: Error: This socket has been ended by the other party Oct 29 21:00:08 volumio volumio[735]: info: Asound.conf file unchanged, so no further update is needed Oct 29 21:00:08 volumio volumio[735]: info: Output device has changed, restarting MPD Oct 29 21:00:08 volumio volumio[735]: info: Output device has changed, restarting Shairport Sync Oct 29 21:00:08 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:08 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 21:00:08 volumio sudo[1803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 29 21:00:08 volumio sudo[1803]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:08 volumio sudo[1803]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:08 volumio sudo[1805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 29 21:00:08 volumio sudo[1805]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:08 volumio systemd[1]: Stopping Music Player Daemon... Oct 29 21:00:08 volumio volumio[735]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 29 21:00:08 volumio volumio[735]: [SpotifyConnect] Creating VLS config file Oct 29 21:00:08 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:08 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:08 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:08 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:08 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:08 volumio volumio[735]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 29 21:00:08 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:09 volumio volumio[735]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 29 21:00:09 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 29 21:00:09 volumio volumio[735]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 29 21:00:09 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 29 21:00:09 volumio volumio[735]: info: MRS: Pushing multiroomSync output for this device Oct 29 21:00:09 volumio volumio[735]: info: MRS: Pushing multiroomSync output Oct 29 21:00:09 volumio volumio[735]: debug: API:emitFavourites Oct 29 21:00:09 volumio volumio[735]: info: Adding audio output: Oct 29 21:00:09 volumio volumio[735]: debug: API:emitFavourites Oct 29 21:00:09 volumio volumio[735]: info: Adding audio output: Oct 29 21:00:09 volumio volumio[735]: debug: API:emitFavourites Oct 29 21:00:09 volumio volumio[735]: debug: API:pushMultiroomDevices Oct 29 21:00:09 volumio sudo[1814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Oct 29 21:00:09 volumio sudo[1814]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:09 volumio systemd[1]: mpd.service: Succeeded. Oct 29 21:00:09 volumio systemd[1]: Stopped Music Player Daemon. Oct 29 21:00:09 volumio volumio[735]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 29 21:00:09 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:09 volumio volumio[735]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 29 21:00:09 volumio volumio[735]: info: Adding METAVOLUMIO REST API Endpoints Oct 29 21:00:09 volumio volumio[735]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 29 21:00:09 volumio volumio[735]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 29 21:00:09 volumio volumio[735]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 29 21:00:09 volumio volumio[735]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 29 21:00:09 volumio volumio[735]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 29 21:00:09 volumio volumio[735]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 29 21:00:09 volumio volumio[735]: info: Preparing CD Folders Oct 29 21:00:09 volumio volumio[735]: info: Adding CD REST API Endpoints Oct 29 21:00:09 volumio volumio[735]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 29 21:00:09 volumio volumio[735]: info: Starting UDEV Watcher for CD Oct 29 21:00:09 volumio volumio[735]: info: Detecting CD presence with UDEV Oct 29 21:00:09 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 29 21:00:09 volumio systemd[1]: Starting Music Player Daemon... Oct 29 21:00:09 volumio volumio[735]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 29 21:00:09 volumio volumio[735]: info: Adding inputs REST Endpoints Oct 29 21:00:09 volumio volumio[735]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 29 21:00:09 volumio volumio[735]: info: Scanning Audio Inputs Oct 29 21:00:09 volumio kernel: Bluetooth: Core ver 2.22 Oct 29 21:00:09 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Oct 29 21:00:09 volumio kernel: Bluetooth: HCI device and connection manager initialized Oct 29 21:00:09 volumio kernel: Bluetooth: HCI socket layer initialized Oct 29 21:00:09 volumio kernel: Bluetooth: L2CAP socket layer initialized Oct 29 21:00:09 volumio kernel: Bluetooth: SCO socket layer initialized Oct 29 21:00:09 volumio sudo[1814]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:09 volumio volumio[735]: info: Checking against Known Cards name Oct 29 21:00:09 volumio volumio[735]: info: Adding Server instance for streaming Oct 29 21:00:09 volumio volumio[735]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 29 21:00:09 volumio volumio[735]: error: Hi Res Audio Failed Login: Missing Login Data Oct 29 21:00:09 volumio volumio[735]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 29 21:00:09 volumio volumio[735]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 29 21:00:09 volumio volumio[735]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 29 21:00:09 volumio volumio[735]: info: Refreshing TIDAL token Oct 29 21:00:09 volumio volumio[735]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 29 21:00:09 volumio volumio[735]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 29 21:00:09 volumio sudo[1818]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 29 21:00:09 volumio sudo[1818]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:09 volumio sudo[1818]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:09 volumio volumio[735]: info: Stopping AccessToken refresher cron for QOBUZ Oct 29 21:00:09 volumio sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 29 21:00:09 volumio sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:09 volumio sudo[1832]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:09 volumio volumio[735]: info: AccessToken refresher cron started for QOBUZ Oct 29 21:00:09 volumio volumio[735]: info: Adding QOBUZ REST API Endpoints Oct 29 21:00:09 volumio volumio[735]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 29 21:00:09 volumio volumio[735]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 29 21:00:09 volumio sudo[1837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 29 21:00:09 volumio sudo[1837]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:09 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:09 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:09 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:09 volumio sudo[1841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 21:00:09 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 29 21:00:09 volumio sudo[1841]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:09 volumio sudo[1841]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:09 volumio sudo[1837]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:09 volumio sudo[1843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 21:00:09 volumio volumio[1839]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Oct 29 21:00:10 volumio volumio[1839]: Reading Config from "/tmp/volspotify.toml" Oct 29 21:00:10 volumio volumio[1839]: Malformed config key: missing field `Authentication` Oct 29 21:00:10 volumio sudo[1843]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:10 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE Oct 29 21:00:10 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Oct 29 21:00:10 volumio sudo[1843]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:10 volumio volumio[735]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 29 21:00:10 volumio volumio[735]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Volumio' Oct 29 21:00:10 volumio volumio[735]: Can't get device info: No such device Oct 29 21:00:10 volumio volumio[735]: info: MPD Permissions set Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:10 volumio sudo[1854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 21:00:10 volumio sudo[1854]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:10 volumio volumio[735]: info: Starting Shairport Sync Oct 29 21:00:10 volumio sudo[1854]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:10 volumio sudo[1856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 21:00:10 volumio sudo[1856]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:10 volumio sudo[1860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 29 21:00:10 volumio sudo[1856]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:10 volumio sudo[1860]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:10 volumio volumio[735]: verbose: New Socket.io Connection to 192.168.87.5 from 192.168.87.78 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: 7 Oct 29 21:00:10 volumio volumio[735]: info: Preparing to generate the ALSA configuration file Oct 29 21:00:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 29 21:00:10 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 29 21:00:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 29 21:00:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:10 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:10 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:10 volumio sudo[1860]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:10 volumio volumio[735]: verbose: New Socket.io Connection to 192.168.87.5 from 192.168.87.78 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 29 21:00:10 volumio sudo[1871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 21:00:10 volumio sudo[1871]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:10 volumio sudo[1871]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:10 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:10 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:10 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:10 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:10 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:10 volumio sudo[1874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 21:00:10 volumio sudo[1874]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:10 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:10 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:10 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:10 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:10 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:10 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:10 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:10 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:10 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:10 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:10 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:10 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:10 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::volumioGetVisibleSources Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:10 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:10 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:10 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:10 volumio sudo[1874]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:10 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 29 21:00:10 volumio volumio[735]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 29 21:00:10 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:10 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:10 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:10 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:10 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:10 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:10 volumio volumio[735]: info: Listing playlists Oct 29 21:00:10 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:10 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:10 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:10 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:10 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:10 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:10 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:10 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:10 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:10 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:10 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:10 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:10 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:10 volumio volumio[735]: info: Shairport-Sync Started Oct 29 21:00:10 volumio volumio[735]: info: Asound.conf file unchanged, so no further update is needed Oct 29 21:00:10 volumio volumio[735]: info: Output device has changed, restarting MPD Oct 29 21:00:11 volumio volumio[735]: info: Output device has changed, restarting Shairport Sync Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 21:00:11 volumio sudo[1880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 29 21:00:11 volumio sudo[1880]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:11 volumio sudo[1880]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:11 volumio sudo[1882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 29 21:00:11 volumio sudo[1882]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:11 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 29 21:00:11 volumio systemd[1]: mpd.service: Succeeded. Oct 29 21:00:11 volumio systemd[1]: Stopped Music Player Daemon. Oct 29 21:00:11 volumio volumio[735]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 29 21:00:11 volumio volumio[735]: [SpotifyConnect] Creating VLS config file Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:11 volumio volumio[735]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:11 volumio systemd[1]: Starting Music Player Daemon... Oct 29 21:00:11 volumio volumio[735]: info: Preparing to generate the ALSA configuration file Oct 29 21:00:11 volumio volumio[735]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:11 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:11 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:11 volumio sudo[1892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 29 21:00:11 volumio sudo[1892]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:11 volumio volumio[735]: info: Access Token successfully retrieved Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 29 21:00:11 volumio volumio[735]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 29 21:00:11 volumio volumio[735]: info: Reading ALSA contributions from plugins. Oct 29 21:00:11 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Oct 29 21:00:11 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:11 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:11 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:11 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:11 volumio sudo[1888]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 29 21:00:11 volumio sudo[1888]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:11 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:11 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:11 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:11 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:11 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:11 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:11 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:11 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:11 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:11 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:11 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:11 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:11 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:11 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:11 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::volumioGetVisibleSources Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:11 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:11 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:11 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:11 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 29 21:00:11 volumio volumio[735]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 29 21:00:11 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:11 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:11 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:11 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:11 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:11 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:11 volumio sudo[1888]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:11 volumio volumio[735]: info: Listing playlists Oct 29 21:00:11 volumio volumio[1894]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Oct 29 21:00:11 volumio volumio[1894]: Reading Config from "/tmp/volspotify.toml" Oct 29 21:00:11 volumio volumio[1894]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 29 21:00:11 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:11 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:11 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:11 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:11 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:11 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:11 volumio sudo[1892]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:11 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:11 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:11 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:11 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:11 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:11 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 29 21:00:11 volumio volumio[1894]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Oct 29 21:00:11 volumio volumio[735]: info: MPD Permissions set Oct 29 21:00:11 volumio volumio[735]: verbose: New Socket.io Connection to 192.168.87.5 from 192.168.87.78 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 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 29 21:00:11 volumio volumio[735]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:11 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:12 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:12 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:12 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetVisibleSources Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 29 21:00:12 volumio volumio[735]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 29 21:00:12 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:12 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: Listing playlists Oct 29 21:00:12 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:12 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:12 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: Received Get System Info Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 21:00:12 volumio volumio[735]: info: Discovery: Getting this device information Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio sudo[1903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 29 21:00:12 volumio sudo[1903]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::volumioGetState Oct 29 21:00:12 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:12 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:12 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 29 21:00:12 volumio volumio[735]: info: Starting Shairport Sync Oct 29 21:00:12 volumio sudo[1906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 29 21:00:12 volumio sudo[1906]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:12 volumio systemd[1]: Started Volumio Bluetooth Module. Oct 29 21:00:12 volumio sudo[1903]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 29 21:00:12 volumio sudo[1909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Oct 29 21:00:12 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 29 21:00:12 volumio sudo[1909]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 29 21:00:12 volumio volumiobt[1907]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Oct 29 21:00:12 volumio sudo[1909]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 29 21:00:12 volumio volumio[735]: info: Successfully retrieved User Session From TIDAL Oct 29 21:00:12 volumio sudo[1906]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:12 volumio volumio[735]: info: TidalConnect service stoped! Oct 29 21:00:13 volumio volumio[735]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 29 21:00:13 volumio volumio[735]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 29 21:00:13 volumio volumio[735]: info: Shairport-Sync Started Oct 29 21:00:13 volumio volumio[735]: info: Volumio BT Module successfully started Oct 29 21:00:13 volumio volumio[735]: info: Asound.conf file written Oct 29 21:00:13 volumio sudo[1924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 29 21:00:13 volumio sudo[1924]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:13 volumio sudo[1924]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:13 volumio volumio[735]: xcb_connection_has_error() returned true Oct 29 21:00:13 volumio volumio[735]: info: Output device has changed, restarting MPD Oct 29 21:00:13 volumio sudo[1929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 29 21:00:13 volumio volumio[735]: info: Output device has changed, restarting Shairport Sync Oct 29 21:00:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 21:00:13 volumio sudo[1929]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:13 volumio sudo[1929]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:13 volumio sudo[1931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 29 21:00:13 volumio sudo[1931]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:13 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 29 21:00:13 volumio systemd[1]: mpd.service: Succeeded. Oct 29 21:00:13 volumio systemd[1]: Stopped Music Player Daemon. Oct 29 21:00:13 volumio volumio[735]: xcb_connection_has_error() returned true Oct 29 21:00:13 volumio volumio[735]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 29 21:00:13 volumio volumio[735]: xcb_connection_has_error() returned true Oct 29 21:00:13 volumio volumio[735]: [SpotifyConnect] Creating VLS config file Oct 29 21:00:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:13 volumio volumio[735]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 29 21:00:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:13 volumio volumio[735]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 29 21:00:13 volumio systemd[1]: Starting Music Player Daemon... Oct 29 21:00:13 volumio sudo[1941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 29 21:00:13 volumio sudo[1941]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:13 volumio sudo[1942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Oct 29 21:00:13 volumio volumio[735]: info: Successfully retrieved User Subscription From TIDAL Oct 29 21:00:13 volumio volumio[735]: info: Adding TIDAL to Browse Sources Oct 29 21:00:13 volumio sudo[1942]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:13 volumio volumio[735]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 29 21:00:13 volumio volumio[735]: info: [1730232013937] CoreMusicLibrary::Adding element TIDAL Oct 29 21:00:13 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 21:00:13 volumio volumio[735]: Cannot find translation for source YouTube Music Oct 29 21:00:13 volumio volumio[735]: Cannot find translation for source TIDAL Oct 29 21:00:13 volumio volumio[735]: debug: API:emitFavourites Oct 29 21:00:13 volumio volumio[735]: info: Stopping AccessToken refresher cron Oct 29 21:00:13 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 29 21:00:13 volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Oct 29 21:00:13 volumio volumiobt[1907]: pulseaudio: no process found Oct 29 21:00:13 volumio sudo[1942]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:13 volumio systemd[1]: volspotconnect.service: Succeeded. Oct 29 21:00:13 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Oct 29 21:00:14 volumio volumio[735]: info: AccessToken refresher cron started Oct 29 21:00:14 volumio volumio[735]: info: Adding TIDAL REST API Endpoints Oct 29 21:00:14 volumio volumio[735]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 29 21:00:14 volumio volumio[735]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 29 21:00:14 volumio volumio[735]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 29 21:00:14 volumio volumio[735]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 29 21:00:14 volumio volumio[735]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 29 21:00:14 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 29 21:00:14 volumio sudo[1941]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:14 volumio volumio[735]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 29 21:00:14 volumio volumio[735]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 29 21:00:14 volumio volumio[1946]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Oct 29 21:00:14 volumio volumio[1946]: Reading Config from "/tmp/volspotify.toml" Oct 29 21:00:14 volumio volumio[1946]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 29 21:00:14 volumio volumio[735]: info: MPD Permissions set Oct 29 21:00:14 volumio sudo[1937]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 29 21:00:14 volumio sudo[1937]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:14 volumio volumio[735]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 29 21:00:14 volumio volumio[735]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 29 21:00:14 volumio volumio[735]: info: MRS: Getting audio outputs on start Oct 29 21:00:14 volumio volumio[735]: info: MRS: Requesting all other devices output Oct 29 21:00:14 volumio volumio[735]: debug: API:emitFavourites Oct 29 21:00:14 volumio dbus-daemon[461]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.15' (uid=1000 pid=1950 comm="/usr/bin/pulseaudio --high-priority=true ") Oct 29 21:00:14 volumio sudo[1937]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:14 volumio volumio[735]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 29 21:00:14 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:14 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:14 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:14 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:14 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:14 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:14 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:14 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:14 volumio volumio[1946]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Oct 29 21:00:14 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Oct 29 21:00:14 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 21:00:14 volumio dbus-daemon[461]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Oct 29 21:00:14 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Oct 29 21:00:14 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 21:00:14 volumio rtkit-daemon[1954]: Successfully called chroot. Oct 29 21:00:14 volumio rtkit-daemon[1954]: Successfully dropped privileges. Oct 29 21:00:14 volumio rtkit-daemon[1954]: Successfully limited resources. Oct 29 21:00:14 volumio rtkit-daemon[1954]: Running. Oct 29 21:00:14 volumio rtkit-daemon[1954]: Watchdog thread running. Oct 29 21:00:14 volumio dbus-daemon[461]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.16' (uid=0 pid=1954 comm="/usr/lib/rtkit/rtkit-daemon ") Oct 29 21:00:14 volumio rtkit-daemon[1954]: Canary thread running. Oct 29 21:00:14 volumio volumio[735]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 29 21:00:14 volumio sudo[1963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 29 21:00:14 volumio sudo[1963]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:14 volumio systemd[1]: Starting Authorization Manager... Oct 29 21:00:14 volumio volumio[735]: info: Starting Shairport Sync Oct 29 21:00:14 volumio systemd[1]: Started Volumio Tidal Connect Service. Oct 29 21:00:14 volumio sudo[1963]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:14 volumio volumio[735]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 29 21:00:14 volumio sudo[1969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 29 21:00:14 volumio sudo[1969]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:14 volumio polkitd[1962]: started daemon version 0.105 using authority implementation `local' version `0.105' Oct 29 21:00:14 volumio dbus-daemon[461]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 29 21:00:14 volumio systemd[1]: Started Authorization Manager. Oct 29 21:00:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 29 21:00:14 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 29 21:00:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 29 21:00:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 29 21:00:14 volumio sudo[1969]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:15 volumio volumio[735]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 29 21:00:15 volumio volumio[735]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 29 21:00:15 volumio volumio[735]: info: Executing endpoint metavolumio Oct 29 21:00:15 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 29 21:00:15 volumio volumio[735]: info: Shairport-Sync Started Oct 29 21:00:15 volumio volumio[735]: info: Executing endpoint metavolumio Oct 29 21:00:15 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 29 21:00:15 volumio volumio[735]: info: Executing endpoint metavolumio Oct 29 21:00:15 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 29 21:00:15 volumio volumio[735]: info: Executing endpoint tc_getconfig Oct 29 21:00:15 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 29 21:00:15 volumio vtcs[1966]: STARTING TidalConnect services, version: 1.4.0.34 Oct 29 21:00:15 volumio dbus-daemon[461]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.37' (uid=1000 pid=1950 comm="/usr/bin/pulseaudio --high-priority=true ") Oct 29 21:00:15 volumio systemd[1]: Starting Bluetooth service... Oct 29 21:00:15 volumio pulseaudio[1950]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 29 21:00:15 volumio pulseaudio[1950]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 29 21:00:15 volumio volumiobt[1907]: Applying permissions Oct 29 21:00:15 volumio volumiobt[1907]: Setting BT discoverable and pairable Oct 29 21:00:15 volumio volumio[735]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 29 21:00:15 volumio vtcs[1966]: STARTED TidalConnect services. Oct 29 21:00:15 volumio volumio[735]: info: Executing endpoint tc_connect Oct 29 21:00:15 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 29 21:00:15 volumio bluetoothd[1982]: Bluetooth daemon 5.50 Oct 29 21:00:15 volumio volumio[735]: info: Connecting to TidalConnect Oct 29 21:00:15 volumio bluetoothd[1982]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Oct 29 21:00:15 volumio dbus-daemon[461]: [system] Successfully activated service 'org.bluez' Oct 29 21:00:15 volumio systemd[1]: Started Bluetooth service. Oct 29 21:00:15 volumio bluetoothd[1982]: Starting SDP server Oct 29 21:00:15 volumio bluetoothd[1982]: Excluding (cli) sap Oct 29 21:00:15 volumio volumio[735]: info: CoreCommandRouter::servicePushState Oct 29 21:00:15 volumio volumio[735]: debug: CoreStateMachine::syncState Oct 29 21:00:15 volumio volumio[735]: info: CoreStateMachine::pushState Oct 29 21:00:15 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:15 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:15 volumio volumio[735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 21:00:15 volumio volumio[735]: info: CoreCommandRouter::volumioPushState Oct 29 21:00:15 volumio volumio[735]: debug: API:pushState Oct 29 21:00:15 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 29 21:00:15 volumio kernel: Bluetooth: BNEP filters: protocol multicast Oct 29 21:00:15 volumio kernel: Bluetooth: BNEP socket layer initialized Oct 29 21:00:15 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:15 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:15 volumio volumio[735]: info: MRS: Pushing multiroomSync output update for this device Oct 29 21:00:15 volumio volumio[735]: debug: API:emitFavourites Oct 29 21:00:15 volumio bluetoothd[1982]: Bluetooth management interface 1.22 initialized Oct 29 21:00:15 volumio dbus-daemon[461]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.38' (uid=0 pid=1982 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Oct 29 21:00:15 volumio volumio[735]: info: MRS: Pushing multiroomSync output Oct 29 21:00:15 volumio volumio[735]: debug: API:emitFavourites Oct 29 21:00:15 volumio volumiobt[1907]: [bluetooth]# -e power on Oct 29 21:00:15 volumio volumiobt[1907]: Invalid command in menu main: -e Oct 29 21:00:15 volumio volumiobt[1907]: Oct 29 21:00:15 volumio volumiobt[1907]: Use "help" for a list of available commands in a menu. Oct 29 21:00:15 volumio volumiobt[1907]: Use "menu " if you want to enter any submenu. Oct 29 21:00:15 volumio volumiobt[1907]: Use "back" if you want to return to menu main. Oct 29 21:00:15 volumio volumiobt[1907]: [bluetooth]# agent on Oct 29 21:00:15 volumio volumiobt[1907]: Failed to register agent object Oct 29 21:00:15 volumio volumiobt[1907]: [bluetooth]# discoverable on Oct 29 21:00:15 volumio volumiobt[1907]: No default controller available Oct 29 21:00:15 volumio volumiobt[1907]: [bluetooth]# pairable on Oct 29 21:00:15 volumio volumiobt[1907]: No default controller available Oct 29 21:00:15 volumio volumiobt[1907]: [bluetooth]# agent NoInputNoOutput Oct 29 21:00:15 volumio volumiobt[1907]: Failed to register agent object Oct 29 21:00:15 volumio volumiobt[1907]: [bluetooth]# default-agent Oct 29 21:00:15 volumio volumiobt[1907]: No agent is registered Oct 29 21:00:15 volumio volumiobt[1907]: [bluetooth]# quit Oct 29 21:00:15 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:15 volumio volumio[735]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Oct 29 21:00:15 volumio volumio[735]: info: CoreCommandRouter::servicePushState Oct 29 21:00:15 volumio pulseaudio[1950]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Oct 29 21:00:15 volumio volumio[735]: debug: CoreStateMachine::syncState Oct 29 21:00:15 volumio volumio[735]: info: CoreStateMachine::pushState Oct 29 21:00:15 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:15 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:15 volumio volumio[735]: info: CoreCommandRouter::volumioPushState Oct 29 21:00:16 volumio volumio[735]: debug: API:pushState Oct 29 21:00:16 volumio volumio[735]: debug: CoreStateMachine::getState Oct 29 21:00:16 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:16 volumio volumio[735]: info: MRS: Pushing multiroomSync output update for this device Oct 29 21:00:16 volumio volumio[735]: info: MRS: Pushing multiroomSync output Oct 29 21:00:16 volumio volumio[735]: debug: API:emitFavourites Oct 29 21:00:16 volumio volumio[735]: info: CorePlayQueue::getTrack 4 Oct 29 21:00:16 volumio volumio[735]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Oct 29 21:00:16 volumio volumiobt[1907]: [59B blob data] Oct 29 21:00:16 volumio volumio[735]: info: Checking Spotify Web API Oct 29 21:00:16 volumio volumio[735]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 21:00:16 volumio systemd[1]: Starting Hostname Service... Oct 29 21:00:16 volumio volumio[735]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Oct 29 21:00:16 volumio volumio[735]: at healthCheck (dgram.js:897:11) Oct 29 21:00:16 volumio volumio[735]: at Socket.send (dgram.js:622:3) Oct 29 21:00:16 volumio volumio[735]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Oct 29 21:00:16 volumio volumio[735]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2354:23) Oct 29 21:00:16 volumio volumio[735]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2694:18) Oct 29 21:00:16 volumio volumio[735]: at listOnTimeout (internal/timers.js:554:17) Oct 29 21:00:16 volumio volumio[735]: at processTimers (internal/timers.js:497:7) { Oct 29 21:00:16 volumio volumio[735]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Oct 29 21:00:16 volumio volumio[735]: } Oct 29 21:00:16 volumio volumio[735]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 21:00:16 volumio dbus-daemon[461]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 29 21:00:16 volumio systemd[1]: Started Hostname Service. Oct 29 21:00:17 volumio sudo[2012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-29 20:59 Oct 29 21:00:17 volumio sudo[2012]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 21:00:17 volumio mpd[1952]: Oct 29 21:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 29 21:00:17 volumio systemd[1]: Started Music Player Daemon. Oct 29 21:00:17 volumio sudo[1931]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:17 volumio sudo[1805]: pam_unix(sudo:session): session closed for user root Oct 29 21:00:17 volumio sudo[1882]: pam_unix(sudo:session): session closed for user root 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="8b17c2f054b42f7617d19172c51eb8801fef450d" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="5ed89c6e5067f886280d87d6c1a3f9930df14028" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 16 Oct 2024 03:57:06 PM CEST" VOLUMIO_VERSION="3.765" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="0d32cf437531a99146c655ee44a741a6"