-- Logs begin at Fri 2024-10-25 19:57:10 EEST, end at Fri 2024-10-25 19:59:20 EEST. -- Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 25 19:58:00 volumio volumio[1155]: info: [1729875480262] CoreMusicLibrary::Adding element Randomizer Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 19:58:00 volumio volumio[1155]: Cannot find translation for source Bandcamp Discover Oct 25 19:58:00 volumio volumio[1155]: Cannot find translation for source Calm Radio Oct 25 19:58:00 volumio volumio[1155]: Cannot find translation for source Mixcloud Oct 25 19:58:00 volumio volumio[1155]: Cannot find translation for source SoundCloud Oct 25 19:58:00 volumio volumio[1155]: Cannot find translation for source YouTube2 Oct 25 19:58:00 volumio volumio[1155]: Cannot find translation for source YouTube Music Oct 25 19:58:00 volumio volumio[1155]: Cannot find translation for source 80s80s Radio Oct 25 19:58:00 volumio volumio[1155]: Cannot find translation for source Mother Earth Radio Oct 25 19:58:00 volumio volumio[1155]: Cannot find translation for source Radio Paradise Oct 25 19:58:00 volumio volumio[1155]: Cannot find translation for source Randomizer Oct 25 19:58:00 volumio volumio[1155]: info: Volumio Calling Home Oct 25 19:58:00 volumio sudo[1902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Oct 25 19:58:00 volumio sudo[1902]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:00 volumio volumio[1155]: info: Preparing to generate the ALSA configuration file Oct 25 19:58:00 volumio volumio[1155]: info: [now-playing] Fetching API key... Oct 25 19:58:00 volumio volumio[1155]: info: [now-playing] App is listening on port 4004. Oct 25 19:58:00 volumio volumio[1155]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Oct 25 19:58:00 volumio volumio[1155]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 25 19:58:00 volumio volumio[1155]: info: Reading ALSA contributions from plugins. Oct 25 19:58:00 volumio volumio[1155]: info: minidlna: Found miniDLNA version 1.2.1 Oct 25 19:58:00 volumio volumio[1155]: info: minidlna: Starting minidlna.service Oct 25 19:58:00 volumio volumio[1155]: info: MPD Permissions set Oct 25 19:58:00 volumio volumio[1155]: info: MPD Permissions set Oct 25 19:58:00 volumio volumio[1155]: info: Upmpdcli Daemon Started Oct 25 19:58:00 volumio volumio[1155]: info: Spotify config file written Oct 25 19:58:00 volumio sudo[1919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 25 19:58:00 volumio sudo[1919]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:00 volumio volumio[1155]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 25 19:58:00 volumio volumio[1155]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 25 19:58:00 volumio sudo[1923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 25 19:58:00 volumio sudo[1923]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:00 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Oct 25 19:58:00 volumio volumio[1155]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:00 volumio sudo[1919]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 25 19:58:00 volumio systemd[1]: Started go-librespot Daemon. Oct 25 19:58:00 volumio go-librespot[1931]: Librespot-go daemon starting... Oct 25 19:58:00 volumio sudo[1923]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 25 19:58:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:00 volumio volumio[1155]: info: No need to fix Spotify hosts Oct 25 19:58:00 volumio sudo[1902]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:00 volumio go-librespot[1931]: time="2024-10-25T19:58:00+03:00" level=info msg="generated new device id: a05420ec4c221f2bf5afcd44ef974dc465d127f8" Oct 25 19:58:00 volumio go-librespot[1931]: time="2024-10-25T19:58:00+03:00" level=debug msg="stored credentials found for 31ig3yynt4nsa6b4kibop756kssy" Oct 25 19:58:00 volumio volumio[1155]: info: Volumio called home Oct 25 19:58:00 volumio volumio[1155]: info: minidlna: systemctl start minidlna.service succeeded. Oct 25 19:58:01 volumio volumio[1155]: info: Starting Shairport Sync Oct 25 19:58:01 volumio sudo[1960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Oct 25 19:58:01 volumio sudo[1960]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:01 volumio volumio[1155]: info: Starting Shairport Sync Oct 25 19:58:01 volumio sudo[1964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 25 19:58:01 volumio sudo[1964]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 25 19:58:01 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 25 19:58:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 25 19:58:01 volumio volumio[1155]: info: Starting Shairport Sync Oct 25 19:58:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 25 19:58:01 volumio sudo[1968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 25 19:58:01 volumio sudo[1968]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:01 volumio sudo[1964]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:01 volumio sudo[1971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 25 19:58:01 volumio sudo[1971]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 25 19:58:01 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 25 19:58:01 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 25 19:58:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 25 19:58:01 volumio volumio[1155]: info: CoreCommandRouter::volumioGetState Oct 25 19:58:01 volumio volumio[1155]: info: CorePlayQueue::getTrack 0 Oct 25 19:58:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 25 19:58:01 volumio sudo[1968]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 25 19:58:01 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 25 19:58:01 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 25 19:58:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 25 19:58:01 volumio mpd[1863]: Oct 25 19:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 25 19:58:01 volumio volumio[1155]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 25 19:58:01 volumio volumio[1155]: SPOTIFY: BQAEQ8xMsD7fFHLLq3Sro6d1vKPSCwhPAS6nJkn2jP6Kxz4LYMddOCFx0gLZt6rnilzzuubEz0CVVrKQWgf-HubhG3Ij3dntC299reVU-r4SOloQ5QaL7_SHYrmMnjJZt2y8yO4su8jVR_Djesgmz1D3A_M6Z13qrkVubO96dgV8nF2F-D0q_7xfEm2kuI38IIE98vSeFmavUyx443cYkHLd2GXGlOUmcZEUx8kPjsDtS1S4Orz3wfjGQxhFeEhIkJ7PheRi99TnmFQ Oct 25 19:58:01 volumio volumio[1155]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 25 19:58:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 25 19:58:01 volumio volumio[1155]: info: New Spotify access token = BQAEQ8xMsD7fFHLLq3Sro6d1vKPSCwhPAS6nJkn2jP6Kxz4LYMddOCFx0gLZt6rnilzzuubEz0CVVrKQWgf-HubhG3Ij3dntC299reVU-r4SOloQ5QaL7_SHYrmMnjJZt2y8yO4su8jVR_Djesgmz1D3A_M6Z13qrkVubO96dgV8nF2F-D0q_7xfEm2kuI38IIE98vSeFmavUyx443cYkHLd2GXGlOUmcZEUx8kPjsDtS1S4Orz3wfjGQxhFeEhIkJ7PheRi99TnmFQ Oct 25 19:58:01 volumio volumio[1155]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 25 19:58:01 volumio sudo[1971]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:01 volumio systemd[1]: Started Music Player Daemon. Oct 25 19:58:01 volumio sudo[1852]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:01 volumio sudo[1841]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:01 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Oct 25 19:58:01 volumio volumio[1155]: info: Shairport-Sync Started Oct 25 19:58:01 volumio volumio[1155]: Error adding Membership: Error: addMembership EINVAL Oct 25 19:58:01 volumio volumio[1155]: info: Shairport-Sync Started Oct 25 19:58:01 volumio volumio[1155]: info: Asound.conf file unchanged, so no further update is needed Oct 25 19:58:01 volumio volumio[1155]: info: Output device has changed, restarting MPD Oct 25 19:58:01 volumio sudo[1990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 25 19:58:01 volumio sudo[1990]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:01 volumio go-librespot[1931]: time="2024-10-25T19:58:01+03: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]" Oct 25 19:58:01 volumio go-librespot[1931]: time="2024-10-25T19:58:01+03: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]" Oct 25 19:58:01 volumio go-librespot[1931]: time="2024-10-25T19:58:01+03: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]" Oct 25 19:58:01 volumio sudo[1990]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:01 volumio go-librespot[1931]: time="2024-10-25T19:58:01+03:00" level=debug msg="zeroconf server listening on port 38337" Oct 25 19:58:01 volumio volumio[1155]: info: Output device has changed, restarting Shairport Sync Oct 25 19:58:01 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:01 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:01 volumio sudo[1993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 19:58:01 volumio sudo[1993]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:01 volumio systemd[1]: Stopping Music Player Daemon... Oct 25 19:58:01 volumio volumio[1155]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 25 19:58:01 volumio systemd[1]: mpd.service: Succeeded. Oct 25 19:58:01 volumio systemd[1]: Stopped Music Player Daemon. Oct 25 19:58:01 volumio systemd[1]: Starting Music Player Daemon... Oct 25 19:58:01 volumio volumio[1155]: Upnp client error: Error: This socket has been ended by the other party Oct 25 19:58:01 volumio go-librespot[1931]: time="2024-10-25T19:58:01+03:00" level=debug msg="obtained new client token: AADWKPpePQoMxolvc4ScpX0NmyzrTC3SGTS4i1ccIQnnsdV/CQVzMlpJSmH+ykDCQXWnFn6LAjQtOnI+FJ+3ZQ4wHysNpRROvuQI+lf8TsOO2EBega/HouPsuh4yvJDM02VquAsJt1+2NcDnoCA4U9+9E7aqSnUso84xUiz9KyMZdE6hwYCuXPRTrzmMEWHnwnuLA2qNDSfTJbpRCtJoFSUaB9uUPQU1PVCkaY7IV81WCB0/pfQs6VqYijDqBQ==" Oct 25 19:58:01 volumio volumio[1155]: info: Shairport-Sync Started Oct 25 19:58:01 volumio volumio-remote-updater[744]: [2024-10-25 19:58:01] [connect] Successful connection Oct 25 19:58:01 volumio sudo[2002]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 19:58:01 volumio sudo[2002]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:01 volumio go-librespot[1931]: time="2024-10-25T19:58:01+03:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 25 19:58:01 volumio sudo[2002]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:01 volumio volumio[1155]: info: MPD Permissions set Oct 25 19:58:01 volumio go-librespot[1931]: time="2024-10-25T19:58:01+03:00" level=debug msg="completed keyexchange" Oct 25 19:58:01 volumio volumio-remote-updater[744]: [2024-10-25 19:58:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729875481 101 Oct 25 19:58:01 volumio volumio[1155]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 8 Oct 25 19:58:01 volumio volumio[1155]: error: Cannot initialize MPD Connection: MPD is not running Oct 25 19:58:01 volumio volumio[1978]: cset: --> shielding system active with Oct 25 19:58:01 volumio volumio[1978]: cset: "system" cpuset of CPUSPEC(0-2) with 221 tasks running Oct 25 19:58:01 volumio volumio[1978]: cset: "user" cpuset of CPUSPEC(3) with 0 tasks running Oct 25 19:58:01 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:01 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:01 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:01 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:01 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:01 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:01 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:01 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:01 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:01 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 25 19:58:01 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Oct 25 19:58:01 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:01 volumio systemd[1]: Reloading. Oct 25 19:58:01 volumio volumio[1155]: info: AutoStart - Plugin is starting Oct 25 19:58:01 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:01 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:01 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:01 volumio volumio[1155]: info: AutoStart - start playing Oct 25 19:58:01 volumio volumio[1155]: info: AutoStart - start playing with no specific position Oct 25 19:58:01 volumio volumio[1155]: info: CoreCommandRouter::volumioPlay Oct 25 19:58:01 volumio volumio[1155]: info: CoreStateMachine::play index 0 Oct 25 19:58:01 volumio volumio[1155]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 19:58:01 volumio volumio[1155]: info: CoreStateMachine::stop Oct 25 19:58:01 volumio volumio[1155]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 19:58:01 volumio volumio[1155]: info: CoreStateMachine::play index undefined Oct 25 19:58:01 volumio volumio[1155]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 19:58:02 volumio volumio[1155]: info: CorePlayQueue::getTrack 0 Oct 25 19:58:02 volumio volumio[1155]: info: CoreStateMachine::startPlaybackTimer Oct 25 19:58:02 volumio volumio[1155]: info: CorePlayQueue::getTrack 0 Oct 25 19:58:02 volumio volumio[1155]: info: [1729875482012] ControllerUPNPBrowser::clearAddPlayTrack Oct 25 19:58:02 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 19:58:02 volumio volumio[1155]: info: Starting Shairport Sync Oct 25 19:58:02 volumio go-librespot[1931]: time="2024-10-25T19:58:02+03:00" level=debug msg="completed challenge" Oct 25 19:58:02 volumio volumio[1155]: SPOTIFY: User informations: {"country":"RO","display_name":"Eutu","email":"eutu51047@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31ig3yynt4nsa6b4kibop756kssy"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31ig3yynt4nsa6b4kibop756kssy","id":"31ig3yynt4nsa6b4kibop756kssy","images":[],"product":"free","type":"user","uri":"spotify:user:31ig3yynt4nsa6b4kibop756kssy"} Oct 25 19:58:02 volumio volumio[1155]: info: Spotify Successfully logged in Oct 25 19:58:02 volumio volumio[1155]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 25 19:58:02 volumio volumio[1155]: info: [1729875482227] CoreMusicLibrary::Adding element Spotify Oct 25 19:58:02 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 19:58:02 volumio sudo[2040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 25 19:58:02 volumio volumio[1155]: Cannot find translation for source Bandcamp Discover Oct 25 19:58:02 volumio volumio[1155]: Cannot find translation for source Calm Radio Oct 25 19:58:02 volumio volumio[1155]: Cannot find translation for source Mixcloud Oct 25 19:58:02 volumio volumio[1155]: Cannot find translation for source SoundCloud Oct 25 19:58:02 volumio volumio[1155]: Cannot find translation for source YouTube2 Oct 25 19:58:02 volumio volumio[1155]: Cannot find translation for source YouTube Music Oct 25 19:58:02 volumio volumio[1155]: Cannot find translation for source 80s80s Radio Oct 25 19:58:02 volumio volumio[1155]: Cannot find translation for source Mother Earth Radio Oct 25 19:58:02 volumio volumio[1155]: Cannot find translation for source Radio Paradise Oct 25 19:58:02 volumio volumio[1155]: Cannot find translation for source Randomizer Oct 25 19:58:02 volumio volumio[1155]: Cannot find translation for source Spotify Oct 25 19:58:02 volumio sudo[2040]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:02 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 25 19:58:02 volumio go-librespot[1931]: time="2024-10-25T19:58:02+03:00" level=debug msg="authenticated as 31ig3yynt4nsa6b4kibop756kssy" Oct 25 19:58:02 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 25 19:58:02 volumio go-librespot[1931]: time="2024-10-25T19:58:02+03:00" level=debug msg="authenticated as 31ig3yynt4nsa6b4kibop756kssy" Oct 25 19:58:02 volumio volumio[1155]: info: [now-playing] OpenWeatherMap API key obtained. Oct 25 19:58:02 volumio sudo[1960]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:02 volumio go-librespot[1931]: time="2024-10-25T19:58:02+03:00" level=debug msg="dealer connection opened" Oct 25 19:58:02 volumio go-librespot[1931]: time="2024-10-25T19:58:02+03:00" level=debug msg="initializing zeroconf session, username: 31ig3yynt4nsa6b4kibop756kssy" Oct 25 19:58:02 volumio go-librespot[1931]: time="2024-10-25T19:58:02+03:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 25 19:58:02 volumio go-librespot[1931]: time="2024-10-25T19:58:02+03:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 25 19:58:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 25 19:58:02 volumio volumio[1155]: info: succeeded Oct 25 19:58:02 volumio mpd[2012]: Oct 25 19:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 25 19:58:02 volumio go-librespot[1931]: time="2024-10-25T19:58:02+03:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 25 19:58:02 volumio go-librespot[1931]: time="2024-10-25T19:58:02+03:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 25 19:58:02 volumio go-librespot[1931]: time="2024-10-25T19:58:02+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Oct 25 19:58:02 volumio go-librespot[1931]: time="2024-10-25T19:58:02+03:00" level=debug msg="autoplay enabled: false" Oct 25 19:58:02 volumio go-librespot[1931]: time="2024-10-25T19:58:02+03:00" level=debug msg="received connection id: ODE5MGIwNWQtM2M2ZC00OTg1LWJiMzQtYTRlNGRhYWE2NTkwK2RlYWxlcit0Y3A6Ly8wYWNhNDFlOC5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArREU5NUQ4RUEwQjdGOTY1QTlDNThFMjkyRkFGM0U2RTExMEQwQ0NBODlBNkEwODE1NjlBMDUxMUU5NkU2RjZERA==" Oct 25 19:58:02 volumio systemd[1]: Started Music Player Daemon. Oct 25 19:58:02 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 25 19:58:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 25 19:58:02 volumio sudo[1993]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 25 19:58:02 volumio sudo[2040]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:02 volumio volumio[1155]: error: MPD error: The expression evaluated to a falsy value: Oct 25 19:58:02 volumio volumio[1155]: assert.ok(self.idling) Oct 25 19:58:02 volumio volumio[1155]: error: The expression evaluated to a falsy value: Oct 25 19:58:02 volumio volumio[1155]: assert.ok(self.idling) Oct 25 19:58:02 volumio volumio[1155]: error: updateQueue error: null Oct 25 19:58:02 volumio volumio[1155]: info: sendMpdCommand stop took 714 milliseconds Oct 25 19:58:02 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 19:58:02 volumio volumio[1155]: info: Shairport-Sync Started Oct 25 19:58:02 volumio volumio[1155]: info: Oct 25 19:58:02 volumio volumio[1155]: ---------------------------- MPD announces system playlist update Oct 25 19:58:02 volumio volumio[1155]: info: Ignoring MPD Status Update Oct 25 19:58:02 volumio volumio[1155]: info: sendMpdCommand clear took 24 milliseconds Oct 25 19:58:02 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.95:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I48394.flac" Oct 25 19:58:02 volumio volumio[1155]: info: Oct 25 19:58:02 volumio volumio[1155]: ---------------------------- MPD announces system playlist update Oct 25 19:58:02 volumio volumio[1155]: info: Ignoring MPD Status Update Oct 25 19:58:02 volumio volumio[1155]: error: updateQueue error: null Oct 25 19:58:02 volumio volumio[1155]: error: updateQueue error: null Oct 25 19:58:02 volumio volumio[1155]: info: ------------------------------ 14ms Oct 25 19:58:02 volumio go-librespot[1931]: time="2024-10-25T19:58:02+03:00" level=debug msg="put connect state because NEW_DEVICE" Oct 25 19:58:02 volumio volumio[1155]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=45.452995&lon=26.337554: Error: Response error: 401 - Unauthorized Oct 25 19:58:02 volumio volumio[1155]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 25 19:58:02 volumio volumio[1155]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 25 19:58:02 volumio volumio[1155]: at async Promise.all (index 1) Oct 25 19:58:02 volumio volumio[1155]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Oct 25 19:58:02 volumio volumio[1155]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Oct 25 19:58:02 volumio volumio[1155]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Oct 25 19:58:02 volumio volumio[1155]: info: [now-playing] Fetching API key... Oct 25 19:58:02 volumio volumio[1155]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=45.452995&lon=26.337554: Error: Response error: 401 - Unauthorized Oct 25 19:58:02 volumio volumio[1155]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 25 19:58:02 volumio volumio[1155]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 25 19:58:02 volumio volumio[1155]: at async Promise.all (index 0) Oct 25 19:58:02 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.95:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I48394.flac" Oct 25 19:58:02 volumio volumio[1155]: error: updateQueue error: null Oct 25 19:58:02 volumio volumio[1155]: info: Oct 25 19:58:02 volumio volumio[1155]: ---------------------------- MPD announces system playlist update Oct 25 19:58:02 volumio volumio[1155]: info: Ignoring MPD Status Update Oct 25 19:58:02 volumio volumio[1155]: info: ------------------------------ 153ms Oct 25 19:58:02 volumio volumio[1155]: info: sendMpdCommand add "http://192.168.1.95:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I48394.flac" took 11 milliseconds Oct 25 19:58:02 volumio volumio[1155]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 19:58:02 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand play Oct 25 19:58:02 volumio volumio[1155]: info: Oct 25 19:58:02 volumio volumio[1155]: ---------------------------- MPD announces system playlist update Oct 25 19:58:02 volumio volumio[1155]: info: Ignoring MPD Status Update Oct 25 19:58:02 volumio volumio[1155]: info: Oct 25 19:58:02 volumio volumio[1155]: ---------------------------- MPD announces system playlist update Oct 25 19:58:02 volumio volumio[1155]: info: Ignoring MPD Status Update Oct 25 19:58:02 volumio volumio[1155]: info: ------------------------------ 9ms Oct 25 19:58:02 volumio volumio[1155]: info: sendMpdCommand play took 7 milliseconds Oct 25 19:58:02 volumio volumio[1155]: info: ------------------------------ 5ms Oct 25 19:58:02 volumio volumio[1155]: info: ------------------------------ 5ms Oct 25 19:58:02 volumio volumio[1155]: info: [yt-cast-receiver] DIAL server listening on port 8098 Oct 25 19:58:02 volumio volumio[1155]: info: CoreCommandRouter::volumioRetrievevolume Oct 25 19:58:02 volumio volumio[1155]: info: Oct 25 19:58:02 volumio volumio[1155]: ---------------------------- MPD announces state update: player Oct 25 19:58:02 volumio volumio[1155]: info: ControllerMpd::getState Oct 25 19:58:02 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand status Oct 25 19:58:02 volumio volumio[1155]: info: Oct 25 19:58:02 volumio volumio[1155]: ---------------------------- MPD announces state update: player Oct 25 19:58:02 volumio volumio[1155]: info: ControllerMpd::getState Oct 25 19:58:02 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand status Oct 25 19:58:02 volumio volumio[1155]: info: Oct 25 19:58:02 volumio volumio[1155]: ---------------------------- MPD announces state update: player Oct 25 19:58:02 volumio volumio[1155]: info: ControllerMpd::getState Oct 25 19:58:02 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand status Oct 25 19:58:02 volumio volumio[1836]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 25 19:58:03 volumio volumio[1155]: info: Oct 25 19:58:03 volumio volumio[1155]: ---------------------------- MPD announces state update: player Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand status took 44 milliseconds Oct 25 19:58:03 volumio volumio[1155]: info: ControllerMpd::getState Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand status Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand status took 42 milliseconds Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand status took 41 milliseconds Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::parseState Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::parseState Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::parseState Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 19:58:03 volumio volumio[1155]: info: Oct 25 19:58:03 volumio volumio[1155]: ---------------------------- MPD announces state update: player Oct 25 19:58:03 volumio volumio[1155]: info: ControllerMpd::getState Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand status Oct 25 19:58:03 volumio volumio[1155]: info: Oct 25 19:58:03 volumio volumio[1155]: ---------------------------- MPD announces state update: player Oct 25 19:58:03 volumio volumio[1155]: info: ControllerMpd::getState Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand status Oct 25 19:58:03 volumio volumio[1155]: info: Oct 25 19:58:03 volumio volumio[1155]: ---------------------------- MPD announces system playlist update Oct 25 19:58:03 volumio volumio[1155]: info: Ignoring MPD Status Update Oct 25 19:58:03 volumio volumio[1155]: info: Oct 25 19:58:03 volumio volumio[1155]: ---------------------------- MPD announces state update: player Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand status took 63 milliseconds Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand playlistinfo took 62 milliseconds Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand playlistinfo took 61 milliseconds Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand playlistinfo took 60 milliseconds Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand status took 58 milliseconds Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand status took 57 milliseconds Oct 25 19:58:03 volumio volumio[1155]: info: ControllerMpd::getState Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand status Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::parseState Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::parseTrackInfo Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::parseTrackInfo Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::parseTrackInfo Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::parseState Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::parseState Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 19:58:03 volumio volumio[1155]: info: ControllerMpd::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::servicePushState Oct 25 19:58:03 volumio volumio[1155]: info: CorePlayQueue::getTrack 0 Oct 25 19:58:03 volumio volumio[1155]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I48394.flac","artist":null,"album":null,"uri":"http://192.168.1.95:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I48394.flac","trackType":"flac"} Oct 25 19:58:03 volumio volumio[1155]: verbose: CURRENT POSITION 0 Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState stateService play Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState currentStatus stop Oct 25 19:58:03 volumio volumio[1155]: info: ControllerMpd::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::servicePushState Oct 25 19:58:03 volumio volumio[1155]: info: CorePlayQueue::getTrack 0 Oct 25 19:58:03 volumio volumio[1155]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I48394.flac","artist":null,"album":null,"uri":"http://192.168.1.95:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I48394.flac","trackType":"flac"} Oct 25 19:58:03 volumio volumio[1155]: verbose: CURRENT POSITION 0 Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState stateService play Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState currentStatus play Oct 25 19:58:03 volumio volumio[1155]: info: Received an update from plugin. extracting info from payload Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1155]: info: ControllerMpd::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::servicePushState Oct 25 19:58:03 volumio volumio[1155]: info: CorePlayQueue::getTrack 0 Oct 25 19:58:03 volumio volumio[1155]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I48394.flac","artist":null,"album":null,"uri":"http://192.168.1.95:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I48394.flac","trackType":"flac"} Oct 25 19:58:03 volumio volumio[1155]: verbose: CURRENT POSITION 0 Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState stateService play Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState currentStatus play Oct 25 19:58:03 volumio volumio[1155]: info: Received an update from plugin. extracting info from payload Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1155]: info: ------------------------------ 159ms Oct 25 19:58:03 volumio volumio[1155]: info: ------------------------------ 175ms Oct 25 19:58:03 volumio volumio[1155]: info: ------------------------------ 172ms Oct 25 19:58:03 volumio volumio[1155]: info: Oct 25 19:58:03 volumio volumio[1155]: ---------------------------- MPD announces system playlist update Oct 25 19:58:03 volumio volumio[1155]: info: Ignoring MPD Status Update Oct 25 19:58:03 volumio volumio[1155]: info: Oct 25 19:58:03 volumio volumio[1155]: ---------------------------- MPD announces state update: player Oct 25 19:58:03 volumio volumio[1155]: info: ControllerMpd::getState Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand status Oct 25 19:58:03 volumio volumio[1155]: info: Oct 25 19:58:03 volumio volumio[1155]: ---------------------------- MPD announces system playlist update Oct 25 19:58:03 volumio volumio[1155]: info: Ignoring MPD Status Update Oct 25 19:58:03 volumio volumio[1155]: info: Oct 25 19:58:03 volumio volumio[1155]: ---------------------------- MPD announces state update: player Oct 25 19:58:03 volumio volumio[1155]: info: ControllerMpd::getState Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand status Oct 25 19:58:03 volumio volumio[1155]: info: ------------------------------ 96ms Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand status took 92 milliseconds Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand playlistinfo took 91 milliseconds Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand playlistinfo took 79 milliseconds Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand playlistinfo took 78 milliseconds Oct 25 19:58:03 volumio volumio[1155]: info: ------------------------------ 28ms Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand status took 18 milliseconds Oct 25 19:58:03 volumio volumio[1155]: info: ------------------------------ 17ms Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand status took 16 milliseconds Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::parseState Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::parseTrackInfo Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::parseState Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::parseState Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 19:58:03 volumio volumio[1155]: info: ControllerMpd::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::servicePushState Oct 25 19:58:03 volumio volumio[1155]: info: CorePlayQueue::getTrack 0 Oct 25 19:58:03 volumio volumio[1155]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"(Feels Like) Heaven","artist":"Fiction Factory","album":"80s 100 Hits","uri":"http://192.168.1.95:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I48394.flac","trackType":"flac"} Oct 25 19:58:03 volumio volumio[1155]: verbose: CURRENT POSITION 0 Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState stateService play Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState currentStatus play Oct 25 19:58:03 volumio volumio[1155]: info: Received an update from plugin. extracting info from payload Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1836]: .....++++ Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1155]: info: ControllerMpd::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::servicePushState Oct 25 19:58:03 volumio volumio[1155]: info: CorePlayQueue::getTrack 0 Oct 25 19:58:03 volumio volumio[1155]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"(Feels Like) Heaven","artist":"Fiction Factory","album":"80s 100 Hits","uri":"http://192.168.1.95:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I48394.flac","trackType":"flac"} Oct 25 19:58:03 volumio volumio[1155]: verbose: CURRENT POSITION 0 Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState stateService play Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState currentStatus play Oct 25 19:58:03 volumio volumio[1155]: info: Received an update from plugin. extracting info from payload Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1155]: info: ControllerMpd::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::servicePushState Oct 25 19:58:03 volumio volumio[1155]: info: CorePlayQueue::getTrack 0 Oct 25 19:58:03 volumio volumio[1155]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"(Feels Like) Heaven","artist":"Fiction Factory","album":"80s 100 Hits","uri":"http://192.168.1.95:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I48394.flac","trackType":"flac"} Oct 25 19:58:03 volumio volumio[1155]: verbose: CURRENT POSITION 0 Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState stateService play Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState currentStatus play Oct 25 19:58:03 volumio volumio[1155]: info: Received an update from plugin. extracting info from payload Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1155]: info: ------------------------------ 224ms Oct 25 19:58:03 volumio volumio[1155]: info: ------------------------------ 217ms Oct 25 19:58:03 volumio volumio[1155]: info: ------------------------------ 216ms Oct 25 19:58:03 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:03 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:03 volumio sudo[2071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:03 volumio sudo[2071]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:03 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:03 volumio sudo[2074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:03 volumio sudo[2074]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:03 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:03 volumio sudo[2077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:03 volumio sudo[2077]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:03 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:03 volumio sudo[2080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:03 volumio sudo[2080]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:03 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:03 volumio sudo[2083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:03 volumio sudo[2083]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:03 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:03 volumio sudo[2086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:03 volumio sudo[2086]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:03 volumio volumio[1836]: ................++++ Oct 25 19:58:03 volumio volumio[1836]: e is 65537 (0x010001) Oct 25 19:58:03 volumio volumio[1836]: writing RSA key Oct 25 19:58:03 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:03 volumio sudo[2090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:03 volumio sudo[2090]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:03 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:03 volumio sudo[2096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:03 volumio sudo[2096]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:03 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:03 volumio sudo[2099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:03 volumio sudo[2099]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:03 volumio sudo[2110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:03 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:03 volumio sudo[2110]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:03 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:03 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:03 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:03 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:03 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:03 volumio volumio[1155]: info: VolumeController:: Volume=50 Mute =false Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioGetState Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1155]: info: Completed starting Core Plugins Oct 25 19:58:03 volumio volumio[1155]: info: ------------------------------------------- Oct 25 19:58:03 volumio volumio[1155]: info: ----- MyVolumio plugins startup ---- Oct 25 19:58:03 volumio volumio[1155]: info: ------------------------------------------- Oct 25 19:58:03 volumio volumio[1155]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 25 19:58:03 volumio systemd[1]: Started peppymeterbasic Daemon. Oct 25 19:58:03 volumio sudo[2090]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:03 volumio sudo[2077]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:03 volumio sudo[2086]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:03 volumio sudo[2071]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:03 volumio sudo[2080]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:03 volumio volumio[1155]: error: error Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand playlistinfo took 693 milliseconds Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand playlistinfo took 692 milliseconds Oct 25 19:58:03 volumio volumio[1155]: info: sendMpdCommand playlistinfo took 691 milliseconds Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::parseTrackInfo Oct 25 19:58:03 volumio sudo[2099]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:03 volumio sudo[2083]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:03 volumio sudo[2074]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::parseTrackInfo Oct 25 19:58:03 volumio volumio[1155]: verbose: ControllerMpd::parseTrackInfo Oct 25 19:58:03 volumio volumio[1155]: info: ControllerMpd::pushState Oct 25 19:58:03 volumio sudo[2096]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::servicePushState Oct 25 19:58:03 volumio volumio[1155]: info: CorePlayQueue::getTrack 0 Oct 25 19:58:03 volumio volumio[1155]: verbose: STATE SERVICE {"status":"play","position":0,"seek":956,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"994 Kbps","isStreaming":false,"title":"(Feels Like) Heaven","artist":"Fiction Factory","album":"80s 100 Hits","uri":"http://192.168.1.95:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I48394.flac","trackType":"flac"} Oct 25 19:58:03 volumio volumio[1155]: verbose: CURRENT POSITION 0 Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState stateService play Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState currentStatus play Oct 25 19:58:03 volumio volumio[1155]: info: Received an update from plugin. extracting info from payload Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio sudo[2110]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1155]: info: ControllerMpd::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::servicePushState Oct 25 19:58:03 volumio volumio[1155]: info: CorePlayQueue::getTrack 0 Oct 25 19:58:03 volumio volumio[1155]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"893 Kbps","isStreaming":false,"title":"(Feels Like) Heaven","artist":"Fiction Factory","album":"80s 100 Hits","uri":"http://192.168.1.95:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I48394.flac","trackType":"flac"} Oct 25 19:58:03 volumio volumio[1155]: verbose: CURRENT POSITION 0 Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState stateService play Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState currentStatus play Oct 25 19:58:03 volumio volumio[1155]: info: Received an update from plugin. extracting info from payload Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1155]: info: ControllerMpd::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::servicePushState Oct 25 19:58:03 volumio volumio[1155]: info: CorePlayQueue::getTrack 0 Oct 25 19:58:03 volumio volumio[1155]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"893 Kbps","isStreaming":false,"title":"(Feels Like) Heaven","artist":"Fiction Factory","album":"80s 100 Hits","uri":"http://192.168.1.95:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I48394.flac","trackType":"flac"} Oct 25 19:58:03 volumio volumio[1155]: verbose: CURRENT POSITION 0 Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState stateService play Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::syncState currentStatus play Oct 25 19:58:03 volumio volumio[1155]: info: Received an update from plugin. extracting info from payload Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:03 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:03 volumio volumio[1155]: info: ------------------------------ 844ms Oct 25 19:58:03 volumio volumio[1155]: info: ------------------------------ 774ms Oct 25 19:58:03 volumio volumio[1155]: info: ------------------------------ 763ms Oct 25 19:58:03 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:03 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:03 volumio sudo[2117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:03 volumio sudo[2117]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:04 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:04 volumio sudo[2117]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:04 volumio sudo[2120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:04 volumio sudo[2120]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:04 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:04 volumio sudo[2120]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:04 volumio sudo[2123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:04 volumio sudo[2123]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:04 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:04 volumio sudo[2123]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:04 volumio sudo[2126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:04 volumio sudo[2126]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:04 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:04 volumio sudo[2126]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:04 volumio sudo[2129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:04 volumio sudo[2129]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:04 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:04 volumio sudo[2129]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:04 volumio sudo[2132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:04 volumio sudo[2132]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:04 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:04 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:04 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:04 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:04 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:04 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:04 volumio sudo[2132]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:04 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:04 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:04 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:04 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:04 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:04 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:04 volumio sudo[2135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:04 volumio sudo[2135]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:04 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:04 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:04 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:04 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:04 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:04 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:04 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:04 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:04 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:04 volumio sudo[2135]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: info: go-librespot daemon successfully initialized Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:04 volumio volumio[1155]: error: error Oct 25 19:58:05 volumio volumio[2112]: libpng warning: iCCP: known incorrect sRGB profile Oct 25 19:58:06 volumio volumio[2112]: libpng warning: iCCP: known incorrect sRGB profile Oct 25 19:58:07 volumio volumio[1155]: verbose: New Socket.io Connection to 192.168.1.109 from 192.168.1.48 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Edg/130.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 25 19:58:07 volumio volumio[1155]: info: Initializing connection to go-librespot Websocket Oct 25 19:58:07 volumio go-librespot[1931]: time="2024-10-25T19:58:07+03:00" level=debug msg="new websocket client" Oct 25 19:58:07 volumio volumio[1155]: info: Connection to go-librespot Websocket established Oct 25 19:58:07 volumio volumio[1155]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 25 19:58:08 volumio volumio[1155]: verbose: New Socket.io Connection to 192.168.1.109 from 192.168.1.48 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Edg/130.0.0.0 Engine version: 3 Transport: polling Total Clients: 10 Oct 25 19:58:10 volumio volumio[1155]: info: Getting Spotify volume Oct 25 19:58:10 volumio volumio[1155]: info: Spotify volume: 100 Oct 25 19:58:10 volumio volumio[1155]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Oct 25 19:58:10 volumio volumio[1155]: info: CoreCommandRouter::volumioGetState Oct 25 19:58:10 volumio volumio[1155]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Oct 25 19:58:10 volumio volumio[1155]: SPOTIFY: SPOTIFY VOLUME 100 Oct 25 19:58:10 volumio volumio[1155]: SPOTIFY: VOLUMIO VOLUME 50 Oct 25 19:58:10 volumio volumio[1155]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 25 19:58:10 volumio volumio[1155]: info: Setting Spotify Volume from Volumio: 50 Oct 25 19:58:11 volumio volumio[1155]: info: [now-playing] OpenWeatherMap API key obtained. Oct 25 19:58:11 volumio volumio[1155]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=45.452995&lon=26.337554: Error: Response error: 401 - Unauthorized Oct 25 19:58:11 volumio volumio[1155]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 25 19:58:11 volumio volumio[1155]: at runMicrotasks () Oct 25 19:58:11 volumio volumio[1155]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 25 19:58:11 volumio volumio[1155]: at async Promise.all (index 1) Oct 25 19:58:11 volumio volumio[1155]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Oct 25 19:58:11 volumio volumio[1155]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Oct 25 19:58:11 volumio volumio[1155]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Oct 25 19:58:11 volumio volumio[1155]: (node:1155) UnhandledPromiseRejectionWarning: Error: Response error: 401 - Unauthorized Oct 25 19:58:11 volumio volumio[1155]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 25 19:58:11 volumio volumio[1155]: at runMicrotasks () Oct 25 19:58:11 volumio volumio[1155]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 25 19:58:11 volumio volumio[1155]: at async Promise.all (index 1) Oct 25 19:58:11 volumio volumio[1155]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Oct 25 19:58:11 volumio volumio[1155]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Oct 25 19:58:11 volumio volumio[1155]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Oct 25 19:58:11 volumio volumio[1155]: (Use `node --trace-warnings ...` to show where the warning was created) Oct 25 19:58:11 volumio volumio[1155]: (node:1155) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Oct 25 19:58:11 volumio volumio[1155]: (node:1155) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Oct 25 19:58:11 volumio volumio[1155]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=45.452995&lon=26.337554: Error: Response error: 401 - Unauthorized Oct 25 19:58:11 volumio volumio[1155]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 25 19:58:11 volumio volumio[1155]: at runMicrotasks () Oct 25 19:58:11 volumio volumio[1155]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 25 19:58:11 volumio volumio[1155]: at async Promise.all (index 0) Oct 25 19:58:11 volumio volumio[1155]: SPOTIFY: SETTING SPOTIFY VOLUME 50 Oct 25 19:58:11 volumio volumio[1155]: info: Sending Spotify command with payload to local API: /player/volume Oct 25 19:58:11 volumio go-librespot[1931]: time="2024-10-25T19:58:11+03:00" level=debug msg="update volume to 32767/65535" Oct 25 19:58:11 volumio go-librespot[1931]: time="2024-10-25T19:58:11+03:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 25 19:58:11 volumio go-librespot[1931]: time="2024-10-25T19:58:11+03:00" level=trace msg="emitting websocket event: volume" Oct 25 19:58:11 volumio volumio[1155]: SPOTIFY: received: {"type":"volume","data":{"value":50,"max":100}} Oct 25 19:58:11 volumio volumio[1155]: SPOTIFY: RECEIVED SPOTIFY VOLUME 50 Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 25 19:58:12 volumio volumio[1155]: info: Adding plugin bluetooth to MyMusic Plugins Oct 25 19:58:12 volumio volumio[1155]: info: Adding plugin multiroom to MyMusic Plugins Oct 25 19:58:12 volumio volumio[1155]: info: Adding plugin metavolumio to MyMusic Plugins Oct 25 19:58:12 volumio volumio[1155]: info: Adding plugin cd_controller to MyMusic Plugins Oct 25 19:58:12 volumio volumio[1155]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 25 19:58:12 volumio volumio[1155]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 25 19:58:12 volumio volumio[1155]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 25 19:58:13 volumio volumio[1155]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 25 19:58:13 volumio volumio[1155]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 25 19:58:13 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:13 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:13 volumio volumio[1155]: info: Starting MyVolumio Remote Streaming Endpoints Oct 25 19:58:13 volumio volumio[1155]: info: MyVolumio login type: Token Oct 25 19:58:13 volumio volumio[1155]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 25 19:58:13 volumio volumio[1155]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 25 19:58:15 volumio volumio[1155]: info: Starting Streaming Service Transparent Proxy Oct 25 19:58:15 volumio volumio[1155]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 25 19:58:15 volumio volumio[1155]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 25 19:58:15 volumio volumio[1155]: info: Streaming services startup Oct 25 19:58:15 volumio volumio[1155]: info: Starting Streaming Daemon Oct 25 19:58:15 volumio sudo[2242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 25 19:58:15 volumio sudo[2242]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:15 volumio sudo[2242]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:15 volumio volumio[1155]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 25 19:58:15 volumio volumio[1155]: error: Cannot start Volumio Streaming Daemon Oct 25 19:58:15 volumio volumio[1155]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 25 19:58:15 volumio volumio[1155]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 25 19:58:15 volumio volumio[1155]: Upnp client error: Error: This socket has been ended by the other party Oct 25 19:58:15 volumio volumio[1155]: STREAMING PROXY: Starting server on port 3245 Oct 25 19:58:15 volumio volumio[1155]: Node JS runtime: 14 Oct 25 19:58:17 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Oct 25 19:58:18 volumio volumio[1155]: verbose: New Socket.io Connection to 192.168.1.109 from 192.168.1.48 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Edg/130.0.0.0 Engine version: 3 Transport: polling Total Clients: 12 Oct 25 19:58:18 volumio volumio[1155]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 25 19:58:19 volumio volumio[1155]: info: MyVolumio token set successfully Oct 25 19:58:19 volumio volumio[1155]: info: MYVOLUMIO: Adding device Oct 25 19:58:19 volumio volumio[1155]: info: MYVOLUMIO: Evaluating Server Oct 25 19:58:19 volumio volumio[1155]: info: MyVolumio status changed Oct 25 19:58:19 volumio volumio[1155]: info: Streaming services startup Oct 25 19:58:19 volumio volumio[1155]: info: Starting Streaming Daemon Oct 25 19:58:20 volumio volumio[1155]: info: Removing browser output: myVolumio user plan is not superstar Oct 25 19:58:20 volumio volumio[1155]: info: Removing audio output: Oct 25 19:58:20 volumio volumio[1155]: info: Stoppping Tunnel 1 Oct 25 19:58:20 volumio sudo[2279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 25 19:58:20 volumio sudo[2279]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:20 volumio sudo[2279]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:20 volumio volumio[1155]: verbose: New Socket.io Connection to 192.168.1.109 from 192.168.1.48 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Edg/130.0.0.0 Engine version: 3 Transport: polling Total Clients: 13 Oct 25 19:58:20 volumio volumio[1155]: error: Cannot start Volumio Streaming Daemon Oct 25 19:58:20 volumio volumio[1155]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 25 19:58:20 volumio volumio[1155]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 25 19:58:20 volumio sudo[2282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 25 19:58:20 volumio sudo[2282]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio sudo[2282]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::volumioGetState Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::volumioGetVisibleSources Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 19:58:20 volumio volumio[1155]: info: Listing playlists Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:20 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:20 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:20 volumio volumio[1155]: info: Remote SSH Stopped Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:20 volumio volumio[1155]: info: Setting Geolocation for MyVolumio to eu7 Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:20 volumio volumio[1155]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 25 19:58:21 volumio volumio[1155]: info: Updating MyVolumio device info Oct 25 19:58:21 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:24 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:24 volumio volumio[1155]: info: MYVOLUMIO: Adding device Oct 25 19:58:24 volumio volumio[1155]: info: MYVOLUMIO: Evaluating Server Oct 25 19:58:25 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 19:58:25 volumio volumio[1155]: info: Discovery: Getting this device information Oct 25 19:58:25 volumio volumio[1155]: info: CoreCommandRouter::volumioGetState Oct 25 19:58:25 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 19:58:26 volumio volumio[1155]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Oct 25 19:58:26 volumio volumio[2112]: libpng warning: iCCP: known incorrect sRGB profile Oct 25 19:58:26 volumio volumio[2112]: libpng warning: iCCP: known incorrect sRGB profile Oct 25 19:58:26 volumio volumio[2112]: libpng warning: iCCP: known incorrect sRGB profile Oct 25 19:58:26 volumio volumio[1155]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Oct 25 19:58:26 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Oct 25 19:58:26 volumio volumio[1155]: info: Listing playlists Oct 25 19:58:26 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:26 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:26 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:26 volumio volumio[1155]: info: CoreCommandRouter::volumioGetVisibleSources Oct 25 19:58:26 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 19:58:26 volumio volumio[1155]: info: CoreCommandRouter::volumioGetState Oct 25 19:58:26 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 19:58:26 volumio volumio[1155]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Heaven","album":"80s 100 Hits","artist":"Fiction Factory","duration":"210","uri":"http://192.168.1.95:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I48394.flac","service":""} Oct 25 19:58:26 volumio volumio[1155]: info: [now-playing] LRCLIB getLyrics(): song: "Heaven" album: "80s 100 Hits" artist: "Fiction Factory" duration: "210" Oct 25 19:58:26 volumio volumio[1155]: info: [now-playing] LRCLIB getLyrics() API URL: https://lrclib.net/api/get?track_name=Heaven&artist_name=Fiction+Factory&album_name=80s+100+Hits&duration=210 Oct 25 19:58:27 volumio volumio[1155]: error: [now-playing] Error fetching lyrics from LRCLIB (URL: https://lrclib.net/api/get?track_name=Heaven&artist_name=Fiction+Factory&album_name=80s+100+Hits&duration=210): Error: Response error: 404 - Not Found Oct 25 19:58:27 volumio volumio[1155]: at apiGet (/data/plugins/user_interface/now_playing/dist/lib/api/lrclib/index.js:21:15) Oct 25 19:58:27 volumio volumio[1155]: at runMicrotasks () Oct 25 19:58:27 volumio volumio[1155]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 25 19:58:27 volumio volumio[1155]: at async Function.getLyrics (/data/plugins/user_interface/now_playing/dist/lib/api/lrclib/index.js:34:24) Oct 25 19:58:27 volumio volumio[1155]: at async DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:62:37) Oct 25 19:58:27 volumio volumio[1155]: at async /data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:131:40 Oct 25 19:58:27 volumio volumio[1155]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=45.452995&lon=26.337554: Error: Response error: 401 - Unauthorized Oct 25 19:58:27 volumio volumio[1155]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 25 19:58:27 volumio volumio[1155]: at runMicrotasks () Oct 25 19:58:27 volumio volumio[1155]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 25 19:58:27 volumio volumio[1155]: at async Promise.all (index 1) Oct 25 19:58:27 volumio volumio[1155]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Oct 25 19:58:27 volumio volumio[1155]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Oct 25 19:58:27 volumio volumio[1155]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Oct 25 19:58:27 volumio volumio[1155]: info: [now-playing] Fetching API key... Oct 25 19:58:27 volumio volumio[1155]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=45.452995&lon=26.337554: Error: Response error: 401 - Unauthorized Oct 25 19:58:27 volumio volumio[1155]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 25 19:58:27 volumio volumio[1155]: at runMicrotasks () Oct 25 19:58:27 volumio volumio[1155]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 25 19:58:27 volumio volumio[1155]: at async Promise.all (index 0) Oct 25 19:58:28 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 25 19:58:28 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:28 volumio volumio[1155]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 25 19:58:28 volumio volumio[1155]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Oct 25 19:58:28 volumio volumio[1155]: info: CoreCommandRouter::volumioGetState Oct 25 19:58:28 volumio volumio[1155]: info: [now-playing] OpenWeatherMap API key obtained. Oct 25 19:58:28 volumio volumio[1155]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=45.452995&lon=26.337554: Error: Response error: 401 - Unauthorized Oct 25 19:58:28 volumio volumio[1155]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 25 19:58:28 volumio volumio[1155]: at runMicrotasks () Oct 25 19:58:28 volumio volumio[1155]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 25 19:58:28 volumio volumio[1155]: at async Promise.all (index 0) Oct 25 19:58:28 volumio volumio[1155]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Oct 25 19:58:28 volumio volumio[1155]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Oct 25 19:58:28 volumio volumio[1155]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Oct 25 19:58:28 volumio volumio[1155]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not fetch weather: Response error: 401 - Unauthorized Oct 25 19:58:28 volumio volumio[1155]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:114:19) Oct 25 19:58:28 volumio volumio[1155]: at runMicrotasks () Oct 25 19:58:28 volumio volumio[1155]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 25 19:58:28 volumio volumio[1155]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:28) Oct 25 19:58:28 volumio volumio[1155]: (node:1155) UnhandledPromiseRejectionWarning: Error: Response error: 401 - Unauthorized Oct 25 19:58:28 volumio volumio[1155]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 25 19:58:28 volumio volumio[1155]: at runMicrotasks () Oct 25 19:58:28 volumio volumio[1155]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 25 19:58:28 volumio volumio[1155]: at async Promise.all (index 0) Oct 25 19:58:28 volumio volumio[1155]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Oct 25 19:58:28 volumio volumio[1155]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Oct 25 19:58:28 volumio volumio[1155]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Oct 25 19:58:28 volumio volumio[1155]: (node:1155) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 4) Oct 25 19:58:28 volumio volumio[1155]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=45.452995&lon=26.337554: Error: Response error: 401 - Unauthorized Oct 25 19:58:28 volumio volumio[1155]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 25 19:58:28 volumio volumio[1155]: at runMicrotasks () Oct 25 19:58:28 volumio volumio[1155]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 25 19:58:28 volumio volumio[1155]: at async Promise.all (index 1) Oct 25 19:58:28 volumio volumio[1155]: info: Setting Geolocation for MyVolumio to eu7 Oct 25 19:58:28 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:28 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:28 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:29 volumio volumio[1155]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 25 19:58:29 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 19:58:29 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:29 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 25 19:58:29 volumio volumio[1155]: info: Updating MyVolumio device info Oct 25 19:58:29 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:29 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:29 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:30 volumio volumio-remote-updater[744]: No test mode Oct 25 19:58:30 volumio volumio-remote-updater[744]: No alpha test mode Oct 25 19:58:30 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 25 19:58:30 volumio volumio[1155]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 25 19:58:32 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:33 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:33 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:33 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 25 19:58:33 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 25 19:58:33 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 25 19:58:33 volumio volumio[1155]: info: CoreCommandRouter::volumioGetBrowseSources Oct 25 19:58:33 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 19:58:33 volumio volumio[1155]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 25 19:58:33 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 25 19:58:35 volumio volumio[1155]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 25 19:58:35 volumio volumio[1155]: info: Completed starting MyVolumio Plugin Oct 25 19:58:35 volumio volumio[1155]: info: BOOT COMPLETED Oct 25 19:58:35 volumio volumio[1155]: [Metrics] CommandRouter: 53s 720.73ms Oct 25 19:58:35 volumio volumio[1155]: info: CoreCommandRouter::volumiosetStartupVolume Oct 25 19:58:35 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 19:58:35 volumio volumio[1155]: info: VolumeController:: Setting startup Volume 50 Oct 25 19:58:35 volumio volumio[1155]: info: VolumeController::SetAlsaVolume50 Oct 25 19:58:35 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:58:35 volumio volumio[1155]: info: CoreCommandRouter::Close All Modals sent Oct 25 19:58:35 volumio volumio[1155]: info: CoreCommandRouter::Close All Modals sent Oct 25 19:58:35 volumio volumio[1155]: info: CoreCommandRouter::volumioGetState Oct 25 19:58:35 volumio volumio[1155]: info: CoreStateMachine::pushState Oct 25 19:58:35 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 19:58:35 volumio volumio[1155]: info: CoreCommandRouter::volumioPushState Oct 25 19:58:35 volumio volumio[1155]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 25 19:58:35 volumio volumio[1155]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:58:35 volumio volumio[1155]: info: CoreStateMachine::getQueue Oct 25 19:58:35 volumio volumio[1155]: info: CorePlayQueue::getQueue Oct 25 19:58:35 volumio volumio[1155]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Oct 25 19:58:35 volumio sudo[2353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 25 19:58:35 volumio sudo[2353]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 19:58:35 volumio volumio[1155]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 25 19:58:35 volumio volumio[1155]: aplay: main:828: audio open error: Device or resource busy Oct 25 19:58:35 volumio sudo[2353]: pam_unix(sudo:session): session closed for user root Oct 25 19:58:35 volumio volumio[1155]: info: peppymeterbasic Daemon Started Oct 25 19:58:36 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 25 19:58:36 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 19:58:36 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 25 19:58:36 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:36 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 25 19:58:36 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 19:58:42 volumio volumio[1155]: info: Retrieving Cloud Streaming UI Oct 25 19:58:42 volumio volumio[1155]: info: Getting Tidal Cloud Configuration Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 19:58:42 volumio volumio[1155]: info: Getting Qobuz Cloud Configuration Oct 25 19:58:42 volumio volumio[1155]: info: Asking plugin for UI Config Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 19:58:42 volumio volumio[1155]: info: Getting Spotify Cloud Configuration Oct 25 19:58:42 volumio volumio[1155]: info: Asking plugin for UI Config Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 19:58:42 volumio volumio[1155]: info: Saving Spotify Acccount Oct 25 19:58:42 volumio volumio[1155]: info: Got Tidal Cloud Configuration Oct 25 19:58:42 volumio volumio[1155]: info: Got it Oct 25 19:58:42 volumio volumio[1155]: info: Got it Oct 25 19:58:42 volumio volumio[1155]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 19:58:42 volumio volumio[1155]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::volumioGetBrowseSources Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::volumioGetBrowseSources Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::volumioGetBrowseSources Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:42 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 25 19:58:43 volumio volumio[1155]: info: Error browsing device Epson Stylus Photo PX730 for shares : Error: Command failed: echo volumio | smbclient -L EPSON4B9C0F.local. Oct 25 19:58:43 volumio volumio[1155]: Unable to initialize messaging context Oct 25 19:58:44 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 25 19:58:44 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 25 19:58:46 volumio volumio[2112]: libpng warning: iCCP: known incorrect sRGB profile Oct 25 19:58:46 volumio volumio[2112]: libpng warning: iCCP: known incorrect sRGB profile Oct 25 19:58:46 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:50 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:52 volumio volumio[1155]: error: error Oct 25 19:58:52 volumio volumio[1155]: error: error Oct 25 19:58:53 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 25 19:58:53 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 25 19:58:54 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:58:58 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:59:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 25 19:59:00 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 25 19:59:02 volumio volumio[1155]: info: Error browsing device Epson Stylus Photo PX730 for shares : Error: Command failed: echo volumio | smbclient -L EPSON4B9C0F.local. Oct 25 19:59:02 volumio volumio[1155]: Unable to initialize messaging context Oct 25 19:59:02 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:59:06 volumio volumio[2112]: libpng warning: iCCP: known incorrect sRGB profile Oct 25 19:59:06 volumio volumio[2112]: libpng warning: iCCP: known incorrect sRGB profile Oct 25 19:59:06 volumio volumio[2112]: libpng warning: iCCP: known incorrect sRGB profile Oct 25 19:59:06 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 25 19:59:08 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 19:59:08 volumio volumio[1155]: info: Preload queue cleared Oct 25 19:59:10 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 19:59:10 volumio volumio[1155]: info: Preload queue cleared Oct 25 19:59:14 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 19:59:15 volumio volumio[1155]: info: Preload queue cleared Oct 25 19:59:20 volumio volumio[1155]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 19:59:20 volumio volumio[1155]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 19:59:20 volumio volumio[1155]: TypeError: Cannot read property 'length' of undefined Oct 25 19:59:20 volumio volumio[1155]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 25 19:59:20 volumio volumio[1155]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 25 19:59:20 volumio volumio[1155]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 25 19:59:20 volumio volumio[1155]: at Parser.emit (events.js:400:28) Oct 25 19:59:20 volumio volumio[1155]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 25 19:59:20 volumio volumio[1155]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 25 19:59:20 volumio volumio[1155]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 25 19:59:20 volumio volumio[1155]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 25 19:59:20 volumio volumio[1155]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 25 19:59:20 volumio volumio[1155]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 25 19:59:20 volumio volumio[1155]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 25 19:59:20 volumio volumio[1155]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 25 19:59:20 volumio volumio[1155]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 25 19:59:20 volumio volumio[1155]: at IncomingMessage.emit (events.js:412:35) Oct 25 19:59:20 volumio volumio[1155]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 25 19:59:20 volumio volumio[1155]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 25 19:59:20 volumio volumio[1155]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 19:59:20 volumio sudo[2578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-25 19:58 Oct 25 19:59:20 volumio sudo[2578]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"