-- Logs begin at Tue 2024-02-27 04:14:27 +03, end at Sat 2024-05-04 12:42:02 +03. --
May 04 12:41:00 volumio volumio[5364]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion
May 04 12:41:00 volumio volumio[5364]: info: Preparing to generate the ALSA configuration file
May 04 12:41:00 volumio volumio[5364]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
May 04 12:41:00 volumio volumio[5364]: info: Reading ALSA contributions from plugins.
May 04 12:41:00 volumio volumio[5364]: info: Asound.conf file unchanged, so no further update is needed
May 04 12:41:00 volumio volumio[5364]: info: Output device has changed, restarting MPD
May 04 12:41:00 volumio sudo[5645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 04 12:41:00 volumio sudo[5645]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:00 volumio volumio[5364]: info: Output device has changed, restarting Shairport Sync
May 04 12:41:00 volumio sudo[5645]: pam_unix(sudo:session): session closed for user root
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:00 volumio sudo[5648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 04 12:41:00 volumio sudo[5648]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:00 volumio systemd[1]: Stopping Music Player Daemon...
May 04 12:41:00 volumio volumio[5364]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 04 12:41:00 volumio volumio[5364]: info: ___________ START PLUGINS ___________
May 04 12:41:00 volumio systemd[1]: mpd.service: Succeeded.
May 04 12:41:00 volumio systemd[1]: Stopped Music Player Daemon.
May 04 12:41:00 volumio volumio[5364]: info: ControllerMpd::onStart: Initializing MPD
May 04 12:41:00 volumio volumio[5364]: info: Creating MPD Configuration file
May 04 12:41:00 volumio sudo[5655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 04 12:41:00 volumio sudo[5655]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:00 volumio systemd[1]: Starting Music Player Daemon...
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 04 12:41:00 volumio volumio[5364]: info: [1714815660436] CoreMusicLibrary::Adding element Media Servers
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 04 12:41:00 volumio sudo[5655]: pam_unix(sudo:session): session closed for user root
May 04 12:41:00 volumio sudo[5657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 04 12:41:00 volumio sudo[5657]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:00 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
May 04 12:41:00 volumio systemd[1]: mpd.service: Succeeded.
May 04 12:41:00 volumio systemd[1]: Stopped Music Player Daemon.
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:00 volumio systemd[1]: Starting Music Player Daemon...
May 04 12:41:00 volumio volumio[5364]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:00 volumio volumio[5364]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 04 12:41:00 volumio volumio[5364]: info: [1714815660595] CoreMusicLibrary::Adding element Last_100
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 04 12:41:00 volumio volumio[5364]: info: [1714815660602] CoreMusicLibrary::Adding element Webradio
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 04 12:41:00 volumio volumio[5364]: info: Creating Spotify config file
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:00 volumio volumio[5364]: info: [squeezelite_mc] Starting proxy server...
May 04 12:41:00 volumio volumio[5364]: info: Loading i18n strings for locale en
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:00 volumio volumio[5364]: info: FusionDsp - mixtype--------------------- Hardware
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 04 12:41:00 volumio volumio[5364]: info: [1714815660803] CoreMusicLibrary::Adding element 80s80s Radio
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 04 12:41:00 volumio volumio[5364]: Cannot find translation for source 80s80s Radio
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 04 12:41:00 volumio volumio[5364]: info: [1714815660840] CoreMusicLibrary::Adding element Mother Earth Radio
May 04 12:41:00 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 04 12:41:00 volumio volumio[5364]: Cannot find translation for source 80s80s Radio
May 04 12:41:00 volumio volumio[5364]: Cannot find translation for source Mother Earth Radio
May 04 12:41:00 volumio volumio[5364]: info: Volumio Calling Home
May 04 12:41:00 volumio sudo[5668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver
May 04 12:41:00 volumio sudo[5668]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:00 volumio systemd[1]: Starting Logitech Media Server Daemon...
May 04 12:41:00 volumio volumio[5364]: info: [squeezelite_mc] Proxy server started on port 37259
May 04 12:41:00 volumio systemd[1]: Started Logitech Media Server Daemon.
May 04 12:41:00 volumio volumio[5364]: info: Preparing to generate the ALSA configuration file
May 04 12:41:00 volumio sudo[5668]: pam_unix(sudo:session): session closed for user root
May 04 12:41:00 volumio systemd[5675]: logitechmediaserver.service: Failed to execute command: No such file or directory
May 04 12:41:00 volumio systemd[5675]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory
May 04 12:41:00 volumio systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC
May 04 12:41:00 volumio systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'.
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::volumioRetrievevolume
May 04 12:41:01 volumio volumio[5364]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
May 04 12:41:01 volumio volumio[5364]: info: Reading ALSA contributions from plugins.
May 04 12:41:01 volumio volumio[5364]: info: logitechmediaserver started
May 04 12:41:01 volumio volumio[5364]: info: MPD Permissions set
May 04 12:41:01 volumio volumio[5364]: info: MPD Permissions set
May 04 12:41:01 volumio volumio[5364]: info: Volumio called home
May 04 12:41:01 volumio volumio[5364]: info: Spotify config file written
May 04 12:41:01 volumio sudo[5679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
May 04 12:41:01 volumio sudo[5679]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:01 volumio systemd[1]: Stopping go-librespot Daemon...
May 04 12:41:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM
May 04 12:41:01 volumio systemd[1]: go-librespot-daemon.service: Succeeded.
May 04 12:41:01 volumio systemd[1]: Stopped go-librespot Daemon.
May 04 12:41:01 volumio volumio[5364]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2
May 04 12:41:01 volumio systemd[1]: Started go-librespot Daemon.
May 04 12:41:01 volumio go-librespot[5681]: Librespot-go daemon starting...
May 04 12:41:01 volumio sudo[5679]: pam_unix(sudo:session): session closed for user root
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio go-librespot[5681]: time="2024-05-04T12:41:01+03:00" level=info msg="generated new device id: c78a6f364ba2a49955d390e7e83389fda7fc6758"
May 04 12:41:01 volumio go-librespot[5681]: time="2024-05-04T12:41:01+03:00" level=debug msg="stored credentials found for 21ilcdv7hed2hwyqknvhx2xla"
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: VolumeController:: Volume=50 Mute =false
May 04 12:41:01 volumio volumio[5364]: info: CoreStateMachine::pushState
May 04 12:41:01 volumio volumio[5364]: info: CorePlayQueue::getTrack 0
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::volumioPushState
May 04 12:41:01 volumio volumio[5364]: info: [squeezelite_mc] Server discovery started
May 04 12:41:01 volumio volumio[5364]: info: [squeezelite_mc] Player finder started
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 12:41:01 volumio volumio[5364]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true
May 04 12:41:01 volumio volumio[5364]: info: [squeezelite_mc] Card 0 supports the following ALSA formats: ["U8","S16_LE"]
May 04 12:41:01 volumio volumio[5364]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"0","mixerType":"Hardware","mixer":"PCM"}
May 04 12:41:01 volumio volumio[5364]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf
May 04 12:41:01 volumio volumio[5364]: info: Starting Shairport Sync
May 04 12:41:01 volumio volumio[5364]: info: Starting Shairport Sync
May 04 12:41:01 volumio sudo[5704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 04 12:41:01 volumio sudo[5704]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:01 volumio volumio[5364]: info: Starting Shairport Sync
May 04 12:41:01 volumio go-librespot[5681]: time="2024-05-04T12:41:01+03:00" level=debug msg="obtained new client token: AABdLAKuAfonbLG2cH1Id3YlxEPuOlQfXlsHQQbGJzbQ+LCqB8RKSh49rGzkOPy5vuyd8M2nfby6DXtjHCT5bozGittmLd8ul1gXEXpX/V4WlXHzbieyuenG1NjOVbE/Gt0QANQvLq1eBlcZwghunOh4qLthGF85ZGJ2/7of8OfhpcUCkQZIDPq0jW5zCJdMHx2MT43fe33tudnhDA8zgC2m0BdLWJJj4iAv0H18JA6UBaqhe1fY2XbJUIInNRM="
May 04 12:41:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 04 12:41:01 volumio systemd[1]: shairport-sync.service: Succeeded.
May 04 12:41:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 04 12:41:01 volumio volumio[5364]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}
May 04 12:41:01 volumio sudo[5709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 04 12:41:01 volumio sudo[5708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 04 12:41:01 volumio sudo[5708]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 04 12:41:01 volumio sudo[5709]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:01 volumio sudo[5702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf
May 04 12:41:01 volumio sudo[5702]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:01 volumio sudo[5704]: pam_unix(sudo:session): session closed for user root
May 04 12:41:01 volumio sudo[5702]: pam_unix(sudo:session): session closed for user root
May 04 12:41:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 04 12:41:01 volumio systemd[1]: shairport-sync.service: Succeeded.
May 04 12:41:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 04 12:41:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 04 12:41:01 volumio sudo[5708]: pam_unix(sudo:session): session closed for user root
May 04 12:41:01 volumio sudo[5709]: pam_unix(sudo:session): session closed for user root
May 04 12:41:01 volumio volumio[5364]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
May 04 12:41:01 volumio volumio[5364]: SPOTIFY: BQAaOZNvD1yYqD4rr64Wkc-jvsYwkvd-XFVA3CrxSioIUsrIkZDMQv4SWp_lyxYB77lLnDOzmsKcLGJ_nElvO2_DZb9KUdXBzQXNVWe7KlrwuefbxD2ba8lCJEeRGCQKJWGVyzAMEnR8BtpE9OCCt5IrPdPTEEK5xLdVm0M4ladivGj-UNp9_vX-mDEa-uPfKxZTlIxHDCTr2iEeOB9QSC1yMsFpnvyaxXjSALELuHU5yhuld0zvtXBKLpmMMcZwLay5SMs
May 04 12:41:01 volumio volumio[5364]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
May 04 12:41:01 volumio volumio[5364]: info: New Spotify access token = BQAaOZNvD1yYqD4rr64Wkc-jvsYwkvd-XFVA3CrxSioIUsrIkZDMQv4SWp_lyxYB77lLnDOzmsKcLGJ_nElvO2_DZb9KUdXBzQXNVWe7KlrwuefbxD2ba8lCJEeRGCQKJWGVyzAMEnR8BtpE9OCCt5IrPdPTEEK5xLdVm0M4ladivGj-UNp9_vX-mDEa-uPfKxZTlIxHDCTr2iEeOB9QSC1yMsFpnvyaxXjSALELuHU5yhuld0zvtXBKLpmMMcZwLay5SMs
May 04 12:41:01 volumio volumio[5364]: info: Spotify credentials grant success - running version from March 24, 2019
May 04 12:41:01 volumio go-librespot[5681]: time="2024-05-04T12:41:01+03:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
May 04 12:41:01 volumio mpd[5660]: May 04 12:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
May 04 12:41:01 volumio systemd[1]: Started Music Player Daemon.
May 04 12:41:01 volumio sudo[5648]: pam_unix(sudo:session): session closed for user root
May 04 12:41:01 volumio sudo[5657]: pam_unix(sudo:session): session closed for user root
May 04 12:41:01 volumio volumio[5364]: info: Asound.conf file unchanged, so no further update is needed
May 04 12:41:01 volumio volumio[5364]: info: Output device has changed, restarting MPD
May 04 12:41:02 volumio sudo[5721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 04 12:41:02 volumio volumio[5364]: info: Output device has changed, restarting Shairport Sync
May 04 12:41:02 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:02 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:02 volumio sudo[5721]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:02 volumio sudo[5721]: pam_unix(sudo:session): session closed for user root
May 04 12:41:02 volumio sudo[5723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 04 12:41:02 volumio sudo[5723]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:02 volumio systemd[1]: Stopping Music Player Daemon...
May 04 12:41:02 volumio volumio[5364]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 04 12:41:02 volumio systemd[1]: mpd.service: Succeeded.
May 04 12:41:02 volumio systemd[1]: Stopped Music Player Daemon.
May 04 12:41:02 volumio go-librespot[5681]: time="2024-05-04T12:41:02+03:00" level=debug msg="completed keyexchange"
May 04 12:41:02 volumio volumio[5364]: info: Shairport-Sync Started
May 04 12:41:02 volumio volumio[5364]: Error adding Membership: Error: addMembership EINVAL
May 04 12:41:02 volumio volumio[5364]: info: Shairport-Sync Started
May 04 12:41:02 volumio volumio[5364]: info: Shairport-Sync Started
May 04 12:41:02 volumio volumio[5364]: info: [squeezelite_mc] Executing alsactl -L -R nrestore
May 04 12:41:02 volumio systemd[1]: Starting Music Player Daemon...
May 04 12:41:02 volumio sudo[5734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore
May 04 12:41:02 volumio sudo[5734]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:02 volumio volumio[5364]: error: Cannot initialize MPD Connection: MPD is not running
May 04 12:41:02 volumio volumio[5364]: info: MPD Permissions set
May 04 12:41:02 volumio volumio[5364]: info: CoreCommandRouter::volumioGetState
May 04 12:41:02 volumio volumio[5364]: info: CorePlayQueue::getTrack 0
May 04 12:41:02 volumio sudo[5734]: pam_unix(sudo:session): session closed for user root
May 04 12:41:02 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:02 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:02 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:02 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:02 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:02 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:02 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:02 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:02 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:02 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:02 volumio volumio[5364]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service
May 04 12:41:02 volumio volumio[5364]: info: Starting Shairport Sync
May 04 12:41:02 volumio sudo[5744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 04 12:41:02 volumio sudo[5744]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 04 12:41:02 volumio systemd[1]: shairport-sync.service: Succeeded.
May 04 12:41:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 04 12:41:02 volumio sudo[5742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service
May 04 12:41:02 volumio sudo[5742]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:02 volumio volumio[5364]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true
May 04 12:41:02 volumio sudo[5742]: pam_unix(sudo:session): session closed for user root
May 04 12:41:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 04 12:41:02 volumio sudo[5744]: pam_unix(sudo:session): session closed for user root
May 04 12:41:02 volumio volumio[5364]: SPOTIFY: User informations: {"display_name":"Taner Gür","external_urls":{"spotify":"https://open.spotify.com/user/21ilcdv7hed2hwyqknvhx2xla"},"href":"https://api.spotify.com/v1/users/21ilcdv7hed2hwyqknvhx2xla","id":"21ilcdv7hed2hwyqknvhx2xla","images":[{"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=10152159714485528&height=50&width=50&ext=1717405701&hash=AbZb59oPeCiWt-IxVAEoru_s","height":64,"width":64},{"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=10152159714485528&height=300&width=300&ext=1717405701&hash=AbZV_ED4PslCx0SUAlY_-qrP","height":300,"width":300}],"type":"user","uri":"spotify:user:21ilcdv7hed2hwyqknvhx2xla","followers":{"href":null,"total":4},"country":"TR","product":"premium","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"tanergur@msn.com"}
May 04 12:41:02 volumio volumio[5364]: info: Spotify Successfully logged in
May 04 12:41:02 volumio volumio[5364]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 04 12:41:02 volumio volumio[5364]: info: [1714815662525] CoreMusicLibrary::Adding element Spotify
May 04 12:41:02 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 04 12:41:02 volumio volumio[5364]: Cannot find translation for source 80s80s Radio
May 04 12:41:02 volumio volumio[5364]: Cannot find translation for source Mother Earth Radio
May 04 12:41:02 volumio volumio[5364]: Cannot find translation for source Spotify
May 04 12:41:02 volumio sudo[5749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
May 04 12:41:02 volumio sudo[5749]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:02 volumio volumio[5364]: info: Shairport-Sync Started
May 04 12:41:02 volumio systemd[1]: Reloading.
May 04 12:41:02 volumio go-librespot[5681]: time="2024-05-04T12:41:02+03:00" level=debug msg="completed challenge"
May 04 12:41:02 volumio go-librespot[5681]: time="2024-05-04T12:41:02+03:00" level=debug msg="authenticated as 21ilcdv7hed2hwyqknvhx2xla"
May 04 12:41:02 volumio volumio[5364]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
May 04 12:41:02 volumio volumio[5364]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo
May 04 12:41:02 volumio volumio[5364]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
May 04 12:41:02 volumio volumio[5364]: info: camilladsp spawned new process with pid 5772, instance 1, run: true
May 04 12:41:02 volumio volumio[5364]: info: camilladsp service started and running in background, instance 1
May 04 12:41:02 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:02 volumio go-librespot[5681]: time="2024-05-04T12:41:02+03:00" level=debug msg="authenticated as 21ilcdv7hed2hwyqknvhx2xla"
May 04 12:41:02 volumio volumio[5364]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA
May 04 12:41:03 volumio volumio[5364]: info: FusionDsp loaded
May 04 12:41:03 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 04 12:41:03 volumio sudo[5782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
May 04 12:41:03 volumio sudo[5782]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:03 volumio go-librespot[5681]: time="2024-05-04T12:41:03+03:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
May 04 12:41:03 volumio volumio[5364]: info: FusionDsp - Reporting Fusion DSP Enabled
May 04 12:41:03 volumio volumio[5364]: info: Adding Signal Path Element [object Object]
May 04 12:41:03 volumio volumio[5364]: info: Adding fusiondspeq DSP Signal Path Element
May 04 12:41:03 volumio volumio[5364]: info: FusionDsp - ---- installed callbackRead
May 04 12:41:03 volumio volumio[5364]: info: FusionDsp - If filter freq >samplerate/2 then disable it
May 04 12:41:03 volumio volumio[5364]: info: FusionDsp - Nulleq applied
May 04 12:41:03 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.
May 04 12:41:03 volumio volumio[5364]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3
May 04 12:41:03 volumio go-librespot[5681]: time="2024-05-04T12:41:03+03:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
May 04 12:41:03 volumio volumio[5364]: info: FusionDsp - {"Reload":{"result":"Ok"}}
May 04 12:41:03 volumio volumio[5364]: info: [squeezelite_mc] Notification listener started
May 04 12:41:03 volumio volumio[5364]: info: [squeezelite_mc] Getting players connected to Taner's Media (192.168.1.191)
May 04 12:41:03 volumio volumio[5364]: info: [squeezelite_mc] Players connected to Taner's Media (192.168.1.191): [{"id":"cc:cc:54:06:cc:3e","uuid":null,"ip":"192.168.1.191","name":"TVPLUS HP4416","server":{"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}},{"id":"aa:aa:eb:e9:e4:3a","uuid":null,"ip":"192.168.1.191","name":"volumio","server":{"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:bc:b1:6f","uuid":null,"ip":"192.168.1.100","name":"volumio","server":{"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}},{"id":"30:9c:23:03:24:73","uuid":null,"ip":"192.168.1.191","name":"SqueezeLite","server":{"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:b5:10:2b:bb","uuid":null,"ip":"192.168.1.191","name":"Libratone","server":{"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}},{"id":"aa:aa:b5:10:2b:bb","uuid":null,"ip":"192.168.1.191","name":"Libratone","server":{"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:67:43:d2:61","uuid":null,"ip":"192.168.1.191","name":"Volumio-UPnP/AV","server":{"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}}]
May 04 12:41:03 volumio volumio[5364]: info: [squeezelite_mc] Player found: {"id":"b8:27:eb:bc:b1:6f","uuid":null,"ip":"192.168.1.100","name":"volumio","server":{"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}}
May 04 12:41:03 volumio go-librespot[5681]: time="2024-05-04T12:41:03+03:00" level=debug msg="dealer connection opened"
May 04 12:41:03 volumio go-librespot[5681]: time="2024-05-04T12:41:03+03:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
May 04 12:41:03 volumio go-librespot[5681]: time="2024-05-04T12:41:03+03:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
May 04 12:41:03 volumio go-librespot[5681]: time="2024-05-04T12:41:03+03:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
May 04 12:41:03 volumio go-librespot[5681]: time="2024-05-04T12:41:03+03:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
May 04 12:41:03 volumio go-librespot[5681]: time="2024-05-04T12:41:03+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513"
May 04 12:41:03 volumio go-librespot[5681]: time="2024-05-04T12:41:03+03:00" level=debug msg="received connection id: YzEyNzZhYzItNDJlZS00YjIxLThkZWItYjY4NmU3NTFiZGY4K2RlYWxlcit0Y3A6Ly9nZXc0LWRlYWxlci1hLXprZ3QuZ2V3NC5zcG90aWZ5Lm5ldDo1NzAwK0VDODg0QzI5NTYzMjYyNDlGQkFERkY3OUZBQkRFRDhDMjBEMkRCRDUxRTg4NDlBQTJCNEQwRkNCOEUyRDM5NzE="
May 04 12:41:03 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly.
May 04 12:41:03 volumio go-librespot[5681]: time="2024-05-04T12:41:03+03:00" level=debug msg="put connect state because NEW_DEVICE"
May 04 12:41:03 volumio mpd[5729]: May 04 12:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
May 04 12:41:03 volumio volumio[5364]: error: MPD error: The expression evaluated to a falsy value:
May 04 12:41:03 volumio volumio[5364]: assert.ok(self.idling)
May 04 12:41:03 volumio volumio[5364]: error: The expression evaluated to a falsy value:
May 04 12:41:03 volumio volumio[5364]: assert.ok(self.idling)
May 04 12:41:03 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:03 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:03 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:03 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 12:41:03 volumio systemd[1]: Started Music Player Daemon.
May 04 12:41:03 volumio sudo[5723]: pam_unix(sudo:session): session closed for user root
May 04 12:41:03 volumio sudo[5749]: pam_unix(sudo:session): session closed for user root
May 04 12:41:03 volumio volumio[5364]: error: updateQueue error: null
May 04 12:41:03 volumio volumio[5364]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
May 04 12:41:03 volumio sudo[5782]: pam_unix(sudo:session): session closed for user root
May 04 12:41:03 volumio volumio[5364]: error: updateQueue error: null
May 04 12:41:03 volumio sudo[5788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
May 04 12:41:03 volumio sudo[5788]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:03 volumio sudo[5788]: pam_unix(sudo:session): session closed for user root
May 04 12:41:03 volumio volumio[5364]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true
May 04 12:41:03 volumio sudo[5791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite
May 04 12:41:03 volumio sudo[5791]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:03 volumio systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator...
May 04 12:41:03 volumio volumio[5364]: info: [squeezelite_mc] 'client' notification received from Taner's Media (192.168.1.191); type is 'disconnect'
May 04 12:41:03 volumio volumio[5364]: info: CoreCommandRouter::volumioGetState
May 04 12:41:03 volumio volumio[5364]: info: CorePlayQueue::getTrack 0
May 04 12:41:04 volumio systemd[1]: squeezelite.service: Succeeded.
May 04 12:41:04 volumio systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator.
May 04 12:41:04 volumio sudo[5791]: pam_unix(sudo:session): session closed for user root
May 04 12:41:04 volumio volumio[5364]: (node:5364) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '0' of undefined
May 04 12:41:04 volumio volumio[5364]: at PlayerStatusMonitor._PlayerStatusMonitor_parsePlayerStatusResult (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:180:37)
May 04 12:41:04 volumio volumio[5364]: at PlayerStatusMonitor._PlayerStatusMonitor_getStatusAndEmit (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:118:129)
May 04 12:41:04 volumio volumio[5364]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
May 04 12:41:04 volumio volumio[5364]: at async PlayerStatusMonitor.start (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:47:9)
May 04 12:41:04 volumio volumio[5364]: at async PlayerFinder. (/data/plugins/music_service/squeezelite_mc/dist/index.js:686:13)
May 04 12:41:04 volumio volumio[5364]: (Use `node --trace-warnings ...` to show where the warning was created)
May 04 12:41:04 volumio volumio[5364]: (node:5364) 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: 1)
May 04 12:41:04 volumio volumio[5364]: (node:5364) [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.
May 04 12:41:04 volumio volumio[5364]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
May 04 12:41:04 volumio sudo[5794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
May 04 12:41:04 volumio sudo[5794]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:04 volumio volumio[5364]: info: go-librespot daemon successfully initialized
May 04 12:41:04 volumio sudo[5794]: pam_unix(sudo:session): session closed for user root
May 04 12:41:04 volumio volumio[5364]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log
May 04 12:41:04 volumio sudo[5798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log
May 04 12:41:04 volumio sudo[5798]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:04 volumio sudo[5798]: pam_unix(sudo:session): session closed for user root
May 04 12:41:04 volumio volumio[5364]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true
May 04 12:41:04 volumio sudo[5801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite
May 04 12:41:04 volumio sudo[5801]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:04 volumio systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator...
May 04 12:41:04 volumio systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator.
May 04 12:41:04 volumio sudo[5801]: pam_unix(sudo:session): session closed for user root
May 04 12:41:04 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=1)
May 04 12:41:04 volumio volumio[5364]: info: camilladsp spawned new process with pid 5807, instance 1, run: true
May 04 12:41:04 volumio volumio[5364]: info: [squeezelite_mc] 'client' notification received from Taner's Media (192.168.1.191); type is 'connect'
May 04 12:41:04 volumio volumio[5364]: info: [squeezelite_mc] Getting players connected to Taner's Media (192.168.1.191)
May 04 12:41:04 volumio volumio[5364]: info: [squeezelite_mc] Players connected to Taner's Media (192.168.1.191): [{"id":"cc:cc:54:06:cc:3e","uuid":null,"ip":"192.168.1.191","name":"TVPLUS HP4416","server":{"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}},{"id":"aa:aa:eb:e9:e4:3a","uuid":null,"ip":"192.168.1.191","name":"volumio","server":{"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:bc:b1:6f","uuid":null,"ip":"192.168.1.100","name":"volumio","server":{"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}},{"id":"30:9c:23:03:24:73","uuid":null,"ip":"192.168.1.191","name":"SqueezeLite","server":{"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:b5:10:2b:bb","uuid":null,"ip":"192.168.1.191","name":"Libratone","server":{"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}},{"id":"aa:aa:b5:10:2b:bb","uuid":null,"ip":"192.168.1.191","name":"Libratone","server":{"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:67:43:d2:61","uuid":null,"ip":"192.168.1.191","name":"Volumio-UPnP/AV","server":{"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}}]
May 04 12:41:04 volumio volumio[5364]: info: [squeezelite_mc] Player found: {"id":"b8:27:eb:bc:b1:6f","uuid":null,"ip":"192.168.1.100","name":"volumio","server":{"ip":"192.168.1.191","name":"Taner's Media","ver":"8.3.1","uuid":"dc4650ba-628d-449f-8225-507c20291073","jsonPort":"9000","cliPort":"9090"}}
May 04 12:41:05 volumio volumio[5364]: (node:5364) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '0' of undefined
May 04 12:41:05 volumio volumio[5364]: at PlayerStatusMonitor._PlayerStatusMonitor_parsePlayerStatusResult (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:180:37)
May 04 12:41:05 volumio volumio[5364]: at PlayerStatusMonitor._PlayerStatusMonitor_getStatusAndEmit (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:118:129)
May 04 12:41:05 volumio volumio[5364]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
May 04 12:41:05 volumio volumio[5364]: (node:5364) 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)
May 04 12:41:05 volumio volumio[5364]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
May 04 12:41:05 volumio sudo[5815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
May 04 12:41:05 volumio sudo[5815]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:05 volumio sudo[5815]: pam_unix(sudo:session): session closed for user root
May 04 12:41:05 volumio volumio[5364]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
May 04 12:41:05 volumio sudo[5818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
May 04 12:41:05 volumio sudo[5818]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:05 volumio sudo[5818]: pam_unix(sudo:session): session closed for user root
May 04 12:41:05 volumio volumio[5364]: (node:5364) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '0' of undefined
May 04 12:41:05 volumio volumio[5364]: at PlayerStatusMonitor._PlayerStatusMonitor_parsePlayerStatusResult (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:180:37)
May 04 12:41:05 volumio volumio[5364]: at PlayerStatusMonitor._PlayerStatusMonitor_getStatusAndEmit (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:118:129)
May 04 12:41:05 volumio volumio[5364]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
May 04 12:41:05 volumio volumio[5364]: at async PlayerStatusMonitor.start (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:47:9)
May 04 12:41:05 volumio volumio[5364]: at async PlayerFinder. (/data/plugins/music_service/squeezelite_mc/dist/index.js:686:13)
May 04 12:41:06 volumio volumio[5364]: (node:5364) 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: 3)
May 04 12:41:06 volumio volumio[5364]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
May 04 12:41:06 volumio sudo[5821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
May 04 12:41:06 volumio sudo[5821]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:06 volumio sudo[5821]: pam_unix(sudo:session): session closed for user root
May 04 12:41:06 volumio ntpd[678]: Soliciting pool server 91.151.84.199
May 04 12:41:07 volumio volumio[5364]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
May 04 12:41:07 volumio sudo[5824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
May 04 12:41:07 volumio sudo[5824]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:07 volumio sudo[5824]: pam_unix(sudo:session): session closed for user root
May 04 12:41:07 volumio volumio[5364]: info: Initializing connection to go-librespot Websocket
May 04 12:41:07 volumio volumio[5364]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
May 04 12:41:07 volumio go-librespot[5681]: time="2024-05-04T12:41:07+03:00" level=debug msg="new websocket client"
May 04 12:41:07 volumio volumio[5364]: info: Connection to go-librespot Websocket established
May 04 12:41:07 volumio sudo[5827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
May 04 12:41:07 volumio sudo[5827]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:07 volumio sudo[5827]: pam_unix(sudo:session): session closed for user root
May 04 12:41:07 volumio volumio[5364]: info: Completed starting Core Plugins
May 04 12:41:07 volumio volumio[5364]: info: -------------------------------------------
May 04 12:41:07 volumio volumio[5364]: info: ----- MyVolumio plugins startup ----
May 04 12:41:07 volumio volumio[5364]: info: -------------------------------------------
May 04 12:41:07 volumio volumio[5364]: info: [MyVolumio PluginManager] Fetching plans data....
May 04 12:41:08 volumio volumio[5364]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
May 04 12:41:10 volumio volumio[5364]: info: Getting Spotify volume
May 04 12:41:10 volumio volumio[5364]: info: Spotify volume: 100
May 04 12:41:10 volumio volumio[5364]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4
May 04 12:41:10 volumio volumio[5364]: info: CoreCommandRouter::volumioGetState
May 04 12:41:10 volumio volumio[5364]: info: CorePlayQueue::getTrack 0
May 04 12:41:10 volumio volumio[5364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50
May 04 12:41:10 volumio volumio[5364]: SPOTIFY: SPOTIFY VOLUME 100
May 04 12:41:10 volumio volumio[5364]: SPOTIFY: VOLUMIO VOLUME 50
May 04 12:41:10 volumio volumio[5364]: SPOTIFY: DELTA VOLUME ENOUGH: true
May 04 12:41:10 volumio volumio[5364]: info: Setting Spotify Volume from Volumio: 50
May 04 12:41:10 volumio wpa_supplicant[767]: wlan0: CTRL-EVENT-DISCONNECTED bssid=98:0d:67:3a:c5:41 reason=0 locally_generated=1
May 04 12:41:10 volumio dhcpcd[780]: wlan0: carrier lost
May 04 12:41:10 volumio wpa_supplicant[767]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
May 04 12:41:10 volumio wpa_supplicant[767]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=TR
May 04 12:41:10 volumio avahi-daemon[530]: Withdrawing address record for 192.168.1.100 on wlan0.
May 04 12:41:10 volumio avahi-daemon[530]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.100.
May 04 12:41:10 volumio avahi-daemon[530]: Interface wlan0.IPv4 no longer relevant for mDNS.
May 04 12:41:10 volumio dhcpcd[780]: wlan0: deleting route to 192.168.1.0/24
May 04 12:41:10 volumio dhcpcd[780]: wlan0: deleting default route via 192.168.1.1
May 04 12:41:10 volumio volumio[5364]: info: Discovery: A device disappeared from network
May 04 12:41:10 volumio volumio[5364]: info: Discovery: Device volumio disappeared from network
May 04 12:41:11 volumio wpa_supplicant[767]: wlan0: Trying to associate with SSID 'Taner-Balkon'
May 04 12:41:12 volumio dhcpcd[780]: wlan0: carrier acquired
May 04 12:41:12 volumio wpa_supplicant[767]: wlan0: Associated with 98:0d:67:3a:c5:41
May 04 12:41:12 volumio wpa_supplicant[767]: wlan0: CTRL-EVENT-CONNECTED - Connection to 98:0d:67:3a:c5:41 completed [id=0 id_str=]
May 04 12:41:12 volumio wpa_supplicant[767]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
May 04 12:41:12 volumio wpa_supplicant[767]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE
May 04 12:41:12 volumio dhcpcd[780]: wlan0: IAID eb:bc:b1:6f
May 04 12:41:12 volumio dhcpcd[780]: wlan0: probing address 192.168.1.100/24
May 04 12:41:12 volumio volumio[5364]: SPOTIFY: SETTING SPOTIFY VOLUME 50
May 04 12:41:12 volumio volumio[5364]: info: Sending Spotify command with payload to local API: /player/volume
May 04 12:41:12 volumio go-librespot[5681]: time="2024-05-04T12:41:12+03:00" level=debug msg="update volume to 32767/65535"
May 04 12:41:12 volumio dhcpcd[780]: wlan0: carrier lost
May 04 12:41:12 volumio dhcpcd[780]: wlan0: carrier acquired
May 04 12:41:12 volumio dhcpcd[780]: wlan0: IAID eb:bc:b1:6f
May 04 12:41:12 volumio dhcpcd[780]: wlan0: probing address 192.168.1.100/24
May 04 12:41:13 volumio dhcpcd[780]: wlan0: soliciting an IPv6 router
May 04 12:41:13 volumio ntpd[678]: Deleting interface #63 wlan0, 192.168.1.100#123, interface stats: received=14, sent=14, dropped=1, active_time=17 secs
May 04 12:41:13 volumio ntpd[678]: 91.151.84.199 local addr 192.168.1.100 ->
May 04 12:41:13 volumio ntpd[678]: 62.12.173.11 local addr 192.168.1.100 ->
May 04 12:41:13 volumio ntpd[678]: 162.159.200.1 local addr 192.168.1.100 ->
May 04 12:41:13 volumio ntpd[678]: 62.12.173.12 local addr 192.168.1.100 ->
May 04 12:41:13 volumio ntpd[678]: 192.36.143.130 local addr 192.168.1.100 ->
May 04 12:41:13 volumio ntpd[678]: 45.136.155.37 local addr 192.168.1.100 ->
May 04 12:41:13 volumio ntpd[678]: 85.199.214.98 local addr 192.168.1.100 ->
May 04 12:41:13 volumio ntpd[678]: 194.27.222.5 local addr 192.168.1.100 ->
May 04 12:41:13 volumio ntpd[678]: 194.27.156.207 local addr 192.168.1.100 ->
May 04 12:41:13 volumio ntpd[678]: 46.197.32.68 local addr 192.168.1.100 ->
May 04 12:41:13 volumio ntpd[678]: 162.159.200.123 local addr 192.168.1.100 ->
May 04 12:41:13 volumio ntpd[678]: 176.235.250.150 local addr 192.168.1.100 ->
May 04 12:41:13 volumio ntpd[678]: 161.9.147.35 local addr 192.168.1.100 ->
May 04 12:41:17 volumio dhcpcd[780]: wlan0: using static address 192.168.1.100/24
May 04 12:41:17 volumio avahi-daemon[530]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.100.
May 04 12:41:17 volumio avahi-daemon[530]: New relevant interface wlan0.IPv4 for mDNS.
May 04 12:41:17 volumio avahi-daemon[530]: Registering new address record for 192.168.1.100 on wlan0.IPv4.
May 04 12:41:17 volumio dhcpcd[780]: wlan0: adding route to 192.168.1.0/24
May 04 12:41:17 volumio dhcpcd[780]: wlan0: adding default route via 192.168.1.1
May 04 12:41:18 volumio volumio[5364]: info: Discovery: adding 67740b66-9021-4760-b5d4-f22c10ad4b4d
May 04 12:41:18 volumio volumio[5364]: info: Discovery: Found device Volumio
May 04 12:41:18 volumio volumio[5364]: info: CoreCommandRouter::volumioGetState
May 04 12:41:18 volumio volumio[5364]: info: CorePlayQueue::getTrack 0
May 04 12:41:18 volumio ntpd[678]: Listen normally on 64 wlan0 192.168.1.100:123
May 04 12:41:18 volumio ntpd[678]: new interface(s) found: waking up resolver
May 04 12:41:19 volumio go-librespot[5681]: time="2024-05-04T12:41:19+03:00" level=debug msg="put connect state because VOLUME_CHANGED"
May 04 12:41:19 volumio go-librespot[5681]: time="2024-05-04T12:41:19+03:00" level=trace msg="emitting websocket event: volume"
May 04 12:41:19 volumio volumio[5364]: SPOTIFY: received: {"type":"volume","data":{"value":50,"max":100}}
May 04 12:41:19 volumio volumio[5364]: SPOTIFY: RECEIVED SPOTIFY VOLUME 50
May 04 12:41:23 volumio ntpd[678]: 45.136.155.37 local addr 192.168.1.100 ->
May 04 12:41:23 volumio ntpd[678]: 192.36.143.130 local addr 192.168.1.100 ->
May 04 12:41:23 volumio ntpd[678]: 62.12.173.12 local addr 192.168.1.100 ->
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
May 04 12:41:24 volumio volumio[5364]: info: Adding plugin bluetooth to MyMusic Plugins
May 04 12:41:24 volumio volumio[5364]: info: Adding plugin multiroom to MyMusic Plugins
May 04 12:41:24 volumio volumio[5364]: info: Adding plugin metavolumio to MyMusic Plugins
May 04 12:41:24 volumio volumio[5364]: info: Adding plugin cd_controller to MyMusic Plugins
May 04 12:41:24 volumio volumio[5364]: info: Adding plugin smart_inputs to MyMusic Plugins
May 04 12:41:24 volumio volumio[5364]: info: Adding plugin tidalconnect to MyMusic Plugins
May 04 12:41:24 volumio volumio[5364]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
May 04 12:41:25 volumio volumio[5364]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
May 04 12:41:25 volumio volumio[5364]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
May 04 12:41:25 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:25 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:25 volumio volumio[5364]: info: Starting MyVolumio Remote Streaming Endpoints
May 04 12:41:25 volumio volumio[5364]: info: MyVolumio login type: Token
May 04 12:41:25 volumio volumio[5364]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
May 04 12:41:25 volumio volumio[5364]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
May 04 12:41:26 volumio volumio[5364]: info: Starting Streaming Service Transparent Proxy
May 04 12:41:26 volumio volumio[5364]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
May 04 12:41:26 volumio volumio[5364]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
May 04 12:41:26 volumio volumio[5364]: info: Streaming services startup
May 04 12:41:26 volumio volumio[5364]: info: Starting Streaming Daemon
May 04 12:41:26 volumio sudo[5911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 04 12:41:26 volumio sudo[5911]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:26 volumio volumio[5364]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
May 04 12:41:26 volumio sudo[5911]: pam_unix(sudo:session): session closed for user root
May 04 12:41:26 volumio volumio[5364]: error: Cannot start Volumio Streaming Daemon
May 04 12:41:26 volumio volumio[5364]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 04 12:41:26 volumio volumio[5364]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 04 12:41:27 volumio volumio[5364]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
May 04 12:41:27 volumio volumio[5364]: STREAMING PROXY: Starting server on port 3245
May 04 12:41:27 volumio volumio[5364]: Node JS runtime: 14
May 04 12:41:27 volumio volumio[5364]: info: MyVolumio token set successfully
May 04 12:41:27 volumio volumio[5364]: info: MYVOLUMIO: Adding device
May 04 12:41:27 volumio volumio[5364]: info: MYVOLUMIO: Evaluating Server
May 04 12:41:28 volumio volumio[5364]: info: MyVolumio status changed
May 04 12:41:28 volumio volumio[5364]: info: Streaming services startup
May 04 12:41:28 volumio volumio[5364]: info: Starting Streaming Daemon
May 04 12:41:28 volumio volumio[5364]: info: Removing browser output: myVolumio user plan is not superstar
May 04 12:41:28 volumio volumio[5364]: info: Removing audio output:
May 04 12:41:28 volumio volumio[5364]: info: Stoppping Tunnel 1
May 04 12:41:28 volumio sudo[5936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 04 12:41:28 volumio sudo[5936]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:28 volumio sudo[5936]: pam_unix(sudo:session): session closed for user root
May 04 12:41:28 volumio sudo[5939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
May 04 12:41:28 volumio sudo[5939]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 12:41:28 volumio volumio[5364]: error: Cannot start Volumio Streaming Daemon
May 04 12:41:28 volumio volumio[5364]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 04 12:41:28 volumio volumio[5364]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 04 12:41:28 volumio sudo[5939]: pam_unix(sudo:session): session closed for user root
May 04 12:41:28 volumio volumio[5364]: info: Remote SSH Stopped
May 04 12:41:28 volumio volumio[5364]: info: Setting Geolocation for MyVolumio to eu10
May 04 12:41:28 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:28 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:28 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:28 volumio volumio[5364]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
May 04 12:41:29 volumio volumio[5364]: info: Updating MyVolumio device info
May 04 12:41:29 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:30 volumio volumio[5364]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
May 04 12:41:30 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 04 12:41:30 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:30 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 04 12:41:30 volumio volumio-remote-updater[509]: No test mode
May 04 12:41:30 volumio volumio-remote-updater[509]: No alpha test mode
May 04 12:41:30 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
May 04 12:41:31 volumio volumio[5364]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Fix for mpd crashing with wrongly formatted cue files
- Restore install-to-disk on x86 and ThinkerBoard
- Fix for Waveshare 11.9inch DSI Display touch panel on RPi
- Fix for missing metadata on CD playback
- Fix for metadata with Classic and Modern UI
- Fix for factory-reset from USB on Motivo
- Fix for Volumio logo on boot on Motivo
- Various fixes for TIDAL Connect
- Fix japanese and thai characters on HDMI display
- Fix for keyboard opening on select on mobile
NEW ADDITIONS
- Add filtering to QOBUZ New releases
- Bump MPD to 0.23.15-3
- Add support for Waveshare 4.0inch DSI Display on RPi
- Improvements for reducing boot time
- Add patched driver for ax88179 on RPi
","title":"Update v3.661","updateavailable":true}
May 04 12:41:31 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 04 12:41:32 volumio volumio[5364]: info: MYVOLUMIO: Adding device
May 04 12:41:32 volumio volumio[5364]: info: MYVOLUMIO: Evaluating Server
May 04 12:41:34 volumio volumio[5364]: info: Setting Geolocation for MyVolumio to eu6
May 04 12:41:34 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:35 volumio volumio[5364]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
May 04 12:41:35 volumio volumio[5364]: info: Updating MyVolumio device info
May 04 12:41:35 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:36 volumio volumio[5364]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
May 04 12:41:37 volumio ntpd[678]: Soliciting pool server 2606:4700:f1::1
May 04 12:41:38 volumio ntpd[678]: Soliciting pool server 101.44.222.53
May 04 12:41:39 volumio ntpd[678]: Soliciting pool server 45.136.155.37
May 04 12:41:39 volumio ntpd[678]: Soliciting pool server 45.136.155.37
May 04 12:41:40 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 04 12:41:40 volumio volumio[5364]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5
May 04 12:41:40 volumio volumio[5364]: info: CoreCommandRouter::volumioGetState
May 04 12:41:40 volumio volumio[5364]: info: CorePlayQueue::getTrack 0
May 04 12:41:45 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:45 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:45 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
May 04 12:41:45 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
May 04 12:41:45 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
May 04 12:41:45 volumio volumio[5364]: info: CoreCommandRouter::volumioGetBrowseSources
May 04 12:41:45 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 04 12:41:46 volumio volumio[5364]: error: MyVolumio Plugin failed to authenticate in a timely fashion
May 04 12:41:46 volumio volumio[5364]: info: Completed starting MyVolumio Plugin
May 04 12:41:46 volumio volumio[5364]: info: BOOT COMPLETED
May 04 12:41:46 volumio volumio[5364]: [Metrics] CommandRouter: 82s 950.78ms
May 04 12:41:46 volumio volumio[5364]: info: CoreCommandRouter::volumiosetStartupVolume
May 04 12:41:46 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 12:41:46 volumio volumio[5364]: info: VolumeController:: Setting startup Volume 50
May 04 12:41:46 volumio volumio[5364]: info: VolumeController::SetAlsaVolume50
May 04 12:41:46 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 12:41:46 volumio volumio[5364]: info: CoreCommandRouter::Close All Modals sent
May 04 12:41:46 volumio volumio[5364]: info: CoreCommandRouter::Close All Modals sent
May 04 12:41:46 volumio volumio[5364]: info: [squeezelite_mc] Setting Squeezelite volume to 50
May 04 12:41:46 volumio volumio[5364]: info: CoreStateMachine::pushState
May 04 12:41:46 volumio volumio[5364]: info: CorePlayQueue::getTrack 0
May 04 12:41:46 volumio volumio[5364]: info: CoreCommandRouter::volumioPushState
May 04 12:41:46 volumio volumio[5364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50
May 04 12:41:46 volumio volumio[5364]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
May 04 12:41:46 volumio volumio[5364]: info: FusionDsp - ---- read samplerate from file: 44100
May 04 12:41:46 volumio volumio[5364]: info: camilladsp stopping service pid 5807...
May 04 12:41:46 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=1)
May 04 12:41:46 volumio volumio[5364]: info: camilladsp service terminated, instance 1
May 04 12:41:46 volumio volumio[5364]: info: FusionDsp - If filter freq >samplerate/2 then disable it
May 04 12:41:46 volumio volumio[5364]: info: FusionDsp - Nulleq applied
May 04 12:41:46 volumio volumio[5364]: info: camilladsp service started and running in background, instance 1
May 04 12:41:46 volumio volumio[5364]: info: camilladsp spawned new process with pid 5966, instance 1, run: true
May 04 12:41:46 volumio volumio[5364]: (node:5364) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '0' of undefined
May 04 12:41:46 volumio volumio[5364]: at PlayerStatusMonitor._PlayerStatusMonitor_parsePlayerStatusResult (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:180:37)
May 04 12:41:46 volumio volumio[5364]: at PlayerStatusMonitor._PlayerStatusMonitor_getStatusAndEmit (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:118:129)
May 04 12:41:46 volumio volumio[5364]: at runMicrotasks ()
May 04 12:41:46 volumio volumio[5364]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
May 04 12:41:46 volumio volumio[5364]: (node:5364) 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)
May 04 12:41:46 volumio volumio[5364]: (node:5364) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '0' of undefined
May 04 12:41:46 volumio volumio[5364]: at PlayerStatusMonitor._PlayerStatusMonitor_parsePlayerStatusResult (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:180:37)
May 04 12:41:46 volumio volumio[5364]: at PlayerStatusMonitor._PlayerStatusMonitor_getStatusAndEmit (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:118:129)
May 04 12:41:46 volumio volumio[5364]: at runMicrotasks ()
May 04 12:41:46 volumio volumio[5364]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
May 04 12:41:46 volumio volumio[5364]: (node:5364) 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: 5)
May 04 12:41:47 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
May 04 12:41:47 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 04 12:41:47 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
May 04 12:41:51 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=1)
May 04 12:41:51 volumio volumio[5364]: info: camilladsp spawned new process with pid 6013, instance 1, run: true
May 04 12:41:55 volumio wpa_supplicant[767]: wlan0: CTRL-EVENT-DISCONNECTED bssid=98:0d:67:3a:c5:41 reason=0 locally_generated=1
May 04 12:41:55 volumio dhcpcd[780]: wlan0: carrier lost
May 04 12:41:55 volumio wpa_supplicant[767]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
May 04 12:41:55 volumio wpa_supplicant[767]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=TR
May 04 12:41:55 volumio avahi-daemon[530]: Withdrawing address record for 192.168.1.100 on wlan0.
May 04 12:41:55 volumio avahi-daemon[530]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.100.
May 04 12:41:55 volumio avahi-daemon[530]: Interface wlan0.IPv4 no longer relevant for mDNS.
May 04 12:41:55 volumio dhcpcd[780]: wlan0: deleting route to 192.168.1.0/24
May 04 12:41:55 volumio dhcpcd[780]: wlan0: deleting default route via 192.168.1.1
May 04 12:41:55 volumio volumio[5364]: info: Discovery: A device disappeared from network
May 04 12:41:55 volumio volumio[5364]: info: Discovery: Device volumio disappeared from network
May 04 12:41:55 volumio wpa_supplicant[767]: wlan0: Trying to associate with SSID 'Taner-Balkon'
May 04 12:41:56 volumio dhcpcd[780]: wlan0: carrier acquired
May 04 12:41:56 volumio wpa_supplicant[767]: wlan0: Associated with 98:0d:67:3a:c5:41
May 04 12:41:56 volumio wpa_supplicant[767]: wlan0: CTRL-EVENT-CONNECTED - Connection to 98:0d:67:3a:c5:41 completed [id=0 id_str=]
May 04 12:41:56 volumio wpa_supplicant[767]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
May 04 12:41:56 volumio wpa_supplicant[767]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE
May 04 12:41:56 volumio dhcpcd[780]: wlan0: IAID eb:bc:b1:6f
May 04 12:41:56 volumio dhcpcd[780]: wlan0: probing address 192.168.1.100/24
May 04 12:41:56 volumio dhcpcd[780]: wlan0: carrier lost
May 04 12:41:56 volumio dhcpcd[780]: wlan0: carrier acquired
May 04 12:41:56 volumio dhcpcd[780]: wlan0: IAID eb:bc:b1:6f
May 04 12:41:56 volumio dhcpcd[780]: wlan0: probing address 192.168.1.100/24
May 04 12:41:57 volumio dhcpcd[780]: wlan0: soliciting an IPv6 router
May 04 12:41:58 volumio ntpd[678]: Deleting interface #64 wlan0, 192.168.1.100#123, interface stats: received=30, sent=30, dropped=0, active_time=40 secs
May 04 12:41:58 volumio ntpd[678]: 45.136.155.37 local addr 192.168.1.100 ->
May 04 12:41:58 volumio ntpd[678]: 101.44.222.53 local addr 192.168.1.100 ->
May 04 12:41:58 volumio ntpd[678]: 62.12.173.11 local addr 192.168.1.100 ->
May 04 12:41:58 volumio ntpd[678]: 162.159.200.1 local addr 192.168.1.100 ->
May 04 12:41:58 volumio ntpd[678]: 85.199.214.98 local addr 192.168.1.100 ->
May 04 12:41:58 volumio ntpd[678]: 194.27.222.5 local addr 192.168.1.100 ->
May 04 12:41:58 volumio ntpd[678]: 194.27.156.207 local addr 192.168.1.100 ->
May 04 12:41:58 volumio ntpd[678]: 46.197.32.68 local addr 192.168.1.100 ->
May 04 12:41:58 volumio ntpd[678]: 162.159.200.123 local addr 192.168.1.100 ->
May 04 12:41:58 volumio ntpd[678]: 176.235.250.150 local addr 192.168.1.100 ->
May 04 12:41:58 volumio ntpd[678]: 161.9.147.35 local addr 192.168.1.100 ->
May 04 12:41:58 volumio ntpd[678]: 91.151.84.199 local addr 192.168.1.100 ->
May 04 12:42:01 volumio volumio[5364]: info: UPDATER: Scheduling automatic update
May 04 12:42:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
May 04 12:42:01 volumio volumio[5364]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
May 04 12:42:01 volumio volumio[5364]: info: UPDATER: Auto update will take place at: Sun May 05 2024 04:45:01 GMT+0300 (GMT+03:00)
May 04 12:42:01 volumio volumio[5364]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 04 12:42:01 volumio volumio[5364]: Error: send ENETUNREACH 255.255.255.255:3483
May 04 12:42:01 volumio volumio[5364]: at doSend (dgram.js:692:16)
May 04 12:42:01 volumio volumio[5364]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12)
May 04 12:42:01 volumio volumio[5364]: at afterDns (dgram.js:638:5)
May 04 12:42:01 volumio volumio[5364]: at processTicksAndRejections (internal/process/task_queues.js:81:21) {
May 04 12:42:01 volumio volumio[5364]: errno: -101,
May 04 12:42:01 volumio volumio[5364]: code: 'ENETUNREACH',
May 04 12:42:01 volumio volumio[5364]: syscall: 'send',
May 04 12:42:01 volumio volumio[5364]: address: '255.255.255.255',
May 04 12:42:01 volumio volumio[5364]: port: 3483
May 04 12:42:01 volumio volumio[5364]: }
May 04 12:42:01 volumio volumio[5364]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 04 12:42:01 volumio dhcpcd[780]: wlan0: using static address 192.168.1.100/24
May 04 12:42:01 volumio avahi-daemon[530]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.100.
May 04 12:42:01 volumio dhcpcd[780]: wlan0: adding route to 192.168.1.0/24
May 04 12:42:01 volumio dhcpcd[780]: wlan0: adding default route via 192.168.1.1
May 04 12:42:01 volumio avahi-daemon[530]: New relevant interface wlan0.IPv4 for mDNS.
May 04 12:42:01 volumio avahi-daemon[530]: Registering new address record for 192.168.1.100 on wlan0.IPv4.
May 04 12:42:02 volumio sudo[6103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-04 12:41
May 04 12:42:02 volumio sudo[6103]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET"
VOLUMIO_VERSION="3.631"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"