-- Logs begin at Thu 2019-02-14 04:11:59 CST, end at Fri 2025-02-28 17:48:03 CST. -- Feb 28 17:47:02 volumio ntpd[752]: Soliciting pool server 207.192.69.118 Feb 28 17:47:03 volumio ntpd[752]: Soliciting pool server 216.31.16.12 Feb 28 17:47:03 volumio ntpd[752]: Soliciting pool server 137.110.222.27 Feb 28 17:47:04 volumio ntpd[752]: Soliciting pool server 172.234.37.140 Feb 28 17:47:04 volumio ntpd[752]: Soliciting pool server 89.116.246.10 Feb 28 17:47:05 volumio ntpd[752]: Soliciting pool server 173.249.203.72 Feb 28 17:47:05 volumio ntpd[752]: Soliciting pool server 24.229.44.105 Feb 28 17:47:06 volumio ntpd[752]: Soliciting pool server 166.88.142.52 Feb 28 17:47:07 volumio volumio[974]: error: Plugin system_controller networkfs failed to complete 'onVolumioStart' in a timely fashion Feb 28 17:47:07 volumio volumio[974]: info: Preparing to generate the ALSA configuration file Feb 28 17:47:07 volumio volumio[974]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 28 17:47:07 volumio volumio[974]: info: Reading ALSA contributions from plugins. Feb 28 17:47:07 volumio volumio[974]: info: Asound.conf file unchanged, so no further update is needed Feb 28 17:47:07 volumio volumio[974]: info: Output device has changed, restarting MPD Feb 28 17:47:07 volumio volumio[974]: info: Output device has changed, restarting Shairport Sync Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:07 volumio sudo[1367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 28 17:47:07 volumio sudo[1367]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:07 volumio sudo[1367]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:07 volumio sudo[1369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 28 17:47:07 volumio sudo[1369]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:07 volumio systemd[1]: Stopping Music Player Daemon... Feb 28 17:47:07 volumio volumio[974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 28 17:47:07 volumio volumio[974]: info: ___________ START PLUGINS ___________ Feb 28 17:47:07 volumio volumio[974]: info: ControllerMpd::onStart: Initializing MPD Feb 28 17:47:07 volumio volumio[974]: info: Creating MPD Configuration file Feb 28 17:47:07 volumio sudo[1376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 28 17:47:07 volumio sudo[1376]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 28 17:47:07 volumio volumio[974]: info: [1740786427611] CoreMusicLibrary::Adding element Media Servers Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 17:47:07 volumio sudo[1376]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:07 volumio sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 28 17:47:07 volumio sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:07 volumio ntpd[752]: Soliciting pool server 204.2.134.163 Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:07 volumio systemd[1]: mpd.service: Succeeded. Feb 28 17:47:07 volumio systemd[1]: Stopped Music Player Daemon. Feb 28 17:47:07 volumio volumio[974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:07 volumio systemd[1]: Starting Music Player Daemon... Feb 28 17:47:07 volumio volumio[974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 28 17:47:07 volumio volumio[974]: info: [1740786427750] CoreMusicLibrary::Adding element Last_100 Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 28 17:47:07 volumio volumio[974]: info: [1740786427754] CoreMusicLibrary::Adding element Webradio Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 17:47:07 volumio volumio[974]: info: Initializing BBC Radios Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:07 volumio volumio[974]: info: Loading i18n strings for locale en Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:07 volumio volumio[974]: info: FusionDsp - mixtype--------------------- Hardware Feb 28 17:47:07 volumio sudo[1384]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 28 17:47:07 volumio sudo[1384]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:07 volumio sudo[1384]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:07 volumio volumio[974]: info: Loading i18n strings for locale en Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 28 17:47:07 volumio volumio[974]: info: [1740786427895] CoreMusicLibrary::Adding element Pandora Radio Feb 28 17:47:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 17:47:07 volumio volumio[974]: Cannot find translation for source Pandora Radio Feb 28 17:47:07 volumio volumio[974]: info: [1740786427898] ControllerPandora::initializeMQTT Feb 28 17:47:07 volumio volumio[974]: info: [1740786427899] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Feb 28 17:47:07 volumio volumio[974]: info: Volumio Calling Home Feb 28 17:47:08 volumio volumio[974]: info: Preparing to generate the ALSA configuration file Feb 28 17:47:08 volumio volumio[974]: info: [1740786428054] ControllerPandora::initialSetup Feb 28 17:47:08 volumio volumio[974]: info: [1740786428063] ControllerPandora::PandoraHandler::init Feb 28 17:47:08 volumio volumio[974]: info: [1740786428064] ControllerPandora::PandoraHandler::loadErrorCodes Feb 28 17:47:08 volumio volumio[974]: info: [1740786428081] [Pandora] PandoraHandler::setMaxStationTracks: 16 Feb 28 17:47:08 volumio volumio[974]: info: [1740786428082] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty Feb 28 17:47:08 volumio volumio[974]: info: [1740786428084] ControllerPandora::flushPandora Feb 28 17:47:08 volumio volumio[974]: info: CorePlayQueue::getQueue Feb 28 17:47:08 volumio volumio[974]: info: CorePlayQueue::clearAddPlayQueue Feb 28 17:47:08 volumio volumio[974]: info: CorePlayQueue::saveQueue Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::serviceClearAddPlayTracks Feb 28 17:47:08 volumio volumio[974]: error: WARNING: No clearAddPlayTracks method for service webradio Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::volumioPushQueue Feb 28 17:47:08 volumio volumio[974]: info: [1740786428093] ControllerPandora::validateAndSetAccountOptions Feb 28 17:47:08 volumio volumio[974]: info: [1740786428094] ControllerPandora::PandoraHandler::setAccountOptions Feb 28 17:47:08 volumio volumio[974]: info: [1740786428096] ControllerPandora::flushPandora Feb 28 17:47:08 volumio volumio[974]: info: CorePlayQueue::getQueue Feb 28 17:47:08 volumio volumio[974]: info: CorePlayQueue::clearAddPlayQueue Feb 28 17:47:08 volumio volumio[974]: info: CorePlayQueue::saveQueue Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::serviceClearAddPlayTracks Feb 28 17:47:08 volumio volumio[974]: error: WARNING: No clearAddPlayTracks method for service webradio Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::volumioPushQueue Feb 28 17:47:08 volumio volumio[974]: info: [1740786428104] [Pandora] PreventAuthTimeout::init: Timer loaded Feb 28 17:47:08 volumio volumio[974]: info: [1740786428104] [Pandora] PreventAuthTimeout::init: Interval set to 10800000 ms Feb 28 17:47:08 volumio volumio[974]: info: [1740786428105] [Pandora] PreventAuthTimeout::init: Starting delayed timer after next interval Feb 28 17:47:08 volumio volumio[974]: info: [1740786428106] ControllerPandora::PandoraHandler::pandoraLoginAndGetStations Feb 28 17:47:08 volumio volumio[974]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 28 17:47:08 volumio volumio[974]: info: Reading ALSA contributions from plugins. Feb 28 17:47:08 volumio volumio[974]: info: minidlna: Found miniDLNA version 1.2.1 Feb 28 17:47:08 volumio volumio[974]: info: minidlna: Starting minidlna.service Feb 28 17:47:08 volumio volumio[974]: info: MPD Permissions set Feb 28 17:47:08 volumio volumio[974]: info: Volumio called home Feb 28 17:47:08 volumio sudo[1406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Feb 28 17:47:08 volumio sudo[1406]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:08 volumio volumio[974]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Feb 28 17:47:08 volumio sudo[1406]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:08 volumio ntpd[752]: Soliciting pool server 159.203.158.197 Feb 28 17:47:08 volumio volumio[974]: info: minidlna: systemctl start minidlna.service succeeded. Feb 28 17:47:08 volumio volumio[974]: info: Starting Shairport Sync Feb 28 17:47:08 volumio ntpd[752]: Soliciting pool server 216.229.4.69 Feb 28 17:47:08 volumio volumio[974]: info: Starting Shairport Sync Feb 28 17:47:08 volumio sudo[1419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 28 17:47:08 volumio volumio[974]: info: Starting Shairport Sync Feb 28 17:47:08 volumio sudo[1419]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:08 volumio sudo[1421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 28 17:47:08 volumio sudo[1425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 28 17:47:08 volumio sudo[1425]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:08 volumio sudo[1421]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 28 17:47:08 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 28 17:47:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Feb 28 17:47:08 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Feb 28 17:47:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 28 17:47:08 volumio sudo[1421]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:08 volumio sudo[1425]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:08 volumio sudo[1419]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:08 volumio volumio[974]: info: Asound.conf file unchanged, so no further update is needed Feb 28 17:47:08 volumio volumio[974]: info: Output device has changed, restarting MPD Feb 28 17:47:08 volumio volumio[974]: info: Output device has changed, restarting Shairport Sync Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:08 volumio sudo[1431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 28 17:47:08 volumio sudo[1431]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:08 volumio sudo[1431]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:08 volumio sudo[1434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 28 17:47:08 volumio sudo[1434]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:08 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 28 17:47:08 volumio systemd[1]: mpd.service: Succeeded. Feb 28 17:47:08 volumio systemd[1]: Stopped Music Player Daemon. Feb 28 17:47:09 volumio volumio[974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 28 17:47:09 volumio volumio[974]: info: Shairport-Sync Started Feb 28 17:47:09 volumio volumio[974]: Error adding Membership: Error: addMembership EINVAL Feb 28 17:47:09 volumio volumio[974]: info: Shairport-Sync Started Feb 28 17:47:09 volumio volumio[974]: info: Shairport-Sync Started Feb 28 17:47:09 volumio volumio[974]: info: MPD Permissions set Feb 28 17:47:09 volumio systemd[1]: Starting Music Player Daemon... Feb 28 17:47:09 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:09 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:09 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:09 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:09 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:09 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:09 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:09 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:09 volumio volumio[974]: info: Starting Shairport Sync Feb 28 17:47:09 volumio sudo[1440]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 28 17:47:09 volumio sudo[1440]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:09 volumio sudo[1440]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:09 volumio sudo[1447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 28 17:47:09 volumio sudo[1447]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:09 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 28 17:47:09 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 28 17:47:09 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 28 17:47:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 28 17:47:09 volumio sudo[1447]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:09 volumio volumio[974]: info: Shairport-Sync Started Feb 28 17:47:09 volumio volumio[974]: info: [1740786429384] [Pandora] PandoraHandler::pandoraLoginAndGetStations::pandoraLogin: [<=- Logged in to Pandora Servers -=>] Feb 28 17:47:09 volumio volumio[974]: info: [1740786429392] [Pandora] validateAndSetAccountOptions: ***DEBUG*** self.loggedIn: true Feb 28 17:47:09 volumio volumio[974]: info: [1740786429394] [Pandora] ExpireOldTracks::init: Timer loaded Feb 28 17:47:09 volumio volumio[974]: info: [1740786429395] [Pandora] ExpireOldTracks::init: Interval set to 300000 ms Feb 28 17:47:09 volumio volumio[974]: info: [1740786429395] [Pandora] ExpireOldTracks::init: Starting delayed timer after next interval Feb 28 17:47:09 volumio volumio[974]: info: [1740786429396] ControllerPandora::PandoraHandler::fillStationData Feb 28 17:47:09 volumio ntpd[752]: Soliciting pool server 23.168.24.210 Feb 28 17:47:09 volumio ntpd[752]: Soliciting pool server 172.104.25.121 Feb 28 17:47:09 volumio volumio[974]: 0+0 records in Feb 28 17:47:09 volumio volumio[974]: 0+0 records out Feb 28 17:47:09 volumio volumio[974]: 0 bytes copied, 0.000149688 s, 0.0 kB/s Feb 28 17:47:09 volumio volumio[974]: info: camilladsp spawned new process with pid 1462, instance 1, run: true Feb 28 17:47:09 volumio volumio[974]: info: camilladsp service started and running in background, instance 1 Feb 28 17:47:09 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:09 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 28 17:47:09 volumio volumio[974]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Feb 28 17:47:10 volumio volumio[974]: info: FusionDsp loaded Feb 28 17:47:10 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 17:47:10 volumio sudo[1466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Feb 28 17:47:10 volumio sudo[1466]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:10 volumio volumio[974]: info: FusionDsp - Reporting Fusion DSP Enabled Feb 28 17:47:10 volumio volumio[974]: info: Adding Signal Path Element [object Object] Feb 28 17:47:10 volumio volumio[974]: info: Adding fusiondspeq DSP Signal Path Element Feb 28 17:47:10 volumio volumio[974]: info: FusionDsp - ---- installed callbackRead Feb 28 17:47:10 volumio volumio[974]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 28 17:47:10 volumio volumio[974]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 28 17:47:10 volumio volumio[974]: info: FusionDsp - eq1: Feb 28 17:47:10 volumio volumio[974]: type: Biquad Feb 28 17:47:10 volumio volumio[974]: parameters: Feb 28 17:47:10 volumio volumio[974]: type: Peaking Feb 28 17:47:10 volumio volumio[974]: freq: 25 Feb 28 17:47:10 volumio volumio[974]: q: 1.1 Feb 28 17:47:10 volumio volumio[974]: gain: 0 Feb 28 17:47:10 volumio volumio[974]: eq2: Feb 28 17:47:10 volumio volumio[974]: type: Biquad Feb 28 17:47:10 volumio volumio[974]: parameters: Feb 28 17:47:10 volumio volumio[974]: type: Peaking Feb 28 17:47:10 volumio volumio[974]: freq: 40 Feb 28 17:47:10 volumio volumio[974]: q: 1.1 Feb 28 17:47:10 volumio volumio[974]: gain: 0 Feb 28 17:47:10 volumio volumio[974]: eq3: Feb 28 17:47:10 volumio volumio[974]: type: Biquad Feb 28 17:47:10 volumio volumio[974]: parameters: Feb 28 17:47:10 volumio volumio[974]: type: Peaking Feb 28 17:47:10 volumio volumio[974]: freq: 63 Feb 28 17:47:10 volumio volumio[974]: q: 1.1 Feb 28 17:47:10 volumio volumio[974]: gain: 0 Feb 28 17:47:10 volumio volumio[974]: eq4: Feb 28 17:47:10 volumio volumio[974]: type: Biquad Feb 28 17:47:10 volumio volumio[974]: parameters: Feb 28 17:47:10 volumio volumio[974]: type: Peaking Feb 28 17:47:10 volumio volumio[974]: freq: 100 Feb 28 17:47:10 volumio volumio[974]: q: 1.1 Feb 28 17:47:10 volumio volumio[974]: gain: 0 Feb 28 17:47:10 volumio volumio[974]: eq5: Feb 28 17:47:10 volumio volumio[974]: type: Biquad Feb 28 17:47:10 volumio volumio[974]: parameters: Feb 28 17:47:10 volumio volumio[974]: type: Peaking Feb 28 17:47:10 volumio volumio[974]: freq: 160 Feb 28 17:47:10 volumio volumio[974]: q: 1.1 Feb 28 17:47:10 volumio volumio[974]: gain: 0 Feb 28 17:47:10 volumio volumio[974]: eq6: Feb 28 17:47:10 volumio volumio[974]: type: Biquad Feb 28 17:47:10 volumio volumio[974]: parameters: Feb 28 17:47:10 volumio volumio[974]: type: Peaking Feb 28 17:47:10 volumio volumio[974]: freq: 250 Feb 28 17:47:10 volumio volumio[974]: q: 1.1 Feb 28 17:47:10 volumio volumio[974]: gain: 0 Feb 28 17:47:10 volumio volumio[974]: eq7: Feb 28 17:47:10 volumio volumio[974]: type: Biquad Feb 28 17:47:10 volumio volumio[974]: parameters: Feb 28 17:47:10 volumio volumio[974]: type: Peaking Feb 28 17:47:10 volumio volumio[974]: freq: 400 Feb 28 17:47:10 volumio volumio[974]: q: 1.1 Feb 28 17:47:10 volumio volumio[974]: gain: 0 Feb 28 17:47:10 volumio volumio[974]: eq8: Feb 28 17:47:10 volumio volumio[974]: type: Biquad Feb 28 17:47:10 volumio volumio[974]: parameters: Feb 28 17:47:10 volumio volumio[974]: type: Peaking Feb 28 17:47:10 volumio volumio[974]: freq: 630 Feb 28 17:47:10 volumio volumio[974]: q: 1.1 Feb 28 17:47:10 volumio volumio[974]: gain: 0 Feb 28 17:47:10 volumio volumio[974]: eq9: Feb 28 17:47:10 volumio volumio[974]: type: Biquad Feb 28 17:47:10 volumio volumio[974]: parameters: Feb 28 17:47:10 volumio volumio[974]: type: Peaking Feb 28 17:47:10 volumio volumio[974]: freq: 1000 Feb 28 17:47:10 volumio volumio[974]: q: 1.1 Feb 28 17:47:10 volumio volumio[974]: gain: 0 Feb 28 17:47:10 volumio volumio[974]: eq10: Feb 28 17:47:10 volumio volumio[974]: type: Biquad Feb 28 17:47:10 volumio volumio[974]: parameters: Feb 28 17:47:10 volumio volumio[974]: type: Peaking Feb 28 17:47:10 volumio volumio[974]: freq: 1600 Feb 28 17:47:10 volumio volumio[974]: q: 1.1 Feb 28 17:47:10 volumio volumio[974]: gain: 0 Feb 28 17:47:10 volumio volumio[974]: eq11: Feb 28 17:47:10 volumio volumio[974]: type: Biquad Feb 28 17:47:10 volumio volumio[974]: parameters: Feb 28 17:47:10 volumio volumio[974]: type: Peaking Feb 28 17:47:10 volumio volumio[974]: freq: 2500 Feb 28 17:47:10 volumio volumio[974]: q: 1.1 Feb 28 17:47:10 volumio volumio[974]: gain: 0 Feb 28 17:47:10 volumio volumio[974]: eq12: Feb 28 17:47:10 volumio volumio[974]: type: Biquad Feb 28 17:47:10 volumio volumio[974]: parameters: Feb 28 17:47:10 volumio volumio[974]: type: Peaking Feb 28 17:47:10 volumio volumio[974]: freq: 4000 Feb 28 17:47:10 volumio volumio[974]: q: 1.1 Feb 28 17:47:10 volumio volumio[974]: gain: 0 Feb 28 17:47:10 volumio volumio[974]: eq13: Feb 28 17:47:10 volumio volumio[974]: type: Biquad Feb 28 17:47:10 volumio volumio[974]: parameters: Feb 28 17:47:10 volumio volumio[974]: type: Peaking Feb 28 17:47:10 volumio volumio[974]: freq: 6300 Feb 28 17:47:10 volumio volumio[974]: q: 1.1 Feb 28 17:47:10 volumio volumio[974]: gain: 0 Feb 28 17:47:10 volumio volumio[974]: eq14: Feb 28 17:47:10 volumio volumio[974]: type: Biquad Feb 28 17:47:10 volumio volumio[974]: parameters: Feb 28 17:47:10 volumio volumio[974]: type: Peaking Feb 28 17:47:10 volumio volumio[974]: freq: 10000 Feb 28 17:47:10 volumio volumio[974]: q: 1.1 Feb 28 17:47:10 volumio volumio[974]: gain: 0 Feb 28 17:47:10 volumio volumio[974]: eq15: Feb 28 17:47:10 volumio volumio[974]: type: Biquad Feb 28 17:47:10 volumio volumio[974]: parameters: Feb 28 17:47:10 volumio volumio[974]: type: Peaking Feb 28 17:47:10 volumio volumio[974]: freq: 16000 Feb 28 17:47:10 volumio volumio[974]: q: 1.1 Feb 28 17:47:10 volumio volumio[974]: gain: 0 Feb 28 17:47:10 volumio systemd[1]: Started FusionDsp Daemon. Feb 28 17:47:10 volumio sudo[1466]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:10 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 28 17:47:10 volumio volumio[974]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 28 17:47:10 volumio mpd[1449]: Feb 28 17:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 28 17:47:10 volumio systemd[1]: Started Music Player Daemon. Feb 28 17:47:10 volumio sudo[1434]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:10 volumio sudo[1369]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:10 volumio sudo[1378]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:10 volumio ntpd[752]: Soliciting pool server 108.45.124.89 Feb 28 17:47:10 volumio ntpd[752]: Soliciting pool server 24.111.79.186 Feb 28 17:47:11 volumio volumio[974]: info: Completed starting Core Plugins Feb 28 17:47:11 volumio volumio[974]: info: ------------------------------------------- Feb 28 17:47:11 volumio volumio[974]: info: ----- MyVolumio plugins startup ---- Feb 28 17:47:11 volumio volumio[974]: info: ------------------------------------------- Feb 28 17:47:11 volumio volumio[974]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 28 17:47:11 volumio ntpd[752]: receive: Unexpected origin timestamp 0xeb6cc97e.7fcdecfc does not match aorg 0000000000.00000000 from server@172.234.37.140 xmt 0xeb6cc97f.b7ead11d Feb 28 17:47:11 volumio ntpd[752]: receive: Unexpected origin timestamp 0xeb6cc97e.7fc36041 does not match aorg 0000000000.00000000 from server@216.229.4.69 xmt 0xeb6cc97f.ba57da4f Feb 28 17:47:11 volumio volumio[974]: error: updateQueue error: null Feb 28 17:47:11 volumio ntpd[752]: receive: Unexpected origin timestamp 0xeb6cc97e.7fcbda2f does not match aorg 0000000000.00000000 from server@89.116.246.10 xmt 0xeb6cc97f.bbdb82d8 Feb 28 17:47:11 volumio volumio[974]: info: MPD running with PID1449 Feb 28 17:47:11 volumio volumio[974]: ,establishing connection Feb 28 17:47:11 volumio ntpd[752]: receive: Unexpected origin timestamp 0xeb6cc97e.7fca8b07 does not match aorg 0000000000.00000000 from server@204.2.134.163 xmt 0xeb6cc97f.bbcd2ba1 Feb 28 17:47:11 volumio volumio[974]: error: MPD error: The expression evaluated to a falsy value: Feb 28 17:47:11 volumio volumio[974]: assert.ok(self.idling) Feb 28 17:47:11 volumio volumio[974]: error: The expression evaluated to a falsy value: Feb 28 17:47:11 volumio volumio[974]: assert.ok(self.idling) Feb 28 17:47:11 volumio volumio[974]: error: MPD error: The expression evaluated to a falsy value: Feb 28 17:47:11 volumio volumio[974]: assert.ok(self.idling) Feb 28 17:47:11 volumio volumio[974]: error: The expression evaluated to a falsy value: Feb 28 17:47:11 volumio volumio[974]: assert.ok(self.idling) Feb 28 17:47:11 volumio volumio[974]: error: updateQueue error: null Feb 28 17:47:12 volumio ntpd[752]: Soliciting pool server 23.141.40.124 Feb 28 17:47:16 volumio volumio[974]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 28 17:47:20 volumio volumio[974]: info: Adding plugin bluetooth to MyMusic Plugins Feb 28 17:47:20 volumio volumio[974]: info: Adding plugin multiroom to MyMusic Plugins Feb 28 17:47:20 volumio volumio[974]: info: Adding plugin metavolumio to MyMusic Plugins Feb 28 17:47:20 volumio volumio[974]: info: Adding plugin cd_controller to MyMusic Plugins Feb 28 17:47:20 volumio volumio[974]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 28 17:47:20 volumio volumio[974]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 28 17:47:20 volumio volumio[974]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 28 17:47:21 volumio volumio[974]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 28 17:47:21 volumio volumio[974]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 28 17:47:21 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:21 volumio volumio[974]: info: Starting MyVolumio Remote Streaming Endpoints Feb 28 17:47:21 volumio volumio[974]: info: MyVolumio login type: Token Feb 28 17:47:21 volumio volumio[974]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 28 17:47:21 volumio volumio[974]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 28 17:47:22 volumio volumio[974]: info: Starting Streaming Service Transparent Proxy Feb 28 17:47:22 volumio volumio[974]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 28 17:47:22 volumio volumio[974]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 28 17:47:22 volumio volumio[974]: info: Streaming services startup Feb 28 17:47:22 volumio volumio[974]: info: Starting Streaming Daemon Feb 28 17:47:22 volumio sudo[1507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 28 17:47:22 volumio sudo[1507]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:22 volumio volumio[974]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 28 17:47:22 volumio sudo[1507]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:22 volumio volumio[974]: error: Cannot start Volumio Streaming Daemon Feb 28 17:47:22 volumio volumio[974]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 28 17:47:22 volumio volumio[974]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 28 17:47:23 volumio volumio[974]: error: MyVolumio Custom Token format not valid, refreshing it Feb 28 17:47:23 volumio volumio[974]: info: MyVolumio login type: Token Feb 28 17:47:23 volumio volumio[974]: STREAMING PROXY: Starting server on port 3245 Feb 28 17:47:23 volumio volumio[974]: Node JS runtime: 14 Feb 28 17:47:24 volumio volumio[974]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 28 17:47:24 volumio volumio[974]: info: MyVolumio token set successfully Feb 28 17:47:24 volumio volumio[974]: info: MYVOLUMIO: Adding device Feb 28 17:47:24 volumio volumio[974]: info: MYVOLUMIO: Evaluating Server Feb 28 17:47:24 volumio volumio[974]: info: MyVolumio status changed Feb 28 17:47:24 volumio volumio[974]: info: Streaming services startup Feb 28 17:47:24 volumio volumio[974]: info: Starting Streaming Daemon Feb 28 17:47:24 volumio volumio[974]: info: Removing browser output: myVolumio user plan is not superstar Feb 28 17:47:24 volumio volumio[974]: info: Removing audio output: Feb 28 17:47:24 volumio volumio[974]: info: Stoppping Tunnel 1 Feb 28 17:47:24 volumio sudo[1546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 28 17:47:24 volumio sudo[1546]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:24 volumio sudo[1548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 28 17:47:24 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:24 volumio sudo[1546]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:24 volumio volumio[974]: error: Cannot start Volumio Streaming Daemon Feb 28 17:47:24 volumio volumio[974]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 28 17:47:24 volumio volumio[974]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 28 17:47:25 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:25 volumio volumio[974]: info: Remote SSH Stopped Feb 28 17:47:26 volumio volumio[974]: info: Setting Geolocation for MyVolumio to us3 Feb 28 17:47:26 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:26 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:26 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:27 volumio volumio[974]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 28 17:47:27 volumio volumio[974]: info: Updating MyVolumio device info Feb 28 17:47:27 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:28 volumio volumio[974]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 28 17:47:31 volumio volumio[974]: info: MYVOLUMIO: Adding device Feb 28 17:47:31 volumio volumio[974]: info: MYVOLUMIO: Evaluating Server Feb 28 17:47:33 volumio volumio[974]: info: Setting Geolocation for MyVolumio to us3 Feb 28 17:47:33 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:33 volumio volumio[974]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 28 17:47:34 volumio volumio[974]: info: Updating MyVolumio device info Feb 28 17:47:34 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:34 volumio volumio[974]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 28 17:47:36 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 28 17:47:36 volumio volumio[974]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 28 17:47:36 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Feb 28 17:47:36 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Feb 28 17:47:36 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 17:47:36 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 17:47:36 volumio volumio[974]: info: Discovery: Getting this device information Feb 28 17:47:36 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Feb 28 17:47:36 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Feb 28 17:47:36 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 17:47:37 volumio volumio[974]: verbose: New Socket.io Connection to 192.168.1.193:3000 from 192.168.1.195 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Feb 28 17:47:37 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Feb 28 17:47:37 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Feb 28 17:47:37 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 28 17:47:37 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 28 17:47:38 volumio sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 28 17:47:38 volumio sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:38 volumio sudo[1584]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:38 volumio sudo[1586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 17:47:38 volumio sudo[1586]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:38 volumio sudo[1586]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:39 volumio volumio[974]: verbose: New Socket.io Connection to 192.168.1.193 from 192.168.1.195 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 28 17:47:39 volumio volumio-remote-updater[584]: No test mode Feb 28 17:47:39 volumio volumio-remote-updater[584]: No alpha test mode Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 28 17:47:39 volumio sudo[1593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 28 17:47:39 volumio sudo[1593]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:39 volumio sudo[1593]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:39 volumio sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 17:47:39 volumio sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 17:47:39 volumio volumio[974]: info: Discovery: Getting this device information Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Feb 28 17:47:39 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 17:47:39 volumio sudo[1595]: pam_unix(sudo:session): session closed for user root Feb 28 17:47:39 volumio volumio[974]: verbose: New Socket.io Connection to 192.168.1.193 from 192.168.1.195 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::volumioGetVisibleSources Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Feb 28 17:47:39 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 28 17:47:39 volumio volumio[974]: info: Received Get System Info Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 17:47:39 volumio volumio[974]: info: Discovery: Getting this device information Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Feb 28 17:47:39 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Feb 28 17:47:39 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Feb 28 17:47:39 volumio volumio[974]: info: Listing playlists Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 28 17:47:39 volumio volumio[974]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 28 17:47:39 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 28 17:47:40 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 28 17:47:41 volumio volumio[974]: info: Received Get System Info Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 17:47:41 volumio volumio[974]: info: Discovery: Getting this device information Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Feb 28 17:47:41 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::volumioGetBrowseSources Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::volumioGetQueue Feb 28 17:47:41 volumio volumio[974]: info: CoreStateMachine::getQueue Feb 28 17:47:41 volumio volumio[974]: info: CorePlayQueue::getQueue Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 28 17:47:41 volumio volumio[974]: info: Received Get System Info Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 17:47:41 volumio volumio[974]: info: Discovery: Getting this device information Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Feb 28 17:47:41 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Feb 28 17:47:41 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 17:47:42 volumio volumio[974]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 28 17:47:42 volumio volumio[974]: info: Completed starting MyVolumio Plugin Feb 28 17:47:42 volumio volumio[974]: [Metrics] CommandRouter: 80s 38.03ms Feb 28 17:47:42 volumio volumio[974]: info: CoreCommandRouter::volumiosetStartupVolume Feb 28 17:47:42 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:47:42 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:47:42 volumio volumio[974]: info: CoreCommandRouter::Close All Modals sent Feb 28 17:47:42 volumio volumio[974]: info: CoreCommandRouter::Close All Modals sent Feb 28 17:47:42 volumio volumio[974]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 28 17:47:42 volumio volumio[974]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 28 17:47:42 volumio volumio[974]: info: camilladsp stopping service pid 1462... Feb 28 17:47:43 volumio volumio[974]: info: camilladsp service terminated, instance 1 Feb 28 17:47:43 volumio volumio[974]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 28 17:47:43 volumio volumio[974]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Feb 28 17:47:43 volumio volumio[974]: 2+0 records in Feb 28 17:47:43 volumio volumio[974]: 2+0 records out Feb 28 17:47:43 volumio volumio[974]: 65536 bytes (66 kB, 64 KiB) copied, 0.000470052 s, 139 MB/s Feb 28 17:47:43 volumio volumio[974]: info: camilladsp spawned new process with pid 1608, instance 1, run: true Feb 28 17:47:43 volumio volumio[974]: info: camilladsp service started and running in background, instance 1 Feb 28 17:47:43 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 28 17:47:43 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 28 17:47:43 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 28 17:47:43 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 28 17:47:44 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 28 17:47:44 volumio volumio[974]: info: CURURI: music-library Feb 28 17:47:44 volumio volumio[974]: error: Failed LSINFO: null Feb 28 17:47:44 volumio volumio[974]: info: Preload queue cleared Feb 28 17:47:48 volumio volumio[974]: 0+0 records in Feb 28 17:47:48 volumio volumio[974]: 0+0 records out Feb 28 17:47:48 volumio volumio[974]: 0 bytes copied, 0.000141666 s, 0.0 kB/s Feb 28 17:47:48 volumio volumio[974]: info: camilladsp spawned new process with pid 1631, instance 1, run: true Feb 28 17:47:48 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 28 17:47:49 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 28 17:47:49 volumio volumio[974]: info: BOOT COMPLETED Feb 28 17:47:50 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 28 17:47:50 volumio volumio[974]: info: CURURI: music-library Feb 28 17:47:50 volumio volumio[974]: error: Failed LSINFO: null Feb 28 17:47:50 volumio volumio[974]: info: Preload queue cleared Feb 28 17:47:55 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 28 17:47:55 volumio volumio[974]: info: Preload queue cleared Feb 28 17:47:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 28 17:47:57 volumio volumio[974]: info: Preload queue cleared Feb 28 17:47:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 28 17:47:57 volumio volumio[974]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 28 17:47:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 28 17:47:57 volumio volumio[974]: info: Received Get System Version Feb 28 17:47:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 17:47:57 volumio volumio[974]: info: Received Get System Info Feb 28 17:47:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 17:47:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 17:47:57 volumio volumio[974]: info: Discovery: Getting this device information Feb 28 17:47:57 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Feb 28 17:47:57 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Feb 28 17:47:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 17:47:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 28 17:47:59 volumio volumio[974]: info: Preload queue cleared Feb 28 17:48:02 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 28 17:48:02 volumio volumio[974]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 28 17:48:02 volumio volumio[974]: TypeError: Cannot read property 'length' of undefined Feb 28 17:48:02 volumio volumio[974]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Feb 28 17:48:02 volumio volumio[974]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Feb 28 17:48:02 volumio volumio[974]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Feb 28 17:48:02 volumio volumio[974]: at Parser.emit (events.js:315:20) Feb 28 17:48:02 volumio volumio[974]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Feb 28 17:48:02 volumio volumio[974]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Feb 28 17:48:02 volumio volumio[974]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Feb 28 17:48:02 volumio volumio[974]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Feb 28 17:48:02 volumio volumio[974]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Feb 28 17:48:02 volumio volumio[974]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Feb 28 17:48:02 volumio volumio[974]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Feb 28 17:48:02 volumio volumio[974]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Feb 28 17:48:02 volumio volumio[974]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Feb 28 17:48:02 volumio volumio[974]: at IncomingMessage.emit (events.js:327:22) Feb 28 17:48:02 volumio volumio[974]: at endReadableNT (internal/streams/readable.js:1327:12) Feb 28 17:48:02 volumio volumio[974]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Feb 28 17:48:02 volumio volumio[974]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 28 17:48:03 volumio sudo[1708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-28 17:47 Feb 28 17:48:03 volumio sudo[1708]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"