-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Thu 2024-11-28 17:04:04 MSK. -- Nov 28 17:03:00 volumio volumio[976]: info: CoreCommandRouter::volumioPlay Nov 28 17:03:00 volumio volumio[976]: info: CoreStateMachine::play index undefined Nov 28 17:03:00 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 17:03:00 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:10 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 17:03:10 volumio volumio[976]: info: Retrieving Cloud Streaming UI Nov 28 17:03:10 volumio volumio[976]: info: Getting Tidal Cloud Configuration Nov 28 17:03:10 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 17:03:10 volumio volumio[976]: info: Getting Qobuz Cloud Configuration Nov 28 17:03:10 volumio volumio[976]: info: Asking plugin for UI Config Nov 28 17:03:10 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 17:03:10 volumio volumio[976]: info: Getting Spotify Cloud Configuration Nov 28 17:03:10 volumio volumio[976]: info: Asking plugin for UI Config Nov 28 17:03:10 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 17:03:10 volumio volumio[976]: info: Saving Spotify Acccount Nov 28 17:03:10 volumio volumio[976]: info: Got Tidal Cloud Configuration Nov 28 17:03:10 volumio volumio[976]: info: Got it Nov 28 17:03:10 volumio volumio[976]: info: Got it Nov 28 17:03:10 volumio volumio[976]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 28 17:03:10 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 17:03:10 volumio volumio[976]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 28 17:03:10 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 28 17:03:10 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 28 17:03:10 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 28 17:03:10 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 17:03:10 volumio volumio[976]: info: CoreCommandRouter::volumioGetBrowseSources Nov 28 17:03:10 volumio volumio[976]: info: CoreCommandRouter::volumioGetBrowseSources Nov 28 17:03:10 volumio volumio[976]: info: CoreCommandRouter::volumioGetBrowseSources Nov 28 17:03:11 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 28 17:03:11 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 28 17:03:15 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 28 17:03:16 volumio ntpd[774]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 28 17:03:19 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 28 17:03:23 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 28 17:03:27 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 28 17:03:29 volumio sudo[2359]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:29 volumio volumio[976]: info: Cannot mount NAS Starbob at system boot, trial number 2 ,retrying in 5 seconds Nov 28 17:03:31 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 28 17:03:34 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Nov 28 17:03:34 volumio volumio[976]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 17:03:34 volumio volumio[976]: TypeError: Cannot read property 'replace' of undefined Nov 28 17:03:34 volumio volumio[976]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Nov 28 17:03:34 volumio volumio[976]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Nov 28 17:03:34 volumio volumio[976]: at listOnTimeout (internal/timers.js:554:17) Nov 28 17:03:34 volumio volumio[976]: at processTimers (internal/timers.js:497:7) Nov 28 17:03:34 volumio volumio[976]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 17:03:35 volumio sudo[2855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-28 17:02 Nov 28 17:03:35 volumio sudo[2855]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:35 volumio sudo[2855]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:35 volumio volumio-remote-updater[603]: [2024-11-28 17:03:35] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 28 17:03:35 volumio volumio-remote-updater[603]: [2024-11-28 17:03:35] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 28 17:03:35 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 28 17:03:35 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 28 17:03:35 volumio systemd[1]: Started dynamicswap service. Nov 28 17:03:35 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 28 17:03:35 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 28 17:03:35 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Nov 28 17:03:36 volumio systemd[1]: Started dynamicswap service. Nov 28 17:03:36 volumio systemd[1]: Stopped Volumio Backend Module. Nov 28 17:03:36 volumio systemd[1]: Started Volumio Backend Module. Nov 28 17:03:36 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 28 17:03:37 volumio volumio[2870]: info: ------------------------------------------- Nov 28 17:03:37 volumio volumio[2870]: info: ----- Volumio3 ---- Nov 28 17:03:37 volumio volumio[2870]: info: ------------------------------------------- Nov 28 17:03:37 volumio volumio[2870]: info: ----- System startup ---- Nov 28 17:03:37 volumio volumio[2870]: info: ------------------------------------------- Nov 28 17:03:37 volumio volumio[2870]: info: MYVOLUMIO Environment detected Nov 28 17:03:37 volumio volumio[2870]: info: Plugin folders cleanup Nov 28 17:03:37 volumio volumio[2870]: info: Scanning into folder /volumio/app/plugins/ Nov 28 17:03:37 volumio volumio[2870]: info: Scanning category audio_interface Nov 28 17:03:37 volumio volumio[2870]: info: Scanning category miscellanea Nov 28 17:03:37 volumio volumio[2870]: info: Scanning category music_service Nov 28 17:03:37 volumio volumio[2870]: info: Scanning category plugins.json Nov 28 17:03:37 volumio volumio[2870]: info: Scanning category system_controller Nov 28 17:03:37 volumio volumio[2870]: info: Scanning category user_interface Nov 28 17:03:37 volumio volumio[2870]: info: Scanning into folder /data/plugins/ Nov 28 17:03:37 volumio volumio[2870]: info: Scanning category user_interface Nov 28 17:03:37 volumio volumio[2870]: info: Plugin folders cleanup completed Nov 28 17:03:37 volumio volumio[2870]: info: ------------------------------------------- Nov 28 17:03:37 volumio volumio[2870]: info: ----- Core plugins startup ---- Nov 28 17:03:37 volumio volumio[2870]: info: ------------------------------------------- Nov 28 17:03:37 volumio volumio[2870]: info: Loading plugins from folder /volumio/app/plugins/ Nov 28 17:03:37 volumio volumio[2870]: info: Adding plugin upnp to MyMusic Plugins Nov 28 17:03:37 volumio volumio[2870]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 28 17:03:37 volumio volumio[2870]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 28 17:03:37 volumio volumio[2870]: info: Loading plugins from folder /data/plugins/ Nov 28 17:03:37 volumio volumio[2870]: info: Loading plugin "system"... Nov 28 17:03:37 volumio volumio[2870]: info: Loading plugin "appearance"... Nov 28 17:03:38 volumio volumio[2870]: info: Loading plugin "network"... Nov 28 17:03:38 volumio volumio[2870]: info: Refreshing Cached IP Addresses Nov 28 17:03:38 volumio sudo[2896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 17:03:38 volumio sudo[2896]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:38 volumio sudo[2898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 17:03:38 volumio sudo[2898]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:38 volumio sudo[2896]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:38 volumio sudo[2898]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:38 volumio volumio[2870]: info: Loading plugin "services"... Nov 28 17:03:38 volumio volumio[2870]: info: Loading plugin "alsa_controller"... Nov 28 17:03:38 volumio sudo[2907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 28 17:03:38 volumio sudo[2907]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:38 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 17:03:38 volumio volumio[2870]: info: Loading plugin "wizard"... Nov 28 17:03:38 volumio volumio[2870]: info: Loading plugin "networkfs"... Nov 28 17:03:38 volumio volumio[2870]: info: Starting Udev Watcher for removable devices Nov 28 17:03:38 volumio sudo[2927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.4/pentium/z: /mnt/NAS/starbob Nov 28 17:03:38 volumio sudo[2927]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:38 volumio sudo[2929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.4://Pentium/Z /mnt/NAS/Starbob Nov 28 17:03:38 volumio sudo[2929]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:38 volumio kernel: CIFS: Attempting to mount //192.168.1.4/pentium/z: Nov 28 17:03:38 volumio volumio[2870]: info: Ignoring mount for partition: boot Nov 28 17:03:38 volumio volumio[2870]: info: Ignoring mount for partition: volumio Nov 28 17:03:38 volumio volumio[2870]: info: Ignoring mount for partition: volumio_data Nov 28 17:03:38 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 28 17:03:38 volumio volumio[2870]: info: Loading plugin "volumio_command_line_client"... Nov 28 17:03:38 volumio volumio[2870]: info: Loading plugin "upnp"... Nov 28 17:03:38 volumio volumio[2870]: info: [1732802618511] Starting Upmpd Daemon Nov 28 17:03:38 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 28 17:03:38 volumio volumio[2870]: info: Loading plugin "my_music"... Nov 28 17:03:38 volumio volumio[2870]: info: Loading plugin "mpd"... Nov 28 17:03:38 volumio sudo[2927]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:38 volumio kernel: CIFS: Status code returned 0xc0000022 STATUS_ACCESS_DENIED Nov 28 17:03:38 volumio kernel: CIFS: VFS: \\192.168.1.4 Send error in SessSetup = -13 Nov 28 17:03:38 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Nov 28 17:03:38 volumio volumio[2870]: info: Loading plugin "upnp_browser"... Nov 28 17:03:38 volumio volumio[2870]: info: Loading plugin "alarm-clock"... Nov 28 17:03:39 volumio volumio[2870]: info: Loading plugin "airplay_emulation"... Nov 28 17:03:39 volumio volumio[2870]: info: Starting Shairport Sync Nov 28 17:03:39 volumio volumio[2870]: info: Loading plugin "last_100"... Nov 28 17:03:39 volumio volumio[2870]: info: Loading plugin "webradio"... Nov 28 17:03:39 volumio volumio[2870]: info: Loading plugin "i2s_dacs"... Nov 28 17:03:39 volumio volumio[2870]: info: Loading plugin "volumiodiscovery"... Nov 28 17:03:39 volumio volumio[2870]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 28 17:03:39 volumio volumio[2870]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 17:03:39 volumio volumio[2870]: *** WARNING *** For more information see Nov 28 17:03:39 volumio node[2870]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 28 17:03:39 volumio volumio[2870]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 28 17:03:39 volumio volumio[2870]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 17:03:39 volumio volumio[2870]: *** WARNING *** For more information see Nov 28 17:03:39 volumio node[2870]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 17:03:39 volumio node[2870]: *** WARNING *** For more information see Nov 28 17:03:39 volumio node[2870]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 28 17:03:39 volumio node[2870]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 17:03:39 volumio node[2870]: *** WARNING *** For more information see Nov 28 17:03:39 volumio volumio[2870]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 28 17:03:39 volumio volumio[2870]: info: Discovery: Started advertising with name: Volumio Nov 28 17:03:39 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 28 17:03:39 volumio volumio[2870]: info: Loading plugin "outputs"... Nov 28 17:03:39 volumio volumio[2870]: info: Loading plugin "albumart"... Nov 28 17:03:39 volumio volumio[2870]: info: Plugin example_plugin is not enabled Nov 28 17:03:39 volumio volumio[2870]: info: Loading plugin "inputs"... Nov 28 17:03:39 volumio volumio[2870]: info: Loading plugin "updater_comm"... Nov 28 17:03:39 volumio volumio[2870]: info: Plugin mpdemulation is not enabled Nov 28 17:03:39 volumio volumio[2870]: info: Loading plugin "rest_api"... Nov 28 17:03:39 volumio volumio[2870]: info: Loading plugin "websocket"... Nov 28 17:03:39 volumio volumio[2870]: info: Starting Socket.io Server version 2.3.0 Nov 28 17:03:39 volumio volumio[2870]: info: Loading plugin "touch_display"... Nov 28 17:03:39 volumio volumio[2870]: Forking 3 albumart workers Nov 28 17:03:39 volumio volumio[2870]: info: Applying required configuration parameters for plugin touch_display Nov 28 17:03:39 volumio volumio[2870]: info: Loading i18n strings for locale ru Nov 28 17:03:39 volumio volumio[2870]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 28 17:03:39 volumio volumio[2870]: Updating browse sources language Nov 28 17:03:39 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 17:03:39 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 17:03:39 volumio volumio[2870]: info: CoreCommandRouter::initPlayerControls Nov 28 17:03:39 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:03:39 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:03:39 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:03:39 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:03:39 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:03:39 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 17:03:39 volumio volumio[2870]: Express server listening on port 3000 Nov 28 17:03:39 volumio volumio[2870]: [Metrics] WebUI: 3s 160.83ms Nov 28 17:03:39 volumio volumio[2870]: info: CoreStateMachine::resetVolumioState Nov 28 17:03:39 volumio volumio[2870]: info: CoreStateMachine::getcurrentVolume Nov 28 17:03:39 volumio volumio[2870]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 17:03:40 volumio volumio[2870]: info: Volumio Network Manager: Network status updated: 2 Nov 28 17:03:40 volumio volumio[2870]: info: Cannot mount NAS starbob at system boot, trial number 1 ,retrying in 5 seconds Nov 28 17:03:40 volumio volumio[2870]: Starting albumart workers Nov 28 17:03:40 volumio volumio[2870]: Starting albumart workers Nov 28 17:03:40 volumio volumio[2870]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 28 17:03:40 volumio volumio[2870]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Nov 28 17:03:40 volumio volumio[2870]: info: Reloading queue from file Nov 28 17:03:40 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:03:40 volumio volumio[2870]: Starting albumart workers Nov 28 17:03:40 volumio volumio[2870]: info: CoreStateMachine::setRepeat null single undefined Nov 28 17:03:40 volumio volumio[2870]: info: CoreStateMachine::pushState Nov 28 17:03:40 volumio volumio[2870]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:40 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:03:40 volumio volumio[2870]: info: CoreCommandRouter::volumioPushState Nov 28 17:03:40 volumio volumio[2870]: info: CoreStateMachine::setRandom null Nov 28 17:03:40 volumio volumio[2870]: info: CoreStateMachine::pushState Nov 28 17:03:40 volumio volumio[2870]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:40 volumio volumio[2870]: info: CoreCommandRouter::volumioPushState Nov 28 17:03:40 volumio volumio[2870]: info: Setting Device type: Raspberry PI Nov 28 17:03:40 volumio volumio[2870]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 28 17:03:40 volumio volumio[2870]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Nov 28 17:03:40 volumio volumio[2870]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 28 17:03:40 volumio volumio[2870]: info: VolumeController:: Volume=86 Mute =false Nov 28 17:03:40 volumio volumio[2870]: info: CoreStateMachine::pushState Nov 28 17:03:40 volumio volumio[2870]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:40 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:03:40 volumio volumio[2870]: info: CoreCommandRouter::volumioPushState Nov 28 17:03:40 volumio volumio[2870]: info: CoreStateMachine::updateTrackBlock Nov 28 17:03:40 volumio volumio[2870]: info: CorePlayQueue::getTrackBlock Nov 28 17:03:40 volumio volumio[2870]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 17:03:40 volumio volumio[2870]: info: Discovery: adding f5ea5e41-3311-45ab-86e6-ecf51a741929 Nov 28 17:03:40 volumio volumio[2870]: info: Discovery: Found device Volumio Nov 28 17:03:40 volumio volumio[2870]: info: CoreCommandRouter::volumioGetState Nov 28 17:03:40 volumio volumio[2870]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:40 volumio volumio[2870]: info: VolumeController:: Volume=86 Mute =false Nov 28 17:03:40 volumio volumio[2870]: info: CoreStateMachine::pushState Nov 28 17:03:40 volumio volumio[2870]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:40 volumio volumio[2870]: info: CoreCommandRouter::volumioPushState Nov 28 17:03:40 volumio volumio-remote-updater[603]: [2024-11-28 17:03:40] [connect] Successful connection Nov 28 17:03:40 volumio volumio-remote-updater[603]: [2024-11-28 17:03:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732802620 101 Nov 28 17:03:40 volumio volumio[2870]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Nov 28 17:03:41 volumio volumio[2870]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 28 17:03:41 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 28 17:03:41 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 17:03:41 volumio volumio[2870]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 17:03:41 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 17:03:41 volumio volumio[2870]: info: CoreCommandRouter::volumioGetState Nov 28 17:03:41 volumio volumio[2870]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:41 volumio volumio[2870]: info: CoreCommandRouter::volumioGetState Nov 28 17:03:41 volumio volumio[2870]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:41 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 28 17:03:41 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 28 17:03:41 volumio volumio[2870]: info: Received Get System Info Nov 28 17:03:41 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 17:03:41 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 17:03:41 volumio volumio[2870]: info: Discovery: Getting this device information Nov 28 17:03:41 volumio volumio[2870]: info: CoreCommandRouter::volumioGetState Nov 28 17:03:41 volumio volumio[2870]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:41 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 17:03:41 volumio volumio[2870]: info: CoreCommandRouter::volumioGetState Nov 28 17:03:41 volumio volumio[2870]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:41 volumio volumio[2870]: info: Listing playlists Nov 28 17:03:41 volumio volumio[2870]: info: CoreCommandRouter::volumioGetQueue Nov 28 17:03:41 volumio volumio[2870]: info: CoreStateMachine::getQueue Nov 28 17:03:41 volumio volumio[2870]: info: CorePlayQueue::getQueue Nov 28 17:03:42 volumio sudo[2907]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:43 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 28 17:03:45 volumio sudo[3007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.4/pentium/z: /mnt/NAS/starbob Nov 28 17:03:45 volumio sudo[3007]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:45 volumio kernel: CIFS: Attempting to mount //192.168.1.4/pentium/z: Nov 28 17:03:45 volumio kernel: CIFS: Status code returned 0xc0000022 STATUS_ACCESS_DENIED Nov 28 17:03:45 volumio kernel: CIFS: VFS: \\192.168.1.4 Send error in SessSetup = -13 Nov 28 17:03:45 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Nov 28 17:03:45 volumio sudo[3007]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:45 volumio volumio[2870]: info: Cannot mount NAS starbob at system boot, trial number 2 ,retrying in 5 seconds Nov 28 17:03:47 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 28 17:03:48 volumio sudo[3030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 17:03:48 volumio sudo[3030]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:48 volumio sudo[3030]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:48 volumio sudo[3032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 17:03:48 volumio sudo[3032]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:48 volumio sudo[3032]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:48 volumio sudo[3036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 28 17:03:48 volumio sudo[3036]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:48 volumio sudo[3036]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:48 volumio volumio[2870]: info: Upmpdcli Daemon Started Nov 28 17:03:49 volumio volumio[2870]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Nov 28 17:03:50 volumio volumio[2870]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 17:03:50 volumio volumio[2870]: TypeError: Cannot read property 'replace' of undefined Nov 28 17:03:50 volumio volumio[2870]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Nov 28 17:03:50 volumio volumio[2870]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Nov 28 17:03:50 volumio volumio[2870]: at listOnTimeout (internal/timers.js:554:17) Nov 28 17:03:50 volumio volumio[2870]: at processTimers (internal/timers.js:497:7) Nov 28 17:03:50 volumio volumio[2870]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 17:03:50 volumio sudo[3049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-28 17:02 Nov 28 17:03:50 volumio sudo[3049]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:50 volumio sudo[3049]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:50 volumio volumio-remote-updater[603]: [2024-11-28 17:03:50] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 28 17:03:50 volumio volumio-remote-updater[603]: [2024-11-28 17:03:50] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 28 17:03:50 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 28 17:03:50 volumio sudo[2929]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:50 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 28 17:03:50 volumio systemd[1]: Started dynamicswap service. Nov 28 17:03:50 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 28 17:03:50 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 28 17:03:50 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Nov 28 17:03:50 volumio systemd[1]: Stopped Volumio Backend Module. Nov 28 17:03:50 volumio systemd[1]: Started Volumio Backend Module. Nov 28 17:03:50 volumio systemd[1]: Started dynamicswap service. Nov 28 17:03:50 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 28 17:03:51 volumio volumio[3063]: info: ------------------------------------------- Nov 28 17:03:51 volumio volumio[3063]: info: ----- Volumio3 ---- Nov 28 17:03:51 volumio volumio[3063]: info: ------------------------------------------- Nov 28 17:03:51 volumio volumio[3063]: info: ----- System startup ---- Nov 28 17:03:51 volumio volumio[3063]: info: ------------------------------------------- Nov 28 17:03:52 volumio volumio[3063]: info: MYVOLUMIO Environment detected Nov 28 17:03:52 volumio volumio[3063]: info: Plugin folders cleanup Nov 28 17:03:52 volumio volumio[3063]: info: Scanning into folder /volumio/app/plugins/ Nov 28 17:03:52 volumio volumio[3063]: info: Scanning category audio_interface Nov 28 17:03:52 volumio volumio[3063]: info: Scanning category miscellanea Nov 28 17:03:52 volumio volumio[3063]: info: Scanning category music_service Nov 28 17:03:52 volumio volumio[3063]: info: Scanning category plugins.json Nov 28 17:03:52 volumio volumio[3063]: info: Scanning category system_controller Nov 28 17:03:52 volumio volumio[3063]: info: Scanning category user_interface Nov 28 17:03:52 volumio volumio[3063]: info: Scanning into folder /data/plugins/ Nov 28 17:03:52 volumio volumio[3063]: info: Scanning category user_interface Nov 28 17:03:52 volumio volumio[3063]: info: Plugin folders cleanup completed Nov 28 17:03:52 volumio volumio[3063]: info: ------------------------------------------- Nov 28 17:03:52 volumio volumio[3063]: info: ----- Core plugins startup ---- Nov 28 17:03:52 volumio volumio[3063]: info: ------------------------------------------- Nov 28 17:03:52 volumio volumio[3063]: info: Loading plugins from folder /volumio/app/plugins/ Nov 28 17:03:52 volumio volumio[3063]: info: Adding plugin upnp to MyMusic Plugins Nov 28 17:03:52 volumio volumio[3063]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 28 17:03:52 volumio volumio[3063]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 28 17:03:52 volumio volumio[3063]: info: Loading plugins from folder /data/plugins/ Nov 28 17:03:52 volumio volumio[3063]: info: Loading plugin "system"... Nov 28 17:03:52 volumio volumio[3063]: info: Loading plugin "appearance"... Nov 28 17:03:52 volumio volumio[3063]: info: Loading plugin "network"... Nov 28 17:03:52 volumio volumio[3063]: info: Refreshing Cached IP Addresses Nov 28 17:03:53 volumio sudo[3090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 17:03:53 volumio sudo[3090]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:53 volumio sudo[3090]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:53 volumio sudo[3092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 17:03:53 volumio sudo[3092]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:53 volumio sudo[3092]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "services"... Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "alsa_controller"... Nov 28 17:03:53 volumio sudo[3101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 28 17:03:53 volumio sudo[3101]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:53 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "wizard"... Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "networkfs"... Nov 28 17:03:53 volumio sudo[3118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.4/pentium/z: /mnt/NAS/starbob Nov 28 17:03:53 volumio sudo[3118]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:53 volumio volumio[3063]: info: Starting Udev Watcher for removable devices Nov 28 17:03:53 volumio sudo[3120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.4://Pentium/Z /mnt/NAS/Starbob Nov 28 17:03:53 volumio sudo[3120]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:53 volumio kernel: CIFS: Attempting to mount //192.168.1.4/pentium/z: Nov 28 17:03:53 volumio volumio[3063]: info: Ignoring mount for partition: boot Nov 28 17:03:53 volumio volumio[3063]: info: Ignoring mount for partition: volumio Nov 28 17:03:53 volumio volumio[3063]: info: Ignoring mount for partition: volumio_data Nov 28 17:03:53 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "volumio_command_line_client"... Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "upnp"... Nov 28 17:03:53 volumio volumio[3063]: info: [1732802633205] Starting Upmpd Daemon Nov 28 17:03:53 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "my_music"... Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "mpd"... Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "upnp_browser"... Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "alarm-clock"... Nov 28 17:03:53 volumio sudo[3118]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:53 volumio kernel: CIFS: Status code returned 0xc0000022 STATUS_ACCESS_DENIED Nov 28 17:03:53 volumio kernel: CIFS: VFS: \\192.168.1.4 Send error in SessSetup = -13 Nov 28 17:03:53 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "airplay_emulation"... Nov 28 17:03:53 volumio volumio[3063]: info: Starting Shairport Sync Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "last_100"... Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "webradio"... Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "i2s_dacs"... Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "volumiodiscovery"... Nov 28 17:03:53 volumio volumio[3063]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 28 17:03:53 volumio volumio[3063]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 17:03:53 volumio volumio[3063]: *** WARNING *** For more information see Nov 28 17:03:53 volumio volumio[3063]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 28 17:03:53 volumio node[3063]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 28 17:03:53 volumio volumio[3063]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 17:03:53 volumio volumio[3063]: *** WARNING *** For more information see Nov 28 17:03:53 volumio node[3063]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 17:03:53 volumio node[3063]: *** WARNING *** For more information see Nov 28 17:03:53 volumio node[3063]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 28 17:03:53 volumio node[3063]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 17:03:53 volumio node[3063]: *** WARNING *** For more information see Nov 28 17:03:53 volumio volumio[3063]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 28 17:03:53 volumio volumio[3063]: info: Discovery: Started advertising with name: Volumio Nov 28 17:03:53 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "outputs"... Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "albumart"... Nov 28 17:03:53 volumio volumio[3063]: info: Plugin example_plugin is not enabled Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "inputs"... Nov 28 17:03:53 volumio volumio[3063]: info: Loading plugin "updater_comm"... Nov 28 17:03:54 volumio volumio[3063]: info: Plugin mpdemulation is not enabled Nov 28 17:03:54 volumio volumio[3063]: info: Loading plugin "rest_api"... Nov 28 17:03:54 volumio volumio[3063]: info: Loading plugin "websocket"... Nov 28 17:03:54 volumio volumio[3063]: info: Starting Socket.io Server version 2.3.0 Nov 28 17:03:54 volumio volumio[3063]: info: Loading plugin "touch_display"... Nov 28 17:03:54 volumio volumio[3063]: Forking 3 albumart workers Nov 28 17:03:54 volumio volumio[3063]: info: Applying required configuration parameters for plugin touch_display Nov 28 17:03:54 volumio volumio[3063]: info: Loading i18n strings for locale ru Nov 28 17:03:54 volumio volumio[3063]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 28 17:03:54 volumio volumio[3063]: Updating browse sources language Nov 28 17:03:54 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 17:03:54 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 17:03:54 volumio volumio[3063]: info: CoreCommandRouter::initPlayerControls Nov 28 17:03:54 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:03:54 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:03:54 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:03:54 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:03:54 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:03:54 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:03:54 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:03:54 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:03:54 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 17:03:54 volumio volumio[3063]: Express server listening on port 3000 Nov 28 17:03:54 volumio volumio[3063]: [Metrics] WebUI: 3s 205.05ms Nov 28 17:03:54 volumio volumio[3063]: info: CoreStateMachine::resetVolumioState Nov 28 17:03:54 volumio volumio[3063]: info: CoreStateMachine::getcurrentVolume Nov 28 17:03:54 volumio volumio[3063]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 17:03:54 volumio volumio[3063]: info: Volumio Network Manager: Network status updated: 2 Nov 28 17:03:54 volumio volumio[3063]: Starting albumart workers Nov 28 17:03:54 volumio volumio[3063]: info: Cannot mount NAS starbob at system boot, trial number 1 ,retrying in 5 seconds Nov 28 17:03:54 volumio volumio[3063]: Starting albumart workers Nov 28 17:03:54 volumio volumio[3063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 28 17:03:54 volumio volumio[3063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Nov 28 17:03:54 volumio volumio[3063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 28 17:03:54 volumio volumio[3063]: info: Reloading queue from file Nov 28 17:03:54 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:03:54 volumio volumio[3063]: Starting albumart workers Nov 28 17:03:55 volumio volumio[3063]: info: CoreStateMachine::setRepeat null single undefined Nov 28 17:03:55 volumio volumio[3063]: info: CoreStateMachine::pushState Nov 28 17:03:55 volumio volumio[3063]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:55 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:03:55 volumio volumio[3063]: info: CoreCommandRouter::volumioPushState Nov 28 17:03:55 volumio volumio[3063]: info: CoreStateMachine::setRandom null Nov 28 17:03:55 volumio volumio[3063]: info: CoreStateMachine::pushState Nov 28 17:03:55 volumio volumio[3063]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:55 volumio volumio[3063]: info: CoreCommandRouter::volumioPushState Nov 28 17:03:55 volumio volumio[3063]: info: Setting Device type: Raspberry PI Nov 28 17:03:55 volumio volumio[3063]: info: VolumeController:: Volume=86 Mute =false Nov 28 17:03:55 volumio volumio[3063]: info: CoreStateMachine::pushState Nov 28 17:03:55 volumio volumio[3063]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:55 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:03:55 volumio volumio[3063]: info: CoreCommandRouter::volumioPushState Nov 28 17:03:55 volumio volumio[3063]: info: CoreStateMachine::updateTrackBlock Nov 28 17:03:55 volumio volumio[3063]: info: CorePlayQueue::getTrackBlock Nov 28 17:03:55 volumio volumio[3063]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 17:03:55 volumio volumio[3063]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 28 17:03:55 volumio volumio[3063]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Nov 28 17:03:55 volumio volumio[3063]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 28 17:03:55 volumio volumio[3063]: info: VolumeController:: Volume=86 Mute =false Nov 28 17:03:55 volumio volumio[3063]: info: CoreStateMachine::pushState Nov 28 17:03:55 volumio volumio[3063]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:55 volumio volumio[3063]: info: CoreCommandRouter::volumioPushState Nov 28 17:03:55 volumio volumio[3063]: info: Discovery: adding f5ea5e41-3311-45ab-86e6-ecf51a741929 Nov 28 17:03:55 volumio volumio[3063]: info: Discovery: Found device Volumio Nov 28 17:03:55 volumio volumio[3063]: info: CoreCommandRouter::volumioGetState Nov 28 17:03:55 volumio volumio[3063]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:55 volumio volumio-remote-updater[603]: [2024-11-28 17:03:55] [connect] Successful connection Nov 28 17:03:55 volumio volumio-remote-updater[603]: [2024-11-28 17:03:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732802635 101 Nov 28 17:03:55 volumio volumio[3063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Nov 28 17:03:57 volumio sudo[3101]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:58 volumio volumio[3063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 28 17:03:58 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 28 17:03:58 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 28 17:03:58 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 17:03:58 volumio volumio[3063]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 17:03:58 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 17:03:58 volumio volumio[3063]: info: CoreCommandRouter::volumioGetState Nov 28 17:03:58 volumio volumio[3063]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:58 volumio volumio[3063]: info: CoreCommandRouter::volumioGetState Nov 28 17:03:58 volumio volumio[3063]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:58 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 28 17:03:58 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 28 17:03:58 volumio volumio[3063]: info: Received Get System Info Nov 28 17:03:58 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 17:03:58 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 17:03:58 volumio volumio[3063]: info: Discovery: Getting this device information Nov 28 17:03:58 volumio volumio[3063]: info: CoreCommandRouter::volumioGetState Nov 28 17:03:58 volumio volumio[3063]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:58 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 17:03:58 volumio volumio[3063]: info: CoreCommandRouter::volumioGetState Nov 28 17:03:58 volumio volumio[3063]: info: CorePlayQueue::getTrack 0 Nov 28 17:03:58 volumio volumio[3063]: info: Listing playlists Nov 28 17:03:58 volumio volumio[3063]: info: CoreCommandRouter::volumioGetQueue Nov 28 17:03:58 volumio volumio[3063]: info: CoreStateMachine::getQueue Nov 28 17:03:58 volumio volumio[3063]: info: CorePlayQueue::getQueue Nov 28 17:03:59 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 28 17:03:59 volumio sudo[3239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.4/pentium/z: /mnt/NAS/starbob Nov 28 17:03:59 volumio sudo[3239]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:03:59 volumio kernel: CIFS: Attempting to mount //192.168.1.4/pentium/z: Nov 28 17:03:59 volumio kernel: CIFS: Status code returned 0xc0000022 STATUS_ACCESS_DENIED Nov 28 17:03:59 volumio kernel: CIFS: VFS: \\192.168.1.4 Send error in SessSetup = -13 Nov 28 17:03:59 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Nov 28 17:03:59 volumio sudo[3239]: pam_unix(sudo:session): session closed for user root Nov 28 17:03:59 volumio volumio[3063]: info: Cannot mount NAS starbob at system boot, trial number 2 ,retrying in 5 seconds Nov 28 17:04:03 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 28 17:04:03 volumio sudo[3259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 17:04:03 volumio sudo[3259]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:04:03 volumio sudo[3259]: pam_unix(sudo:session): session closed for user root Nov 28 17:04:03 volumio sudo[3261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 17:04:03 volumio sudo[3261]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:04:03 volumio sudo[3261]: pam_unix(sudo:session): session closed for user root Nov 28 17:04:03 volumio sudo[3265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 28 17:04:03 volumio sudo[3265]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:04:03 volumio sudo[3265]: pam_unix(sudo:session): session closed for user root Nov 28 17:04:03 volumio volumio[3063]: info: Upmpdcli Daemon Started Nov 28 17:04:04 volumio volumio[3063]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Nov 28 17:04:04 volumio volumio[3063]: verbose: ControllerMpd::sendMpdCommand update Nov 28 17:04:04 volumio volumio[3063]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 17:04:04 volumio volumio[3063]: TypeError: Cannot read property 'then' of undefined Nov 28 17:04:04 volumio volumio[3063]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Nov 28 17:04:04 volumio volumio[3063]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1899:15) Nov 28 17:04:04 volumio volumio[3063]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1097:32) Nov 28 17:04:04 volumio volumio[3063]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1085:26) Nov 28 17:04:04 volumio volumio[3063]: at Socket.emit (events.js:315:20) Nov 28 17:04:04 volumio volumio[3063]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 28 17:04:04 volumio volumio[3063]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 28 17:04:04 volumio volumio[3063]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 17:04:04 volumio sudo[3276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-28 17:03 Nov 28 17:04:04 volumio sudo[3276]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"