-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2024-05-04 11:47:26 CEST. -- May 04 11:46:02 test-volumio volumio[978]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 May 04 11:46:05 test-volumio volumio[978]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 11:46:05 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 04 11:46:05 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 04 11:46:05 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 04 11:46:05 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 04 11:46:05 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 11:46:05 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 11:46:05 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 11:46:05 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 11:46:05 test-volumio volumio[978]: info: CoreCommandRouter::volumioGetBrowseSources May 04 11:46:05 test-volumio volumio[978]: info: CoreCommandRouter::volumioGetBrowseSources May 04 11:46:05 test-volumio volumio[978]: info: CoreCommandRouter::volumioGetBrowseSources May 04 11:46:05 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:46:05 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 04 11:46:09 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:46:12 test-volumio volumio[978]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 May 04 11:46:13 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:46:13 test-volumio volumiologrotate[513]: ls: cannot access '/var/log/samba/log.wb-TEST': No such file or directory May 04 11:46:13 test-volumio volumiologrotate[513]: ls: cannot access 'VOLUMIO': No such file or directory May 04 11:46:17 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:46:21 test-volumio volumio[978]: error: Plugin system_controller networkfs failed to complete 'onVolumioStart' in a timely fashion May 04 11:46:21 test-volumio volumio[978]: info: Preparing to generate the ALSA configuration file May 04 11:46:21 test-volumio volumio[978]: info: Asound.conf file unchanged, so no further update is needed May 04 11:46:21 test-volumio volumio[978]: info: Output device has changed, restarting MPD May 04 11:46:21 test-volumio volumio[978]: info: Output device has changed, restarting Shairport Sync May 04 11:46:21 test-volumio sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 04 11:46:21 test-volumio sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 11:46:21 test-volumio sudo[1160]: pam_unix(sudo:session): session closed for user root May 04 11:46:21 test-volumio sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 04 11:46:21 test-volumio sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 11:46:21 test-volumio volumio[978]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 04 11:46:21 test-volumio volumio[978]: info: ___________ START PLUGINS ___________ May 04 11:46:21 test-volumio volumio[978]: info: ControllerMpd::onStart: Initializing MPD May 04 11:46:21 test-volumio volumio[978]: info: Creating MPD Configuration file May 04 11:46:21 test-volumio sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 04 11:46:21 test-volumio sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 11:46:21 test-volumio volumio[978]: info: [1714815981356] CoreMusicLibrary::Adding element Server multimediali May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 11:46:21 test-volumio sudo[1170]: pam_unix(sudo:session): session closed for user root May 04 11:46:21 test-volumio sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 04 11:46:21 test-volumio sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 11:46:21 test-volumio systemd[1]: Listening on mpd.socket. May 04 11:46:21 test-volumio systemd[1]: Starting Music Player Daemon... May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 11:46:21 test-volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 04 11:46:21 test-volumio systemd[1]: mpd.service: Succeeded. May 04 11:46:21 test-volumio systemd[1]: Stopped Music Player Daemon. May 04 11:46:21 test-volumio systemd[1]: Starting Music Player Daemon... May 04 11:46:21 test-volumio volumio[978]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 11:46:21 test-volumio volumio[978]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 11:46:21 test-volumio volumio[978]: info: [1714815981501] CoreMusicLibrary::Adding element Last_100 May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 11:46:21 test-volumio volumio[978]: info: [1714815981507] CoreMusicLibrary::Adding element Webradio May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 11:46:21 test-volumio volumio[978]: info: Adding Calm Radio to Browse Sources May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 11:46:21 test-volumio volumio[978]: info: [1714815981525] CoreMusicLibrary::Adding element Calm Radio May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Calm Radio May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 11:46:21 test-volumio volumio[978]: info: [1714815981559] CoreMusicLibrary::Adding element Mixcloud May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Calm Radio May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Mixcloud May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 11:46:21 test-volumio volumio[978]: info: [1714815981584] CoreMusicLibrary::Adding element SoundCloud May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Calm Radio May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Mixcloud May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source SoundCloud May 04 11:46:21 test-volumio volumio[978]: info: AutoStart - onStart May 04 11:46:21 test-volumio volumio[978]: info: [now-playing] ConfigUpdater: config is up to date. May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 11:46:21 test-volumio volumio[978]: info: [1714815981654] CoreMusicLibrary::Adding element 80s80s Radio May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Calm Radio May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Mixcloud May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source SoundCloud May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source 80s80s Radio May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 11:46:21 test-volumio volumio[978]: info: [1714815981669] CoreMusicLibrary::Adding element Mother Earth Radio May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Calm Radio May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Mixcloud May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source SoundCloud May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source 80s80s Radio May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Mother Earth Radio May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 11:46:21 test-volumio volumio[978]: info: [1714815981780] CoreMusicLibrary::Adding element Personal Radio May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Calm Radio May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Mixcloud May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source SoundCloud May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source 80s80s Radio May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Mother Earth Radio May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Personal Radio May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 11:46:21 test-volumio volumio[978]: info: [1714815981788] CoreMusicLibrary::Adding element LastFM May 04 11:46:21 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Calm Radio May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Mixcloud May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source SoundCloud May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source 80s80s Radio May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Mother Earth Radio May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source Personal Radio May 04 11:46:21 test-volumio volumio[978]: Cannot find translation for source LastFM May 04 11:46:21 test-volumio volumio[978]: info: [LastFM] scrobbler initiated! May 04 11:46:21 test-volumio volumio[978]: info: [LastFM] extended logging: false May 04 11:46:21 test-volumio volumio[978]: info: [LastFM] try scrobble stream/radio plays: true May 04 11:46:21 test-volumio volumio[978]: info: [LastFM] Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" May 04 11:46:21 test-volumio volumio[978]: info: [LastFM] Left init routine May 04 11:46:21 test-volumio volumio[978]: info: [LastFM] Socket already connected: true May 04 11:46:21 test-volumio volumio[978]: info: Volumio Calling Home May 04 11:46:21 test-volumio volumio[978]: info: [LastFM] finished init with error: Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" May 04 11:46:21 test-volumio volumio[978]: info: [now-playing] App is listening on port 4004. May 04 11:46:21 test-volumio volumio[978]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds May 04 11:46:22 test-volumio volumio[978]: info: MPD Permissions set May 04 11:46:22 test-volumio volumio[978]: info: MPD Permissions set May 04 11:46:22 test-volumio volumio[978]: info: Volumio called home May 04 11:46:22 test-volumio volumio[978]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 50 May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:46:22 test-volumio volumio[978]: info: Starting Shairport Sync May 04 11:46:22 test-volumio volumio[978]: info: Starting Shairport Sync May 04 11:46:22 test-volumio volumio[978]: info: Starting Shairport Sync May 04 11:46:22 test-volumio volumio[978]: (node:978) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. May 04 11:46:22 test-volumio volumio[978]: (Use `node --trace-deprecation ...` to show where the warning was created) May 04 11:46:22 test-volumio volumio[978]: (node:978) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. May 04 11:46:22 test-volumio volumio[978]: info: CoreCommandRouter::volumioGetState May 04 11:46:22 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:23 test-volumio sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 04 11:46:23 test-volumio sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 04 11:46:23 test-volumio sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 11:46:23 test-volumio sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 04 11:46:23 test-volumio sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 11:46:23 test-volumio sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 11:46:23 test-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 04 11:46:23 test-volumio systemd[1]: shairport-sync.service: Succeeded. May 04 11:46:23 test-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 04 11:46:23 test-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 04 11:46:23 test-volumio sudo[1195]: pam_unix(sudo:session): session closed for user root May 04 11:46:23 test-volumio sudo[1197]: pam_unix(sudo:session): session closed for user root May 04 11:46:23 test-volumio sudo[1199]: pam_unix(sudo:session): session closed for user root May 04 11:46:23 test-volumio volumio[978]: info: Shairport-Sync Started May 04 11:46:23 test-volumio volumio[978]: Error adding Membership: Error: addMembership EINVAL May 04 11:46:23 test-volumio volumio[978]: info: Shairport-Sync Started May 04 11:46:23 test-volumio volumio[978]: info: Shairport-Sync Started May 04 11:46:25 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:46:26 test-volumio mpd[1179]: May 04 11:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 04 11:46:26 test-volumio systemd[1]: Started Music Player Daemon. May 04 11:46:26 test-volumio sudo[1162]: pam_unix(sudo:session): session closed for user root May 04 11:46:26 test-volumio sudo[1172]: pam_unix(sudo:session): session closed for user root May 04 11:46:26 test-volumio volumio[978]: info: Completed starting Core Plugins May 04 11:46:26 test-volumio volumio[978]: info: ------------------------------------------- May 04 11:46:26 test-volumio volumio[978]: info: ----- MyVolumio plugins startup ---- May 04 11:46:26 test-volumio volumio[978]: info: ------------------------------------------- May 04 11:46:26 test-volumio volumio[978]: info: [MyVolumio PluginManager] Fetching plans data.... May 04 11:46:26 test-volumio volumio[978]: error: MPD error: The expression evaluated to a falsy value: May 04 11:46:26 test-volumio volumio[978]: assert.ok(self.idling) May 04 11:46:26 test-volumio volumio[978]: error: The expression evaluated to a falsy value: May 04 11:46:26 test-volumio volumio[978]: assert.ok(self.idling) May 04 11:46:26 test-volumio volumio[978]: info: MPD running with PID1179 May 04 11:46:26 test-volumio volumio[978]: ,establishing connection May 04 11:46:26 test-volumio volumio[978]: error: updateQueue error: null May 04 11:46:26 test-volumio volumio[978]: error: updateQueue error: null May 04 11:46:29 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:46:30 test-volumio volumio[1111]: Generating RSA private key, 4096 bit long modulus (2 primes) May 04 11:46:31 test-volumio volumio[978]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 04 11:46:33 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 04 11:46:33 test-volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand rescan May 04 11:46:33 test-volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 04 11:46:33 test-volumio volumio[978]: info: May 04 11:46:33 test-volumio volumio[978]: ---------------------------- MPD announces state update: update May 04 11:46:33 test-volumio volumio[978]: info: sendMpdCommand rescan took 8 milliseconds May 04 11:46:33 test-volumio volumio[978]: info: ControllerMpd::getState May 04 11:46:33 test-volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 04 11:46:33 test-volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 04 11:46:33 test-volumio volumio[978]: info: May 04 11:46:33 test-volumio volumio[978]: ---------------------------- MPD announces state update: update May 04 11:46:33 test-volumio volumio[978]: info: ControllerMpd::getState May 04 11:46:33 test-volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 04 11:46:33 test-volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 04 11:46:33 test-volumio volumio[978]: info: May 04 11:46:33 test-volumio volumio[978]: ---------------------------- MPD announces state update: update May 04 11:46:33 test-volumio volumio[978]: info: ControllerMpd::getState May 04 11:46:33 test-volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 04 11:46:33 test-volumio volumio[978]: info: sendMpdCommand status took 16 milliseconds May 04 11:46:33 test-volumio volumio[978]: info: sendMpdCommand status took 11 milliseconds May 04 11:46:33 test-volumio volumio[978]: info: sendMpdCommand status took 10 milliseconds May 04 11:46:33 test-volumio volumio[978]: info: sendMpdCommand status took 9 milliseconds May 04 11:46:33 test-volumio volumio[978]: info: sendMpdCommand status took 7 milliseconds May 04 11:46:33 test-volumio volumio[978]: info: sendMpdCommand status took 5 milliseconds May 04 11:46:33 test-volumio volumio[978]: verbose: ControllerMpd::parseState May 04 11:46:34 test-volumio volumio[978]: info: Command Router : Notfying DB Updatetrue May 04 11:46:34 test-volumio volumio[978]: verbose: ControllerMpd::parseState May 04 11:46:34 test-volumio volumio[978]: verbose: ControllerMpd::parseState May 04 11:46:34 test-volumio volumio[978]: info: Command Router : Notfying DB Updatetrue May 04 11:46:34 test-volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent May 04 11:46:34 test-volumio volumio[978]: verbose: ControllerMpd::parseState May 04 11:46:34 test-volumio volumio[978]: verbose: ControllerMpd::parseState May 04 11:46:34 test-volumio volumio[978]: info: Command Router : Notfying DB Updatetrue May 04 11:46:34 test-volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent May 04 11:46:34 test-volumio volumio[978]: verbose: ControllerMpd::parseState May 04 11:46:34 test-volumio volumio[978]: info: ControllerMpd::pushState May 04 11:46:34 test-volumio volumio[978]: info: CoreCommandRouter::servicePushState May 04 11:46:34 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:34 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:34 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:34 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:34 test-volumio volumio[978]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 04 11:46:34 test-volumio volumio[978]: verbose: CURRENT POSITION 0 May 04 11:46:34 test-volumio volumio[978]: info: CoreStateMachine::syncState stateService stop May 04 11:46:34 test-volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop May 04 11:46:34 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:34 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:34 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 11:46:34 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:34 test-volumio volumio[978]: info: No code May 04 11:46:34 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:34 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:34 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:34 test-volumio volumio[978]: info: ControllerMpd::pushState May 04 11:46:34 test-volumio volumio[978]: info: CoreCommandRouter::servicePushState May 04 11:46:34 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:34 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:34 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:34 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:34 test-volumio volumio[978]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 04 11:46:34 test-volumio volumio[978]: verbose: CURRENT POSITION 0 May 04 11:46:34 test-volumio volumio[978]: info: CoreStateMachine::syncState stateService stop May 04 11:46:34 test-volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop May 04 11:46:34 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:34 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:34 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:34 test-volumio volumio[978]: info: No code May 04 11:46:34 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:34 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:34 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:34 test-volumio volumio[978]: info: ControllerMpd::pushState May 04 11:46:34 test-volumio volumio[978]: info: CoreCommandRouter::servicePushState May 04 11:46:34 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:34 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:34 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:35 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:35 test-volumio volumio[978]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 04 11:46:35 test-volumio volumio[978]: verbose: CURRENT POSITION 0 May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::syncState stateService stop May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:35 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:35 test-volumio volumio[978]: info: No code May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:35 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:35 test-volumio volumio[978]: info: ------------------------------ 1982ms May 04 11:46:35 test-volumio volumio[978]: info: ------------------------------ 1978ms May 04 11:46:35 test-volumio volumio[978]: info: ------------------------------ 1976ms May 04 11:46:35 test-volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 04 11:46:35 test-volumio volumio[978]: info: May 04 11:46:35 test-volumio volumio[978]: ---------------------------- MPD announces state update: update May 04 11:46:35 test-volumio volumio[978]: info: ControllerMpd::getState May 04 11:46:35 test-volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 04 11:46:35 test-volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 04 11:46:35 test-volumio volumio[978]: info: May 04 11:46:35 test-volumio volumio[978]: ---------------------------- MPD announces state update: update May 04 11:46:35 test-volumio volumio[978]: info: ControllerMpd::getState May 04 11:46:35 test-volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 04 11:46:35 test-volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 04 11:46:35 test-volumio volumio[978]: info: May 04 11:46:35 test-volumio volumio[978]: ---------------------------- MPD announces state update: update May 04 11:46:35 test-volumio volumio[978]: info: ControllerMpd::getState May 04 11:46:35 test-volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 04 11:46:35 test-volumio volumio[978]: info: sendMpdCommand status took 46 milliseconds May 04 11:46:35 test-volumio volumio[978]: info: sendMpdCommand status took 45 milliseconds May 04 11:46:35 test-volumio volumio[978]: info: sendMpdCommand status took 44 milliseconds May 04 11:46:35 test-volumio volumio[978]: info: sendMpdCommand status took 41 milliseconds May 04 11:46:35 test-volumio volumio[978]: info: sendMpdCommand status took 41 milliseconds May 04 11:46:35 test-volumio volumio[978]: info: sendMpdCommand status took 39 milliseconds May 04 11:46:35 test-volumio volumio[978]: verbose: ControllerMpd::parseState May 04 11:46:35 test-volumio volumio[978]: info: Command Router : Notfying DB Updatefalse May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent May 04 11:46:35 test-volumio volumio[978]: verbose: ControllerMpd::parseState May 04 11:46:35 test-volumio volumio[978]: verbose: ControllerMpd::parseState May 04 11:46:35 test-volumio volumio[978]: info: Command Router : Notfying DB Updatefalse May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent May 04 11:46:35 test-volumio volumio[978]: verbose: ControllerMpd::parseState May 04 11:46:35 test-volumio volumio[978]: verbose: ControllerMpd::parseState May 04 11:46:35 test-volumio volumio[978]: info: Command Router : Notfying DB Updatefalse May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent May 04 11:46:35 test-volumio volumio[978]: verbose: ControllerMpd::parseState May 04 11:46:35 test-volumio volumio[978]: info: ControllerMpd::pushState May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::servicePushState May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:35 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:35 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:35 test-volumio volumio[978]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 04 11:46:35 test-volumio volumio[978]: verbose: CURRENT POSITION 0 May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::syncState stateService stop May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:35 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:35 test-volumio volumio[978]: info: No code May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:35 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:35 test-volumio volumio[978]: info: ControllerMpd::pushState May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::servicePushState May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:35 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:35 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:35 test-volumio volumio[978]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 04 11:46:35 test-volumio volumio[978]: verbose: CURRENT POSITION 0 May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::syncState stateService stop May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:35 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:35 test-volumio volumio[978]: info: No code May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:35 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:35 test-volumio volumio[978]: info: ControllerMpd::pushState May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::servicePushState May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:35 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:35 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:35 test-volumio volumio[978]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 04 11:46:35 test-volumio volumio[978]: verbose: CURRENT POSITION 0 May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::syncState stateService stop May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:35 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:35 test-volumio volumio[978]: info: No code May 04 11:46:35 test-volumio volumio[978]: info: CoreStateMachine::pushState May 04 11:46:35 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 04 11:46:35 test-volumio volumio[978]: info: ------------------------------ 207ms May 04 11:46:35 test-volumio volumio[978]: info: ------------------------------ 211ms May 04 11:46:35 test-volumio volumio[978]: info: ------------------------------ 210ms May 04 11:46:35 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:46:35 test-volumio volumio[1111]: ...................................++++ May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 04 11:46:35 test-volumio volumio[978]: info: Adding plugin bluetooth to MyMusic Plugins May 04 11:46:35 test-volumio volumio[978]: info: Adding plugin multiroom to MyMusic Plugins May 04 11:46:35 test-volumio volumio[978]: info: Adding plugin metavolumio to MyMusic Plugins May 04 11:46:35 test-volumio volumio[978]: info: Adding plugin cd_controller to MyMusic Plugins May 04 11:46:35 test-volumio volumio[978]: info: Adding plugin smart_inputs to MyMusic Plugins May 04 11:46:35 test-volumio volumio[978]: info: Adding plugin tidalconnect to MyMusic Plugins May 04 11:46:35 test-volumio volumio[978]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 04 11:46:37 test-volumio volumio[978]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 04 11:46:37 test-volumio volumio[978]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 04 11:46:37 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 11:46:37 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 11:46:37 test-volumio volumio[978]: info: Starting MyVolumio Remote Streaming Endpoints May 04 11:46:37 test-volumio volumio[978]: info: MyVolumio login type: Token May 04 11:46:37 test-volumio volumio[978]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 04 11:46:37 test-volumio volumio[978]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 04 11:46:38 test-volumio volumio[1111]: .................++++ May 04 11:46:38 test-volumio volumio[1111]: e is 65537 (0x010001) May 04 11:46:38 test-volumio volumio[1111]: writing RSA key May 04 11:46:38 test-volumio volumio[978]: info: Starting Streaming Service Transparent Proxy May 04 11:46:38 test-volumio volumio[978]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 04 11:46:38 test-volumio volumio[978]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 04 11:46:38 test-volumio volumio[978]: info: Streaming services startup May 04 11:46:38 test-volumio volumio[978]: info: Starting Streaming Daemon May 04 11:46:38 test-volumio sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 04 11:46:38 test-volumio sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 11:46:38 test-volumio volumio[978]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 04 11:46:38 test-volumio sudo[1266]: pam_unix(sudo:session): session closed for user root May 04 11:46:38 test-volumio volumio[978]: error: Cannot start Volumio Streaming Daemon May 04 11:46:38 test-volumio volumio[978]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 04 11:46:38 test-volumio volumio[978]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 04 11:46:38 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:46:39 test-volumio volumio[978]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 04 11:46:39 test-volumio volumio[978]: STREAMING PROXY: Starting server on port 3245 May 04 11:46:39 test-volumio volumio[978]: Node JS runtime: 14 May 04 11:46:41 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:46:41 test-volumio volumio[978]: info: MyVolumio token set successfully May 04 11:46:41 test-volumio volumio[978]: info: MYVOLUMIO: Adding device May 04 11:46:41 test-volumio volumio[978]: info: MYVOLUMIO: Evaluating Server May 04 11:46:41 test-volumio volumio[978]: info: AutoStart - Plugin is starting May 04 11:46:41 test-volumio volumio[978]: info: CoreCommandRouter::volumioGetQueue May 04 11:46:41 test-volumio volumio[978]: info: CoreStateMachine::getQueue May 04 11:46:41 test-volumio volumio[978]: info: CorePlayQueue::getQueue May 04 11:46:41 test-volumio volumio[978]: info: MyVolumio status changed May 04 11:46:41 test-volumio volumio[978]: info: Streaming services startup May 04 11:46:41 test-volumio volumio[978]: info: Starting Streaming Daemon May 04 11:46:41 test-volumio volumio[978]: info: Removing browser output: myVolumio user plan is not superstar May 04 11:46:41 test-volumio volumio[978]: info: Removing audio output: May 04 11:46:41 test-volumio volumio[978]: info: Stoppping Tunnel 1 May 04 11:46:41 test-volumio sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 04 11:46:41 test-volumio sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 11:46:41 test-volumio sudo[1294]: pam_unix(sudo:session): session closed for user root May 04 11:46:41 test-volumio volumio[978]: error: Cannot start Volumio Streaming Daemon May 04 11:46:41 test-volumio volumio[978]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 04 11:46:41 test-volumio volumio[978]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 04 11:46:41 test-volumio sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 04 11:46:41 test-volumio sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 11:46:42 test-volumio sudo[1297]: pam_unix(sudo:session): session closed for user root May 04 11:46:42 test-volumio volumio[978]: info: Remote SSH Stopped May 04 11:46:42 test-volumio volumio[978]: info: Setting Geolocation for MyVolumio to eu6 May 04 11:46:42 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 11:46:42 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 11:46:42 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 11:46:42 test-volumio volumio[978]: info: Successfully Added MyVolumio device May 04 11:46:43 test-volumio volumio[978]: info: Updating MyVolumio device info May 04 11:46:43 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 11:46:43 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 11:46:43 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 11:46:43 test-volumio volumio[978]: info: Successfully Updated MyVolumio device May 04 11:46:45 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:46:46 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare May 04 11:46:46 test-volumio volumio[978]: info: Share LUCABBI successfully unmounted May 04 11:46:46 test-volumio sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.2/Musica /mnt/NAS/NAS May 04 11:46:46 test-volumio sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 11:46:47 test-volumio kernel: Key type cifs.spnego registered May 04 11:46:47 test-volumio kernel: Key type cifs.idmap registered May 04 11:46:47 test-volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. May 04 11:46:47 test-volumio kernel: CIFS: Attempting to mount \\192.168.1.2\Musica May 04 11:46:49 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:46:51 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 11:46:51 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 11:46:51 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 04 11:46:51 test-volumio volumio-remote-updater[484]: No test mode May 04 11:46:51 test-volumio volumio-remote-updater[484]: No alpha test mode May 04 11:46:51 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 04 11:46:51 test-volumio volumio[978]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 04 11:46:51 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 04 11:46:52 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 04 11:46:52 test-volumio volumio[978]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 51 May 04 11:46:52 test-volumio volumio[978]: info: CoreCommandRouter::volumioGetState May 04 11:46:52 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:46:53 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:46:53 test-volumio sudo[1300]: pam_unix(sudo:session): session closed for user root May 04 11:46:53 test-volumio volumio[978]: info: An error occurred mounting the new share. Rolling back configuration May 04 11:46:53 test-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 04 11:46:53 test-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 04 11:46:54 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 04 11:46:57 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:57 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:57 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 04 11:46:57 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 04 11:46:57 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 04 11:46:57 test-volumio volumio[978]: info: CoreCommandRouter::volumioGetBrowseSources May 04 11:46:57 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 11:46:57 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:46:58 test-volumio volumio[978]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 04 11:46:58 test-volumio volumio[978]: info: Completed starting MyVolumio Plugin May 04 11:46:58 test-volumio volumio[978]: info: BOOT COMPLETED May 04 11:46:58 test-volumio volumio[978]: [Metrics] CommandRouter: 86s 811.58ms May 04 11:46:58 test-volumio volumio[978]: info: CoreCommandRouter::volumiosetStartupVolume May 04 11:46:58 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 11:46:58 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 11:46:58 test-volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent May 04 11:46:58 test-volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent May 04 11:46:59 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 04 11:46:59 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 04 11:46:59 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 04 11:47:01 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:47:05 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare May 04 11:47:05 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:47:06 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 04 11:47:09 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:47:13 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:47:13 test-volumio volumiologrotate[513]: ls: cannot access '/var/log/samba/log.wb-TEST': No such file or directory May 04 11:47:13 test-volumio volumiologrotate[513]: ls: cannot access 'VOLUMIO': No such file or directory May 04 11:47:16 test-volumio systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. May 04 11:47:16 test-volumio systemd[1]: Starting Preprocess NFS configuration... May 04 11:47:16 test-volumio systemd[1]: Reached target Host and Network Name Lookups. May 04 11:47:16 test-volumio systemd[1]: nfs-config.service: Succeeded. May 04 11:47:16 test-volumio systemd[1]: Started Preprocess NFS configuration. May 04 11:47:16 test-volumio systemd[1]: Starting NFS status monitor for NFSv2/3 locking.... May 04 11:47:16 test-volumio systemd[1]: Starting Notify NFS peers of a restart... May 04 11:47:16 test-volumio sm-notify[1378]: Version 1.3.3 starting May 04 11:47:16 test-volumio systemd[1]: rpc-statd-notify.service: Succeeded. May 04 11:47:16 test-volumio systemd[1]: Started Notify NFS peers of a restart. May 04 11:47:16 test-volumio rpc.statd[1379]: Version 1.3.3 starting May 04 11:47:16 test-volumio rpc.statd[1379]: Flags: TI-RPC May 04 11:47:16 test-volumio rpc.statd[1379]: Failed to read /var/lib/nfs/state: Success May 04 11:47:16 test-volumio rpc.statd[1379]: Initializing NSM state May 04 11:47:17 test-volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 11:47:17 test-volumio systemd[1]: Started NFS status monitor for NFSv2/3 locking.. May 04 11:47:20 test-volumio volumio[978]: info: CoreCommandRouter::volumioGetState May 04 11:47:20 test-volumio volumio[978]: info: CorePlayQueue::getTrack 0 May 04 11:47:20 test-volumio sudo[1023]: pam_unix(sudo:session): session closed for user root May 04 11:47:20 test-volumio volumio[978]: info: Cannot mount NAS LUCABBI at system boot, trial number 1 ,retrying in 5 seconds May 04 11:47:25 test-volumio volumio[978]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 11:47:25 test-volumio volumio[978]: TypeError: Cannot read property 'replace' of undefined May 04 11:47:25 test-volumio volumio[978]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) May 04 11:47:25 test-volumio volumio[978]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) May 04 11:47:25 test-volumio volumio[978]: at listOnTimeout (internal/timers.js:554:17) May 04 11:47:25 test-volumio volumio[978]: at processTimers (internal/timers.js:497:7) May 04 11:47:25 test-volumio volumio[978]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 11:47:26 test-volumio sudo[1389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-04 11:46 May 04 11:47:26 test-volumio sudo[1389]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"