-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Tue 2025-07-01 12:16:52 +07. --
Jul 01 12:15:51 volumio3 systemd-timedated[1012]: Changed local time to Tue Jul 1 12:15:51 2025
Jul 01 12:15:51 volumio3 sudo[1010]: pam_unix(sudo:session): session closed for user root
Jul 01 12:15:51 volumio3 volumio-time-update[603]: volumio-time-update-util: System time updated successfully.
Jul 01 12:15:51 volumio3 systemd[1]: Starting Daily apt download activities...
Jul 01 12:15:51 volumio3 systemd[1]: Started Volumio Time Update Utility.
Jul 01 12:15:51 volumio3 nmbd[766]: [2025/07/01 12:15:51.862784, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Jul 01 12:15:51 volumio3 nmbd[766]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Jul 01 12:15:51 volumio3 systemd[1]: Started Samba NMB Daemon.
Jul 01 12:15:51 volumio3 systemd[1]: Starting Samba Winbind Daemon...
Jul 01 12:15:52 volumio3 volumio[977]: info: MYVOLUMIO Environment detected
Jul 01 12:15:52 volumio3 winbindd[1033]: [2025/07/01 12:15:52.452753, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache)
Jul 01 12:15:52 volumio3 winbindd[1033]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Jul 01 12:15:52 volumio3 winbindd[1033]: [2025/07/01 12:15:52.548979, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Jul 01 12:15:52 volumio3 systemd[1]: Started Samba Winbind Daemon.
Jul 01 12:15:52 volumio3 winbindd[1033]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
Jul 01 12:15:52 volumio3 systemd[1]: Starting Samba SMB Daemon...
Jul 01 12:15:53 volumio3 volumio[977]: info: Plugin folders cleanup
Jul 01 12:15:53 volumio3 volumio[977]: info: Scanning into folder /volumio/app/plugins/
Jul 01 12:15:53 volumio3 volumio[977]: info: Scanning category audio_interface
Jul 01 12:15:53 volumio3 volumio[977]: info: Scanning category miscellanea
Jul 01 12:15:53 volumio3 volumio[977]: info: Scanning category music_service
Jul 01 12:15:53 volumio3 volumio[977]: info: Scanning category plugins.json
Jul 01 12:15:53 volumio3 volumio[977]: info: Scanning category system_controller
Jul 01 12:15:53 volumio3 volumio[977]: info: Scanning category user_interface
Jul 01 12:15:53 volumio3 volumio[977]: info: Scanning into folder /data/plugins/
Jul 01 12:15:53 volumio3 volumio[977]: info: Scanning category system_controller
Jul 01 12:15:53 volumio3 volumio[977]: info: Plugin folders cleanup completed
Jul 01 12:15:53 volumio3 volumio[977]: info: -------------------------------------------
Jul 01 12:15:53 volumio3 volumio[977]: info: ----- Core plugins startup ----
Jul 01 12:15:53 volumio3 volumio[977]: info: -------------------------------------------
Jul 01 12:15:53 volumio3 volumio[977]: info: Loading plugins from folder /volumio/app/plugins/
Jul 01 12:15:53 volumio3 volumio[977]: info: Adding plugin upnp to MyMusic Plugins
Jul 01 12:15:53 volumio3 volumio[977]: info: Adding plugin airplay_emulation to MyMusic Plugins
Jul 01 12:15:53 volumio3 volumio[977]: info: Adding plugin upnp_browser to MyMusic Plugins
Jul 01 12:15:53 volumio3 volumio[977]: info: Loading plugins from folder /data/plugins/
Jul 01 12:15:53 volumio3 volumio[977]: info: Loading plugin "system"...
Jul 01 12:15:53 volumio3 volumio[977]: info: Loading plugin "appearance"...
Jul 01 12:15:53 volumio3 systemd[1]: Started Samba SMB Daemon.
Jul 01 12:15:53 volumio3 systemd[1]: Reached target Multi-User System.
Jul 01 12:15:53 volumio3 smbd[1040]: [2025/07/01 12:15:53.472433, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Jul 01 12:15:53 volumio3 systemd[1]: Reached target Graphical Interface.
Jul 01 12:15:53 volumio3 smbd[1040]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Jul 01 12:15:53 volumio3 systemd[1]: Starting Update UTMP about System Runlevel Changes...
Jul 01 12:15:53 volumio3 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Jul 01 12:15:53 volumio3 systemd[1]: Started Update UTMP about System Runlevel Changes.
Jul 01 12:15:54 volumio3 systemd[1]: apt-daily.service: Succeeded.
Jul 01 12:15:54 volumio3 systemd[1]: Started Daily apt download activities.
Jul 01 12:15:54 volumio3 systemd[1]: Starting Daily apt upgrade and clean activities...
Jul 01 12:15:54 volumio3 volumio[977]: info: Loading plugin "network"...
Jul 01 12:15:54 volumio3 volumio[977]: info: Refreshing Cached IP Addresses
Jul 01 12:15:55 volumio3 sudo[1088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 01 12:15:55 volumio3 sudo[1088]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:15:55 volumio3 sudo[1091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 01 12:15:55 volumio3 sudo[1091]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:15:55 volumio3 sudo[1088]: pam_unix(sudo:session): session closed for user root
Jul 01 12:15:55 volumio3 volumio[977]: info: Loading plugin "services"...
Jul 01 12:15:55 volumio3 sudo[1091]: pam_unix(sudo:session): session closed for user root
Jul 01 12:15:55 volumio3 volumio[977]: info: Loading plugin "alsa_controller"...
Jul 01 12:15:55 volumio3 sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 01 12:15:55 volumio3 sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:15:55 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 01 12:15:55 volumio3 volumio[977]: info: Loading plugin "wizard"...
Jul 01 12:15:55 volumio3 volumio[977]: info: Loading plugin "networkfs"...
Jul 01 12:15:55 volumio3 volumio[977]: info: Starting Udev Watcher for removable devices
Jul 01 12:15:55 volumio3 sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=987400318,password=2106,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.6.59/987400318_Home1 /mnt/NAS/H99
Jul 01 12:15:55 volumio3 sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:15:55 volumio3 volumio[977]: info: Ignoring mount for partition: boot
Jul 01 12:15:55 volumio3 volumio[977]: info: Ignoring mount for partition: volumio
Jul 01 12:15:55 volumio3 volumio[977]: info: Ignoring mount for partition: volumio_data
Jul 01 12:15:55 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jul 01 12:15:55 volumio3 volumio[977]: info: Loading plugin "volumio_command_line_client"...
Jul 01 12:15:55 volumio3 volumio[977]: info: Loading plugin "upnp"...
Jul 01 12:15:55 volumio3 volumio[977]: info: [1751346955387] Starting Upmpd Daemon
Jul 01 12:15:55 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jul 01 12:15:55 volumio3 volumio[977]: info: Loading plugin "my_music"...
Jul 01 12:15:55 volumio3 volumio[977]: info: Loading plugin "mpd"...
Jul 01 12:15:55 volumio3 kernel: Key type cifs.spnego registered
Jul 01 12:15:55 volumio3 kernel: Key type cifs.idmap registered
Jul 01 12:15:55 volumio3 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount.
Jul 01 12:15:55 volumio3 kernel: CIFS: Attempting to mount //192.168.6.59/987400318_Home1
Jul 01 12:15:55 volumio3 sudo[1105]: pam_unix(sudo:session): session closed for user root
Jul 01 12:15:55 volumio3 volumio[977]: info: Loading plugin "upnp_browser"...
Jul 01 12:15:56 volumio3 volumio[977]: info: Loading plugin "alarm-clock"...
Jul 01 12:15:56 volumio3 volumio[977]: info: Loading plugin "airplay_emulation"...
Jul 01 12:15:56 volumio3 volumio[977]: info: Starting Shairport Sync
Jul 01 12:15:56 volumio3 volumio[977]: info: Loading plugin "last_100"...
Jul 01 12:15:56 volumio3 volumio[977]: info: Loading plugin "webradio"...
Jul 01 12:15:56 volumio3 systemd[1]: apt-daily-upgrade.service: Succeeded.
Jul 01 12:15:56 volumio3 systemd[1]: Started Daily apt upgrade and clean activities.
Jul 01 12:15:56 volumio3 systemd[1]: Startup finished in 14.161s (kernel) + 27.570s (userspace) = 41.732s.
Jul 01 12:15:56 volumio3 volumio[977]: info: Loading plugin "i2s_dacs"...
Jul 01 12:15:56 volumio3 volumio[977]: info: Loading plugin "volumiodiscovery"...
Jul 01 12:15:57 volumio3 volumio[977]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jul 01 12:15:57 volumio3 volumio[977]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jul 01 12:15:57 volumio3 volumio[977]: *** WARNING *** For more information see
Jul 01 12:15:57 volumio3 volumio[977]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jul 01 12:15:57 volumio3 node[977]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jul 01 12:15:57 volumio3 volumio[977]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jul 01 12:15:57 volumio3 volumio[977]: *** WARNING *** For more information see
Jul 01 12:15:57 volumio3 node[977]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jul 01 12:15:57 volumio3 node[977]: *** WARNING *** For more information see
Jul 01 12:15:57 volumio3 node[977]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jul 01 12:15:57 volumio3 node[977]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jul 01 12:15:57 volumio3 node[977]: *** WARNING *** For more information see
Jul 01 12:15:57 volumio3 volumio[977]: info: Applying required configuration parameters for plugin volumiodiscovery
Jul 01 12:15:57 volumio3 volumio[977]: info: Discovery: Started advertising with name: Volumio3
Jul 01 12:15:57 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jul 01 12:15:57 volumio3 volumio[977]: info: Plugin autostart is not enabled
Jul 01 12:15:57 volumio3 volumio[977]: info: Loading plugin "outputs"...
Jul 01 12:15:57 volumio3 volumio[977]: info: Loading plugin "albumart"...
Jul 01 12:15:57 volumio3 volumio[977]: info: Plugin example_plugin is not enabled
Jul 01 12:15:57 volumio3 volumio[977]: info: Loading plugin "inputs"...
Jul 01 12:15:57 volumio3 volumio[977]: info: Loading plugin "updater_comm"...
Jul 01 12:15:57 volumio3 volumio-remote-updater[608]: [2025-07-01 12:15:57] [connect] Successful connection
Jul 01 12:15:57 volumio3 volumio[977]: info: Plugin mpdemulation is not enabled
Jul 01 12:15:57 volumio3 volumio[977]: info: Loading plugin "rest_api"...
Jul 01 12:15:57 volumio3 volumio[977]: info: Loading plugin "websocket"...
Jul 01 12:15:57 volumio3 volumio[977]: info: Starting Socket.io Server version 2.3.0
Jul 01 12:15:57 volumio3 volumio[977]: info: Loading i18n strings for locale en
Jul 01 12:15:57 volumio3 volumio[977]: Updating browse sources language
Jul 01 12:15:57 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 01 12:15:57 volumio3 volumio[977]: Forking 3 albumart workers
Jul 01 12:15:57 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 01 12:15:57 volumio3 volumio[977]: info: CoreCommandRouter::initPlayerControls
Jul 01 12:15:57 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:15:57 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:15:57 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:15:57 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:15:57 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:15:57 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:15:57 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:15:57 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:15:57 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 01 12:15:57 volumio3 volumio[977]: Express server listening on port 3000
Jul 01 12:15:57 volumio3 volumio[977]: [Metrics] WebUI: 8s 289.92ms
Jul 01 12:15:58 volumio3 volumio[977]: info: CoreStateMachine::resetVolumioState
Jul 01 12:15:58 volumio3 volumio[977]: info: CoreStateMachine::getcurrentVolume
Jul 01 12:15:58 volumio3 volumio[977]: info: CoreCommandRouter::volumioRetrievevolume
Jul 01 12:15:58 volumio3 volumio[977]: info: CoreStateMachine::pushState
Jul 01 12:15:58 volumio3 volumio[977]: info: CorePlayQueue::getTrack 0
Jul 01 12:15:58 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 01 12:15:58 volumio3 volumio[977]: info: CoreCommandRouter::volumioPushState
Jul 01 12:15:58 volumio3 volumio[977]: info: Volumio Network Manager: Network status updated: 2
Jul 01 12:15:58 volumio3 volumio-remote-updater[608]: [2025-07-01 12:15:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751346957 101
Jul 01 12:15:58 volumio3 volumio[977]: 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: 1
Jul 01 12:15:58 volumio3 volumio[977]: info: Reloading queue from file
Jul 01 12:15:58 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:15:58 volumio3 volumio[977]: info: CoreStateMachine::setRepeat null single undefined
Jul 01 12:15:58 volumio3 volumio[977]: info: CoreStateMachine::pushState
Jul 01 12:15:58 volumio3 volumio[977]: info: CorePlayQueue::getTrack 0
Jul 01 12:15:58 volumio3 volumio[977]: info: CoreCommandRouter::volumioPushState
Jul 01 12:15:58 volumio3 volumio[977]: info: CoreStateMachine::setRandom null
Jul 01 12:15:58 volumio3 volumio[977]: info: CoreStateMachine::pushState
Jul 01 12:15:58 volumio3 volumio[977]: info: CorePlayQueue::getTrack 0
Jul 01 12:15:58 volumio3 volumio[977]: info: CoreCommandRouter::volumioPushState
Jul 01 12:15:58 volumio3 volumio[977]: info: Setting Device type: Raspberry PI
Jul 01 12:15:58 volumio3 volumio[977]: info: Discovery: adding ae3f5564-92b3-4b69-8c58-23b428e78784
Jul 01 12:15:58 volumio3 volumio[977]: info: Discovery: Found device Volumio3
Jul 01 12:15:58 volumio3 volumio[977]: info: CoreCommandRouter::volumioGetState
Jul 01 12:15:58 volumio3 volumio[977]: info: CorePlayQueue::getTrack 0
Jul 01 12:15:58 volumio3 volumio[977]: Starting albumart workers
Jul 01 12:15:58 volumio3 volumio[977]: Starting albumart workers
Jul 01 12:15:58 volumio3 volumio[977]: Starting albumart workers
Jul 01 12:16:01 volumio3 sudo[1127]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:01 volumio3 volumio[977]: info: Cannot mount NAS H99 at system boot, trial number 1 ,retrying in 5 seconds
Jul 01 12:16:01 volumio3 volumio[977]: info: Completed loading Core Plugins
Jul 01 12:16:01 volumio3 kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Jul 01 12:16:01 volumio3 kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Jul 01 12:16:01 volumio3 volumio[977]: info: Preparing to generate the ALSA configuration file
Jul 01 12:16:01 volumio3 volumio[977]: info: Asound.conf file unchanged, so no further update is needed
Jul 01 12:16:01 volumio3 volumio[977]: info: Output device has changed, restarting MPD
Jul 01 12:16:01 volumio3 volumio[977]: info: Output device has changed, restarting Shairport Sync
Jul 01 12:16:01 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:01 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:01 volumio3 sudo[1237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jul 01 12:16:01 volumio3 sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:01 volumio3 sudo[1237]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:01 volumio3 sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jul 01 12:16:01 volumio3 sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:01 volumio3 systemd[1]: Stopping Music Player Daemon...
Jul 01 12:16:01 volumio3 volumio[977]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jul 01 12:16:01 volumio3 volumio[977]: info: ___________ START PLUGINS ___________
Jul 01 12:16:01 volumio3 volumio[977]: info: ControllerMpd::onStart: Initializing MPD
Jul 01 12:16:01 volumio3 volumio[977]: info: Creating MPD Configuration file
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jul 01 12:16:02 volumio3 volumio[977]: info: [1751346962007] CoreMusicLibrary::Adding element Media Servers
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 01 12:16:02 volumio3 sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jul 01 12:16:02 volumio3 sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:02 volumio3 sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jul 01 12:16:02 volumio3 sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:02 volumio3 sudo[1246]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:02 volumio3 systemd[1]: mpd.service: Succeeded.
Jul 01 12:16:02 volumio3 systemd[1]: Stopped Music Player Daemon.
Jul 01 12:16:02 volumio3 volumio[977]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jul 01 12:16:02 volumio3 volumio[977]: info: [1751346962174] CoreMusicLibrary::Adding element Last_100
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jul 01 12:16:02 volumio3 volumio[977]: info: [1751346962177] CoreMusicLibrary::Adding element Webradio
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 01 12:16:02 volumio3 systemd[1]: Starting Music Player Daemon...
Jul 01 12:16:02 volumio3 systemd[1]: systemd-fsckd.service: Succeeded.
Jul 01 12:16:02 volumio3 volumio[977]: info: Initializing BBC Radios
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: Volumio Calling Home
Jul 01 12:16:02 volumio3 sudo[1255]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jul 01 12:16:02 volumio3 sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:02 volumio3 sudo[1255]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:02 volumio3 volumio[977]: info: MPD Permissions set
Jul 01 12:16:02 volumio3 volumio[977]: info: MPD Permissions set
Jul 01 12:16:02 volumio3 volumio[977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:02 volumio3 volumio[977]: info: CoreCommandRouter::volumioGetState
Jul 01 12:16:02 volumio3 volumio[977]: info: CorePlayQueue::getTrack 0
Jul 01 12:16:02 volumio3 volumio[977]: info: Starting Shairport Sync
Jul 01 12:16:02 volumio3 volumio[977]: info: Starting Shairport Sync
Jul 01 12:16:02 volumio3 volumio[977]: info: Starting Shairport Sync
Jul 01 12:16:02 volumio3 sudo[1282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jul 01 12:16:02 volumio3 sudo[1282]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:02 volumio3 sudo[1284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jul 01 12:16:02 volumio3 sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:02 volumio3 sudo[1287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jul 01 12:16:02 volumio3 sudo[1287]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:02 volumio3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jul 01 12:16:02 volumio3 systemd[1]: shairport-sync.service: Succeeded.
Jul 01 12:16:02 volumio3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jul 01 12:16:02 volumio3 volumio[977]: info: Volumio called home
Jul 01 12:16:02 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jul 01 12:16:02 volumio3 sudo[1282]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:02 volumio3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jul 01 12:16:02 volumio3 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Jul 01 12:16:02 volumio3 systemd[1]: shairport-sync.service: Succeeded.
Jul 01 12:16:02 volumio3 volumio[977]: info: Shairport-Sync Started
Jul 01 12:16:02 volumio3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jul 01 12:16:02 volumio3 volumio[977]: Error adding Membership: Error: addMembership EINVAL
Jul 01 12:16:02 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jul 01 12:16:02 volumio3 sudo[1287]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:03 volumio3 sudo[1284]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:03 volumio3 volumio[977]: info: Shairport-Sync Started
Jul 01 12:16:03 volumio3 volumio[977]: info: Shairport-Sync Started
Jul 01 12:16:03 volumio3 sh[513]: timed out
Jul 01 12:16:03 volumio3 dhcpcd[546]: timed out
Jul 01 12:16:03 volumio3 sh[513]: dhcpcd exited
Jul 01 12:16:03 volumio3 dhcpcd[546]: dhcpcd exited
Jul 01 12:16:03 volumio3 sh[513]: ifup: failed to bring up eth0
Jul 01 12:16:03 volumio3 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE
Jul 01 12:16:03 volumio3 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'.
Jul 01 12:16:03 volumio3 mpd[1272]: Jul 01 12:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jul 01 12:16:04 volumio3 systemd[1]: Started Music Player Daemon.
Jul 01 12:16:04 volumio3 sudo[1248]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:04 volumio3 sudo[1239]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:04 volumio3 volumio[977]: info: Completed starting Core Plugins
Jul 01 12:16:04 volumio3 volumio[977]: info: -------------------------------------------
Jul 01 12:16:04 volumio3 volumio[977]: info: ----- MyVolumio plugins startup ----
Jul 01 12:16:04 volumio3 volumio[977]: info: -------------------------------------------
Jul 01 12:16:04 volumio3 volumio[977]: info: [MyVolumio PluginManager] Fetching plans data....
Jul 01 12:16:04 volumio3 volumio[977]: error: MPD error: The expression evaluated to a falsy value:
Jul 01 12:16:04 volumio3 volumio[977]: assert.ok(self.idling)
Jul 01 12:16:04 volumio3 volumio[977]: error: The expression evaluated to a falsy value:
Jul 01 12:16:04 volumio3 volumio[977]: assert.ok(self.idling)
Jul 01 12:16:04 volumio3 volumio[977]: error: updateQueue error: null
Jul 01 12:16:04 volumio3 volumio[977]: info: MPD running with PID1272
Jul 01 12:16:04 volumio3 volumio[977]: ,establishing connection
Jul 01 12:16:04 volumio3 volumio[977]: error: updateQueue error: null
Jul 01 12:16:05 volumio3 sudo[1315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 01 12:16:05 volumio3 sudo[1315]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:05 volumio3 sudo[1315]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:05 volumio3 sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 01 12:16:05 volumio3 sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:05 volumio3 sudo[1317]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:05 volumio3 sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Jul 01 12:16:05 volumio3 sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:05 volumio3 systemd[1]: Started UPnP Renderer front-end to MPD.
Jul 01 12:16:05 volumio3 sudo[1321]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:05 volumio3 volumio[977]: info: Upmpdcli Daemon Started
Jul 01 12:16:05 volumio3 volumio[1323]: Generating RSA private key, 4096 bit long modulus (2 primes)
Jul 01 12:16:06 volumio3 systemd[1]: systemd-hostnamed.service: Succeeded.
Jul 01 12:16:06 volumio3 sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=987400318,password=2106,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.6.59/987400318_Home1 /mnt/NAS/H99
Jul 01 12:16:06 volumio3 sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:06 volumio3 kernel: CIFS: Attempting to mount //192.168.6.59/987400318_Home1
Jul 01 12:16:10 volumio3 volumio[977]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Jul 01 12:16:10 volumio3 volumio[1323]: ...................................++++
Jul 01 12:16:13 volumio3 kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Jul 01 12:16:13 volumio3 kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Jul 01 12:16:13 volumio3 sudo[1343]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:13 volumio3 volumio[977]: info: Cannot mount NAS H99 at system boot, trial number 2 ,retrying in 5 seconds
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Jul 01 12:16:14 volumio3 volumio[977]: info: Adding plugin bluetooth to MyMusic Plugins
Jul 01 12:16:14 volumio3 volumio[977]: info: Adding plugin multiroom to MyMusic Plugins
Jul 01 12:16:14 volumio3 volumio[977]: info: Adding plugin metavolumio to MyMusic Plugins
Jul 01 12:16:14 volumio3 volumio[977]: info: Adding plugin cd_controller to MyMusic Plugins
Jul 01 12:16:14 volumio3 volumio[977]: info: Adding plugin smart_inputs to MyMusic Plugins
Jul 01 12:16:14 volumio3 volumio[977]: info: Adding plugin tidalconnect to MyMusic Plugins
Jul 01 12:16:14 volumio3 volumio[977]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Jul 01 12:16:16 volumio3 volumio[977]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Jul 01 12:16:16 volumio3 volumio[977]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Jul 01 12:16:16 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:16 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:16 volumio3 volumio[977]: info: Starting MyVolumio Remote Streaming Endpoints
Jul 01 12:16:16 volumio3 volumio[977]: info: MyVolumio login type: Token
Jul 01 12:16:16 volumio3 volumio[977]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Jul 01 12:16:16 volumio3 volumio[977]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Jul 01 12:16:17 volumio3 volumio[977]: info: Starting Streaming Service Transparent Proxy
Jul 01 12:16:17 volumio3 volumio[977]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Jul 01 12:16:17 volumio3 volumio[977]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Jul 01 12:16:17 volumio3 volumio[977]: info: Streaming services startup
Jul 01 12:16:17 volumio3 volumio[977]: info: Starting Streaming Daemon
Jul 01 12:16:17 volumio3 sudo[1372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jul 01 12:16:17 volumio3 sudo[1372]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:17 volumio3 sudo[1372]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:17 volumio3 volumio[977]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Jul 01 12:16:18 volumio3 volumio[977]: error: Cannot start Volumio Streaming Daemon
Jul 01 12:16:18 volumio3 volumio[977]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jul 01 12:16:18 volumio3 volumio[977]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jul 01 12:16:18 volumio3 sudo[1380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=987400318,password=2106,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.6.59/987400318_Home1 /mnt/NAS/H99
Jul 01 12:16:18 volumio3 sudo[1380]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:18 volumio3 kernel: CIFS: Attempting to mount //192.168.6.59/987400318_Home1
Jul 01 12:16:19 volumio3 volumio[1323]: .............................................................++++
Jul 01 12:16:19 volumio3 volumio[1323]: e is 65537 (0x010001)
Jul 01 12:16:19 volumio3 volumio[1323]: writing RSA key
Jul 01 12:16:19 volumio3 volumio[977]: STREAMING PROXY: Starting server on port 3245
Jul 01 12:16:19 volumio3 volumio[977]: Node JS runtime: 14
Jul 01 12:16:19 volumio3 volumio[977]: error: MyVolumio Custom Token format not valid, refreshing it
Jul 01 12:16:21 volumio3 systemd[1]: systemd-timedated.service: Succeeded.
Jul 01 12:16:24 volumio3 sudo[1380]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:24 volumio3 kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Jul 01 12:16:24 volumio3 kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Jul 01 12:16:27 volumio3 volumio[977]: info: MyVolumio login type: Token
Jul 01 12:16:27 volumio3 volumio[977]: info: Cannot mount NAS H99 at system boot, trial number 3 ,retrying in 5 seconds
Jul 01 12:16:27 volumio3 sudo[1419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 01 12:16:28 volumio3 sudo[1419]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:28 volumio3 sudo[1419]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:28 volumio3 sudo[1421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 01 12:16:28 volumio3 sudo[1421]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:28 volumio3 sudo[1421]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:28 volumio3 volumio[977]: verbose: New Socket.io Connection to 192.168.6.144 from 192.168.6.252 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3
Jul 01 12:16:28 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jul 01 12:16:28 volumio3 volumio[977]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 01 12:16:28 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 01 12:16:28 volumio3 volumio[977]: info: CoreCommandRouter::volumioGetState
Jul 01 12:16:28 volumio3 volumio[977]: info: CorePlayQueue::getTrack 0
Jul 01 12:16:28 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jul 01 12:16:28 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jul 01 12:16:28 volumio3 volumio[977]: info: Received Get System Info
Jul 01 12:16:28 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 01 12:16:28 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 01 12:16:28 volumio3 volumio[977]: info: Discovery: Getting this device information
Jul 01 12:16:28 volumio3 volumio[977]: info: CoreCommandRouter::volumioGetState
Jul 01 12:16:28 volumio3 volumio[977]: info: CorePlayQueue::getTrack 0
Jul 01 12:16:28 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 01 12:16:28 volumio3 volumio[977]: info: CoreCommandRouter::volumioGetState
Jul 01 12:16:28 volumio3 volumio[977]: info: CorePlayQueue::getTrack 0
Jul 01 12:16:28 volumio3 volumio[977]: info: Listing playlists
Jul 01 12:16:28 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jul 01 12:16:28 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 01 12:16:28 volumio3 volumio[977]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Jul 01 12:16:28 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jul 01 12:16:29 volumio3 volumio[977]: info: CoreCommandRouter::volumioGetState
Jul 01 12:16:29 volumio3 volumio[977]: info: CorePlayQueue::getTrack 0
Jul 01 12:16:29 volumio3 volumio[977]: info: MyVolumio token set successfully
Jul 01 12:16:29 volumio3 volumio[977]: info: MYVOLUMIO: Adding device
Jul 01 12:16:29 volumio3 volumio[977]: info: MYVOLUMIO: Evaluating Server
Jul 01 12:16:30 volumio3 volumio[977]: info: MyVolumio status changed
Jul 01 12:16:30 volumio3 volumio[977]: info: Streaming services startup
Jul 01 12:16:30 volumio3 volumio[977]: info: Starting Streaming Daemon
Jul 01 12:16:30 volumio3 volumio[977]: info: Removing browser output: myVolumio user plan is not superstar
Jul 01 12:16:30 volumio3 volumio[977]: info: Removing audio output:
Jul 01 12:16:30 volumio3 volumio[977]: info: Stoppping Tunnel 1
Jul 01 12:16:30 volumio3 sudo[1442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jul 01 12:16:30 volumio3 sudo[1442]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:30 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Jul 01 12:16:30 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 01 12:16:30 volumio3 volumio[977]: info: Received Get System Info
Jul 01 12:16:30 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 01 12:16:30 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 01 12:16:30 volumio3 volumio[977]: info: Discovery: Getting this device information
Jul 01 12:16:30 volumio3 volumio[977]: info: CoreCommandRouter::volumioGetState
Jul 01 12:16:30 volumio3 sudo[1442]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:30 volumio3 volumio[977]: info: CorePlayQueue::getTrack 0
Jul 01 12:16:30 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 01 12:16:30 volumio3 sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Jul 01 12:16:30 volumio3 sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:30 volumio3 volumio[977]: error: Cannot start Volumio Streaming Daemon
Jul 01 12:16:30 volumio3 volumio[977]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jul 01 12:16:30 volumio3 volumio[977]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jul 01 12:16:30 volumio3 sudo[1445]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:30 volumio3 volumio[977]: info: Remote SSH Stopped
Jul 01 12:16:30 volumio3 volumio[977]: info: Setting Geolocation for MyVolumio to as1
Jul 01 12:16:30 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:31 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 01 12:16:31 volumio3 volumio[977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Jul 01 12:16:31 volumio3 volumio[977]: info: CoreCommandRouter::volumioGetState
Jul 01 12:16:31 volumio3 volumio[977]: info: CorePlayQueue::getTrack 0
Jul 01 12:16:31 volumio3 volumio[977]: info: CoreCommandRouter::volumioGetQueue
Jul 01 12:16:31 volumio3 volumio[977]: info: CoreStateMachine::getQueue
Jul 01 12:16:31 volumio3 volumio[977]: info: CorePlayQueue::getQueue
Jul 01 12:16:31 volumio3 volumio[977]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jul 01 12:16:31 volumio3 volumio[977]: info: Updating MyVolumio device info
Jul 01 12:16:31 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:31 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:31 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:32 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 01 12:16:32 volumio3 volumio[977]: info: Received Get System Info
Jul 01 12:16:32 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 01 12:16:32 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 01 12:16:32 volumio3 volumio[977]: info: Discovery: Getting this device information
Jul 01 12:16:32 volumio3 volumio[977]: info: CoreCommandRouter::volumioGetState
Jul 01 12:16:32 volumio3 volumio[977]: info: CorePlayQueue::getTrack 0
Jul 01 12:16:32 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 01 12:16:32 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 01 12:16:32 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:32 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jul 01 12:16:32 volumio3 volumio[977]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jul 01 12:16:32 volumio3 volumio-remote-updater[608]: No test mode
Jul 01 12:16:32 volumio3 volumio-remote-updater[608]: No alpha test mode
Jul 01 12:16:32 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jul 01 12:16:32 volumio3 sudo[1454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=987400318,password=2106,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.6.59/987400318_Home1 /mnt/NAS/H99
Jul 01 12:16:32 volumio3 sudo[1454]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:32 volumio3 kernel: CIFS: Attempting to mount //192.168.6.59/987400318_Home1
Jul 01 12:16:33 volumio3 volumio[977]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Various Fixes for QOBUZ Connect
- Various Fixes for Tidal Connect
","title":"Update v3.816","updateavailable":true}
Jul 01 12:16:33 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jul 01 12:16:34 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 01 12:16:34 volumio3 volumio[977]: info: Preload queue cleared
Jul 01 12:16:35 volumio3 volumio[977]: info: MYVOLUMIO: Adding device
Jul 01 12:16:35 volumio3 volumio[977]: info: MYVOLUMIO: Evaluating Server
Jul 01 12:16:35 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 01 12:16:36 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:36 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:36 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jul 01 12:16:36 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jul 01 12:16:36 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jul 01 12:16:36 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jul 01 12:16:36 volumio3 volumio[977]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 01 12:16:36 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 01 12:16:36 volumio3 volumio[977]: info: Preload queue cleared
Jul 01 12:16:36 volumio3 volumio[977]: info: Setting Geolocation for MyVolumio to as1
Jul 01 12:16:36 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:36 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:36 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:37 volumio3 volumio[977]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jul 01 12:16:37 volumio3 volumio[977]: info: Updating MyVolumio device info
Jul 01 12:16:37 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:37 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:37 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:37 volumio3 volumio[977]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Jul 01 12:16:37 volumio3 volumio[977]: info: Completed starting MyVolumio Plugin
Jul 01 12:16:37 volumio3 volumio[977]: [Metrics] CommandRouter: 47s 72.25ms
Jul 01 12:16:37 volumio3 volumio[977]: info: CoreCommandRouter::volumiosetStartupVolume
Jul 01 12:16:37 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 01 12:16:37 volumio3 volumio[977]: info: VolumeController:: Setting startup Volume 100
Jul 01 12:16:37 volumio3 volumio[977]: info: VolumeController::SetAlsaVolume100
Jul 01 12:16:37 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 01 12:16:37 volumio3 volumio[977]: info: CoreCommandRouter::Close All Modals sent
Jul 01 12:16:37 volumio3 volumio[977]: info: CoreCommandRouter::Close All Modals sent
Jul 01 12:16:37 volumio3 volumio[977]: info: CoreStateMachine::pushState
Jul 01 12:16:37 volumio3 volumio[977]: info: CorePlayQueue::getTrack 0
Jul 01 12:16:37 volumio3 volumio[977]: info: CoreCommandRouter::volumioPushState
Jul 01 12:16:37 volumio3 volumio[977]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jul 01 12:16:38 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Jul 01 12:16:38 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jul 01 12:16:38 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 01 12:16:38 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jul 01 12:16:38 volumio3 sudo[1454]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:38 volumio3 volumio[977]: info: Cannot mount NAS H99 at system boot, trial number 4 ,retrying in 5 seconds
Jul 01 12:16:38 volumio3 kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Jul 01 12:16:38 volumio3 kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Jul 01 12:16:39 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 01 12:16:39 volumio3 volumio[977]: info: Preload queue cleared
Jul 01 12:16:42 volumio3 ntpd[799]: Soliciting pool server 115.165.161.155
Jul 01 12:16:42 volumio3 ntpd[799]: Soliciting pool server 27.71.27.209
Jul 01 12:16:43 volumio3 ntpd[799]: Soliciting pool server 115.165.161.155
Jul 01 12:16:43 volumio3 ntpd[799]: Soliciting pool server 103.199.19.135
Jul 01 12:16:43 volumio3 sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=987400318,password=2106,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.6.59/987400318_Home1 /mnt/NAS/H99
Jul 01 12:16:43 volumio3 sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 01 12:16:43 volumio3 kernel: CIFS: Attempting to mount //192.168.6.59/987400318_Home1
Jul 01 12:16:44 volumio3 ntpd[799]: Soliciting pool server 103.184.124.254
Jul 01 12:16:44 volumio3 ntpd[799]: Soliciting pool server 115.165.161.155
Jul 01 12:16:44 volumio3 volumio[977]: info: BOOT COMPLETED
Jul 01 12:16:46 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 01 12:16:46 volumio3 volumio[977]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Jul 01 12:16:46 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Jul 01 12:16:46 volumio3 volumio[977]: info: Received Get System Version
Jul 01 12:16:46 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 01 12:16:46 volumio3 volumio[977]: info: Received Get System Info
Jul 01 12:16:46 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 01 12:16:46 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 01 12:16:46 volumio3 volumio[977]: info: Discovery: Getting this device information
Jul 01 12:16:46 volumio3 volumio[977]: info: CoreCommandRouter::volumioGetState
Jul 01 12:16:46 volumio3 volumio[977]: info: CorePlayQueue::getTrack 0
Jul 01 12:16:46 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 01 12:16:49 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 01 12:16:49 volumio3 volumio[977]: info: Preload queue cleared
Jul 01 12:16:51 volumio3 sudo[1529]: pam_unix(sudo:session): session closed for user root
Jul 01 12:16:51 volumio3 volumio[977]: info: Cannot mount NAS at system boot, trial number 4 ,stopping
Jul 01 12:16:51 volumio3 kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Jul 01 12:16:51 volumio3 kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Jul 01 12:16:51 volumio3 volumio[977]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 01 12:16:51 volumio3 volumio[977]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 01 12:16:51 volumio3 volumio[977]: TypeError: Cannot read property 'length' of undefined
Jul 01 12:16:51 volumio3 volumio[977]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25
Jul 01 12:16:51 volumio3 volumio[977]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13
Jul 01 12:16:51 volumio3 volumio[977]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18)
Jul 01 12:16:51 volumio3 volumio[977]: at Parser.emit (events.js:315:20)
Jul 01 12:16:51 volumio3 volumio[977]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26)
Jul 01 12:16:51 volumio3 volumio[977]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35)
Jul 01 12:16:51 volumio3 volumio[977]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5)
Jul 01 12:16:51 volumio3 volumio[977]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7)
Jul 01 12:16:51 volumio3 volumio[977]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13)
Jul 01 12:16:51 volumio3 volumio[977]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31)
Jul 01 12:16:51 volumio3 volumio[977]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59)
Jul 01 12:16:51 volumio3 volumio[977]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19)
Jul 01 12:16:51 volumio3 volumio[977]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15)
Jul 01 12:16:51 volumio3 volumio[977]: at IncomingMessage.emit (events.js:327:22)
Jul 01 12:16:51 volumio3 volumio[977]: at endReadableNT (internal/streams/readable.js:1327:12)
Jul 01 12:16:51 volumio3 volumio[977]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Jul 01 12:16:51 volumio3 volumio[977]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 01 12:16:52 volumio3 sudo[1559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-01 12:15
Jul 01 12:16:52 volumio3 sudo[1559]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET"
VOLUMIO_VERSION="3.785"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"