-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Sun 2024-05-05 19:51:36 BST. -- May 05 19:50:00 volumio volumio[893]: info: CALLMETHOD: system_controller system enableSSH true May 05 19:50:00 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , enableSSH May 05 19:50:00 volumio sudo[1626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ssh.service May 05 19:50:00 volumio sudo[1626]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:50:00 volumio systemd[1]: Starting OpenBSD Secure Shell server... May 05 19:50:00 volumio volumio[893]: ------------------------------------ BT MESSAGE: BT STATUS: running May 05 19:50:00 volumio sshd[1629]: Server listening on 0.0.0.0 port 22. May 05 19:50:00 volumio sshd[1629]: Server listening on :: port 22. May 05 19:50:00 volumio systemd[1]: Started OpenBSD Secure Shell server. May 05 19:50:00 volumio sudo[1626]: pam_unix(sudo:session): session closed for user root May 05 19:50:00 volumio sudo[1630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable ssh.service May 05 19:50:00 volumio sudo[1630]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:50:01 volumio systemd[1]: Reloading. May 05 19:50:01 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 05 19:50:01 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. May 05 19:50:01 volumio systemd[1]: Reloading. May 05 19:50:01 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 05 19:50:02 volumio systemd[1]: Reloading. May 05 19:50:02 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 05 19:50:02 volumio sudo[1630]: pam_unix(sudo:session): session closed for user root May 05 19:50:02 volumio volumio[893]: info: enable SSH service success May 05 19:50:13 volumio sshd[1680]: Accepted password for volumio from 192.168.0.226 port 48356 ssh2 May 05 19:50:13 volumio sshd[1680]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 05 19:50:13 volumio systemd[1]: Created slice User Slice of UID 1000. May 05 19:50:13 volumio systemd[1]: Starting User Runtime Directory /run/user/1000... May 05 19:50:13 volumio volumio[893]: ------------------------------------ BT MESSAGE: BT STATUS: running May 05 19:50:13 volumio systemd-logind[539]: New session 1 of user volumio. May 05 19:50:13 volumio volumio[893]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 05 19:50:13 volumio volumio[893]: ------------------------------------ BT MESSAGE: BT STATUS: running May 05 19:50:13 volumio systemd[1]: Started User Runtime Directory /run/user/1000. May 05 19:50:13 volumio systemd[1]: Starting User Manager for UID 1000... May 05 19:50:13 volumio volumio[893]: ------------------------------------ BT MESSAGE: BT STATUS: running May 05 19:50:13 volumio systemd[1686]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) May 05 19:50:13 volumio systemd[1686]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). May 05 19:50:13 volumio systemd[1686]: Starting D-Bus User Message Bus Socket. May 05 19:50:13 volumio systemd[1686]: Reached target Paths. May 05 19:50:13 volumio systemd[1686]: Listening on Sound System. May 05 19:50:13 volumio systemd[1686]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). May 05 19:50:13 volumio systemd[1686]: Listening on GnuPG cryptographic agent and passphrase cache. May 05 19:50:13 volumio systemd[1686]: Reached target Timers. May 05 19:50:13 volumio systemd[1686]: Listening on GnuPG network certificate management daemon. May 05 19:50:13 volumio systemd[1686]: Listening on GnuPG cryptographic agent (ssh-agent emulation). May 05 19:50:13 volumio systemd[1686]: Listening on D-Bus User Message Bus Socket. May 05 19:50:13 volumio systemd[1686]: Reached target Sockets. May 05 19:50:13 volumio systemd[1686]: Reached target Basic System. May 05 19:50:13 volumio systemd[1]: Started User Manager for UID 1000. May 05 19:50:13 volumio systemd[1686]: Starting Music Player Daemon... May 05 19:50:13 volumio systemd[1]: Started Session 1 of user volumio. May 05 19:50:13 volumio mpd[1697]: exception: failed to open log file "/var/log/mpd.log" (config line 7): Permission denied May 05 19:50:14 volumio systemd[1686]: mpd.service: Main process exited, code=exited, status=1/FAILURE May 05 19:50:14 volumio systemd[1686]: mpd.service: Failed with result 'exit-code'. May 05 19:50:14 volumio systemd[1686]: Failed to start Music Player Daemon. May 05 19:50:14 volumio systemd[1686]: Reached target Default. May 05 19:50:14 volumio systemd[1686]: Startup finished in 854ms. May 05 19:50:32 volumio sudo[1715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 19:50:32 volumio sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:50:32 volumio sudo[1715]: pam_unix(sudo:session): session closed for user root May 05 19:50:32 volumio sudo[1718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 19:50:32 volumio sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:50:32 volumio sudo[1718]: pam_unix(sudo:session): session closed for user root May 05 19:50:32 volumio volumio[893]: verbose: New Socket.io Connection to 192.168.0.201 from 192.168.0.226 UA: Mozilla/5.0 (X11; Linux x86_64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 8 May 05 19:50:33 volumio sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 19:50:33 volumio sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:50:33 volumio sudo[1721]: pam_unix(sudo:session): session closed for user root May 05 19:50:33 volumio sudo[1724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 19:50:33 volumio sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:50:33 volumio sudo[1724]: pam_unix(sudo:session): session closed for user root May 05 19:50:33 volumio volumio[893]: verbose: New Socket.io Connection to 192.168.0.201 from 192.168.0.226 UA: Mozilla/5.0 (X11; Linux x86_64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 9 May 05 19:50:33 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 05 19:50:33 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 05 19:50:33 volumio volumio[893]: info: CoreCommandRouter::volumioGetVisibleSources May 05 19:50:33 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 19:50:33 volumio volumio[893]: info: CoreCommandRouter::volumioGetState May 05 19:50:33 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:33 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 05 19:50:33 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 19:50:33 volumio volumio[893]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 05 19:50:33 volumio volumio[893]: info: Received Get System Info May 05 19:50:33 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 19:50:33 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 19:50:33 volumio volumio[893]: info: Discovery: Getting this device information May 05 19:50:33 volumio volumio[893]: info: CoreCommandRouter::volumioGetState May 05 19:50:33 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:33 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 19:50:33 volumio volumio[893]: info: CoreCommandRouter::volumioGetState May 05 19:50:33 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:33 volumio volumio[893]: info: Listing playlists May 05 19:50:33 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 05 19:50:33 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 05 19:50:33 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 05 19:50:34 volumio volumio[893]: info: CoreCommandRouter::volumioGetState May 05 19:50:34 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:35 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 05 19:50:35 volumio volumio[893]: info: Received Get System Info May 05 19:50:35 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 19:50:35 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 19:50:35 volumio volumio[893]: info: Discovery: Getting this device information May 05 19:50:35 volumio volumio[893]: info: CoreCommandRouter::volumioGetState May 05 19:50:35 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:35 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 19:50:36 volumio volumio[893]: info: Executing endpoint metavolumio May 05 19:50:36 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 05 19:50:36 volumio volumio[893]: info: Executing endpoint metavolumio May 05 19:50:36 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 05 19:50:36 volumio volumio[893]: info: Executing endpoint metavolumio May 05 19:50:36 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 05 19:50:36 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 05 19:50:36 volumio volumio[893]: info: Received Get System Info May 05 19:50:36 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 19:50:36 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 19:50:36 volumio volumio[893]: info: Discovery: Getting this device information May 05 19:50:36 volumio volumio[893]: info: CoreCommandRouter::volumioGetState May 05 19:50:36 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:36 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 19:50:38 volumio volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 19:50:38 volumio volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 19:50:38 volumio volumio[893]: info: Retrieving Cloud Streaming UI May 05 19:50:38 volumio volumio[893]: info: Getting Tidal Cloud Configuration May 05 19:50:38 volumio volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 19:50:39 volumio volumio[893]: info: Getting Qobuz Cloud Configuration May 05 19:50:39 volumio volumio[893]: info: Asking plugin for UI Config May 05 19:50:39 volumio volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 19:50:39 volumio volumio[893]: info: Getting Spotify Cloud Configuration May 05 19:50:39 volumio volumio[893]: info: Asking plugin for UI Config May 05 19:50:39 volumio volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 19:50:39 volumio volumio[893]: info: Saving Spotify Acccount May 05 19:50:39 volumio volumio[893]: info: Got it May 05 19:50:39 volumio volumio[893]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 05 19:50:39 volumio volumio[893]: info: Got Tidal Cloud Configuration May 05 19:50:39 volumio volumio[893]: info: Got it May 05 19:50:39 volumio volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 19:50:39 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 05 19:50:39 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 05 19:50:39 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 05 19:50:39 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 05 19:50:39 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 19:50:39 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 19:50:39 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 19:50:39 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 19:50:39 volumio volumio[893]: info: CoreCommandRouter::volumioGetBrowseSources May 05 19:50:39 volumio volumio[893]: info: CoreCommandRouter::volumioGetBrowseSources May 05 19:50:39 volumio volumio[893]: info: CoreCommandRouter::volumioGetBrowseSources May 05 19:50:39 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 19:50:39 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 05 19:50:43 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 19:50:47 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 19:50:50 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare May 05 19:50:51 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 05 19:50:51 volumio volumio[893]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 05 19:50:51 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 05 19:50:51 volumio volumio[893]: info: Received Get System Version May 05 19:50:51 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 19:50:51 volumio volumio[893]: info: Received Get System Info May 05 19:50:51 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 19:50:51 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 19:50:51 volumio volumio[893]: info: Discovery: Getting this device information May 05 19:50:51 volumio volumio[893]: info: CoreCommandRouter::volumioGetState May 05 19:50:51 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:51 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 19:50:51 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 05 19:50:51 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 19:50:54 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 05 19:50:54 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand rescan May 05 19:50:54 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status May 05 19:50:54 volumio volumio[893]: info: May 05 19:50:54 volumio volumio[893]: ---------------------------- MPD announces state update: update May 05 19:50:54 volumio volumio[893]: info: sendMpdCommand rescan took 9 milliseconds May 05 19:50:54 volumio volumio[893]: info: ControllerMpd::getState May 05 19:50:54 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status May 05 19:50:55 volumio volumio[893]: info: MPD Database updated - AlbumList cache refreshed May 05 19:50:55 volumio volumio[893]: info: May 05 19:50:55 volumio volumio[893]: ---------------------------- MPD announces state update: database May 05 19:50:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status May 05 19:50:55 volumio volumio[893]: info: May 05 19:50:55 volumio volumio[893]: ---------------------------- MPD announces state update: update May 05 19:50:55 volumio volumio[893]: info: ControllerMpd::getState May 05 19:50:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status May 05 19:50:55 volumio volumio[893]: info: ControllerMpd::getState May 05 19:50:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status May 05 19:50:55 volumio volumio[893]: info: sendMpdCommand status took 529 milliseconds May 05 19:50:55 volumio volumio[893]: info: sendMpdCommand status took 525 milliseconds May 05 19:50:55 volumio volumio[893]: verbose: ControllerMpd::parseState May 05 19:50:55 volumio volumio[893]: info: Command Router : Notfying DB Updatefalse May 05 19:50:55 volumio volumio[893]: info: CoreCommandRouter::Close All Modals sent May 05 19:50:55 volumio volumio[893]: verbose: ControllerMpd::parseState May 05 19:50:55 volumio volumio[893]: info: ControllerMpd::pushState May 05 19:50:55 volumio volumio[893]: info: CoreCommandRouter::servicePushState May 05 19:50:55 volumio volumio[893]: info: CoreStateMachine::pushState May 05 19:50:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output update for this device May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output May 05 19:50:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:55 volumio volumio[893]: 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 05 19:50:55 volumio volumio[893]: verbose: CURRENT POSITION 0 May 05 19:50:55 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop May 05 19:50:55 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop May 05 19:50:55 volumio volumio[893]: info: CoreStateMachine::pushState May 05 19:50:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output update for this device May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output May 05 19:50:55 volumio volumio[893]: info: No code May 05 19:50:55 volumio volumio[893]: info: CoreStateMachine::pushState May 05 19:50:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output update for this device May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output May 05 19:50:55 volumio volumio[893]: info: ------------------------------ 638ms May 05 19:50:55 volumio volumio[893]: info: sendMpdCommand status took 115 milliseconds May 05 19:50:55 volumio volumio[893]: info: sendMpdCommand status took 113 milliseconds May 05 19:50:55 volumio volumio[893]: info: sendMpdCommand status took 113 milliseconds May 05 19:50:55 volumio volumio[893]: verbose: ControllerMpd::parseState May 05 19:50:55 volumio volumio[893]: info: Command Router : Notfying DB Updatefalse May 05 19:50:55 volumio volumio[893]: info: CoreCommandRouter::Close All Modals sent May 05 19:50:55 volumio volumio[893]: verbose: ControllerMpd::parseState May 05 19:50:55 volumio volumio[893]: verbose: ControllerMpd::parseState May 05 19:50:55 volumio volumio[893]: info: ControllerMpd::pushState May 05 19:50:55 volumio volumio[893]: info: CoreCommandRouter::servicePushState May 05 19:50:55 volumio volumio[893]: info: CoreStateMachine::pushState May 05 19:50:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output update for this device May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output May 05 19:50:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:55 volumio volumio[893]: 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 05 19:50:55 volumio volumio[893]: verbose: CURRENT POSITION 0 May 05 19:50:55 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop May 05 19:50:55 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop May 05 19:50:55 volumio volumio[893]: info: CoreStateMachine::pushState May 05 19:50:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output update for this device May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output May 05 19:50:55 volumio volumio[893]: info: No code May 05 19:50:55 volumio volumio[893]: info: CoreStateMachine::pushState May 05 19:50:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output update for this device May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output May 05 19:50:55 volumio volumio[893]: info: ControllerMpd::pushState May 05 19:50:55 volumio volumio[893]: info: CoreCommandRouter::servicePushState May 05 19:50:55 volumio volumio[893]: info: CoreStateMachine::pushState May 05 19:50:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output update for this device May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output May 05 19:50:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:55 volumio volumio[893]: 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 05 19:50:55 volumio volumio[893]: verbose: CURRENT POSITION 0 May 05 19:50:55 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop May 05 19:50:55 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop May 05 19:50:55 volumio volumio[893]: info: CoreStateMachine::pushState May 05 19:50:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output update for this device May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output May 05 19:50:55 volumio volumio[893]: info: No code May 05 19:50:55 volumio volumio[893]: info: CoreStateMachine::pushState May 05 19:50:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 May 05 19:50:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output update for this device May 05 19:50:55 volumio volumio[893]: info: MRS: Pushing multiroomSync output May 05 19:50:55 volumio volumio[893]: info: ------------------------------ 236ms May 05 19:50:55 volumio volumio[893]: info: ------------------------------ 237ms May 05 19:50:55 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 19:50:59 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 19:51:01 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 05 19:51:01 volumio systemd[1]: Starting Preprocess NFS configuration... May 05 19:51:01 volumio systemd[1]: Reached target Host and Network Name Lookups. May 05 19:51:01 volumio volumio[893]: ------------------------------------ BT MESSAGE: BT STATUS: running May 05 19:51:01 volumio systemd[1]: nfs-config.service: Succeeded. May 05 19:51:01 volumio systemd[1]: Started Preprocess NFS configuration. May 05 19:51:01 volumio systemd[1]: Starting NFS status monitor for NFSv2/3 locking.... May 05 19:51:01 volumio systemd[1]: Starting Notify NFS peers of a restart... May 05 19:51:01 volumio volumio[893]: ------------------------------------ BT MESSAGE: BT STATUS: running May 05 19:51:01 volumio sm-notify[1793]: Version 1.3.3 starting May 05 19:51:01 volumio systemd[1]: rpc-statd-notify.service: Succeeded. May 05 19:51:01 volumio systemd[1]: Started Notify NFS peers of a restart. May 05 19:51:01 volumio rpc.statd[1794]: Version 1.3.3 starting May 05 19:51:01 volumio rpc.statd[1794]: Flags: TI-RPC May 05 19:51:01 volumio rpc.statd[1794]: Failed to read /var/lib/nfs/state: Success May 05 19:51:01 volumio rpc.statd[1794]: Initializing NSM state May 05 19:51:01 volumio systemd[1]: Started NFS status monitor for NFSv2/3 locking.. May 05 19:51:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 19:51:07 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 19:51:11 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 19:51:15 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 19:51:19 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 19:51:23 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 19:51:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 19:51:29 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare May 05 19:51:29 volumio volumio[893]: info: Share Zeus successfully unmounted May 05 19:51:29 volumio sudo[1798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //zeus/home/ams/plex/music/flac /mnt/NAS/Zeus May 05 19:51:29 volumio sudo[1798]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:51:29 volumio kernel: CIFS: Attempting to mount \\zeus\home May 05 19:51:29 volumio sudo[1798]: pam_unix(sudo:session): session closed for user root May 05 19:51:29 volumio volumio[893]: info: An error occurred mounting the new share. Rolling back configuration May 05 19:51:29 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 05 19:51:29 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 05 19:51:30 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 05 19:51:30 volumio sudo[1537]: pam_unix(sudo:session): session closed for user root May 05 19:51:30 volumio volumio[893]: info: Cannot mount NAS Zeus at system boot, trial number 2 ,retrying in 5 seconds May 05 19:51:31 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 19:51:35 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 19:51:35 volumio volumio[893]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 19:51:35 volumio volumio[893]: TypeError: Cannot read property 'replace' of undefined May 05 19:51:35 volumio volumio[893]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) May 05 19:51:35 volumio volumio[893]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) May 05 19:51:35 volumio volumio[893]: at listOnTimeout (internal/timers.js:554:17) May 05 19:51:35 volumio volumio[893]: at processTimers (internal/timers.js:497:7) May 05 19:51:35 volumio volumio[893]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 19:51:36 volumio sudo[1814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-05 19:50 May 05 19:51:36 volumio sudo[1814]: 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"