-- Logs begin at Sun 2024-07-07 13:18:36 UTC, end at Sun 2024-07-07 13:22:09 UTC. -- Jul 07 13:21:04 musical-fidelity volumio[2423]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jul 07 13:21:04 musical-fidelity volumio[2423]: info: CoreCommandRouter::Close All Modals sent Jul 07 13:21:04 musical-fidelity volumio[2423]: info: CoreCommandRouter::Close All Modals sent Jul 07 13:21:04 musical-fidelity volumio[2423]: Express server listening on port 3000 Jul 07 13:21:04 musical-fidelity volumio[2423]: [Metrics] WebUI: 14s 589.35ms Jul 07 13:21:04 musical-fidelity volumio[2423]: Volumio Calling Home Jul 07 13:21:04 musical-fidelity volumio[2423]: info: CoreStateMachine::resetVolumioState Jul 07 13:21:04 musical-fidelity volumio[2423]: info: CoreStateMachine::getcurrentVolume Jul 07 13:21:04 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 13:21:04 musical-fidelity volumio[2423]: info: Stopping AccessToken refresher cron for QOBUZ Jul 07 13:21:04 musical-fidelity volumio[2423]: info: AccessToken refresher cron started for QOBUZ Jul 07 13:21:04 musical-fidelity volumio[2423]: info: Cannot mount NAS Ds at system boot, trial number 2 ,retrying in 5 seconds Jul 07 13:21:04 musical-fidelity volumio[2423]: info: Cannot mount NAS Ds at system boot, trial number 2 ,retrying in 5 seconds Jul 07 13:21:04 musical-fidelity volumio[2423]: info: Cannot mount NAS Ds at system boot, trial number 2 ,retrying in 5 seconds Jul 07 13:21:04 musical-fidelity volumio[2423]: info: Cannot mount NAS Ds at system boot, trial number 2 ,retrying in 5 seconds Jul 07 13:21:04 musical-fidelity volumio[2423]: info: Cannot mount NAS Ds at system boot, trial number 2 ,retrying in 5 seconds Jul 07 13:21:04 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 13:21:04 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:04 musical-fidelity sudo[2655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jul 07 13:21:04 musical-fidelity sudo[2655]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:04 musical-fidelity sudo[2655]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:04 musical-fidelity sudo[2663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 07 13:21:04 musical-fidelity sudo[2663]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:04 musical-fidelity sudo[2663]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:04 musical-fidelity sudo[2669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 13:21:04 musical-fidelity sudo[2669]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:04 musical-fidelity sudo[2669]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:04 musical-fidelity volumio[2423]: info: Serial port opened successfully Jul 07 13:21:04 musical-fidelity sudo[2694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart leddaemon.service Jul 07 13:21:04 musical-fidelity sudo[2694]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:04 musical-fidelity systemd[1]: Stopping LED Daemon Service... Jul 07 13:21:04 musical-fidelity systemd[1]: Starting LED Daemon Service... Jul 07 13:21:04 musical-fidelity systemd[1]: Started LED Daemon Service. Jul 07 13:21:04 musical-fidelity sudo[2694]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:04 musical-fidelity led-daemon[2700]: Starting Network LED Daemon Jul 07 13:21:04 musical-fidelity volumio[2423]: info: Setting Device type: Raspberry PI Jul 07 13:21:04 musical-fidelity volumio[2423]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 07 13:21:04 musical-fidelity volumio[2423]: [Metrics] SpotifyConnect: 6s 170.92ms Jul 07 13:21:04 musical-fidelity volumio[2423]: info: Bluetooth name changed to musical-fidelity Jul 07 13:21:04 musical-fidelity volumio[2423]: info: Shairport-Sync Stopped Jul 07 13:21:04 musical-fidelity volumio[2423]: info: MPD running with PID747 Jul 07 13:21:04 musical-fidelity volumio[2423]: ,establishing connection Jul 07 13:21:04 musical-fidelity sudo[2710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Jul 07 13:21:04 musical-fidelity sudo[2710]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:04 musical-fidelity sudo[2710]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:05 musical-fidelity volumio[2423]: info: VolumeController:: Volume=100 Mute =false Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreStateMachine::pushState Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioPushState Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreStateMachine::updateTrackBlock Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CorePlayQueue::getTrackBlock Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 07 13:21:05 musical-fidelity volumio[2423]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:05 musical-fidelity volumio[2423]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:05 musical-fidelity volumio[2423]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:05 musical-fidelity volumio[2423]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jul 07 13:21:05 musical-fidelity volumio[2423]: xcb_connection_has_error() returned true Jul 07 13:21:05 musical-fidelity volumio[2423]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Jul 07 13:21:05 musical-fidelity volumio[2423]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 07 13:21:05 musical-fidelity sudo[2721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jul 07 13:21:05 musical-fidelity sudo[2721]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:05 musical-fidelity volumio-remote-updater[707]: [2024-07-07 13:21:05] [connect] Successful connection Jul 07 13:21:05 musical-fidelity sudo[2721]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:05 musical-fidelity volumio[2423]: No protocol specified Jul 07 13:21:05 musical-fidelity volumio[2423]: xcb_connection_has_error() returned true Jul 07 13:21:05 musical-fidelity volumio[2423]: info: Reloading queue from file Jul 07 13:21:05 musical-fidelity volumio[2423]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Jul 07 13:21:05 musical-fidelity sudo[2733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 07 13:21:05 musical-fidelity sudo[2733]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:05 musical-fidelity systemd[1]: Stopping RAAT DAEMON... Jul 07 13:21:05 musical-fidelity systemd[1]: Starting RAAT DAEMON... Jul 07 13:21:05 musical-fidelity systemd[1]: Started RAAT DAEMON. Jul 07 13:21:05 musical-fidelity sudo[2733]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:05 musical-fidelity pulseaudio[2738]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 07 13:21:05 musical-fidelity pulseaudio[2738]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 07 13:21:05 musical-fidelity pulseaudio[2738]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Jul 07 13:21:05 musical-fidelity bluetoothd[695]: Endpoint registered: sender=:1.37 path=/MediaEndpoint/A2DPSource Jul 07 13:21:05 musical-fidelity bluetoothd[695]: Endpoint registered: sender=:1.37 path=/MediaEndpoint/A2DPSink Jul 07 13:21:05 musical-fidelity volumio[2423]: error: updateQueue error: null Jul 07 13:21:05 musical-fidelity volumio[2423]: error: Failed LSINFO: Error: [50@0] {} No such directory Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 13:21:05 musical-fidelity volumio[2423]: No protocol specified Jul 07 13:21:05 musical-fidelity volumio[2423]: xcb_connection_has_error() returned true Jul 07 13:21:05 musical-fidelity volumio[2423]: error: Failed to fix hosts file for Spotify: Error: EACCES: permission denied, open '/etc/hosts' Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreStateMachine::setRepeat false single undefined Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreStateMachine::pushState Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioPushState Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreStateMachine::setRandom null Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreStateMachine::pushState Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioPushState Jul 07 13:21:05 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:21:06 musical-fidelity volumio[2423]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:06 musical-fidelity volumio[2423]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:06 musical-fidelity volumio[2423]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Jul 07 13:21:06 musical-fidelity volumio[2423]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Jul 07 13:21:06 musical-fidelity volumio[2423]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:06 musical-fidelity volumio[2423]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:06 musical-fidelity volumio[2423]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:06 musical-fidelity volumio[2423]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:06 musical-fidelity volumio[2423]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:06 musical-fidelity volumio[2423]: info: Raat Daemon started successfully Jul 07 13:21:06 musical-fidelity volumio[2423]: info: VolumeController:: Volume=undefined Mute =false Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CoreStateMachine::pushState Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioPushState Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:06 musical-fidelity volumio-remote-updater[707]: [2024-07-07 13:21:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1720358465 101 Jul 07 13:21:06 musical-fidelity volumio[2423]: info: Executing endpoint restartRAATSocket Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 07 13:21:06 musical-fidelity sudo[2769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 07 13:21:06 musical-fidelity sudo[2769]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:06 musical-fidelity systemd[1]: Started UPnP Renderer front-end to MPD. Jul 07 13:21:06 musical-fidelity sudo[2769]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:06 musical-fidelity volumio[2423]: info: Volumio called home Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:06 musical-fidelity volumio[2423]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Jul 07 13:21:06 musical-fidelity volumio[2423]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Jul 07 13:21:06 musical-fidelity volumio[2423]: info: Updating push state because input changed Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumiosetSourceActivempd Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:06 musical-fidelity volumio[2423]: info: Upmpdcli Daemon Started Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 07 13:21:06 musical-fidelity volumio[2423]: Discovery: adding 35083494-0616-445b-90e6-c13a91a1ac91 Jul 07 13:21:06 musical-fidelity volumio[2423]: info: mDNS: Found device musical-fidelity Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:06 musical-fidelity volumio[2423]: Discovery: this is already registered, 35083494-0616-445b-90e6-c13a91a1ac91 Jul 07 13:21:06 musical-fidelity volumio[2423]: info: mDNS: Found device musical-fidelity Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioGetVisibleSources Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioGetQueue Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CoreStateMachine::getQueue Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CorePlayQueue::getQueue Jul 07 13:21:06 musical-fidelity volumio[2423]: info: Listing playlists Jul 07 13:21:06 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 07 13:21:06 musical-fidelity volumio[2423]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 07 13:21:07 musical-fidelity volumio[2423]: info: Access Token successfully retrieved Jul 07 13:21:07 musical-fidelity volumio-streaming-daemon[2603]: Checking variant in static cache Jul 07 13:21:07 musical-fidelity volumio[2423]: info: Fetching Streaming Services browse cache Jul 07 13:21:07 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jul 07 13:21:07 musical-fidelity volumio[2423]: info: Setting Additional System Software info: Hardware Firmware Version: 117 Hardware Revision: 1 Jul 07 13:21:07 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 07 13:21:07 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioGetVisibleSources Jul 07 13:21:07 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:07 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:07 musical-fidelity volumio[2423]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:07 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioGetQueue Jul 07 13:21:07 musical-fidelity volumio[2423]: info: CoreStateMachine::getQueue Jul 07 13:21:07 musical-fidelity volumio[2423]: info: CorePlayQueue::getQueue Jul 07 13:21:07 musical-fidelity volumio[2423]: info: Listing playlists Jul 07 13:21:07 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 07 13:21:07 musical-fidelity volumio[2423]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 07 13:21:08 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:08 musical-fidelity volumio[2423]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:08 musical-fidelity volumio[2423]: info: Successfully retrieved User Session From TIDAL Jul 07 13:21:08 musical-fidelity volumio[2423]: info: Successfully retrieved User Subscription From TIDAL Jul 07 13:21:08 musical-fidelity volumio[2423]: info: Adding TIDAL to Browse Sources Jul 07 13:21:08 musical-fidelity volumio[2423]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 07 13:21:08 musical-fidelity volumio[2423]: info: [1720358468667] CoreMusicLibrary::Adding element TIDAL Jul 07 13:21:08 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:08 musical-fidelity volumio[2423]: info: Stopping AccessToken refresher cron Jul 07 13:21:08 musical-fidelity volumio[2423]: info: AccessToken refresher cron started Jul 07 13:21:08 musical-fidelity volumio[2423]: info: Checking Spotify Web API Jul 07 13:21:09 musical-fidelity volumio[2423]: info: Cannot mount NAS Ds at system boot, trial number 3 ,retrying in 5 seconds Jul 07 13:21:09 musical-fidelity volumio[2423]: info: Cannot mount NAS Ds at system boot, trial number 3 ,retrying in 5 seconds Jul 07 13:21:09 musical-fidelity volumio[2423]: info: Cannot mount NAS Ds at system boot, trial number 3 ,retrying in 5 seconds Jul 07 13:21:09 musical-fidelity volumio[2423]: info: Cannot mount NAS Ds at system boot, trial number 3 ,retrying in 5 seconds Jul 07 13:21:09 musical-fidelity volumio[2423]: info: Cannot mount NAS Ds at system boot, trial number 3 ,retrying in 5 seconds Jul 07 13:21:09 musical-fidelity volumio[2423]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jul 07 13:21:09 musical-fidelity sudo[2801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Ds Jul 07 13:21:09 musical-fidelity sudo[2801]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:09 musical-fidelity sudo[2801]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:09 musical-fidelity volumio[2423]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 13:21:09 musical-fidelity volumio[2423]: { Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/Ds' Jul 07 13:21:09 musical-fidelity volumio[2423]: at Object.fs.rmdirSync (fs.js:846:18) Jul 07 13:21:09 musical-fidelity volumio[2423]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Jul 07 13:21:09 musical-fidelity volumio[2423]: at ChildProcess.exithandler (child_process.js:267:7) Jul 07 13:21:09 musical-fidelity volumio[2423]: at emitTwo (events.js:126:13) Jul 07 13:21:09 musical-fidelity volumio[2423]: at ChildProcess.emit (events.js:214:7) Jul 07 13:21:09 musical-fidelity volumio[2423]: at maybeClose (internal/child_process.js:925:16) Jul 07 13:21:09 musical-fidelity volumio[2423]: at Process.ChildProcess._handle.onexit (internal/child_process.js:209:5) Jul 07 13:21:09 musical-fidelity volumio[2423]: errno: -16, Jul 07 13:21:09 musical-fidelity volumio[2423]: code: 'EBUSY', Jul 07 13:21:09 musical-fidelity volumio[2423]: syscall: 'rmdir', Jul 07 13:21:09 musical-fidelity volumio[2423]: path: '/mnt/NAS/Ds' } Jul 07 13:21:09 musical-fidelity volumio[2423]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 13:21:10 musical-fidelity sudo[2819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-07 13:20 Jul 07 13:21:10 musical-fidelity sudo[2819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:10 musical-fidelity sudo[2819]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:10 musical-fidelity volumio-remote-updater[707]: [2024-07-07 13:21:10] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 07 13:21:10 musical-fidelity volumio-remote-updater[707]: [2024-07-07 13:21:10] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 07 13:21:10 musical-fidelity systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Jul 07 13:21:10 musical-fidelity bluetoothd[695]: Endpoint unregistered: sender=:1.37 path=/MediaEndpoint/A2DPSource Jul 07 13:21:10 musical-fidelity bluetoothd[695]: Endpoint unregistered: sender=:1.37 path=/MediaEndpoint/A2DPSink Jul 07 13:21:10 musical-fidelity systemd[1]: Unit volumio.service entered failed state. Jul 07 13:21:10 musical-fidelity systemd[1]: Starting dynamicswap service... Jul 07 13:21:10 musical-fidelity systemd[1]: Started dynamicswap service. Jul 07 13:21:11 musical-fidelity systemd[1]: volumio.service holdoff time over, scheduling restart. Jul 07 13:21:11 musical-fidelity systemd[1]: Starting dynamicswap service... Jul 07 13:21:11 musical-fidelity systemd[1]: Started dynamicswap service. Jul 07 13:21:11 musical-fidelity systemd[1]: Stopping Volumio Backend Module... Jul 07 13:21:11 musical-fidelity systemd[1]: Starting Volumio Backend Module... Jul 07 13:21:11 musical-fidelity systemd[1]: Started Volumio Backend Module. Jul 07 13:21:12 musical-fidelity volumio[2843]: info: ------------------------------------------- Jul 07 13:21:12 musical-fidelity volumio[2843]: info: ----- Volumio2 ---- Jul 07 13:21:12 musical-fidelity volumio[2843]: info: ------------------------------------------- Jul 07 13:21:12 musical-fidelity volumio[2843]: info: ----- System startup ---- Jul 07 13:21:12 musical-fidelity volumio[2843]: info: ------------------------------------------- Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Plugin folders cleanup Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Scanning into folder /volumio/app/plugins/ Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Scanning category audio_interface Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Scanning category miscellanea Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Scanning category music_service Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Scanning category plugins.json Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Scanning category system_controller Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Scanning category user_interface Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Scanning into folder /data/plugins/ Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Plugin folders cleanup completed Jul 07 13:21:13 musical-fidelity volumio[2843]: info: ------------------------------------------- Jul 07 13:21:13 musical-fidelity volumio[2843]: info: ----- Core plugins startup ---- Jul 07 13:21:13 musical-fidelity volumio[2843]: info: ------------------------------------------- Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Loading plugins from folder /volumio/app/plugins/ Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Adding plugin upnp to MyMusic Plugins Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Adding plugin metavolumio to MyMusic Plugins Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Adding plugin cd_controller to MyMusic Plugins Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Adding plugin raat to MyMusic Plugins Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Loading plugins from folder /data/plugins/ Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Loading plugin "system"... Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Applying required configuration parameters for plugin system Jul 07 13:21:13 musical-fidelity volumio[2843]: info: Loading plugin "appearance"... Jul 07 13:21:15 musical-fidelity volumio[2843]: info: Loading plugin "network"... Jul 07 13:21:15 musical-fidelity volumio[2843]: info: Refreshing Cached IP Addresses Jul 07 13:21:15 musical-fidelity sudo[2884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 07 13:21:15 musical-fidelity volumio[2843]: info: Loading plugin "services"... Jul 07 13:21:15 musical-fidelity volumio[2843]: info: Loading plugin "alsa_controller"... Jul 07 13:21:15 musical-fidelity sudo[2884]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:15 musical-fidelity sudo[2887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 13:21:15 musical-fidelity sudo[2884]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:15 musical-fidelity sudo[2887]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:15 musical-fidelity volumio[2843]: info: Applying required configuration parameters for plugin alsa_controller Jul 07 13:21:15 musical-fidelity sudo[2887]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:15 musical-fidelity volumio[2843]: info: Loading plugin "wizard"... Jul 07 13:21:15 musical-fidelity volumio[2843]: info: Loading plugin "volumio_command_line_client"... Jul 07 13:21:15 musical-fidelity volumio[2843]: info: Loading plugin "upnp"... Jul 07 13:21:15 musical-fidelity volumio[2843]: info: [1720358475256] Starting Upmpd Daemon Jul 07 13:21:15 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 07 13:21:15 musical-fidelity volumio[2843]: info: Loading plugin "my_music"... Jul 07 13:21:15 musical-fidelity volumio[2843]: info: Loading plugin "mpd"... Jul 07 13:21:15 musical-fidelity volumio[2843]: info: Loading plugin "upnp_browser"... Jul 07 13:21:15 musical-fidelity volumio-remote-updater[707]: [2024-07-07 13:21:15] [connect] Successful connection Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Loading plugin "networkfs"... Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Cannot mount NAS Ds at system boot, trial number 1 ,retrying in 5 seconds Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Cannot mount NAS Ds at system boot, trial number 1 ,retrying in 5 seconds Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Cannot mount NAS Ds at system boot, trial number 1 ,retrying in 5 seconds Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Cannot mount NAS Ds at system boot, trial number 1 ,retrying in 5 seconds Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Cannot mount NAS Ds at system boot, trial number 1 ,retrying in 5 seconds Jul 07 13:21:16 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Loading plugin "bluetooth"... Jul 07 13:21:16 musical-fidelity volumio[2843]: info: [1720358476312] Starting BluetoothController Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Loading plugin "multiroom"... Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Loading plugin "alarm-clock"... Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Loading plugin "metavolumio"... Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Loading plugin "airplay_emulation"... Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Starting Shairport Sync Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Loading plugin "cd_controller"... Jul 07 13:21:16 musical-fidelity sudo[2915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Jul 07 13:21:16 musical-fidelity sudo[2915]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Plugin last_100 is not enabled Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Loading plugin "raat"... Jul 07 13:21:16 musical-fidelity systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 07 13:21:16 musical-fidelity sudo[2915]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:16 musical-fidelity volumio[2843]: info: RAAT Plugin loaded Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Adding restartRAATSocket REST API Endpoint Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Loading plugin "streaming_services"... Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Loading plugin "webradio"... Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Loading plugin "i2s_dacs"... Jul 07 13:21:16 musical-fidelity volumio[2843]: info: I2S DAC not set, start Auto-detection Jul 07 13:21:16 musical-fidelity volumio[2843]: info: Loading plugin "volumiodiscovery"... Jul 07 13:21:16 musical-fidelity volumio[2843]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 07 13:21:16 musical-fidelity node[2843]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 07 13:21:16 musical-fidelity node[2843]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 07 13:21:16 musical-fidelity node[2843]: *** WARNING *** For more information see Jul 07 13:21:16 musical-fidelity node[2843]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 07 13:21:16 musical-fidelity node[2843]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 07 13:21:16 musical-fidelity node[2843]: *** WARNING *** For more information see Jul 07 13:21:17 musical-fidelity volumio[2843]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 07 13:21:17 musical-fidelity volumio[2843]: *** WARNING *** For more information see Jul 07 13:21:17 musical-fidelity volumio[2843]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 07 13:21:17 musical-fidelity volumio[2843]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 07 13:21:17 musical-fidelity volumio[2843]: *** WARNING *** For more information see Jul 07 13:21:17 musical-fidelity volumio[2843]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 07 13:21:17 musical-fidelity volumio[2843]: Discovery: StartAdv! undefined Jul 07 13:21:17 musical-fidelity volumio[2843]: Discovery: Started advertising... musical-fidelity - undefined Jul 07 13:21:17 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 07 13:21:17 musical-fidelity volumio[2843]: info: Plugin outputs is not enabled Jul 07 13:21:17 musical-fidelity volumio[2843]: info: Loading plugin "albumart"... Jul 07 13:21:17 musical-fidelity volumio[2843]: info: Plugin example_plugin is not enabled Jul 07 13:21:17 musical-fidelity volumio[2843]: info: Loading plugin "hi_res_audio"... Jul 07 13:21:17 musical-fidelity volumio[2843]: info: Applying required configuration parameters for plugin hi_res_audio Jul 07 13:21:17 musical-fidelity volumio[2843]: info: Loading plugin "inputs"... Jul 07 13:21:17 musical-fidelity volumio[2843]: info: Adding inputs REST Endpoints Jul 07 13:21:17 musical-fidelity volumio[2843]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/inputs Jul 07 13:21:17 musical-fidelity volumio[2843]: info: Scanning Audio Inputs Jul 07 13:21:17 musical-fidelity volumio[2843]: info: Checking against Known Cards name Jul 07 13:21:17 musical-fidelity volumio[2843]: info: Loading plugin "qobuz"... Jul 07 13:21:17 musical-fidelity volumio[2843]: Forking 3 albumart workers Jul 07 13:21:18 musical-fidelity volumio[2843]: Starting albumart workers Jul 07 13:21:18 musical-fidelity volumio[2843]: Starting albumart workers Jul 07 13:21:18 musical-fidelity volumio[2843]: Starting albumart workers Jul 07 13:21:19 musical-fidelity volumio[2843]: info: Loading plugin "spop"... Jul 07 13:21:19 musical-fidelity volumio[2843]: info: Loading plugin "tidal"... Jul 07 13:21:20 musical-fidelity volumio[2843]: info: Loading plugin "gpios"... Jul 07 13:21:20 musical-fidelity volumio[2843]: error: ATTENTION!!!: Plugin gpios does not return adequate promise from onVolumioStart: please update! Jul 07 13:21:20 musical-fidelity volumio[2843]: info: Loading plugin "updater_comm"... Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Plugin mpdemulation is not enabled Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Loading plugin "rest_api"... Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Loading plugin "websocket"... Jul 07 13:21:21 musical-fidelity volumio[2843]: info: ___________ START PLUGINS ___________ Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Adding METAVOLUMIO REST API Endpoints Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 07 13:21:21 musical-fidelity volumio[2843]: info: [1720358481202] CoreMusicLibrary::Adding element Shairport-Sync Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:21 musical-fidelity sudo[2997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name musical-fidelity Jul 07 13:21:21 musical-fidelity sudo[2997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Preparing CD Folders Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Adding CD REST API Endpoints Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Detecting CD presence with Flag file Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Starting RAAT Plugin Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jul 07 13:21:21 musical-fidelity sudo[2997]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Additional UI Settings Added for plugin music_service/raat Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Not Reporting Auto name since its the default one Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Streaming services startup Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Starting Streaming Daemon Jul 07 13:21:21 musical-fidelity sudo[3011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 07 13:21:21 musical-fidelity sudo[3011]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:21 musical-fidelity systemd[1]: Stopping Volumio Streaming Daemon... Jul 07 13:21:21 musical-fidelity systemd[1]: Starting Volumio Streaming Daemon... Jul 07 13:21:21 musical-fidelity systemd[1]: Started Volumio Streaming Daemon. Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 07 13:21:21 musical-fidelity sudo[3011]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:21 musical-fidelity volumio[2843]: info: [1720358481352] CoreMusicLibrary::Adding element Webradio Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 07 13:21:21 musical-fidelity volumio-streaming-daemon[3022]: ############################ Jul 07 13:21:21 musical-fidelity volumio-streaming-daemon[3022]: # Volumio Streaming Daemon # Jul 07 13:21:21 musical-fidelity volumio-streaming-daemon[3022]: # Running on port 7777 # Jul 07 13:21:21 musical-fidelity volumio-streaming-daemon[3022]: ############################ Jul 07 13:21:21 musical-fidelity volumio-streaming-daemon[3022]: Checking variant in static cache Jul 07 13:21:21 musical-fidelity volumio-streaming-daemon[3022]: Enabled services: Jul 07 13:21:21 musical-fidelity volumio-streaming-daemon[3022]: 1) tidal Jul 07 13:21:21 musical-fidelity volumio-streaming-daemon[3022]: 2) qobuz Jul 07 13:21:21 musical-fidelity volumio[2843]: error: Hi Res Audio Failed Login: Missing Login Data Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 07 13:21:21 musical-fidelity volumio[2843]: [SpotifyConnect] Creating VLS config file Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: [SpotifyConnect] Starting metadata listener Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Refreshing TIDAL token Jul 07 13:21:21 musical-fidelity sudo[3033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jul 07 13:21:21 musical-fidelity sudo[3033]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:21 musical-fidelity volumio[2843]: error: Plugin gpios does not return adequate promise from onStart: please update! Jul 07 13:21:21 musical-fidelity systemd[1]: Started Volspotconnect2 Daemon. Jul 07 13:21:21 musical-fidelity volumio[2843]: info: Loading i18n strings for locale zh Jul 07 13:21:21 musical-fidelity sudo[3033]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:21 musical-fidelity volumio[2843]: Updating browse sources language Jul 07 13:21:21 musical-fidelity volumio[2843]: Cannot find translation for sourceBluetooth Jul 07 13:21:21 musical-fidelity volumio[2843]: Cannot find translation for sourceUSB/PC Jul 07 13:21:21 musical-fidelity volumio[2843]: Cannot find translation for sourceShairport-Sync Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::initPlayerControls Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 13:21:21 musical-fidelity volumio[2843]: info: BOOT COMPLETED Jul 07 13:21:21 musical-fidelity volumio[2843]: [Metrics] CommandRouter: 8s 634.46ms Jul 07 13:21:21 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 13:21:26 musical-fidelity volumio[2843]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jul 07 13:21:26 musical-fidelity volumio[2843]: info: CoreCommandRouter::Close All Modals sent Jul 07 13:21:26 musical-fidelity volumio[2843]: info: CoreCommandRouter::Close All Modals sent Jul 07 13:21:26 musical-fidelity volumio[2843]: Express server listening on port 3000 Jul 07 13:21:26 musical-fidelity volumio[2843]: [Metrics] WebUI: 14s 465.30ms Jul 07 13:21:26 musical-fidelity volumio[2843]: Volumio Calling Home Jul 07 13:21:27 musical-fidelity volumio[2843]: info: CoreStateMachine::resetVolumioState Jul 07 13:21:27 musical-fidelity volumio[2843]: info: CoreStateMachine::getcurrentVolume Jul 07 13:21:27 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 13:21:27 musical-fidelity volumio[2843]: info: Stopping AccessToken refresher cron for QOBUZ Jul 07 13:21:27 musical-fidelity volumio[2843]: info: AccessToken refresher cron started for QOBUZ Jul 07 13:21:27 musical-fidelity volumio[2843]: info: Cannot mount NAS Ds at system boot, trial number 2 ,retrying in 5 seconds Jul 07 13:21:27 musical-fidelity volumio[2843]: info: Cannot mount NAS Ds at system boot, trial number 2 ,retrying in 5 seconds Jul 07 13:21:27 musical-fidelity volumio[2843]: info: Cannot mount NAS Ds at system boot, trial number 2 ,retrying in 5 seconds Jul 07 13:21:27 musical-fidelity volumio[2843]: info: Cannot mount NAS Ds at system boot, trial number 2 ,retrying in 5 seconds Jul 07 13:21:27 musical-fidelity volumio[2843]: info: Cannot mount NAS Ds at system boot, trial number 2 ,retrying in 5 seconds Jul 07 13:21:27 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 13:21:27 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:27 musical-fidelity sudo[3097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jul 07 13:21:27 musical-fidelity sudo[3097]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:27 musical-fidelity sudo[3097]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:27 musical-fidelity sudo[3106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 07 13:21:27 musical-fidelity sudo[3106]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:27 musical-fidelity sudo[3110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 13:21:27 musical-fidelity sudo[3106]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:27 musical-fidelity sudo[3110]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:27 musical-fidelity volumio[2843]: info: Serial port opened successfully Jul 07 13:21:27 musical-fidelity sudo[3110]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:27 musical-fidelity sudo[3134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart leddaemon.service Jul 07 13:21:27 musical-fidelity sudo[3134]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:27 musical-fidelity systemd[1]: Stopping LED Daemon Service... Jul 07 13:21:27 musical-fidelity systemd[1]: Starting LED Daemon Service... Jul 07 13:21:27 musical-fidelity systemd[1]: Started LED Daemon Service. Jul 07 13:21:27 musical-fidelity sudo[3134]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:27 musical-fidelity led-daemon[3140]: Starting Network LED Daemon Jul 07 13:21:27 musical-fidelity volumio[2843]: info: Setting Device type: Raspberry PI Jul 07 13:21:27 musical-fidelity volumio[2843]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 07 13:21:27 musical-fidelity volumio[2843]: [Metrics] SpotifyConnect: 6s 164.66ms Jul 07 13:21:27 musical-fidelity volumio[2843]: info: Bluetooth name changed to musical-fidelity Jul 07 13:21:27 musical-fidelity volumio[2843]: info: Shairport-Sync Stopped Jul 07 13:21:27 musical-fidelity volumio[2843]: info: MPD running with PID747 Jul 07 13:21:27 musical-fidelity volumio[2843]: ,establishing connection Jul 07 13:21:27 musical-fidelity sudo[3150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Jul 07 13:21:27 musical-fidelity sudo[3150]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:27 musical-fidelity sudo[3150]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:27 musical-fidelity volumio[2843]: info: VolumeController:: Volume=100 Mute =false Jul 07 13:21:27 musical-fidelity volumio[2843]: info: CoreStateMachine::pushState Jul 07 13:21:27 musical-fidelity volumio[2843]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:27 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioPushState Jul 07 13:21:27 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:21:27 musical-fidelity volumio[2843]: info: CoreStateMachine::updateTrackBlock Jul 07 13:21:27 musical-fidelity volumio[2843]: info: CorePlayQueue::getTrackBlock Jul 07 13:21:27 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 13:21:27 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 07 13:21:27 musical-fidelity volumio[2843]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:27 musical-fidelity volumio[2843]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:27 musical-fidelity volumio[2843]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:28 musical-fidelity sudo[3160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jul 07 13:21:28 musical-fidelity volumio-remote-updater[707]: [2024-07-07 13:21:28] [connect] Successful connection Jul 07 13:21:28 musical-fidelity sudo[3160]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:28 musical-fidelity volumio[2843]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jul 07 13:21:28 musical-fidelity volumio[2843]: xcb_connection_has_error() returned true Jul 07 13:21:28 musical-fidelity sudo[3160]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:28 musical-fidelity volumio[2843]: info: Volumio called home Jul 07 13:21:28 musical-fidelity volumio[2843]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Jul 07 13:21:28 musical-fidelity volumio[2843]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 07 13:21:28 musical-fidelity volumio[2843]: No protocol specified Jul 07 13:21:28 musical-fidelity volumio[2843]: xcb_connection_has_error() returned true Jul 07 13:21:28 musical-fidelity volumio[2843]: info: Reloading queue from file Jul 07 13:21:28 musical-fidelity volumio[2843]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Jul 07 13:21:28 musical-fidelity sudo[3176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 07 13:21:28 musical-fidelity sudo[3176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:28 musical-fidelity pulseaudio[3173]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 07 13:21:28 musical-fidelity pulseaudio[3173]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 07 13:21:28 musical-fidelity pulseaudio[3173]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Jul 07 13:21:28 musical-fidelity bluetoothd[695]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSource Jul 07 13:21:28 musical-fidelity bluetoothd[695]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSink Jul 07 13:21:28 musical-fidelity systemd[1]: Stopping RAAT DAEMON... Jul 07 13:21:28 musical-fidelity systemd[1]: Starting RAAT DAEMON... Jul 07 13:21:28 musical-fidelity systemd[1]: Started RAAT DAEMON. Jul 07 13:21:28 musical-fidelity sudo[3176]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:28 musical-fidelity volumio[2843]: error: updateQueue error: null Jul 07 13:21:28 musical-fidelity volumio[2843]: error: Failed LSINFO: Error: [50@0] {} No such directory Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 13:21:28 musical-fidelity volumio[2843]: No protocol specified Jul 07 13:21:28 musical-fidelity volumio[2843]: xcb_connection_has_error() returned true Jul 07 13:21:28 musical-fidelity volumio[2843]: error: Failed to fix hosts file for Spotify: Error: EACCES: permission denied, open '/etc/hosts' Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreStateMachine::setRepeat false single undefined Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreStateMachine::pushState Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioPushState Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreStateMachine::setRandom null Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreStateMachine::pushState Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioPushState Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:21:28 musical-fidelity volumio[2843]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:28 musical-fidelity volumio[2843]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:28 musical-fidelity volumio[2843]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:28 musical-fidelity volumio[2843]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Jul 07 13:21:28 musical-fidelity volumio[2843]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Jul 07 13:21:28 musical-fidelity volumio[2843]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:28 musical-fidelity volumio[2843]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:28 musical-fidelity volumio-remote-updater[707]: [2024-07-07 13:21:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1720358488 101 Jul 07 13:21:28 musical-fidelity volumio[2843]: info: Raat Daemon started successfully Jul 07 13:21:28 musical-fidelity volumio[2843]: info: VolumeController:: Volume=undefined Mute =false Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreStateMachine::pushState Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioPushState Jul 07 13:21:28 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:21:29 musical-fidelity volumio[2843]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:29 musical-fidelity volumio[2843]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:29 musical-fidelity volumio[2843]: info: Executing endpoint restartRAATSocket Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 07 13:21:29 musical-fidelity sudo[3209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 07 13:21:29 musical-fidelity sudo[3209]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:29 musical-fidelity systemd[1]: Started UPnP Renderer front-end to MPD. Jul 07 13:21:29 musical-fidelity sudo[3209]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:29 musical-fidelity volumio[2843]: info: Updating push state because input changed Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumiosetSourceActivempd Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:29 musical-fidelity volumio[2843]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Jul 07 13:21:29 musical-fidelity volumio[2843]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Jul 07 13:21:29 musical-fidelity volumio[2843]: info: Upmpdcli Daemon Started Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 07 13:21:29 musical-fidelity volumio[2843]: Discovery: adding 35083494-0616-445b-90e6-c13a91a1ac91 Jul 07 13:21:29 musical-fidelity volumio[2843]: info: mDNS: Found device musical-fidelity Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:29 musical-fidelity volumio[2843]: Discovery: this is already registered, 35083494-0616-445b-90e6-c13a91a1ac91 Jul 07 13:21:29 musical-fidelity volumio[2843]: info: mDNS: Found device musical-fidelity Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioGetVisibleSources Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioGetQueue Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreStateMachine::getQueue Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CorePlayQueue::getQueue Jul 07 13:21:29 musical-fidelity volumio[2843]: info: Listing playlists Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 07 13:21:29 musical-fidelity volumio[2843]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioGetVisibleSources Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioGetQueue Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreStateMachine::getQueue Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CorePlayQueue::getQueue Jul 07 13:21:29 musical-fidelity volumio[2843]: info: Listing playlists Jul 07 13:21:29 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 07 13:21:29 musical-fidelity volumio[2843]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 07 13:21:30 musical-fidelity volumio-streaming-daemon[3022]: Checking variant in static cache Jul 07 13:21:30 musical-fidelity volumio[2843]: info: Fetching Streaming Services browse cache Jul 07 13:21:30 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jul 07 13:21:30 musical-fidelity volumio[2843]: info: Setting Additional System Software info: Hardware Firmware Version: 117 Hardware Revision: 1 Jul 07 13:21:30 musical-fidelity volumio[2843]: info: Access Token successfully retrieved Jul 07 13:21:31 musical-fidelity volumio[2843]: info: Successfully retrieved User Session From TIDAL Jul 07 13:21:31 musical-fidelity volumio[2843]: info: Checking Spotify Web API Jul 07 13:21:31 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 07 13:21:31 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:31 musical-fidelity volumio[2843]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:32 musical-fidelity volumio[2843]: info: Successfully retrieved User Subscription From TIDAL Jul 07 13:21:32 musical-fidelity volumio[2843]: info: Adding TIDAL to Browse Sources Jul 07 13:21:32 musical-fidelity volumio[2843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 07 13:21:32 musical-fidelity volumio[2843]: info: [1720358492067] CoreMusicLibrary::Adding element TIDAL Jul 07 13:21:32 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:32 musical-fidelity volumio[2843]: info: Stopping AccessToken refresher cron Jul 07 13:21:32 musical-fidelity volumio[2843]: info: AccessToken refresher cron started Jul 07 13:21:32 musical-fidelity volumio[2843]: info: Cannot mount NAS Ds at system boot, trial number 3 ,retrying in 5 seconds Jul 07 13:21:32 musical-fidelity volumio[2843]: info: Cannot mount NAS Ds at system boot, trial number 3 ,retrying in 5 seconds Jul 07 13:21:32 musical-fidelity volumio[2843]: info: Cannot mount NAS Ds at system boot, trial number 3 ,retrying in 5 seconds Jul 07 13:21:32 musical-fidelity volumio[2843]: info: Cannot mount NAS Ds at system boot, trial number 3 ,retrying in 5 seconds Jul 07 13:21:32 musical-fidelity volumio[2843]: info: Cannot mount NAS Ds at system boot, trial number 3 ,retrying in 5 seconds Jul 07 13:21:35 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jul 07 13:21:35 musical-fidelity sudo[3260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Ds Jul 07 13:21:35 musical-fidelity sudo[3260]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:35 musical-fidelity volumio[2843]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 07 13:21:35 musical-fidelity sudo[3260]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:35 musical-fidelity volumio[2843]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 13:21:35 musical-fidelity volumio[2843]: { Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/Ds' Jul 07 13:21:35 musical-fidelity volumio[2843]: at Object.fs.rmdirSync (fs.js:846:18) Jul 07 13:21:35 musical-fidelity volumio[2843]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Jul 07 13:21:35 musical-fidelity volumio[2843]: at ChildProcess.exithandler (child_process.js:267:7) Jul 07 13:21:35 musical-fidelity volumio[2843]: at emitTwo (events.js:126:13) Jul 07 13:21:35 musical-fidelity volumio[2843]: at ChildProcess.emit (events.js:214:7) Jul 07 13:21:35 musical-fidelity volumio[2843]: at maybeClose (internal/child_process.js:925:16) Jul 07 13:21:35 musical-fidelity volumio[2843]: at Process.ChildProcess._handle.onexit (internal/child_process.js:209:5) Jul 07 13:21:35 musical-fidelity volumio[2843]: errno: -16, Jul 07 13:21:35 musical-fidelity volumio[2843]: code: 'EBUSY', Jul 07 13:21:35 musical-fidelity volumio[2843]: syscall: 'rmdir', Jul 07 13:21:35 musical-fidelity volumio[2843]: path: '/mnt/NAS/Ds' } Jul 07 13:21:35 musical-fidelity volumio[2843]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 13:21:36 musical-fidelity sudo[3282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-07 13:20 Jul 07 13:21:36 musical-fidelity sudo[3282]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:36 musical-fidelity sudo[3282]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:36 musical-fidelity volumio-remote-updater[707]: [2024-07-07 13:21:36] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 07 13:21:36 musical-fidelity volumio-remote-updater[707]: [2024-07-07 13:21:36] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 07 13:21:36 musical-fidelity systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Jul 07 13:21:36 musical-fidelity bluetoothd[695]: Endpoint unregistered: sender=:1.46 path=/MediaEndpoint/A2DPSource Jul 07 13:21:36 musical-fidelity bluetoothd[695]: Endpoint unregistered: sender=:1.46 path=/MediaEndpoint/A2DPSink Jul 07 13:21:36 musical-fidelity systemd[1]: Unit volumio.service entered failed state. Jul 07 13:21:36 musical-fidelity systemd[1]: Starting dynamicswap service... Jul 07 13:21:36 musical-fidelity systemd[1]: Started dynamicswap service. Jul 07 13:21:37 musical-fidelity systemd[1]: volumio.service holdoff time over, scheduling restart. Jul 07 13:21:37 musical-fidelity systemd[1]: Starting dynamicswap service... Jul 07 13:21:37 musical-fidelity systemd[1]: Started dynamicswap service. Jul 07 13:21:37 musical-fidelity systemd[1]: Stopping Volumio Backend Module... Jul 07 13:21:37 musical-fidelity systemd[1]: Starting Volumio Backend Module... Jul 07 13:21:37 musical-fidelity systemd[1]: Started Volumio Backend Module. Jul 07 13:21:39 musical-fidelity volumio[3307]: info: ------------------------------------------- Jul 07 13:21:39 musical-fidelity volumio[3307]: info: ----- Volumio2 ---- Jul 07 13:21:39 musical-fidelity volumio[3307]: info: ------------------------------------------- Jul 07 13:21:39 musical-fidelity volumio[3307]: info: ----- System startup ---- Jul 07 13:21:39 musical-fidelity volumio[3307]: info: ------------------------------------------- Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Plugin folders cleanup Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Scanning into folder /volumio/app/plugins/ Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Scanning category audio_interface Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Scanning category miscellanea Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Scanning category music_service Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Scanning category plugins.json Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Scanning category system_controller Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Scanning category user_interface Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Scanning into folder /data/plugins/ Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Plugin folders cleanup completed Jul 07 13:21:39 musical-fidelity volumio[3307]: info: ------------------------------------------- Jul 07 13:21:39 musical-fidelity volumio[3307]: info: ----- Core plugins startup ---- Jul 07 13:21:39 musical-fidelity volumio[3307]: info: ------------------------------------------- Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Loading plugins from folder /volumio/app/plugins/ Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Adding plugin upnp to MyMusic Plugins Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Adding plugin metavolumio to MyMusic Plugins Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Adding plugin cd_controller to MyMusic Plugins Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Adding plugin raat to MyMusic Plugins Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Loading plugins from folder /data/plugins/ Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Loading plugin "system"... Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Applying required configuration parameters for plugin system Jul 07 13:21:39 musical-fidelity volumio[3307]: info: Loading plugin "appearance"... Jul 07 13:21:41 musical-fidelity volumio[3307]: info: Loading plugin "network"... Jul 07 13:21:41 musical-fidelity volumio[3307]: info: Refreshing Cached IP Addresses Jul 07 13:21:41 musical-fidelity sudo[3348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 07 13:21:41 musical-fidelity volumio[3307]: info: Loading plugin "services"... Jul 07 13:21:41 musical-fidelity sudo[3348]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:41 musical-fidelity volumio[3307]: info: Loading plugin "alsa_controller"... Jul 07 13:21:41 musical-fidelity sudo[3348]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:41 musical-fidelity sudo[3352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 13:21:41 musical-fidelity sudo[3352]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:41 musical-fidelity sudo[3352]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:41 musical-fidelity volumio[3307]: info: Applying required configuration parameters for plugin alsa_controller Jul 07 13:21:41 musical-fidelity volumio[3307]: info: Loading plugin "wizard"... Jul 07 13:21:41 musical-fidelity volumio[3307]: info: Loading plugin "volumio_command_line_client"... Jul 07 13:21:41 musical-fidelity volumio[3307]: info: Loading plugin "upnp"... Jul 07 13:21:41 musical-fidelity volumio[3307]: info: [1720358501356] Starting Upmpd Daemon Jul 07 13:21:41 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 07 13:21:41 musical-fidelity volumio[3307]: info: Loading plugin "my_music"... Jul 07 13:21:41 musical-fidelity volumio[3307]: info: Loading plugin "mpd"... Jul 07 13:21:41 musical-fidelity volumio[3307]: info: Loading plugin "upnp_browser"... Jul 07 13:21:41 musical-fidelity volumio-remote-updater[707]: [2024-07-07 13:21:41] [connect] Successful connection Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Loading plugin "networkfs"... Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 1 ,retrying in 5 seconds Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 1 ,retrying in 5 seconds Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 1 ,retrying in 5 seconds Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 1 ,retrying in 5 seconds Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 1 ,retrying in 5 seconds Jul 07 13:21:42 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Loading plugin "bluetooth"... Jul 07 13:21:42 musical-fidelity volumio[3307]: info: [1720358502430] Starting BluetoothController Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Loading plugin "multiroom"... Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Loading plugin "alarm-clock"... Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Loading plugin "metavolumio"... Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Loading plugin "airplay_emulation"... Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Starting Shairport Sync Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Loading plugin "cd_controller"... Jul 07 13:21:42 musical-fidelity sudo[3381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Jul 07 13:21:42 musical-fidelity sudo[3381]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Plugin last_100 is not enabled Jul 07 13:21:42 musical-fidelity systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Loading plugin "raat"... Jul 07 13:21:42 musical-fidelity sudo[3381]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:42 musical-fidelity volumio[3307]: info: RAAT Plugin loaded Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Adding restartRAATSocket REST API Endpoint Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Loading plugin "streaming_services"... Jul 07 13:21:42 musical-fidelity volumio[3307]: info: Loading plugin "webradio"... Jul 07 13:21:43 musical-fidelity volumio[3307]: info: Loading plugin "i2s_dacs"... Jul 07 13:21:43 musical-fidelity volumio[3307]: info: I2S DAC not set, start Auto-detection Jul 07 13:21:43 musical-fidelity volumio[3307]: info: Loading plugin "volumiodiscovery"... Jul 07 13:21:43 musical-fidelity volumio[3307]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 07 13:21:43 musical-fidelity node[3307]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 07 13:21:43 musical-fidelity node[3307]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 07 13:21:43 musical-fidelity node[3307]: *** WARNING *** For more information see Jul 07 13:21:43 musical-fidelity node[3307]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 07 13:21:43 musical-fidelity node[3307]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 07 13:21:43 musical-fidelity node[3307]: *** WARNING *** For more information see Jul 07 13:21:43 musical-fidelity volumio[3307]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 07 13:21:43 musical-fidelity volumio[3307]: *** WARNING *** For more information see Jul 07 13:21:43 musical-fidelity volumio[3307]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 07 13:21:43 musical-fidelity volumio[3307]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 07 13:21:43 musical-fidelity volumio[3307]: *** WARNING *** For more information see Jul 07 13:21:43 musical-fidelity volumio[3307]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 07 13:21:43 musical-fidelity volumio[3307]: Discovery: StartAdv! undefined Jul 07 13:21:43 musical-fidelity volumio[3307]: Discovery: Started advertising... musical-fidelity - undefined Jul 07 13:21:43 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 07 13:21:43 musical-fidelity volumio[3307]: info: Plugin outputs is not enabled Jul 07 13:21:43 musical-fidelity volumio[3307]: info: Loading plugin "albumart"... Jul 07 13:21:43 musical-fidelity volumio[3307]: info: Plugin example_plugin is not enabled Jul 07 13:21:43 musical-fidelity volumio[3307]: info: Loading plugin "hi_res_audio"... Jul 07 13:21:43 musical-fidelity volumio[3307]: info: Applying required configuration parameters for plugin hi_res_audio Jul 07 13:21:43 musical-fidelity volumio[3307]: info: Loading plugin "inputs"... Jul 07 13:21:43 musical-fidelity volumio[3307]: info: Adding inputs REST Endpoints Jul 07 13:21:43 musical-fidelity volumio[3307]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/inputs Jul 07 13:21:43 musical-fidelity volumio[3307]: info: Scanning Audio Inputs Jul 07 13:21:43 musical-fidelity volumio[3307]: info: Checking against Known Cards name Jul 07 13:21:43 musical-fidelity volumio[3307]: info: Loading plugin "qobuz"... Jul 07 13:21:43 musical-fidelity volumio[3307]: Forking 3 albumart workers Jul 07 13:21:44 musical-fidelity volumio[3307]: Starting albumart workers Jul 07 13:21:44 musical-fidelity volumio[3307]: Starting albumart workers Jul 07 13:21:44 musical-fidelity volumio[3307]: Starting albumart workers Jul 07 13:21:45 musical-fidelity volumio[3307]: info: Loading plugin "spop"... Jul 07 13:21:45 musical-fidelity volumio[3307]: info: Loading plugin "tidal"... Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Loading plugin "gpios"... Jul 07 13:21:47 musical-fidelity volumio[3307]: error: ATTENTION!!!: Plugin gpios does not return adequate promise from onVolumioStart: please update! Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Loading plugin "updater_comm"... Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Plugin mpdemulation is not enabled Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Loading plugin "rest_api"... Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Loading plugin "websocket"... Jul 07 13:21:47 musical-fidelity volumio[3307]: info: ___________ START PLUGINS ___________ Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Adding METAVOLUMIO REST API Endpoints Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 07 13:21:47 musical-fidelity volumio[3307]: info: [1720358507597] CoreMusicLibrary::Adding element Shairport-Sync Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:47 musical-fidelity sudo[3461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name musical-fidelity Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Preparing CD Folders Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Adding CD REST API Endpoints Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Detecting CD presence with Flag file Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Starting RAAT Plugin Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Additional UI Settings Added for plugin music_service/raat Jul 07 13:21:47 musical-fidelity sudo[3461]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:47 musical-fidelity sudo[3461]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Not Reporting Auto name since its the default one Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Streaming services startup Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Starting Streaming Daemon Jul 07 13:21:47 musical-fidelity sudo[3474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 07 13:21:47 musical-fidelity sudo[3474]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:47 musical-fidelity systemd[1]: Stopping Volumio Streaming Daemon... Jul 07 13:21:47 musical-fidelity systemd[1]: Starting Volumio Streaming Daemon... Jul 07 13:21:47 musical-fidelity systemd[1]: Started Volumio Streaming Daemon. Jul 07 13:21:47 musical-fidelity sudo[3474]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 07 13:21:47 musical-fidelity volumio[3307]: info: [1720358507745] CoreMusicLibrary::Adding element Webradio Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:47 musical-fidelity volumio-streaming-daemon[3487]: ############################ Jul 07 13:21:47 musical-fidelity volumio-streaming-daemon[3487]: # Volumio Streaming Daemon # Jul 07 13:21:47 musical-fidelity volumio-streaming-daemon[3487]: # Running on port 7777 # Jul 07 13:21:47 musical-fidelity volumio-streaming-daemon[3487]: ############################ Jul 07 13:21:47 musical-fidelity volumio-streaming-daemon[3487]: Checking variant in static cache Jul 07 13:21:47 musical-fidelity volumio-streaming-daemon[3487]: Enabled services: Jul 07 13:21:47 musical-fidelity volumio-streaming-daemon[3487]: 1) tidal Jul 07 13:21:47 musical-fidelity volumio-streaming-daemon[3487]: 2) qobuz Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 07 13:21:47 musical-fidelity volumio[3307]: error: Hi Res Audio Failed Login: Missing Login Data Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 07 13:21:47 musical-fidelity volumio[3307]: [SpotifyConnect] Creating VLS config file Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: [SpotifyConnect] Starting metadata listener Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Refreshing TIDAL token Jul 07 13:21:47 musical-fidelity sudo[3498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jul 07 13:21:47 musical-fidelity sudo[3498]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:47 musical-fidelity systemd[1]: Started Volspotconnect2 Daemon. Jul 07 13:21:47 musical-fidelity volumio[3307]: error: Plugin gpios does not return adequate promise from onStart: please update! Jul 07 13:21:47 musical-fidelity sudo[3498]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:47 musical-fidelity volumio[3307]: info: Loading i18n strings for locale zh Jul 07 13:21:47 musical-fidelity volumio[3307]: Updating browse sources language Jul 07 13:21:47 musical-fidelity volumio[3307]: Cannot find translation for sourceBluetooth Jul 07 13:21:47 musical-fidelity volumio[3307]: Cannot find translation for sourceUSB/PC Jul 07 13:21:47 musical-fidelity volumio[3307]: Cannot find translation for sourceShairport-Sync Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::initPlayerControls Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 13:21:47 musical-fidelity volumio[3307]: info: BOOT COMPLETED Jul 07 13:21:47 musical-fidelity volumio[3307]: [Metrics] CommandRouter: 8s 980.80ms Jul 07 13:21:47 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 13:21:53 musical-fidelity volumio[3307]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jul 07 13:21:53 musical-fidelity volumio[3307]: info: CoreCommandRouter::Close All Modals sent Jul 07 13:21:53 musical-fidelity volumio[3307]: info: CoreCommandRouter::Close All Modals sent Jul 07 13:21:53 musical-fidelity volumio[3307]: Express server listening on port 3000 Jul 07 13:21:53 musical-fidelity volumio[3307]: [Metrics] WebUI: 14s 804.72ms Jul 07 13:21:53 musical-fidelity volumio[3307]: Volumio Calling Home Jul 07 13:21:53 musical-fidelity volumio[3307]: info: CoreStateMachine::resetVolumioState Jul 07 13:21:53 musical-fidelity volumio[3307]: info: CoreStateMachine::getcurrentVolume Jul 07 13:21:53 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 13:21:53 musical-fidelity volumio[3307]: info: Stopping AccessToken refresher cron for QOBUZ Jul 07 13:21:53 musical-fidelity volumio[3307]: info: AccessToken refresher cron started for QOBUZ Jul 07 13:21:53 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 2 ,retrying in 5 seconds Jul 07 13:21:53 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 2 ,retrying in 5 seconds Jul 07 13:21:53 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 2 ,retrying in 5 seconds Jul 07 13:21:53 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 2 ,retrying in 5 seconds Jul 07 13:21:53 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 2 ,retrying in 5 seconds Jul 07 13:21:53 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 13:21:53 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 13:21:53 musical-fidelity sudo[3541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jul 07 13:21:53 musical-fidelity sudo[3541]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:53 musical-fidelity sudo[3541]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:53 musical-fidelity sudo[3551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 07 13:21:53 musical-fidelity sudo[3551]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:53 musical-fidelity sudo[3551]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:53 musical-fidelity sudo[3554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 13:21:53 musical-fidelity sudo[3554]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:53 musical-fidelity sudo[3554]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:53 musical-fidelity volumio[3307]: info: Serial port opened successfully Jul 07 13:21:53 musical-fidelity sudo[3581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart leddaemon.service Jul 07 13:21:53 musical-fidelity sudo[3581]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:53 musical-fidelity systemd[1]: Stopping LED Daemon Service... Jul 07 13:21:53 musical-fidelity systemd[1]: Starting LED Daemon Service... Jul 07 13:21:53 musical-fidelity systemd[1]: Started LED Daemon Service. Jul 07 13:21:53 musical-fidelity sudo[3581]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:53 musical-fidelity led-daemon[3587]: Starting Network LED Daemon Jul 07 13:21:54 musical-fidelity volumio[3307]: info: Setting Device type: Raspberry PI Jul 07 13:21:54 musical-fidelity volumio[3307]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 07 13:21:54 musical-fidelity volumio[3307]: [Metrics] SpotifyConnect: 6s 223.00ms Jul 07 13:21:54 musical-fidelity volumio[3307]: info: Bluetooth name changed to musical-fidelity Jul 07 13:21:54 musical-fidelity volumio[3307]: info: Shairport-Sync Stopped Jul 07 13:21:54 musical-fidelity volumio[3307]: info: MPD running with PID747 Jul 07 13:21:54 musical-fidelity volumio[3307]: ,establishing connection Jul 07 13:21:54 musical-fidelity sudo[3598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Jul 07 13:21:54 musical-fidelity sudo[3598]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:54 musical-fidelity sudo[3598]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:54 musical-fidelity volumio[3307]: info: VolumeController:: Volume=100 Mute =false Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CoreStateMachine::pushState Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioPushState Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CoreStateMachine::updateTrackBlock Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrackBlock Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 13:21:54 musical-fidelity volumio-remote-updater[707]: [2024-07-07 13:21:54] [connect] Successful connection Jul 07 13:21:54 musical-fidelity volumio[3307]: info: Volumio called home Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 07 13:21:54 musical-fidelity volumio[3307]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:54 musical-fidelity volumio[3307]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:54 musical-fidelity volumio[3307]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:54 musical-fidelity sudo[3608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jul 07 13:21:54 musical-fidelity sudo[3608]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:54 musical-fidelity sudo[3608]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:54 musical-fidelity volumio[3307]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jul 07 13:21:54 musical-fidelity volumio[3307]: xcb_connection_has_error() returned true Jul 07 13:21:54 musical-fidelity volumio[3307]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Jul 07 13:21:54 musical-fidelity volumio[3307]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 07 13:21:54 musical-fidelity volumio[3307]: No protocol specified Jul 07 13:21:54 musical-fidelity volumio[3307]: xcb_connection_has_error() returned true Jul 07 13:21:54 musical-fidelity pulseaudio[3620]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 07 13:21:54 musical-fidelity pulseaudio[3620]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 07 13:21:54 musical-fidelity pulseaudio[3620]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Jul 07 13:21:54 musical-fidelity bluetoothd[695]: Endpoint registered: sender=:1.56 path=/MediaEndpoint/A2DPSource Jul 07 13:21:54 musical-fidelity bluetoothd[695]: Endpoint registered: sender=:1.56 path=/MediaEndpoint/A2DPSink Jul 07 13:21:54 musical-fidelity volumio[3307]: info: Reloading queue from file Jul 07 13:21:54 musical-fidelity volumio[3307]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Jul 07 13:21:54 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Jul 07 13:21:54 musical-fidelity sudo[3624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 07 13:21:54 musical-fidelity sudo[3624]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:54 musical-fidelity systemd[1]: Stopping RAAT DAEMON... Jul 07 13:21:54 musical-fidelity systemd[1]: Starting RAAT DAEMON... Jul 07 13:21:54 musical-fidelity systemd[1]: Started RAAT DAEMON. Jul 07 13:21:54 musical-fidelity sudo[3624]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:54 musical-fidelity volumio[3307]: error: updateQueue error: null Jul 07 13:21:54 musical-fidelity volumio[3307]: error: Failed LSINFO: Error: [50@0] {} No such directory Jul 07 13:21:55 musical-fidelity volumio-remote-updater[707]: [2024-07-07 13:21:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1720358514 101 Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 13:21:55 musical-fidelity volumio[3307]: No protocol specified Jul 07 13:21:55 musical-fidelity volumio[3307]: xcb_connection_has_error() returned true Jul 07 13:21:55 musical-fidelity volumio[3307]: error: Failed to fix hosts file for Spotify: Error: EACCES: permission denied, open '/etc/hosts' Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreStateMachine::setRepeat false single undefined Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreStateMachine::pushState Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioPushState Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreStateMachine::setRandom null Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreStateMachine::pushState Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioPushState Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:55 musical-fidelity volumio[3307]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:55 musical-fidelity volumio[3307]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:55 musical-fidelity volumio[3307]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:55 musical-fidelity volumio[3307]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Jul 07 13:21:55 musical-fidelity volumio[3307]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Jul 07 13:21:55 musical-fidelity volumio[3307]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:55 musical-fidelity volumio[3307]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:55 musical-fidelity volumio[3307]: info: Raat Daemon started successfully Jul 07 13:21:55 musical-fidelity volumio[3307]: info: VolumeController:: Volume=undefined Mute =false Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreStateMachine::pushState Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioPushState Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:21:55 musical-fidelity volumio[3307]: info: Executing endpoint restartRAATSocket Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 07 13:21:55 musical-fidelity volumio[3307]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 07 13:21:55 musical-fidelity sudo[3657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 07 13:21:55 musical-fidelity sudo[3657]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:55 musical-fidelity systemd[1]: Started UPnP Renderer front-end to MPD. Jul 07 13:21:55 musical-fidelity sudo[3657]: pam_unix(sudo:session): session closed for user root Jul 07 13:21:55 musical-fidelity volumio[3307]: info: Updating push state because input changed Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumiosetSourceActivempd Jul 07 13:21:55 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:56 musical-fidelity volumio[3307]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Jul 07 13:21:56 musical-fidelity volumio[3307]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Jul 07 13:21:56 musical-fidelity volumio[3307]: info: Upmpdcli Daemon Started Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioGetVisibleSources Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioGetQueue Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CoreStateMachine::getQueue Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CorePlayQueue::getQueue Jul 07 13:21:56 musical-fidelity volumio[3307]: info: Listing playlists Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 07 13:21:56 musical-fidelity volumio[3307]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 07 13:21:56 musical-fidelity volumio[3307]: Discovery: adding 35083494-0616-445b-90e6-c13a91a1ac91 Jul 07 13:21:56 musical-fidelity volumio[3307]: info: mDNS: Found device musical-fidelity Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:56 musical-fidelity volumio[3307]: Discovery: this is already registered, 35083494-0616-445b-90e6-c13a91a1ac91 Jul 07 13:21:56 musical-fidelity volumio[3307]: info: mDNS: Found device musical-fidelity Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioGetVisibleSources Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioGetState Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioGetQueue Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CoreStateMachine::getQueue Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CorePlayQueue::getQueue Jul 07 13:21:56 musical-fidelity volumio[3307]: info: Listing playlists Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 07 13:21:56 musical-fidelity volumio[3307]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 07 13:21:56 musical-fidelity volumio-streaming-daemon[3487]: Checking variant in static cache Jul 07 13:21:56 musical-fidelity volumio[3307]: info: Fetching Streaming Services browse cache Jul 07 13:21:56 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jul 07 13:21:56 musical-fidelity volumio[3307]: info: Setting Additional System Software info: Hardware Firmware Version: 117 Hardware Revision: 1 Jul 07 13:21:57 musical-fidelity volumio[3307]: info: Access Token successfully retrieved Jul 07 13:21:58 musical-fidelity volumio[3307]: info: Checking Spotify Web API Jul 07 13:21:58 musical-fidelity volumio[3307]: info: Successfully retrieved User Session From TIDAL Jul 07 13:21:58 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 3 ,retrying in 5 seconds Jul 07 13:21:58 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 3 ,retrying in 5 seconds Jul 07 13:21:58 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 3 ,retrying in 5 seconds Jul 07 13:21:58 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 3 ,retrying in 5 seconds Jul 07 13:21:58 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 3 ,retrying in 5 seconds Jul 07 13:21:58 musical-fidelity volumio[3307]: info: Successfully retrieved User Subscription From TIDAL Jul 07 13:21:58 musical-fidelity volumio[3307]: info: Adding TIDAL to Browse Sources Jul 07 13:21:58 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 07 13:21:58 musical-fidelity volumio[3307]: info: [1720358518860] CoreMusicLibrary::Adding element TIDAL Jul 07 13:21:58 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 13:21:58 musical-fidelity volumio[3307]: info: Stopping AccessToken refresher cron Jul 07 13:21:58 musical-fidelity volumio[3307]: info: AccessToken refresher cron started Jul 07 13:21:59 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 07 13:22:03 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 4 ,retrying in 5 seconds Jul 07 13:22:03 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 4 ,retrying in 5 seconds Jul 07 13:22:03 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 4 ,retrying in 5 seconds Jul 07 13:22:03 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 4 ,retrying in 5 seconds Jul 07 13:22:03 musical-fidelity volumio[3307]: info: Cannot mount NAS Ds at system boot, trial number 4 ,retrying in 5 seconds Jul 07 13:22:03 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 07 13:22:04 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jul 07 13:22:04 musical-fidelity sudo[3722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Ds Jul 07 13:22:04 musical-fidelity sudo[3722]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:22:04 musical-fidelity sudo[3722]: pam_unix(sudo:session): session closed for user root Jul 07 13:22:04 musical-fidelity volumio[3307]: info: Share Ds successfully unmounted Jul 07 13:22:05 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 07 13:22:07 musical-fidelity volumio[3307]: verbose: ControllerMpd::sendMpdCommand status Jul 07 13:22:07 musical-fidelity volumio[3307]: info: Jul 07 13:22:07 musical-fidelity volumio[3307]: ---------------------------- MPD announces state update: update Jul 07 13:22:07 musical-fidelity volumio[3307]: info: ControllerMpd::getState Jul 07 13:22:07 musical-fidelity volumio[3307]: verbose: ControllerMpd::sendMpdCommand status Jul 07 13:22:07 musical-fidelity volumio[3307]: info: sendMpdCommand status took 8 milliseconds Jul 07 13:22:07 musical-fidelity volumio[3307]: info: sendMpdCommand status took 4 milliseconds Jul 07 13:22:07 musical-fidelity volumio[3307]: verbose: ControllerMpd::parseState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: Command Router : Notfying DB Updatetrue Jul 07 13:22:07 musical-fidelity volumio[3307]: verbose: ControllerMpd::parseState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: ControllerMpd::pushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::servicePushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreStateMachine::pushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioPushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:22:07 musical-fidelity volumio[3307]: 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} Jul 07 13:22:07 musical-fidelity volumio[3307]: verbose: CURRENT POSITION 0 Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreStateMachine::syncState stateService stop Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreStateMachine::syncState currentStatus stop Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreStateMachine::pushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioPushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:22:07 musical-fidelity volumio[3307]: info: No code Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreStateMachine::pushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioPushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:22:07 musical-fidelity volumio[3307]: info: ------------------------------ 134ms Jul 07 13:22:07 musical-fidelity volumio[3307]: info: [1720358527795] Database update started Jul 07 13:22:07 musical-fidelity volumio[3307]: info: MPD Database updated - AlbumList cache refreshed Jul 07 13:22:07 musical-fidelity volumio[3307]: info: Jul 07 13:22:07 musical-fidelity volumio[3307]: ---------------------------- MPD announces state update: database Jul 07 13:22:07 musical-fidelity volumio[3307]: verbose: ControllerMpd::sendMpdCommand status Jul 07 13:22:07 musical-fidelity volumio[3307]: info: Jul 07 13:22:07 musical-fidelity volumio[3307]: ---------------------------- MPD announces state update: update Jul 07 13:22:07 musical-fidelity volumio[3307]: info: ControllerMpd::getState Jul 07 13:22:07 musical-fidelity volumio[3307]: verbose: ControllerMpd::sendMpdCommand status Jul 07 13:22:07 musical-fidelity volumio[3307]: info: ControllerMpd::getState Jul 07 13:22:07 musical-fidelity volumio[3307]: verbose: ControllerMpd::sendMpdCommand status Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 07 13:22:07 musical-fidelity volumio[3307]: info: sendMpdCommand status took 20 milliseconds Jul 07 13:22:07 musical-fidelity volumio[3307]: info: sendMpdCommand status took 19 milliseconds Jul 07 13:22:07 musical-fidelity volumio[3307]: info: sendMpdCommand status took 18 milliseconds Jul 07 13:22:07 musical-fidelity volumio[3307]: verbose: ControllerMpd::parseState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: Command Router : Notfying DB Updatefalse Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::Close All Modals sent Jul 07 13:22:07 musical-fidelity volumio[3307]: verbose: ControllerMpd::parseState Jul 07 13:22:07 musical-fidelity volumio[3307]: verbose: ControllerMpd::parseState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: ControllerMpd::pushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::servicePushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreStateMachine::pushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioPushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:22:07 musical-fidelity volumio[3307]: 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} Jul 07 13:22:07 musical-fidelity volumio[3307]: verbose: CURRENT POSITION 0 Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreStateMachine::syncState stateService stop Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreStateMachine::syncState currentStatus stop Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreStateMachine::pushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioPushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:22:07 musical-fidelity volumio[3307]: info: No code Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreStateMachine::pushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioPushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:22:07 musical-fidelity volumio[3307]: info: ControllerMpd::pushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::servicePushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreStateMachine::pushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioPushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:22:07 musical-fidelity volumio[3307]: 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} Jul 07 13:22:07 musical-fidelity volumio[3307]: verbose: CURRENT POSITION 0 Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreStateMachine::syncState stateService stop Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreStateMachine::syncState currentStatus stop Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreStateMachine::pushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioPushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:22:07 musical-fidelity volumio[3307]: info: No code Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreStateMachine::pushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CorePlayQueue::getTrack 0 Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::volumioPushState Jul 07 13:22:07 musical-fidelity volumio[3307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 13:22:07 musical-fidelity volumio[3307]: info: ------------------------------ 108ms Jul 07 13:22:07 musical-fidelity volumio[3307]: info: ------------------------------ 108ms Jul 07 13:22:07 musical-fidelity volumio[3307]: info: Updating RAAT Signal Path Jul 07 13:22:07 musical-fidelity volumio[3307]: info: Updating RAAT Signal Path Jul 07 13:22:08 musical-fidelity volumio[3307]: info: Updating RAAT Signal Path Jul 07 13:22:08 musical-fidelity volumio[3307]: info: Updating RAAT Signal Path Jul 07 13:22:08 musical-fidelity volumio[3307]: info: Updating RAAT Signal Path Jul 07 13:22:08 musical-fidelity volumio[3307]: info: Updating RAAT Signal Path Jul 07 13:22:08 musical-fidelity volumio[3307]: info: Updating RAAT Signal Path Jul 07 13:22:08 musical-fidelity volumio[3307]: info: Updating RAAT Signal Path Jul 07 13:22:08 musical-fidelity volumio[3307]: info: Updating RAAT Signal Path Jul 07 13:22:08 musical-fidelity volumio[3307]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 13:22:08 musical-fidelity volumio[3307]: TypeError: Cannot read property 'replace' of undefined Jul 07 13:22:08 musical-fidelity volumio[3307]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:198:28) Jul 07 13:22:08 musical-fidelity volumio[3307]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:251:20) Jul 07 13:22:08 musical-fidelity volumio[3307]: at ontimeout (timers.js:482:11) Jul 07 13:22:08 musical-fidelity volumio[3307]: at tryOnTimeout (timers.js:317:5) Jul 07 13:22:08 musical-fidelity volumio[3307]: at Timer.listOnTimeout (timers.js:277:5) Jul 07 13:22:08 musical-fidelity sudo[3780]: 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.3.30/Music /mnt/NAS/Ds Jul 07 13:22:08 musical-fidelity volumio[3307]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 13:22:08 musical-fidelity sudo[3780]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 13:22:08 musical-fidelity sudo[3780]: pam_unix(sudo:session): session closed for user root Jul 07 13:22:09 musical-fidelity sudo[3803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-07 13:21 Jul 07 13:22:09 musical-fidelity sudo[3803]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="mf" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 13 16:55:24 CEST 2022" VOLUMIO_VERSION="1.079" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Musical Fidelity" VOLUMIO_MODEL="MX-Stream" VOLUMIO_VENDOR_MODEL="Musical Fidelity MX-Stream" VOLUMIO_HASH="feef4003d1cbcf074f611a1d9cb9a86e"