-- Logs begin at Sun 2025-03-09 12:55:19 CET, end at Tue 2025-03-11 16:58:26 CET. -- Mar 11 16:57:07 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 16:57:07 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 16:57:07 partytower volumio[911]: info: Discovery: Getting this device information Mar 11 16:57:07 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:57:07 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:57:07 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 16:57:17 partytower volumio[911]: info: Received OAUTH Data Mar 11 16:57:17 partytower volumio[911]: info: Executing Spotify Oauth Login Mar 11 16:57:17 partytower volumio[911]: info: Saving Spotify Refresh Token Mar 11 16:57:18 partytower volumio[911]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 11 16:57:18 partytower volumio[911]: SPOTIFY: BQB4Xf-Kb_Zw3jNHPm0ArXVnBHM89o2SK4yjgKkHQSgfQBDYkfTTDRZ1xpcaCtrMmqjHlTBWAfg7od4tdAkTYKq3d4klbyNgnytnLlRyRygEMfn6BWidwqgA4kpx9k8fk_YK7XtjzK7B7gEiyprYUDDozfNLnO47Kl6A2nSrcaVdUdCwg26At_08uLU8jfTQvABWHoVQgSQx3M-XEz-9yGDzsbhYA_Hi1wiA8Iu-q9GlN4svgyU_fYjiv_0IWSQk8USmTZ1twIWBDXHK1Wv3R_8c7lE07BIZ56Sn-AoQ2tk Mar 11 16:57:18 partytower volumio[911]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 11 16:57:18 partytower volumio[911]: info: New Spotify access token = BQB4Xf-Kb_Zw3jNHPm0ArXVnBHM89o2SK4yjgKkHQSgfQBDYkfTTDRZ1xpcaCtrMmqjHlTBWAfg7od4tdAkTYKq3d4klbyNgnytnLlRyRygEMfn6BWidwqgA4kpx9k8fk_YK7XtjzK7B7gEiyprYUDDozfNLnO47Kl6A2nSrcaVdUdCwg26At_08uLU8jfTQvABWHoVQgSQx3M-XEz-9yGDzsbhYA_Hi1wiA8Iu-q9GlN4svgyU_fYjiv_0IWSQk8USmTZ1twIWBDXHK1Wv3R_8c7lE07BIZ56Sn-AoQ2tk Mar 11 16:57:18 partytower volumio[911]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 11 16:57:18 partytower volumio[911]: SPOTIFY: User informations: {"country":"SI","display_name":"RJ Network","email":"rjnetwork87@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31tzw7346d5r54ykhl3ktgmkrj2m"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31tzw7346d5r54ykhl3ktgmkrj2m","id":"31tzw7346d5r54ykhl3ktgmkrj2m","images":[],"product":"premium","type":"user","uri":"spotify:user:31tzw7346d5r54ykhl3ktgmkrj2m"} Mar 11 16:57:18 partytower volumio[911]: info: Creating Spotify config file Mar 11 16:57:18 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 16:57:18 partytower volumio[911]: info: Spotify config file written Mar 11 16:57:18 partytower sudo[22255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 11 16:57:18 partytower sudo[22255]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 16:57:18 partytower systemd[1]: Stopping go-librespot Daemon... Mar 11 16:57:18 partytower systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Mar 11 16:57:18 partytower systemd[1]: go-librespot-daemon.service: Succeeded. Mar 11 16:57:18 partytower systemd[1]: Stopped go-librespot Daemon. Mar 11 16:57:18 partytower volumio[911]: info: Connection to go-librespot Websocket closed Mar 11 16:57:18 partytower volumio[911]: info: Connection to go-librespot Websocket closed Mar 11 16:57:18 partytower volumio[911]: info: Connection to go-librespot Websocket closed Mar 11 16:57:18 partytower systemd[1]: Started go-librespot Daemon. Mar 11 16:57:18 partytower sudo[22255]: pam_unix(sudo:session): session closed for user root Mar 11 16:57:18 partytower go-librespot[22257]: go-librespot daemon starting... Mar 11 16:57:18 partytower go-librespot[22257]: time="2025-03-11T16:57:18+01:00" level=info msg="running go-librespot 0.2.0" Mar 11 16:57:18 partytower go-librespot[22257]: time="2025-03-11T16:57:18+01:00" level=debug msg="app state loaded" Mar 11 16:57:18 partytower go-librespot[22257]: time="2025-03-11T16:57:18+01:00" level=debug msg="stored credentials not found" Mar 11 16:57:18 partytower go-librespot[22257]: time="2025-03-11T16:57:18+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 11 16:57:18 partytower volumio[911]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 11 16:57:18 partytower volumio[911]: SPOTIFY: BQDHx27zg6ooabrtXRWfK7VcYrLKPU3xyCPsyyYtaq0zXHJkndAZ7a-Nh0XYvOvJ_26cP863ulBbmZq_-XM6FiVq6-iqAG4ZG7vPZxAa5xCFceEmCFfUo24pnDvrcPFLq5gvTwBFm8h2JWm4rgmVz4ejQhfHDgtuSdPmWxngxHYzTBNc7nmLob66DbM1ackYctyrFtFlXi-4U9vn0OpYISRLqdEq-GsxdvGANYj_iBtUPfFCitJmz12FwE_irDbrUiqM7nWBWqdwDkwUohFcp5tvTzvd_Rg5dftIvw98Yfc Mar 11 16:57:18 partytower volumio[911]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 11 16:57:18 partytower volumio[911]: info: New Spotify access token = BQDHx27zg6ooabrtXRWfK7VcYrLKPU3xyCPsyyYtaq0zXHJkndAZ7a-Nh0XYvOvJ_26cP863ulBbmZq_-XM6FiVq6-iqAG4ZG7vPZxAa5xCFceEmCFfUo24pnDvrcPFLq5gvTwBFm8h2JWm4rgmVz4ejQhfHDgtuSdPmWxngxHYzTBNc7nmLob66DbM1ackYctyrFtFlXi-4U9vn0OpYISRLqdEq-GsxdvGANYj_iBtUPfFCitJmz12FwE_irDbrUiqM7nWBWqdwDkwUohFcp5tvTzvd_Rg5dftIvw98Yfc Mar 11 16:57:18 partytower volumio[911]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 11 16:57:18 partytower sudo[22266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 11 16:57:18 partytower sudo[22266]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 16:57:18 partytower sudo[22266]: pam_unix(sudo:session): session closed for user root Mar 11 16:57:18 partytower volumio[911]: SPOTIFY: User informations: {"country":"SI","display_name":"RJ Network","email":"rjnetwork87@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31tzw7346d5r54ykhl3ktgmkrj2m"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31tzw7346d5r54ykhl3ktgmkrj2m","id":"31tzw7346d5r54ykhl3ktgmkrj2m","images":[],"product":"premium","type":"user","uri":"spotify:user:31tzw7346d5r54ykhl3ktgmkrj2m"} Mar 11 16:57:18 partytower volumio[911]: info: Spotify Successfully logged in Mar 11 16:57:18 partytower sudo[22269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 16:57:18 partytower sudo[22269]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 16:57:18 partytower volumio[911]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 11 16:57:18 partytower volumio[911]: info: [1741708638665] CoreMusicLibrary::Adding element Spotify Mar 11 16:57:18 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 16:57:18 partytower sudo[22269]: pam_unix(sudo:session): session closed for user root Mar 11 16:57:18 partytower volumio[911]: Cannot find translation for source YouTube Music Mar 11 16:57:18 partytower volumio[911]: Cannot find translation for source Spotify Mar 11 16:57:18 partytower go-librespot[22257]: time="2025-03-11T16:57:18+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 11 16:57:18 partytower go-librespot[22257]: time="2025-03-11T16:57:18+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 11 16:57:18 partytower go-librespot[22257]: time="2025-03-11T16:57:18+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 11 16:57:18 partytower go-librespot[22257]: time="2025-03-11T16:57:18+01:00" level=info msg="zeroconf server listening on port 37357" Mar 11 16:57:18 partytower volumio[911]: verbose: New Socket.io Connection to 192.168.2.134 from 192.168.2.135 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:136.0) Gecko/20100101 Firefox/136.0 Engine version: 3 Transport: polling Total Clients: 6 Mar 11 16:57:18 partytower go-librespot[22257]: time="2025-03-11T16:57:18+01:00" level=debug msg="obtained new client token: AABdT5nlHRSrsLaZlN3SwvzYJjPdAnFUs9+sAzfiPQAzmJW8wXC3XO5H0ay+x0HU4tQSIOtEn6UVwvhmyMj+8QugW1zRmXXYeF61f4t6u2pDNJB9iTuFRm2u9xbaSOhDx2Yewqws10Iyx5CmaxcXlMLXf6t1qefqMkS+7mBNwXNMCAQzNaHXx3VHnn4YsEjTHHilQUufv9utWBVBIVA3m7ZKo3katyjYukKu7jj3Z/8cMRCyUuPEpVvz9nM=" Mar 11 16:57:18 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 11 16:57:18 partytower go-librespot[22257]: time="2025-03-11T16:57:18+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=debug msg="completed keyexchange" Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=debug msg="completed challenge" Mar 11 16:57:19 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=info msg="authenticated AP as 31tzw7346d5r54ykhl3ktgmkrj2m" Mar 11 16:57:19 partytower volumio[911]: info: CoreCommandRouter::volumioGetVisibleSources Mar 11 16:57:19 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 16:57:19 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:57:19 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:57:19 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 11 16:57:19 partytower volumio[911]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 16:57:19 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 11 16:57:19 partytower volumio[911]: info: Received Get System Info Mar 11 16:57:19 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 16:57:19 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 16:57:19 partytower volumio[911]: info: Discovery: Getting this device information Mar 11 16:57:19 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:57:19 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:57:19 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 16:57:19 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:57:19 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:57:19 partytower volumio[911]: info: Listing playlists Mar 11 16:57:19 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 11 16:57:19 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=info msg="authenticated Login5 as 31tzw7346d5r54ykhl3ktgmkrj2m" Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=debug msg="stored credentials for 31tzw7346d5r54ykhl3ktgmkrj2m" Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=debug msg="initializing zeroconf session, username: 31tzw7346d5r54ykhl3ktgmkrj2m" Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=debug msg="dealer connection opened" Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=trace msg="starting accesspoint recv loop" Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=trace msg="starting dealer recv loop" Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=trace msg="received accesspoint ping" Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=debug msg="received connection id: OTViMjJiMzktYzQyYi00NWE3LWIwNGMtOGE0NjYzOWU4MmRmK2RlYWxlcit0Y3A6Ly8wYWNhNDAyNS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArN0E3NDg5NEEwMEFFREVFQzI5NUVFOTUyNTg1QkJDNzExMkU3QjE5QkU5OTlEMUVDM0QyRkY1QTAyRkZFRTlDMw==" Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=trace msg="received accesspoint pong ack" Mar 11 16:57:19 partytower go-librespot[22257]: time="2025-03-11T16:57:19+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 11 16:57:20 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 11 16:57:20 partytower volumio[911]: info: Received Get System Info Mar 11 16:57:20 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 16:57:20 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 16:57:20 partytower volumio[911]: info: Discovery: Getting this device information Mar 11 16:57:20 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:57:20 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:57:20 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 16:57:20 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 11 16:57:21 partytower volumio[911]: info: Initializing connection to go-librespot Websocket Mar 11 16:57:21 partytower go-librespot[22257]: time="2025-03-11T16:57:21+01:00" level=debug msg="new websocket client" Mar 11 16:57:21 partytower volumio[911]: info: Connection to go-librespot Websocket established Mar 11 16:57:21 partytower volumio[911]: info: go-librespot daemon successfully initialized Mar 11 16:57:22 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 11 16:57:22 partytower volumio[911]: info: Received Get System Info Mar 11 16:57:22 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 16:57:22 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 16:57:22 partytower volumio[911]: info: Discovery: Getting this device information Mar 11 16:57:22 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:57:22 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:57:22 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 16:57:24 partytower volumio[911]: info: Getting Spotify volume Mar 11 16:57:24 partytower volumio[911]: info: Spotify volume: 100 Mar 11 16:57:24 partytower volumio[911]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 11 16:57:24 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:57:24 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:57:24 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:57:24 partytower volumio[911]: SPOTIFY: SPOTIFY VOLUME 100 Mar 11 16:57:24 partytower volumio[911]: SPOTIFY: VOLUMIO VOLUME 65 Mar 11 16:57:24 partytower volumio[911]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 11 16:57:24 partytower volumio[911]: info: Setting Spotify Volume from Volumio: 65 Mar 11 16:57:24 partytower volumio[911]: info: Initializing connection to go-librespot Websocket Mar 11 16:57:24 partytower go-librespot[22257]: time="2025-03-11T16:57:24+01:00" level=debug msg="new websocket client" Mar 11 16:57:24 partytower volumio[911]: info: Connection to go-librespot Websocket established Mar 11 16:57:25 partytower volumio[911]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Mar 11 16:57:25 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Mar 11 16:57:25 partytower volumio[911]: info: Creating Spotify config file Mar 11 16:57:25 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 16:57:25 partytower volumio[911]: info: Spotify config file written Mar 11 16:57:25 partytower sudo[22303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 11 16:57:25 partytower sudo[22303]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 16:57:25 partytower systemd[1]: Stopping go-librespot Daemon... Mar 11 16:57:25 partytower systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Mar 11 16:57:25 partytower systemd[1]: go-librespot-daemon.service: Succeeded. Mar 11 16:57:25 partytower systemd[1]: Stopped go-librespot Daemon. Mar 11 16:57:25 partytower volumio[911]: info: Connection to go-librespot Websocket closed Mar 11 16:57:25 partytower volumio[911]: info: Connection to go-librespot Websocket closed Mar 11 16:57:25 partytower systemd[1]: Started go-librespot Daemon. Mar 11 16:57:25 partytower go-librespot[22305]: go-librespot daemon starting... Mar 11 16:57:25 partytower sudo[22303]: pam_unix(sudo:session): session closed for user root Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=info msg="running go-librespot 0.2.0" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="app state loaded" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=info msg="zeroconf server listening on port 43261" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="obtained new client token: AABJ5TZz5P8MrbInelU/cwH5U9yRM73bmguIRDi+XRxIsH+ucHt6ZFErMTGswC9NTXRFujB7ZCHnjw9n6MWC0bZghfVZAooD3Ofq1BU38OQP6wBYMdO+KRY8sUA/6OZRLpUz/ZGoeMp987uOne778wI2l9nxUCQRNGDOl8MLhre3NkZ4WSlm8e0ZthfIZBrnGpBtT3elWFb243x1H0Pky9djMyUvZvUlsKzm43u8ap9JisVy7L8gBsy82/k=" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="completed keyexchange" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="completed challenge" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=info msg="authenticated AP as 31tzw7346d5r54ykhl3ktgmkrj2m" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=info msg="authenticated Login5 as 31tzw7346d5r54ykhl3ktgmkrj2m" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="initializing zeroconf session, username: 31tzw7346d5r54ykhl3ktgmkrj2m" Mar 11 16:57:25 partytower volumio[911]: SPOTIFY: SETTING SPOTIFY VOLUME 65 Mar 11 16:57:25 partytower volumio[911]: info: Sending Spotify command with payload to local API: /player/volume Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="dealer connection opened" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=trace msg="starting accesspoint recv loop" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=trace msg="starting dealer recv loop" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="update volume to 42597/65535" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=trace msg="received accesspoint ping" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 11 16:57:25 partytower go-librespot[22305]: time="2025-03-11T16:57:25+01:00" level=debug msg="received connection id: YjRlOTA0ODUtMzFlZC00OGVjLTlkYjgtY2Y1ZmQyZmYyYmU2K2RlYWxlcit0Y3A6Ly8wYWNhNDExOC5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArNDVBRTYzMkFDODhENUMzOUI0NzcwODFDRkI3NDM3MDRGQUY0QkEyNDY5RjE0QUY2Q0Y3QzI4NjhFRTFBMUU4Mw==" Mar 11 16:57:26 partytower go-librespot[22305]: time="2025-03-11T16:57:26+01:00" level=trace msg="received accesspoint pong ack" Mar 11 16:57:26 partytower go-librespot[22305]: time="2025-03-11T16:57:26+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 11 16:57:27 partytower go-librespot[22305]: time="2025-03-11T16:57:27+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 11 16:57:27 partytower go-librespot[22305]: time="2025-03-11T16:57:27+01:00" level=trace msg="emitting websocket event: volume" Mar 11 16:57:27 partytower volumio[911]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Mar 11 16:57:27 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Mar 11 16:57:27 partytower volumio[911]: info: Creating Spotify config file Mar 11 16:57:27 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 16:57:27 partytower volumio[911]: info: Spotify config file written Mar 11 16:57:27 partytower sudo[22356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 11 16:57:27 partytower sudo[22356]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 16:57:27 partytower volumio[911]: info: Getting Spotify volume Mar 11 16:57:27 partytower volumio[911]: info: Spotify volume: 65 Mar 11 16:57:27 partytower volumio[911]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 11 16:57:27 partytower systemd[1]: Stopping go-librespot Daemon... Mar 11 16:57:27 partytower systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Mar 11 16:57:27 partytower systemd[1]: go-librespot-daemon.service: Succeeded. Mar 11 16:57:27 partytower systemd[1]: Stopped go-librespot Daemon. Mar 11 16:57:27 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:57:27 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:57:27 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:57:27 partytower systemd[1]: Started go-librespot Daemon. Mar 11 16:57:27 partytower go-librespot[22359]: go-librespot daemon starting... Mar 11 16:57:27 partytower sudo[22356]: pam_unix(sudo:session): session closed for user root Mar 11 16:57:27 partytower go-librespot[22359]: time="2025-03-11T16:57:27+01:00" level=info msg="running go-librespot 0.2.0" Mar 11 16:57:27 partytower go-librespot[22359]: time="2025-03-11T16:57:27+01:00" level=debug msg="app state loaded" Mar 11 16:57:27 partytower go-librespot[22359]: time="2025-03-11T16:57:27+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 11 16:57:27 partytower go-librespot[22359]: time="2025-03-11T16:57:27+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 11 16:57:27 partytower go-librespot[22359]: time="2025-03-11T16:57:27+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 11 16:57:27 partytower go-librespot[22359]: time="2025-03-11T16:57:27+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 11 16:57:27 partytower go-librespot[22359]: time="2025-03-11T16:57:27+01:00" level=info msg="zeroconf server listening on port 37147" Mar 11 16:57:27 partytower go-librespot[22359]: time="2025-03-11T16:57:27+01:00" level=debug msg="obtained new client token: AAChedjpfuea9HyMFDyp+tlv/nV7TamRjXcvUYgkLcSH+DIG0O3sVSVqZ3ZId5vcntL3tafY821hLk5DQSAqnJCWEaWCvYpF4tUE2RH2WdYb1VKTM6JnjjezFAAvH73v8kIg8TzvvCqRxAdDhWnQPMUMRK535ahZEd1iy4UNOepm0sqLqiPJbn49wTUAwXvzXI2ILD7epPxpcNpxS4aNgdAPggQKOldqsC30Asj5xUmfTGhe/h/8ISAgQNY=" Mar 11 16:57:27 partytower go-librespot[22359]: time="2025-03-11T16:57:27+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=debug msg="completed keyexchange" Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=debug msg="completed challenge" Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=info msg="authenticated AP as 31tzw7346d5r54ykhl3ktgmkrj2m" Mar 11 16:57:28 partytower volumio[911]: info: Initializing connection to go-librespot Websocket Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=debug msg="new websocket client" Mar 11 16:57:28 partytower volumio[911]: info: Connection to go-librespot Websocket established Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=info msg="authenticated Login5 as 31tzw7346d5r54ykhl3ktgmkrj2m" Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=debug msg="initializing zeroconf session, username: 31tzw7346d5r54ykhl3ktgmkrj2m" Mar 11 16:57:28 partytower volumio[911]: info: go-librespot daemon successfully initialized Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=debug msg="dealer connection opened" Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=trace msg="starting accesspoint recv loop" Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=trace msg="starting dealer recv loop" Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=trace msg="received accesspoint ping" Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=debug msg="received connection id: ZDExMTA4OTgtMjljMS00ZGNkLWEwMGItNjM4ZmU3MjcyYjY1K2RlYWxlcit0Y3A6Ly8wYWNhNDExYS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArNkVDMDM3NTgwMTYzQUI0ODVCMjY5NkI1MkY1QjY0QkM2RTVCN0RGM0ZDRTNGM0M0Rjg2Qjk1QTg0Qzc5QkE0NA==" Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=trace msg="received accesspoint pong ack" Mar 11 16:57:28 partytower go-librespot[22359]: time="2025-03-11T16:57:28+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 11 16:57:28 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 11 16:57:30 partytower volumio[911]: info: go-librespot daemon successfully initialized Mar 11 16:57:31 partytower volumio[911]: info: Getting Spotify volume Mar 11 16:57:31 partytower volumio[911]: info: Spotify volume: 100 Mar 11 16:57:31 partytower volumio[911]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 11 16:57:31 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:57:31 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:57:31 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:57:31 partytower volumio[911]: SPOTIFY: SPOTIFY VOLUME 100 Mar 11 16:57:31 partytower volumio[911]: SPOTIFY: VOLUMIO VOLUME 65 Mar 11 16:57:31 partytower volumio[911]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 11 16:57:31 partytower volumio[911]: info: Setting Spotify Volume from Volumio: 65 Mar 11 16:57:31 partytower volumio[911]: info: Initializing connection to go-librespot Websocket Mar 11 16:57:31 partytower go-librespot[22359]: time="2025-03-11T16:57:31+01:00" level=debug msg="new websocket client" Mar 11 16:57:31 partytower volumio[911]: info: Connection to go-librespot Websocket established Mar 11 16:57:32 partytower volumio[911]: SPOTIFY: SETTING SPOTIFY VOLUME 65 Mar 11 16:57:32 partytower volumio[911]: info: Sending Spotify command with payload to local API: /player/volume Mar 11 16:57:32 partytower go-librespot[22359]: time="2025-03-11T16:57:32+01:00" level=debug msg="update volume to 42597/65535" Mar 11 16:57:33 partytower volumio[911]: info: Initializing connection to go-librespot Websocket Mar 11 16:57:33 partytower go-librespot[22359]: time="2025-03-11T16:57:33+01:00" level=debug msg="new websocket client" Mar 11 16:57:33 partytower volumio[911]: info: Connection to go-librespot Websocket established Mar 11 16:57:33 partytower go-librespot[22359]: time="2025-03-11T16:57:33+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 11 16:57:33 partytower go-librespot[22359]: time="2025-03-11T16:57:33+01:00" level=trace msg="emitting websocket event: volume" Mar 11 16:57:33 partytower volumio[911]: SPOTIFY: received: {"type":"volume","data":{"value":65,"max":100}} Mar 11 16:57:33 partytower volumio[911]: SPOTIFY: RECEIVED SPOTIFY VOLUME 65 Mar 11 16:57:33 partytower volumio[911]: SPOTIFY: received: {"type":"volume","data":{"value":65,"max":100}} Mar 11 16:57:33 partytower volumio[911]: SPOTIFY: RECEIVED SPOTIFY VOLUME 65 Mar 11 16:57:33 partytower volumio[911]: SPOTIFY: received: {"type":"volume","data":{"value":65,"max":100}} Mar 11 16:57:33 partytower volumio[911]: SPOTIFY: RECEIVED SPOTIFY VOLUME 65 Mar 11 16:57:34 partytower volumio[911]: info: Getting Spotify volume Mar 11 16:57:34 partytower volumio[911]: info: Spotify volume: 65 Mar 11 16:57:34 partytower volumio[911]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 11 16:57:34 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:57:34 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:57:34 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:57:36 partytower volumio[911]: info: Getting Spotify volume Mar 11 16:57:36 partytower volumio[911]: info: Spotify volume: 65 Mar 11 16:57:36 partytower volumio[911]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 11 16:57:36 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:57:36 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:57:36 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:57:36 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 11 16:57:36 partytower volumio[911]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 11 16:57:36 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 11 16:57:36 partytower volumio[911]: info: Received Get System Version Mar 11 16:57:36 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 11 16:57:36 partytower volumio[911]: info: Received Get System Info Mar 11 16:57:36 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 16:57:36 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 16:57:36 partytower volumio[911]: info: Discovery: Getting this device information Mar 11 16:57:36 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:57:36 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:57:36 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 16:57:37 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 16:57:37 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 16:57:37 partytower volumio[911]: info: Discovery: Getting this device information Mar 11 16:57:37 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:57:37 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:57:37 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 16:57:58 partytower go-librespot[22359]: time="2025-03-11T16:57:58+01:00" level=trace msg="sent dealer ping" Mar 11 16:57:58 partytower go-librespot[22359]: time="2025-03-11T16:57:58+01:00" level=trace msg="received dealer pong" Mar 11 16:57:58 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:57:58 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:57:59 partytower volumio[911]: info: CoreCommandRouter::volumioGetQueue Mar 11 16:57:59 partytower volumio[911]: info: CoreStateMachine::getQueue Mar 11 16:57:59 partytower volumio[911]: info: CorePlayQueue::getQueue Mar 11 16:58:03 partytower volumio[911]: info: CoreCommandRouter::volumioSeek Mar 11 16:58:03 partytower volumio[911]: info: CoreStateMachine::seek Mar 11 16:58:03 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:58:03 partytower volumio[911]: info: TRACKBLOCK {"service":"ytmusic","uri":"ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Deklica%22%2C%22artist%22%3A%22Ansambel%20Ajda%22%2C%22album%22%3A%22Deklica%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F5fXbALXikhEvSDtfORBefmo76PCU3UCg4Q-G4flaaGyIWgI-TkyB4eO3hrq7R8fW2Jt3YRucFuyI7_D5Hw%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22jFyv86xq97s%22%2C%22playlistId%22%3A%22PLQIFzkExMn5wncfqiWkx5ysUSEFOaH685%22%2C%22params%22%3A%22OAHyAQIIAZIEI1FQOW96bGhISE44SGlrUldhS0tidEg2NjZiN25CcFMtOTRV%22%2C%22index%22%3A36%2C%22playlistSetVideoId%22%3A%224A07556FC5C9B361%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22jFyv86xq97s%22%2C%22playlistId%22%3A%22PLQIFzkExMn5wncfqiWkx5ysUSEFOaH685%22%2C%22params%22%3A%22OAHyAQIIAZIEI1FQOW96bGhISE44SGlrUldhS0tidEg2NjZiN25CcFMtOTRV%22%2C%22index%22%3A36%2C%22playlistSetVideoId%22%3A%224A07556FC5C9B361%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D","albumart":"https://lh3.googleusercontent.com/5fXbALXikhEvSDtfORBefmo76PCU3UCg4Q-G4flaaGyIWgI-TkyB4eO3hrq7R8fW2Jt3YRucFuyI7_D5Hw=w701-h701-l90-rj","artist":"Ansambel Ajda","album":"Deklica","name":"Deklica","title":"Deklica","duration":268,"samplerate":"VBR 256 kbps"} Mar 11 16:58:03 partytower volumio[911]: info: CoreStateMachine::startPlaybackTimer Mar 11 16:58:03 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:58:03 partytower volumio[911]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 11 16:58:03 partytower volumio[911]: info: ControllerMpd::seek Mar 11 16:58:03 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:03 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 16:58:03 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:03 partytower volumio[911]: error: null Mar 11 16:58:03 partytower volumio[911]: info: ControllerMpd::getState Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:58:03 partytower volumio[911]: info: Mar 11 16:58:03 partytower volumio[911]: ---------------------------- MPD announces state update: player Mar 11 16:58:03 partytower volumio[911]: info: ControllerMpd::getState Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:58:03 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:03 partytower volumio[911]: info: Mar 11 16:58:03 partytower volumio[911]: ---------------------------- MPD announces state update: player Mar 11 16:58:03 partytower volumio[911]: info: ControllerMpd::getState Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:58:03 partytower volumio[911]: info: Mar 11 16:58:03 partytower volumio[911]: ---------------------------- MPD announces state update: player Mar 11 16:58:03 partytower volumio[911]: info: ControllerMpd::getState Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:58:03 partytower volumio[911]: info: sendMpdCommand status took 19 milliseconds Mar 11 16:58:03 partytower volumio[911]: info: sendMpdCommand status took 18 milliseconds Mar 11 16:58:03 partytower volumio[911]: info: sendMpdCommand status took 6 milliseconds Mar 11 16:58:03 partytower volumio[911]: info: sendMpdCommand status took 4 milliseconds Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::parseState Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::parseState Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::parseState Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::parseState Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 16:58:03 partytower volumio[911]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 11 16:58:03 partytower volumio[911]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 11 16:58:03 partytower volumio[911]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 11 16:58:03 partytower volumio[911]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::parseTrackInfo Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::parseTrackInfo Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::parseTrackInfo Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::parseTrackInfo Mar 11 16:58:03 partytower volumio[911]: info: ControllerMpd::pushState Mar 11 16:58:03 partytower volumio[911]: info: CoreCommandRouter::servicePushState Mar 11 16:58:03 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:58:03 partytower volumio[911]: verbose: STATE SERVICE {"status":"play","position":0,"seek":266000,"duration":268,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Deklica","artist":"Ansambel Ajda","album":"Deklica","uri":"https://rr2---sn-uxaxh5ji-hcms.googlevideo.com/videoplayback?expire=1741730062&ei=rlzQZ-WZBIyH0u8PoOa0-Qw&ip=213.161.26.137&id=o-AMZgK46WQaRNN5dhDLlb57lIE02zPVtlgzeg1BqGZG_Z&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741708462%2C&mh=80&mm=31%2C29&mn=sn-uxaxh5ji-hcms%2Csn-c0q7lnsl&ms=au%2Crdu&mv=m&mvi=2&pl=19&rms=au%2Cau&ctier=A&pfa=5&gcr=si&initcwndbps=3571250&hightc=yes&siu=1&bui=AUWDL3zIg08_rdjTJuwr9qvU1jdbV7Ol0l-IwLoOnhlwVKoMJacBH108gkZmIPbGCcS5Ld1XFg&spc=RjZbSUJzDO-CD6TT1NuQMEkG-HJkI-KG_yEbM5FyfZ-i6vwGa-IYZYxOTS0GXiBFmL_EvUdkYUum&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=6Tji2iU0ds5X_NriA6Rmt4MQ&rqh=1&gir=yes&clen=8702244&dur=267.821&lmt=1714786116875635&mt=1741708157&fvip=5&keepalive=yes&fexp=51326932%2C51358317%2C51411872&c=WEB_REMIX&sefc=1&txp=2318224&n=V9eycSYlFi8KcQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRQIgE5FHA7KfCHOl5F3zVHsG5GManNVSp7AAA-xIly0glcACIQDRuVLkvDWzVkArE7XOZ_xx2Q6lUSi_-7G8O1HoAFNdwA%3D%3D&sig=AJfQdSswRAIgGd2dAKrWVmOCXJgnNnCXeKKaiMraMSvAahPIu64WXWsCIBQmjSYPm0Gz7RBmW_Arf10u_nyai22BJXMI5NbPqo5d&pot=Ml_G9R3Q3tNFfMbJLnXk6zpsrDFFnGVVMcMyT0muGozEhcjuUhqgl26gaCSA3VT_jn34R7c612xGnttDASiLNGZwChAzPuc8hwDIticxYF2RY6gI0M2f2beHmlU_Z0HF5A%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 11 16:58:03 partytower volumio[911]: verbose: CURRENT POSITION 36 Mar 11 16:58:03 partytower volumio[911]: info: CoreStateMachine::syncState stateService play Mar 11 16:58:03 partytower volumio[911]: info: CoreStateMachine::syncState currentStatus play Mar 11 16:58:03 partytower volumio[911]: info: Received an update from plugin. extracting info from payload Mar 11 16:58:03 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:03 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 16:58:03 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:03 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:03 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:03 partytower volumio[911]: info: ControllerMpd::pushState Mar 11 16:58:03 partytower volumio[911]: info: CoreCommandRouter::servicePushState Mar 11 16:58:03 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:58:03 partytower volumio[911]: verbose: STATE SERVICE {"status":"play","position":0,"seek":266484,"duration":268,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Deklica","artist":"Ansambel Ajda","album":"Deklica","uri":"https://rr2---sn-uxaxh5ji-hcms.googlevideo.com/videoplayback?expire=1741730062&ei=rlzQZ-WZBIyH0u8PoOa0-Qw&ip=213.161.26.137&id=o-AMZgK46WQaRNN5dhDLlb57lIE02zPVtlgzeg1BqGZG_Z&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741708462%2C&mh=80&mm=31%2C29&mn=sn-uxaxh5ji-hcms%2Csn-c0q7lnsl&ms=au%2Crdu&mv=m&mvi=2&pl=19&rms=au%2Cau&ctier=A&pfa=5&gcr=si&initcwndbps=3571250&hightc=yes&siu=1&bui=AUWDL3zIg08_rdjTJuwr9qvU1jdbV7Ol0l-IwLoOnhlwVKoMJacBH108gkZmIPbGCcS5Ld1XFg&spc=RjZbSUJzDO-CD6TT1NuQMEkG-HJkI-KG_yEbM5FyfZ-i6vwGa-IYZYxOTS0GXiBFmL_EvUdkYUum&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=6Tji2iU0ds5X_NriA6Rmt4MQ&rqh=1&gir=yes&clen=8702244&dur=267.821&lmt=1714786116875635&mt=1741708157&fvip=5&keepalive=yes&fexp=51326932%2C51358317%2C51411872&c=WEB_REMIX&sefc=1&txp=2318224&n=V9eycSYlFi8KcQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRQIgE5FHA7KfCHOl5F3zVHsG5GManNVSp7AAA-xIly0glcACIQDRuVLkvDWzVkArE7XOZ_xx2Q6lUSi_-7G8O1HoAFNdwA%3D%3D&sig=AJfQdSswRAIgGd2dAKrWVmOCXJgnNnCXeKKaiMraMSvAahPIu64WXWsCIBQmjSYPm0Gz7RBmW_Arf10u_nyai22BJXMI5NbPqo5d&pot=Ml_G9R3Q3tNFfMbJLnXk6zpsrDFFnGVVMcMyT0muGozEhcjuUhqgl26gaCSA3VT_jn34R7c612xGnttDASiLNGZwChAzPuc8hwDIticxYF2RY6gI0M2f2beHmlU_Z0HF5A%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 11 16:58:03 partytower volumio[911]: verbose: CURRENT POSITION 36 Mar 11 16:58:03 partytower volumio[911]: info: CoreStateMachine::syncState stateService play Mar 11 16:58:03 partytower volumio[911]: info: CoreStateMachine::syncState currentStatus play Mar 11 16:58:03 partytower volumio[911]: info: Received an update from plugin. extracting info from payload Mar 11 16:58:03 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:03 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:03 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:03 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:03 partytower volumio[911]: info: ControllerMpd::pushState Mar 11 16:58:03 partytower volumio[911]: info: CoreCommandRouter::servicePushState Mar 11 16:58:03 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:58:03 partytower volumio[911]: verbose: STATE SERVICE {"status":"play","position":0,"seek":266484,"duration":268,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Deklica","artist":"Ansambel Ajda","album":"Deklica","uri":"https://rr2---sn-uxaxh5ji-hcms.googlevideo.com/videoplayback?expire=1741730062&ei=rlzQZ-WZBIyH0u8PoOa0-Qw&ip=213.161.26.137&id=o-AMZgK46WQaRNN5dhDLlb57lIE02zPVtlgzeg1BqGZG_Z&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741708462%2C&mh=80&mm=31%2C29&mn=sn-uxaxh5ji-hcms%2Csn-c0q7lnsl&ms=au%2Crdu&mv=m&mvi=2&pl=19&rms=au%2Cau&ctier=A&pfa=5&gcr=si&initcwndbps=3571250&hightc=yes&siu=1&bui=AUWDL3zIg08_rdjTJuwr9qvU1jdbV7Ol0l-IwLoOnhlwVKoMJacBH108gkZmIPbGCcS5Ld1XFg&spc=RjZbSUJzDO-CD6TT1NuQMEkG-HJkI-KG_yEbM5FyfZ-i6vwGa-IYZYxOTS0GXiBFmL_EvUdkYUum&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=6Tji2iU0ds5X_NriA6Rmt4MQ&rqh=1&gir=yes&clen=8702244&dur=267.821&lmt=1714786116875635&mt=1741708157&fvip=5&keepalive=yes&fexp=51326932%2C51358317%2C51411872&c=WEB_REMIX&sefc=1&txp=2318224&n=V9eycSYlFi8KcQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRQIgE5FHA7KfCHOl5F3zVHsG5GManNVSp7AAA-xIly0glcACIQDRuVLkvDWzVkArE7XOZ_xx2Q6lUSi_-7G8O1HoAFNdwA%3D%3D&sig=AJfQdSswRAIgGd2dAKrWVmOCXJgnNnCXeKKaiMraMSvAahPIu64WXWsCIBQmjSYPm0Gz7RBmW_Arf10u_nyai22BJXMI5NbPqo5d&pot=Ml_G9R3Q3tNFfMbJLnXk6zpsrDFFnGVVMcMyT0muGozEhcjuUhqgl26gaCSA3VT_jn34R7c612xGnttDASiLNGZwChAzPuc8hwDIticxYF2RY6gI0M2f2beHmlU_Z0HF5A%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 11 16:58:03 partytower volumio[911]: verbose: CURRENT POSITION 36 Mar 11 16:58:03 partytower volumio[911]: info: CoreStateMachine::syncState stateService play Mar 11 16:58:03 partytower volumio[911]: info: CoreStateMachine::syncState currentStatus play Mar 11 16:58:03 partytower volumio[911]: info: Received an update from plugin. extracting info from payload Mar 11 16:58:03 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:03 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:03 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:03 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:03 partytower volumio[911]: info: ------------------------------ 57ms Mar 11 16:58:03 partytower volumio[911]: info: ------------------------------ 44ms Mar 11 16:58:03 partytower volumio[911]: info: ------------------------------ 42ms Mar 11 16:58:03 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:58:03 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:03 partytower volumio[911]: info: Prefetching next song Mar 11 16:58:03 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:03 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:03 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:03 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:03 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:03 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-uxaxh5ji-hcms.googlevideo.com/videoplayback?expire=1741730283&ei=i13QZ9yPHMHQ6dsPmpyjwQg&ip=213.161.26.137&id=o-ANmSMolNWDuXamiZIIIVW-mSjqN1GGam3t1DZOTz04bU&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741708683%2C&mh=H6&mm=31%2C29&mn=sn-uxaxh5ji-hcms%2Csn-c0q7lnsl&ms=au%2Crdu&mv=m&mvi=1&pl=19&rms=au%2Cau&ctier=A&pfa=5&gcr=si&initcwndbps=3571250&hightc=yes&siu=1&bui=AUWDL3yF44J6x4cBWwzX0kPp9VRwj25louQ21fAkes6sMXHGHO9GRAVP5SbY6Fv0MhaLRntQOA&spc=RjZbSYH12P-y1NgoS_2zGS-0tcL6f8DX8lq9YK5Fq9_DvUzsKFrQiaH7EyplodYOSF93xyV8QZMi&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yAJQgEsqxCFJn9jkCoHZoKAQ&rqh=1&gir=yes&clen=9385003&dur=285.521&lmt=1714611420993199&mt=1741708157&fvip=3&keepalive=yes&fexp=51326932%2C51358317%2C51411872&c=WEB_REMIX&sefc=1&txp=2318224&n=pUi2a76vouDyxw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRQIgM0fJvgTfqIGHhSJ3COglAXy7R0wvWJ_fcOMbgnlH1rgCIQCJDEn66uo5rGRjFtLomLkuxspt2M-NZ0Oor1Sp4ZVSLA%3D%3D&sig=AJfQdSswRgIhAIdKGgUVTBGhrBE4m8t9BUW0HgnhfnLyoZhR7nAOt_rsAiEAk2R0bRYakv697GoYncaJvWcl5Eg7LLSdt7hOnZI2lmI%3D&pot=Ml_G9R3Q3tNFfMbJLnXk6zpsrDFFnGVVMcMyT0muGozEhcjuUhqgl26gaCSA3VT_jn34R7c612xGnttDASiLNGZwChAzPuc8hwDIticxYF2RY6gI0M2f2beHmlU_Z0HF5A%3D%3D&cver=1.20211213.00.00&t.YouTube" Mar 11 16:58:03 partytower volumio[911]: info: sendMpdCommand addid "https://rr1---sn-uxaxh5ji-hcms.googlevideo.com/videoplayback?expire=1741730283&ei=i13QZ9yPHMHQ6dsPmpyjwQg&ip=213.161.26.137&id=o-ANmSMolNWDuXamiZIIIVW-mSjqN1GGam3t1DZOTz04bU&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741708683%2C&mh=H6&mm=31%2C29&mn=sn-uxaxh5ji-hcms%2Csn-c0q7lnsl&ms=au%2Crdu&mv=m&mvi=1&pl=19&rms=au%2Cau&ctier=A&pfa=5&gcr=si&initcwndbps=3571250&hightc=yes&siu=1&bui=AUWDL3yF44J6x4cBWwzX0kPp9VRwj25louQ21fAkes6sMXHGHO9GRAVP5SbY6Fv0MhaLRntQOA&spc=RjZbSYH12P-y1NgoS_2zGS-0tcL6f8DX8lq9YK5Fq9_DvUzsKFrQiaH7EyplodYOSF93xyV8QZMi&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yAJQgEsqxCFJn9jkCoHZoKAQ&rqh=1&gir=yes&clen=9385003&dur=285.521&lmt=1714611420993199&mt=1741708157&fvip=3&keepalive=yes&fexp=51326932%2C51358317%2C51411872&c=WEB_REMIX&sefc=1&txp=2318224&n=pUi2a76vouDyxw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRQIgM0fJvgTfqIGHhSJ3COglAXy7R0wvWJ_fcOMbgnlH1rgCIQCJDEn66uo5rGRjFtLomLkuxspt2M-NZ0Oor1Sp4ZVSLA%3D%3D&sig=AJfQdSswRgIhAIdKGgUVTBGhrBE4m8t9BUW0HgnhfnLyoZhR7nAOt_rsAiEAk2R0bRYakv697GoYncaJvWcl5Eg7LLSdt7hOnZI2lmI%3D&pot=Ml_G9R3Q3tNFfMbJLnXk6zpsrDFFnGVVMcMyT0muGozEhcjuUhqgl26gaCSA3VT_jn34R7c612xGnttDASiLNGZwChAzPuc8hwDIticxYF2RY6gI0M2f2beHmlU_Z0HF5A%3D%3D&cver=1.20211213.00.00&t.YouTube" took 4 milliseconds Mar 11 16:58:03 partytower volumio[911]: verbose: MPD COMMAND [object Object] Mar 11 16:58:03 partytower volumio[911]: verbose: MPD COMMAND [object Object] Mar 11 16:58:03 partytower volumio[911]: verbose: MPD COMMAND [object Object] Mar 11 16:58:03 partytower volumio[911]: info: Mar 11 16:58:03 partytower volumio[911]: ---------------------------- MPD announces system playlist update Mar 11 16:58:03 partytower volumio[911]: info: Ignoring MPD Status Update Mar 11 16:58:03 partytower volumio[911]: info: Mar 11 16:58:03 partytower volumio[911]: ---------------------------- MPD announces system playlist update Mar 11 16:58:03 partytower volumio[911]: info: Ignoring MPD Status Update Mar 11 16:58:03 partytower volumio[911]: info: [ytmusic-play] Prefetched and added track to MPD queue: Bury Me in Southern Ground Mar 11 16:58:03 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 11 16:58:03 partytower volumio[911]: info: Mar 11 16:58:03 partytower volumio[911]: ---------------------------- MPD announces system playlist update Mar 11 16:58:03 partytower volumio[911]: info: Ignoring MPD Status Update Mar 11 16:58:03 partytower volumio[911]: info: Mar 11 16:58:03 partytower volumio[911]: ---------------------------- MPD announces system playlist update Mar 11 16:58:03 partytower volumio[911]: info: Ignoring MPD Status Update Mar 11 16:58:03 partytower volumio[911]: info: ------------------------------ 10ms Mar 11 16:58:03 partytower volumio[911]: info: ------------------------------ 10ms Mar 11 16:58:03 partytower volumio[911]: info: sendMpdCommand consume 1 took 8 milliseconds Mar 11 16:58:03 partytower volumio[911]: info: ------------------------------ 7ms Mar 11 16:58:03 partytower volumio[911]: info: ------------------------------ 6ms Mar 11 16:58:03 partytower volumio[911]: info: Mar 11 16:58:03 partytower volumio[911]: ---------------------------- MPD announces system playlist update Mar 11 16:58:03 partytower volumio[911]: info: Ignoring MPD Status Update Mar 11 16:58:03 partytower volumio[911]: info: Mar 11 16:58:03 partytower volumio[911]: ---------------------------- MPD announces system playlist update Mar 11 16:58:03 partytower volumio[911]: info: Ignoring MPD Status Update Mar 11 16:58:03 partytower volumio[911]: info: ------------------------------ 3ms Mar 11 16:58:03 partytower volumio[911]: info: ------------------------------ 3ms Mar 11 16:58:04 partytower volumio[911]: info: Mar 11 16:58:04 partytower volumio[911]: ---------------------------- MPD announces system playlist update Mar 11 16:58:04 partytower volumio[911]: info: Ignoring MPD Status Update Mar 11 16:58:04 partytower volumio[911]: info: ControllerMpd::getState Mar 11 16:58:04 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:58:04 partytower volumio[911]: info: Mar 11 16:58:04 partytower volumio[911]: ---------------------------- MPD announces state update: player Mar 11 16:58:04 partytower volumio[911]: info: ControllerMpd::getState Mar 11 16:58:04 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:58:04 partytower volumio[911]: info: Mar 11 16:58:04 partytower volumio[911]: ---------------------------- MPD announces system playlist update Mar 11 16:58:04 partytower volumio[911]: info: Ignoring MPD Status Update Mar 11 16:58:04 partytower volumio[911]: info: Mar 11 16:58:04 partytower volumio[911]: ---------------------------- MPD announces state update: player Mar 11 16:58:04 partytower volumio[911]: info: ControllerMpd::getState Mar 11 16:58:04 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:58:04 partytower volumio[911]: info: sendMpdCommand status took 4 milliseconds Mar 11 16:58:04 partytower volumio[911]: info: ------------------------------ 5ms Mar 11 16:58:04 partytower volumio[911]: info: sendMpdCommand status took 3 milliseconds Mar 11 16:58:04 partytower volumio[911]: info: ------------------------------ 4ms Mar 11 16:58:04 partytower volumio[911]: info: sendMpdCommand status took 3 milliseconds Mar 11 16:58:04 partytower volumio[911]: verbose: ControllerMpd::parseState Mar 11 16:58:04 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 16:58:04 partytower volumio[911]: verbose: ControllerMpd::parseState Mar 11 16:58:04 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 16:58:04 partytower volumio[911]: verbose: ControllerMpd::parseState Mar 11 16:58:04 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 16:58:04 partytower volumio[911]: info: Mar 11 16:58:04 partytower volumio[911]: ---------------------------- MPD announces system playlist update Mar 11 16:58:04 partytower volumio[911]: info: Ignoring MPD Status Update Mar 11 16:58:04 partytower volumio[911]: info: Mar 11 16:58:04 partytower volumio[911]: ---------------------------- MPD announces state update: player Mar 11 16:58:04 partytower volumio[911]: info: ControllerMpd::getState Mar 11 16:58:04 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:58:04 partytower volumio[911]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 11 16:58:04 partytower volumio[911]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 11 16:58:04 partytower volumio[911]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 11 16:58:04 partytower volumio[911]: info: ------------------------------ 3ms Mar 11 16:58:04 partytower volumio[911]: info: sendMpdCommand status took 2 milliseconds Mar 11 16:58:04 partytower volumio[911]: verbose: ControllerMpd::parseTrackInfo Mar 11 16:58:04 partytower volumio[911]: verbose: ControllerMpd::parseTrackInfo Mar 11 16:58:04 partytower volumio[911]: verbose: ControllerMpd::parseTrackInfo Mar 11 16:58:04 partytower volumio[911]: verbose: ControllerMpd::parseState Mar 11 16:58:04 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 16:58:04 partytower volumio[911]: info: ControllerMpd::pushState Mar 11 16:58:04 partytower volumio[911]: info: CoreCommandRouter::servicePushState Mar 11 16:58:04 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:58:04 partytower volumio[911]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bury Me in Southern Ground","artist":"Rebel Son","album":"Unreconstructed","uri":"https://rr1---sn-uxaxh5ji-hcms.googlevideo.com/videoplayback?expire=1741730283&ei=i13QZ9yPHMHQ6dsPmpyjwQg&ip=213.161.26.137&id=o-ANmSMolNWDuXamiZIIIVW-mSjqN1GGam3t1DZOTz04bU&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741708683%2C&mh=H6&mm=31%2C29&mn=sn-uxaxh5ji-hcms%2Csn-c0q7lnsl&ms=au%2Crdu&mv=m&mvi=1&pl=19&rms=au%2Cau&ctier=A&pfa=5&gcr=si&initcwndbps=3571250&hightc=yes&siu=1&bui=AUWDL3yF44J6x4cBWwzX0kPp9VRwj25louQ21fAkes6sMXHGHO9GRAVP5SbY6Fv0MhaLRntQOA&spc=RjZbSYH12P-y1NgoS_2zGS-0tcL6f8DX8lq9YK5Fq9_DvUzsKFrQiaH7EyplodYOSF93xyV8QZMi&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yAJQgEsqxCFJn9jkCoHZoKAQ&rqh=1&gir=yes&clen=9385003&dur=285.521&lmt=1714611420993199&mt=1741708157&fvip=3&keepalive=yes&fexp=51326932%2C51358317%2C51411872&c=WEB_REMIX&sefc=1&txp=2318224&n=pUi2a76vouDyxw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRQIgM0fJvgTfqIGHhSJ3COglAXy7R0wvWJ_fcOMbgnlH1rgCIQCJDEn66uo5rGRjFtLomLkuxspt2M-NZ0Oor1Sp4ZVSLA%3D%3D&sig=AJfQdSswRgIhAIdKGgUVTBGhrBE4m8t9BUW0HgnhfnLyoZhR7nAOt_rsAiEAk2R0bRYakv697GoYncaJvWcl5Eg7LLSdt7hOnZI2lmI%3D&pot=Ml_G9R3Q3tNFfMbJLnXk6zpsrDFFnGVVMcMyT0muGozEhcjuUhqgl26gaCSA3VT_jn34R7c612xGnttDASiLNGZwChAzPuc8hwDIticxYF2RY6gI0M2f2beHmlU_Z0HF5A%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 11 16:58:04 partytower volumio[911]: verbose: CURRENT POSITION 36 Mar 11 16:58:04 partytower volumio[911]: info: CoreStateMachine::syncState stateService play Mar 11 16:58:04 partytower volumio[911]: info: CoreStateMachine::syncState currentStatus play Mar 11 16:58:04 partytower volumio[911]: info: Received an update from plugin. extracting info from payload Mar 11 16:58:04 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:04 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:58:04 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 16:58:04 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:04 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:58:04 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:04 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:58:04 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:04 partytower volumio[911]: info: ControllerMpd::pushState Mar 11 16:58:04 partytower volumio[911]: info: CoreCommandRouter::servicePushState Mar 11 16:58:04 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:58:04 partytower volumio[911]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bury Me in Southern Ground","artist":"Rebel Son","album":"Unreconstructed","uri":"https://rr1---sn-uxaxh5ji-hcms.googlevideo.com/videoplayback?expire=1741730283&ei=i13QZ9yPHMHQ6dsPmpyjwQg&ip=213.161.26.137&id=o-ANmSMolNWDuXamiZIIIVW-mSjqN1GGam3t1DZOTz04bU&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741708683%2C&mh=H6&mm=31%2C29&mn=sn-uxaxh5ji-hcms%2Csn-c0q7lnsl&ms=au%2Crdu&mv=m&mvi=1&pl=19&rms=au%2Cau&ctier=A&pfa=5&gcr=si&initcwndbps=3571250&hightc=yes&siu=1&bui=AUWDL3yF44J6x4cBWwzX0kPp9VRwj25louQ21fAkes6sMXHGHO9GRAVP5SbY6Fv0MhaLRntQOA&spc=RjZbSYH12P-y1NgoS_2zGS-0tcL6f8DX8lq9YK5Fq9_DvUzsKFrQiaH7EyplodYOSF93xyV8QZMi&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yAJQgEsqxCFJn9jkCoHZoKAQ&rqh=1&gir=yes&clen=9385003&dur=285.521&lmt=1714611420993199&mt=1741708157&fvip=3&keepalive=yes&fexp=51326932%2C51358317%2C51411872&c=WEB_REMIX&sefc=1&txp=2318224&n=pUi2a76vouDyxw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRQIgM0fJvgTfqIGHhSJ3COglAXy7R0wvWJ_fcOMbgnlH1rgCIQCJDEn66uo5rGRjFtLomLkuxspt2M-NZ0Oor1Sp4ZVSLA%3D%3D&sig=AJfQdSswRgIhAIdKGgUVTBGhrBE4m8t9BUW0HgnhfnLyoZhR7nAOt_rsAiEAk2R0bRYakv697GoYncaJvWcl5Eg7LLSdt7hOnZI2lmI%3D&pot=Ml_G9R3Q3tNFfMbJLnXk6zpsrDFFnGVVMcMyT0muGozEhcjuUhqgl26gaCSA3VT_jn34R7c612xGnttDASiLNGZwChAzPuc8hwDIticxYF2RY6gI0M2f2beHmlU_Z0HF5A%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 11 16:58:04 partytower volumio[911]: verbose: CURRENT POSITION 36 Mar 11 16:58:04 partytower volumio[911]: info: CoreStateMachine::syncState stateService play Mar 11 16:58:04 partytower volumio[911]: info: CoreStateMachine::syncState currentStatus play Mar 11 16:58:04 partytower volumio[911]: info: Received an update from plugin. extracting info from payload Mar 11 16:58:04 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:04 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:58:04 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:04 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:04 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:58:04 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:04 partytower volumio[911]: info: ------------------------------ 31ms Mar 11 16:58:04 partytower volumio[911]: info: ------------------------------ 30ms Mar 11 16:58:04 partytower volumio[911]: info: sendMpdCommand playlistinfo took 22 milliseconds Mar 11 16:58:04 partytower volumio[911]: verbose: ControllerMpd::parseTrackInfo Mar 11 16:58:04 partytower volumio[911]: info: ControllerMpd::pushState Mar 11 16:58:04 partytower volumio[911]: info: CoreCommandRouter::servicePushState Mar 11 16:58:04 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:58:04 partytower volumio[911]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bury Me in Southern Ground","artist":"Rebel Son","album":"Unreconstructed","uri":"https://rr1---sn-uxaxh5ji-hcms.googlevideo.com/videoplayback?expire=1741730283&ei=i13QZ9yPHMHQ6dsPmpyjwQg&ip=213.161.26.137&id=o-ANmSMolNWDuXamiZIIIVW-mSjqN1GGam3t1DZOTz04bU&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741708683%2C&mh=H6&mm=31%2C29&mn=sn-uxaxh5ji-hcms%2Csn-c0q7lnsl&ms=au%2Crdu&mv=m&mvi=1&pl=19&rms=au%2Cau&ctier=A&pfa=5&gcr=si&initcwndbps=3571250&hightc=yes&siu=1&bui=AUWDL3yF44J6x4cBWwzX0kPp9VRwj25louQ21fAkes6sMXHGHO9GRAVP5SbY6Fv0MhaLRntQOA&spc=RjZbSYH12P-y1NgoS_2zGS-0tcL6f8DX8lq9YK5Fq9_DvUzsKFrQiaH7EyplodYOSF93xyV8QZMi&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yAJQgEsqxCFJn9jkCoHZoKAQ&rqh=1&gir=yes&clen=9385003&dur=285.521&lmt=1714611420993199&mt=1741708157&fvip=3&keepalive=yes&fexp=51326932%2C51358317%2C51411872&c=WEB_REMIX&sefc=1&txp=2318224&n=pUi2a76vouDyxw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRQIgM0fJvgTfqIGHhSJ3COglAXy7R0wvWJ_fcOMbgnlH1rgCIQCJDEn66uo5rGRjFtLomLkuxspt2M-NZ0Oor1Sp4ZVSLA%3D%3D&sig=AJfQdSswRgIhAIdKGgUVTBGhrBE4m8t9BUW0HgnhfnLyoZhR7nAOt_rsAiEAk2R0bRYakv697GoYncaJvWcl5Eg7LLSdt7hOnZI2lmI%3D&pot=Ml_G9R3Q3tNFfMbJLnXk6zpsrDFFnGVVMcMyT0muGozEhcjuUhqgl26gaCSA3VT_jn34R7c612xGnttDASiLNGZwChAzPuc8hwDIticxYF2RY6gI0M2f2beHmlU_Z0HF5A%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 11 16:58:04 partytower volumio[911]: verbose: CURRENT POSITION 36 Mar 11 16:58:04 partytower volumio[911]: info: CoreStateMachine::syncState stateService play Mar 11 16:58:04 partytower volumio[911]: info: CoreStateMachine::syncState currentStatus play Mar 11 16:58:04 partytower volumio[911]: info: Received an update from plugin. extracting info from payload Mar 11 16:58:04 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:04 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:58:04 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:04 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:04 partytower volumio[911]: info: CorePlayQueue::getTrack 36 Mar 11 16:58:04 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:04 partytower volumio[911]: info: ------------------------------ 37ms Mar 11 16:58:04 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:04 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:04 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:04 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:04 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:04 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:04 partytower volumio[911]: info: CoreStateMachine::startPlaybackTimer Mar 11 16:58:04 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:05 partytower volumio[911]: info: ControllerMpd::getState Mar 11 16:58:05 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:58:05 partytower volumio[911]: info: Mar 11 16:58:05 partytower volumio[911]: ---------------------------- MPD announces state update: player Mar 11 16:58:05 partytower volumio[911]: info: ControllerMpd::getState Mar 11 16:58:05 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:58:05 partytower volumio[911]: info: Mar 11 16:58:05 partytower volumio[911]: ---------------------------- MPD announces state update: player Mar 11 16:58:05 partytower volumio[911]: info: ControllerMpd::getState Mar 11 16:58:05 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:58:05 partytower volumio[911]: info: Mar 11 16:58:05 partytower volumio[911]: ---------------------------- MPD announces state update: player Mar 11 16:58:05 partytower volumio[911]: info: ControllerMpd::getState Mar 11 16:58:05 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:58:05 partytower volumio[911]: info: sendMpdCommand status took 6 milliseconds Mar 11 16:58:05 partytower volumio[911]: info: sendMpdCommand status took 6 milliseconds Mar 11 16:58:05 partytower volumio[911]: info: sendMpdCommand status took 5 milliseconds Mar 11 16:58:05 partytower volumio[911]: verbose: ControllerMpd::parseState Mar 11 16:58:05 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 16:58:05 partytower volumio[911]: verbose: ControllerMpd::parseState Mar 11 16:58:05 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 16:58:05 partytower volumio[911]: verbose: ControllerMpd::parseState Mar 11 16:58:05 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 16:58:05 partytower volumio[911]: info: sendMpdCommand status took 8 milliseconds Mar 11 16:58:05 partytower volumio[911]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 11 16:58:05 partytower volumio[911]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 11 16:58:05 partytower volumio[911]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 11 16:58:05 partytower volumio[911]: verbose: ControllerMpd::parseState Mar 11 16:58:05 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 16:58:05 partytower volumio[911]: verbose: ControllerMpd::parseTrackInfo Mar 11 16:58:05 partytower volumio[911]: verbose: ControllerMpd::parseTrackInfo Mar 11 16:58:05 partytower volumio[911]: verbose: ControllerMpd::parseTrackInfo Mar 11 16:58:05 partytower volumio[911]: info: ControllerMpd::pushState Mar 11 16:58:05 partytower volumio[911]: info: CoreCommandRouter::servicePushState Mar 11 16:58:05 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:05 partytower volumio[911]: verbose: STATE SERVICE {"status":"play","position":0,"seek":85,"duration":286,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bury Me in Southern Ground","artist":"Rebel Son","album":"Unreconstructed","uri":"https://rr1---sn-uxaxh5ji-hcms.googlevideo.com/videoplayback?expire=1741730283&ei=i13QZ9yPHMHQ6dsPmpyjwQg&ip=213.161.26.137&id=o-ANmSMolNWDuXamiZIIIVW-mSjqN1GGam3t1DZOTz04bU&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741708683%2C&mh=H6&mm=31%2C29&mn=sn-uxaxh5ji-hcms%2Csn-c0q7lnsl&ms=au%2Crdu&mv=m&mvi=1&pl=19&rms=au%2Cau&ctier=A&pfa=5&gcr=si&initcwndbps=3571250&hightc=yes&siu=1&bui=AUWDL3yF44J6x4cBWwzX0kPp9VRwj25louQ21fAkes6sMXHGHO9GRAVP5SbY6Fv0MhaLRntQOA&spc=RjZbSYH12P-y1NgoS_2zGS-0tcL6f8DX8lq9YK5Fq9_DvUzsKFrQiaH7EyplodYOSF93xyV8QZMi&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yAJQgEsqxCFJn9jkCoHZoKAQ&rqh=1&gir=yes&clen=9385003&dur=285.521&lmt=1714611420993199&mt=1741708157&fvip=3&keepalive=yes&fexp=51326932%2C51358317%2C51411872&c=WEB_REMIX&sefc=1&txp=2318224&n=pUi2a76vouDyxw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRQIgM0fJvgTfqIGHhSJ3COglAXy7R0wvWJ_fcOMbgnlH1rgCIQCJDEn66uo5rGRjFtLomLkuxspt2M-NZ0Oor1Sp4ZVSLA%3D%3D&sig=AJfQdSswRgIhAIdKGgUVTBGhrBE4m8t9BUW0HgnhfnLyoZhR7nAOt_rsAiEAk2R0bRYakv697GoYncaJvWcl5Eg7LLSdt7hOnZI2lmI%3D&pot=Ml_G9R3Q3tNFfMbJLnXk6zpsrDFFnGVVMcMyT0muGozEhcjuUhqgl26gaCSA3VT_jn34R7c612xGnttDASiLNGZwChAzPuc8hwDIticxYF2RY6gI0M2f2beHmlU_Z0HF5A%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 11 16:58:05 partytower volumio[911]: verbose: CURRENT POSITION 37 Mar 11 16:58:05 partytower volumio[911]: info: CoreStateMachine::syncState stateService play Mar 11 16:58:05 partytower volumio[911]: info: CoreStateMachine::syncState currentStatus play Mar 11 16:58:05 partytower volumio[911]: info: Received an update from plugin. extracting info from payload Mar 11 16:58:05 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:05 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:05 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 16:58:05 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:05 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:05 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:05 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:05 partytower volumio[911]: info: ControllerMpd::pushState Mar 11 16:58:05 partytower volumio[911]: info: CoreCommandRouter::servicePushState Mar 11 16:58:05 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:05 partytower volumio[911]: verbose: STATE SERVICE {"status":"play","position":0,"seek":127,"duration":286,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bury Me in Southern Ground","artist":"Rebel Son","album":"Unreconstructed","uri":"https://rr1---sn-uxaxh5ji-hcms.googlevideo.com/videoplayback?expire=1741730283&ei=i13QZ9yPHMHQ6dsPmpyjwQg&ip=213.161.26.137&id=o-ANmSMolNWDuXamiZIIIVW-mSjqN1GGam3t1DZOTz04bU&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741708683%2C&mh=H6&mm=31%2C29&mn=sn-uxaxh5ji-hcms%2Csn-c0q7lnsl&ms=au%2Crdu&mv=m&mvi=1&pl=19&rms=au%2Cau&ctier=A&pfa=5&gcr=si&initcwndbps=3571250&hightc=yes&siu=1&bui=AUWDL3yF44J6x4cBWwzX0kPp9VRwj25louQ21fAkes6sMXHGHO9GRAVP5SbY6Fv0MhaLRntQOA&spc=RjZbSYH12P-y1NgoS_2zGS-0tcL6f8DX8lq9YK5Fq9_DvUzsKFrQiaH7EyplodYOSF93xyV8QZMi&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yAJQgEsqxCFJn9jkCoHZoKAQ&rqh=1&gir=yes&clen=9385003&dur=285.521&lmt=1714611420993199&mt=1741708157&fvip=3&keepalive=yes&fexp=51326932%2C51358317%2C51411872&c=WEB_REMIX&sefc=1&txp=2318224&n=pUi2a76vouDyxw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRQIgM0fJvgTfqIGHhSJ3COglAXy7R0wvWJ_fcOMbgnlH1rgCIQCJDEn66uo5rGRjFtLomLkuxspt2M-NZ0Oor1Sp4ZVSLA%3D%3D&sig=AJfQdSswRgIhAIdKGgUVTBGhrBE4m8t9BUW0HgnhfnLyoZhR7nAOt_rsAiEAk2R0bRYakv697GoYncaJvWcl5Eg7LLSdt7hOnZI2lmI%3D&pot=Ml_G9R3Q3tNFfMbJLnXk6zpsrDFFnGVVMcMyT0muGozEhcjuUhqgl26gaCSA3VT_jn34R7c612xGnttDASiLNGZwChAzPuc8hwDIticxYF2RY6gI0M2f2beHmlU_Z0HF5A%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 11 16:58:05 partytower volumio[911]: verbose: CURRENT POSITION 37 Mar 11 16:58:05 partytower volumio[911]: info: CoreStateMachine::syncState stateService play Mar 11 16:58:05 partytower volumio[911]: info: CoreStateMachine::syncState currentStatus play Mar 11 16:58:05 partytower volumio[911]: info: Received an update from plugin. extracting info from payload Mar 11 16:58:05 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:05 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:05 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 16:58:05 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:05 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:05 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:05 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:05 partytower volumio[911]: info: ------------------------------ 53ms Mar 11 16:58:05 partytower volumio[911]: info: ------------------------------ 52ms Mar 11 16:58:05 partytower volumio[911]: info: sendMpdCommand playlistinfo took 39 milliseconds Mar 11 16:58:05 partytower volumio[911]: verbose: ControllerMpd::parseTrackInfo Mar 11 16:58:05 partytower volumio[911]: info: ControllerMpd::pushState Mar 11 16:58:05 partytower volumio[911]: info: CoreCommandRouter::servicePushState Mar 11 16:58:05 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:05 partytower volumio[911]: verbose: STATE SERVICE {"status":"play","position":0,"seek":200,"duration":286,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bury Me in Southern Ground","artist":"Rebel Son","album":"Unreconstructed","uri":"https://rr1---sn-uxaxh5ji-hcms.googlevideo.com/videoplayback?expire=1741730283&ei=i13QZ9yPHMHQ6dsPmpyjwQg&ip=213.161.26.137&id=o-ANmSMolNWDuXamiZIIIVW-mSjqN1GGam3t1DZOTz04bU&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741708683%2C&mh=H6&mm=31%2C29&mn=sn-uxaxh5ji-hcms%2Csn-c0q7lnsl&ms=au%2Crdu&mv=m&mvi=1&pl=19&rms=au%2Cau&ctier=A&pfa=5&gcr=si&initcwndbps=3571250&hightc=yes&siu=1&bui=AUWDL3yF44J6x4cBWwzX0kPp9VRwj25louQ21fAkes6sMXHGHO9GRAVP5SbY6Fv0MhaLRntQOA&spc=RjZbSYH12P-y1NgoS_2zGS-0tcL6f8DX8lq9YK5Fq9_DvUzsKFrQiaH7EyplodYOSF93xyV8QZMi&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yAJQgEsqxCFJn9jkCoHZoKAQ&rqh=1&gir=yes&clen=9385003&dur=285.521&lmt=1714611420993199&mt=1741708157&fvip=3&keepalive=yes&fexp=51326932%2C51358317%2C51411872&c=WEB_REMIX&sefc=1&txp=2318224&n=pUi2a76vouDyxw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRQIgM0fJvgTfqIGHhSJ3COglAXy7R0wvWJ_fcOMbgnlH1rgCIQCJDEn66uo5rGRjFtLomLkuxspt2M-NZ0Oor1Sp4ZVSLA%3D%3D&sig=AJfQdSswRgIhAIdKGgUVTBGhrBE4m8t9BUW0HgnhfnLyoZhR7nAOt_rsAiEAk2R0bRYakv697GoYncaJvWcl5Eg7LLSdt7hOnZI2lmI%3D&pot=Ml_G9R3Q3tNFfMbJLnXk6zpsrDFFnGVVMcMyT0muGozEhcjuUhqgl26gaCSA3VT_jn34R7c612xGnttDASiLNGZwChAzPuc8hwDIticxYF2RY6gI0M2f2beHmlU_Z0HF5A%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 11 16:58:05 partytower volumio[911]: verbose: CURRENT POSITION 37 Mar 11 16:58:05 partytower volumio[911]: info: CoreStateMachine::syncState stateService play Mar 11 16:58:05 partytower volumio[911]: info: CoreStateMachine::syncState currentStatus play Mar 11 16:58:05 partytower volumio[911]: info: Received an update from plugin. extracting info from payload Mar 11 16:58:05 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:05 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:05 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 16:58:05 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:05 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:05 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:05 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:05 partytower volumio[911]: info: ------------------------------ 67ms Mar 11 16:58:05 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:05 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:05 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:05 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:05 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:05 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:05 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:05 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:05 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:05 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:07 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 16:58:07 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 16:58:07 partytower volumio[911]: info: Discovery: Getting this device information Mar 11 16:58:07 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:58:07 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:07 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 16:58:11 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:58:11 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:14 partytower volumio[911]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 16:58:14 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 11 16:58:14 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 16:58:14 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 11 16:58:14 partytower volumio[911]: info: Getting Alsa Cards List without I2S DAC Mar 11 16:58:14 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 11 16:58:14 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 16:58:14 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 16:58:14 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 16:58:14 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 16:58:14 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 16:58:14 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 16:58:14 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 16:58:14 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 16:58:14 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 11 16:58:14 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 11 16:58:18 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:58:18 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:21 partytower volumio[911]: info: CoreCommandRouter::volumioClearQueue Mar 11 16:58:21 partytower volumio[911]: info: CoreStateMachine::ClearQueue Mar 11 16:58:21 partytower volumio[911]: info: CoreStateMachine::stop Mar 11 16:58:21 partytower volumio[911]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 11 16:58:21 partytower volumio[911]: info: CoreStateMachine::stPlaybackTimer Mar 11 16:58:21 partytower volumio[911]: info: CoreStateMachine::updateTrackBlock Mar 11 16:58:21 partytower volumio[911]: info: CorePlayQueue::getTrackBlock Mar 11 16:58:21 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:21 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:21 partytower volumio[911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 16:58:21 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:21 partytower volumio[911]: info: CoreStateMachine::serviceStop Mar 11 16:58:21 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:21 partytower volumio[911]: info: CoreCommandRouter::serviceStop Mar 11 16:58:21 partytower volumio[911]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 11 16:58:21 partytower volumio[911]: info: ControllerMpd::stop Mar 11 16:58:21 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand stop Mar 11 16:58:21 partytower volumio[911]: info: CorePlayQueue::clearPlayQueue Mar 11 16:58:21 partytower volumio[911]: info: CorePlayQueue::saveQueue Mar 11 16:58:21 partytower volumio[911]: info: CoreCommandRouter::volumioPushState Mar 11 16:58:21 partytower volumio[911]: info: CoreCommandRouter::volumioPushQueue Mar 11 16:58:21 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:21 partytower volumio[911]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 11 16:58:21 partytower volumio[911]: info: sendMpdCommand stop took 22 milliseconds Mar 11 16:58:21 partytower volumio[911]: info: Mar 11 16:58:21 partytower volumio[911]: ---------------------------- MPD announces state update: player Mar 11 16:58:21 partytower volumio[911]: info: ControllerMpd::getState Mar 11 16:58:21 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:58:21 partytower volumio[911]: info: Mar 11 16:58:21 partytower volumio[911]: ---------------------------- MPD announces state update: player Mar 11 16:58:21 partytower volumio[911]: info: ControllerMpd::getState Mar 11 16:58:21 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:58:21 partytower volumio[911]: info: Mar 11 16:58:21 partytower volumio[911]: ---------------------------- MPD announces state update: player Mar 11 16:58:21 partytower volumio[911]: info: ControllerMpd::getState Mar 11 16:58:21 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:58:21 partytower volumio[911]: info: sendMpdCommand status took 4 milliseconds Mar 11 16:58:21 partytower volumio[911]: info: sendMpdCommand status took 3 milliseconds Mar 11 16:58:21 partytower volumio[911]: info: sendMpdCommand status took 2 milliseconds Mar 11 16:58:21 partytower volumio[911]: verbose: ControllerMpd::parseState Mar 11 16:58:21 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 16:58:21 partytower volumio[911]: verbose: ControllerMpd::parseState Mar 11 16:58:21 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 16:58:21 partytower volumio[911]: verbose: ControllerMpd::parseState Mar 11 16:58:21 partytower volumio[911]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 16:58:21 partytower volumio[911]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 11 16:58:21 partytower volumio[911]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 11 16:58:21 partytower volumio[911]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 11 16:58:21 partytower volumio[911]: verbose: ControllerMpd::parseTrackInfo Mar 11 16:58:21 partytower volumio[911]: verbose: ControllerMpd::parseTrackInfo Mar 11 16:58:21 partytower volumio[911]: verbose: ControllerMpd::parseTrackInfo Mar 11 16:58:21 partytower volumio[911]: info: ControllerMpd::pushState Mar 11 16:58:21 partytower volumio[911]: info: CoreCommandRouter::servicePushState Mar 11 16:58:21 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:21 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:21 partytower volumio[911]: info: ControllerMpd::pushState Mar 11 16:58:21 partytower volumio[911]: info: CoreCommandRouter::servicePushState Mar 11 16:58:21 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:21 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:21 partytower volumio[911]: info: ControllerMpd::pushState Mar 11 16:58:21 partytower volumio[911]: info: CoreCommandRouter::servicePushState Mar 11 16:58:21 partytower volumio[911]: info: CoreStateMachine::pushState Mar 11 16:58:21 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:21 partytower volumio[911]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Mar 11 16:58:21 partytower volumio[911]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Mar 11 16:58:21 partytower volumio[911]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Mar 11 16:58:21 partytower volumio[911]: info: ------------------------------ 18ms Mar 11 16:58:21 partytower volumio[911]: info: ------------------------------ 17ms Mar 11 16:58:21 partytower volumio[911]: info: ------------------------------ 16ms Mar 11 16:58:25 partytower volumio[911]: info: CoreCommandRouter::volumioGetState Mar 11 16:58:25 partytower volumio[911]: info: CorePlayQueue::getTrack 37 Mar 11 16:58:25 partytower volumio[911]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 11 16:58:25 partytower volumio[911]: TypeError: Cannot read property 'name' of undefined Mar 11 16:58:25 partytower volumio[911]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Mar 11 16:58:25 partytower volumio[911]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Mar 11 16:58:25 partytower volumio[911]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Mar 11 16:58:25 partytower volumio[911]: at Socket.emit (events.js:315:20) Mar 11 16:58:25 partytower volumio[911]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 11 16:58:25 partytower volumio[911]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 11 16:58:25 partytower volumio[911]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 11 16:58:26 partytower sudo[22602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-11 16:57 Mar 11 16:58:26 partytower sudo[22602]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"