-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Tue 2025-05-20 10:08:37 BST. -- May 20 10:07:01 stormforce volumio[1132]: info: ------------------------------------------- May 20 10:07:01 stormforce volumio[1132]: info: ----- Volumio3 ---- May 20 10:07:01 stormforce volumio[1132]: info: ------------------------------------------- May 20 10:07:01 stormforce volumio[1132]: info: ----- System startup ---- May 20 10:07:01 stormforce volumio[1132]: info: ------------------------------------------- May 20 10:07:02 stormforce volumio[1132]: info: MYVOLUMIO Environment detected May 20 10:07:02 stormforce volumio[1132]: info: Plugin folders cleanup May 20 10:07:02 stormforce volumio[1132]: info: Scanning into folder /volumio/app/plugins/ May 20 10:07:02 stormforce volumio[1132]: info: Scanning category audio_interface May 20 10:07:02 stormforce volumio[1132]: info: Scanning category miscellanea May 20 10:07:02 stormforce volumio[1132]: info: Scanning category music_service May 20 10:07:02 stormforce volumio[1132]: info: Scanning category plugins.json May 20 10:07:02 stormforce volumio[1132]: info: Scanning category system_controller May 20 10:07:02 stormforce volumio[1132]: info: Scanning category user_interface May 20 10:07:02 stormforce volumio[1132]: info: Scanning into folder /data/plugins/ May 20 10:07:02 stormforce volumio[1132]: info: Plugin folders cleanup completed May 20 10:07:02 stormforce volumio[1132]: info: ------------------------------------------- May 20 10:07:02 stormforce volumio[1132]: info: ----- Core plugins startup ---- May 20 10:07:02 stormforce volumio[1132]: info: ------------------------------------------- May 20 10:07:02 stormforce volumio[1132]: info: Loading plugins from folder /volumio/app/plugins/ May 20 10:07:02 stormforce volumio[1132]: info: Adding plugin upnp to MyMusic Plugins May 20 10:07:02 stormforce volumio[1132]: info: Adding plugin airplay_emulation to MyMusic Plugins May 20 10:07:02 stormforce volumio[1132]: info: Adding plugin upnp_browser to MyMusic Plugins May 20 10:07:02 stormforce volumio[1132]: info: Loading plugins from folder /data/plugins/ May 20 10:07:02 stormforce volumio[1132]: info: Loading plugin "system"... May 20 10:07:02 stormforce volumio[1132]: info: Loading plugin "appearance"... May 20 10:07:04 stormforce volumio[1132]: info: Loading plugin "network"... May 20 10:07:04 stormforce volumio[1132]: info: Refreshing Cached IP Addresses May 20 10:07:04 stormforce sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 20 10:07:04 stormforce sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:04 stormforce sudo[1164]: pam_unix(sudo:session): session closed for user root May 20 10:07:04 stormforce sudo[1166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 20 10:07:04 stormforce sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:04 stormforce sudo[1166]: pam_unix(sudo:session): session closed for user root May 20 10:07:04 stormforce volumio[1132]: info: Loading plugin "services"... May 20 10:07:04 stormforce volumio[1132]: info: Loading plugin "alsa_controller"... May 20 10:07:04 stormforce sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 20 10:07:04 stormforce sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:04 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 10:07:04 stormforce volumio[1132]: info: Loading plugin "wizard"... May 20 10:07:04 stormforce volumio[1132]: info: Loading plugin "networkfs"... May 20 10:07:04 stormforce volumio[1132]: info: Starting Udev Watcher for removable devices May 20 10:07:04 stormforce sudo[1190]: 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.114/Triumph 2 /mnt/NAS/Synology_Drive May 20 10:07:04 stormforce sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:04 stormforce volumio[1132]: info: Ignoring mount for partition: boot May 20 10:07:04 stormforce volumio[1132]: info: Ignoring mount for partition: volumio May 20 10:07:04 stormforce volumio[1132]: info: Ignoring mount for partition: volumio_data May 20 10:07:04 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 10:07:04 stormforce volumio[1132]: info: Loading plugin "volumio_command_line_client"... May 20 10:07:04 stormforce volumio[1132]: info: Loading plugin "upnp"... May 20 10:07:04 stormforce volumio[1132]: info: [1747732024709] Starting Upmpd Daemon May 20 10:07:04 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 10:07:04 stormforce volumio[1132]: info: Loading plugin "my_music"... May 20 10:07:04 stormforce volumio[1132]: info: Loading plugin "mpd"... May 20 10:07:04 stormforce kernel: Key type cifs.spnego registered May 20 10:07:04 stormforce kernel: Key type cifs.idmap registered May 20 10:07:04 stormforce kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. May 20 10:07:04 stormforce kernel: CIFS: Attempting to mount //192.168.1.114/Triumph 2 May 20 10:07:05 stormforce volumio[1132]: info: Loading plugin "upnp_browser"... May 20 10:07:05 stormforce sudo[1175]: pam_unix(sudo:session): session closed for user root May 20 10:07:05 stormforce volumio[1132]: info: Loading plugin "alarm-clock"... May 20 10:07:05 stormforce volumio[1132]: info: Loading plugin "airplay_emulation"... May 20 10:07:05 stormforce volumio[1132]: info: Starting Shairport Sync May 20 10:07:05 stormforce volumio[1132]: info: Loading plugin "last_100"... May 20 10:07:05 stormforce volumio[1132]: info: Loading plugin "webradio"... May 20 10:07:06 stormforce volumio[1132]: info: Loading plugin "i2s_dacs"... May 20 10:07:06 stormforce volumio[1132]: info: I2S DAC not set, start Auto-detection May 20 10:07:06 stormforce volumio[1132]: info: Loading plugin "volumiodiscovery"... May 20 10:07:06 stormforce volumio[1132]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 10:07:06 stormforce volumio[1132]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 10:07:06 stormforce volumio[1132]: *** WARNING *** For more information see May 20 10:07:06 stormforce volumio[1132]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 10:07:06 stormforce volumio[1132]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 10:07:06 stormforce node[1132]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 10:07:06 stormforce volumio[1132]: *** WARNING *** For more information see May 20 10:07:06 stormforce node[1132]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 10:07:06 stormforce node[1132]: *** WARNING *** For more information see May 20 10:07:06 stormforce node[1132]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 10:07:06 stormforce node[1132]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 10:07:06 stormforce node[1132]: *** WARNING *** For more information see May 20 10:07:06 stormforce volumio[1132]: info: Applying required configuration parameters for plugin volumiodiscovery May 20 10:07:06 stormforce volumio[1132]: info: Discovery: Started advertising with name: Stormforce May 20 10:07:06 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 10:07:06 stormforce volumio[1132]: info: Loading plugin "outputs"... May 20 10:07:06 stormforce volumio[1132]: info: Loading plugin "albumart"... May 20 10:07:07 stormforce volumio[1132]: info: Plugin example_plugin is not enabled May 20 10:07:07 stormforce volumio[1132]: info: Loading plugin "inputs"... May 20 10:07:07 stormforce volumio[1132]: info: Loading plugin "updater_comm"... May 20 10:07:07 stormforce volumio[1132]: info: Plugin mpdemulation is not enabled May 20 10:07:07 stormforce volumio[1132]: info: Loading plugin "rest_api"... May 20 10:07:07 stormforce volumio[1132]: info: Loading plugin "websocket"... May 20 10:07:07 stormforce volumio[1132]: info: Starting Socket.io Server version 2.3.0 May 20 10:07:07 stormforce volumio[1132]: info: Loading i18n strings for locale en May 20 10:07:07 stormforce volumio[1132]: Forking 3 albumart workers May 20 10:07:07 stormforce volumio[1132]: Updating browse sources language May 20 10:07:07 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 10:07:07 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 10:07:07 stormforce volumio[1132]: info: CoreCommandRouter::initPlayerControls May 20 10:07:07 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:07 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 10:07:07 stormforce volumio[1132]: Express server listening on port 3000 May 20 10:07:07 stormforce volumio[1132]: [Metrics] WebUI: 7s 801.52ms May 20 10:07:07 stormforce volumio[1132]: info: CoreStateMachine::resetVolumioState May 20 10:07:07 stormforce volumio[1132]: info: CoreStateMachine::getcurrentVolume May 20 10:07:07 stormforce volumio[1132]: info: CoreCommandRouter::volumioRetrievevolume May 20 10:07:07 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 10:07:08 stormforce volumio[1132]: info: Volumio Network Manager: Network status updated: 3 May 20 10:07:08 stormforce volumio[1132]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 20 10:07:08 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 20 10:07:08 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 20 10:07:08 stormforce volumio[1132]: info: Discovery: Getting this device information May 20 10:07:08 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetState May 20 10:07:08 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:08 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 20 10:07:08 stormforce volumio[1132]: info: VolumeController:: Volume=undefined Mute =false May 20 10:07:08 stormforce volumio[1132]: info: CoreStateMachine::pushState May 20 10:07:08 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:08 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 10:07:08 stormforce volumio-remote-updater[625]: [2025-05-20 10:07:08] [connect] Successful connection May 20 10:07:08 stormforce volumio[1132]: info: CoreCommandRouter::volumioPushState May 20 10:07:08 stormforce volumio[1132]: info: CoreStateMachine::updateTrackBlock May 20 10:07:08 stormforce volumio[1132]: info: CorePlayQueue::getTrackBlock May 20 10:07:08 stormforce volumio[1132]: info: CoreCommandRouter::volumioRetrievevolume May 20 10:07:08 stormforce volumio[1132]: info: Setting Device type: Raspberry PI May 20 10:07:08 stormforce volumio[1132]: info: Reloading queue from file May 20 10:07:08 stormforce volumio[1132]: info: CoreStateMachine::setRepeat false single undefined May 20 10:07:08 stormforce volumio[1132]: info: CoreStateMachine::pushState May 20 10:07:08 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:08 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 10:07:08 stormforce volumio[1132]: info: CoreCommandRouter::volumioPushState May 20 10:07:08 stormforce volumio[1132]: info: CoreStateMachine::setRandom null May 20 10:07:08 stormforce volumio[1132]: info: CoreStateMachine::pushState May 20 10:07:08 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:08 stormforce volumio[1132]: info: CoreCommandRouter::volumioPushState May 20 10:07:08 stormforce volumio-remote-updater[625]: [2025-05-20 10:07:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747732028 101 May 20 10:07:08 stormforce volumio[1132]: 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 May 20 10:07:08 stormforce volumio[1132]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 20 10:07:08 stormforce volumio[1132]: info: Discovery: adding 1786a1a6-5d33-4e47-a4bd-f3ba86f9d2d8 May 20 10:07:08 stormforce volumio[1132]: info: Discovery: Found device Stormforce May 20 10:07:08 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetState May 20 10:07:08 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:08 stormforce volumio[1132]: Starting albumart workers May 20 10:07:08 stormforce volumio[1132]: Starting albumart workers May 20 10:07:08 stormforce volumio[1132]: Starting albumart workers May 20 10:07:08 stormforce volumio[1132]: verbose: New Socket.io Connection to 192.168.1.94:3000 from 192.168.1.125 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 May 20 10:07:08 stormforce volumio[1132]: info: Discovery: this is already registered, 1786a1a6-5d33-4e47-a4bd-f3ba86f9d2d8 May 20 10:07:08 stormforce volumio[1132]: info: Discovery: Found device Stormforce May 20 10:07:08 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetState May 20 10:07:08 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:08 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetState May 20 10:07:08 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:08 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 20 10:07:08 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 20 10:07:08 stormforce volumio[1132]: info: VolumeController:: Volume=undefined Mute =false May 20 10:07:08 stormforce volumio[1132]: info: CoreStateMachine::pushState May 20 10:07:08 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:08 stormforce volumio[1132]: info: CoreCommandRouter::volumioPushState May 20 10:07:11 stormforce sudo[1190]: pam_unix(sudo:session): session closed for user root May 20 10:07:11 stormforce volumio[1132]: info: Cannot mount NAS Synology_Drive at system boot, trial number 1 ,retrying in 5 seconds May 20 10:07:11 stormforce volumio[1132]: info: Completed loading Core Plugins May 20 10:07:11 stormforce kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 20 10:07:11 stormforce kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 20 10:07:11 stormforce volumio[1132]: info: Preparing to generate the ALSA configuration file May 20 10:07:11 stormforce volumio[1132]: info: Asound.conf file unchanged, so no further update is needed May 20 10:07:11 stormforce volumio[1132]: info: Output device has changed, restarting MPD May 20 10:07:11 stormforce volumio[1132]: info: Output device has changed, restarting Shairport Sync May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 10:07:11 stormforce sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 10:07:11 stormforce sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:11 stormforce sudo[1272]: pam_unix(sudo:session): session closed for user root May 20 10:07:11 stormforce sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 10:07:11 stormforce sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:11 stormforce systemd[1]: Stopping Music Player Daemon... May 20 10:07:11 stormforce volumio[1132]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 20 10:07:11 stormforce volumio[1132]: info: ___________ START PLUGINS ___________ May 20 10:07:11 stormforce volumio[1132]: info: ControllerMpd::onStart: Initializing MPD May 20 10:07:11 stormforce volumio[1132]: info: Creating MPD Configuration file May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 10:07:11 stormforce volumio[1132]: info: [1747732031228] CoreMusicLibrary::Adding element Media Servers May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 10:07:11 stormforce sudo[1281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 10:07:11 stormforce sudo[1281]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:11 stormforce sudo[1281]: pam_unix(sudo:session): session closed for user root May 20 10:07:11 stormforce sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 10:07:11 stormforce sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 10:07:11 stormforce systemd[1]: mpd.service: Succeeded. May 20 10:07:11 stormforce systemd[1]: Stopped Music Player Daemon. May 20 10:07:11 stormforce volumio[1132]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 10:07:11 stormforce volumio[1132]: info: [1747732031384] CoreMusicLibrary::Adding element Last_100 May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 10:07:11 stormforce volumio[1132]: info: [1747732031387] CoreMusicLibrary::Adding element Webradio May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 10:07:11 stormforce volumio[1132]: info: Initializing BBC Radios May 20 10:07:11 stormforce systemd[1]: Starting Music Player Daemon... May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: Volumio Calling Home May 20 10:07:11 stormforce sudo[1292]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 20 10:07:11 stormforce sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:11 stormforce sudo[1292]: pam_unix(sudo:session): session closed for user root May 20 10:07:11 stormforce volumio[1132]: info: MPD Permissions set May 20 10:07:11 stormforce volumio[1132]: info: MPD Permissions set May 20 10:07:11 stormforce volumio[1132]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 20 10:07:11 stormforce volumio[1132]: info: Volumio called home May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:11 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetState May 20 10:07:11 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:11 stormforce volumio[1132]: info: Starting Shairport Sync May 20 10:07:11 stormforce volumio[1132]: info: Starting Shairport Sync May 20 10:07:11 stormforce volumio[1132]: info: Starting Shairport Sync May 20 10:07:11 stormforce sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 20 10:07:11 stormforce sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:12 stormforce sudo[1319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 20 10:07:12 stormforce sudo[1319]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:12 stormforce systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 20 10:07:12 stormforce systemd[1]: shairport-sync.service: Succeeded. May 20 10:07:12 stormforce systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 20 10:07:12 stormforce sudo[1322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 20 10:07:12 stormforce sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:12 stormforce systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 20 10:07:12 stormforce sudo[1317]: pam_unix(sudo:session): session closed for user root May 20 10:07:12 stormforce volumio[1132]: info: Shairport-Sync Started May 20 10:07:12 stormforce volumio[1132]: Error adding Membership: Error: addMembership EINVAL May 20 10:07:12 stormforce systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 20 10:07:12 stormforce systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 20 10:07:12 stormforce systemd[1]: shairport-sync.service: Succeeded. May 20 10:07:12 stormforce systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 20 10:07:12 stormforce systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 20 10:07:12 stormforce sudo[1322]: pam_unix(sudo:session): session closed for user root May 20 10:07:12 stormforce sudo[1319]: pam_unix(sudo:session): session closed for user root May 20 10:07:12 stormforce volumio[1132]: info: Shairport-Sync Started May 20 10:07:12 stormforce volumio[1132]: info: Shairport-Sync Started May 20 10:07:12 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 20 10:07:12 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 20 10:07:12 stormforce volumio[1132]: info: Discovery: Getting this device information May 20 10:07:12 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetState May 20 10:07:12 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:12 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 20 10:07:13 stormforce mpd[1307]: May 20 10:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 20 10:07:13 stormforce systemd[1]: Started Music Player Daemon. May 20 10:07:13 stormforce sudo[1274]: pam_unix(sudo:session): session closed for user root May 20 10:07:13 stormforce systemd[1]: systemd-fsckd.service: Succeeded. May 20 10:07:13 stormforce sudo[1283]: pam_unix(sudo:session): session closed for user root May 20 10:07:13 stormforce volumio[1132]: info: Completed starting Core Plugins May 20 10:07:13 stormforce volumio[1132]: info: ------------------------------------------- May 20 10:07:13 stormforce volumio[1132]: info: ----- MyVolumio plugins startup ---- May 20 10:07:13 stormforce volumio[1132]: info: ------------------------------------------- May 20 10:07:13 stormforce volumio[1132]: info: [MyVolumio PluginManager] Fetching plans data.... May 20 10:07:13 stormforce volumio[1132]: error: MPD error: The expression evaluated to a falsy value: May 20 10:07:13 stormforce volumio[1132]: assert.ok(self.idling) May 20 10:07:13 stormforce volumio[1132]: error: The expression evaluated to a falsy value: May 20 10:07:13 stormforce volumio[1132]: assert.ok(self.idling) May 20 10:07:13 stormforce volumio[1132]: error: updateQueue error: null May 20 10:07:13 stormforce volumio[1132]: info: MPD running with PID1307 May 20 10:07:13 stormforce volumio[1132]: ,establishing connection May 20 10:07:13 stormforce volumio[1132]: error: updateQueue error: null May 20 10:07:14 stormforce sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 20 10:07:14 stormforce sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:14 stormforce sudo[1352]: pam_unix(sudo:session): session closed for user root May 20 10:07:14 stormforce sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 20 10:07:14 stormforce sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:14 stormforce sudo[1354]: pam_unix(sudo:session): session closed for user root May 20 10:07:14 stormforce sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 20 10:07:14 stormforce sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:14 stormforce systemd[1]: Started UPnP Renderer front-end to MPD. May 20 10:07:14 stormforce sudo[1358]: pam_unix(sudo:session): session closed for user root May 20 10:07:14 stormforce volumio[1132]: info: Upmpdcli Daemon Started May 20 10:07:15 stormforce volumio[1360]: Generating RSA private key, 4096 bit long modulus (2 primes) May 20 10:07:16 stormforce sudo[1377]: 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.114/Triumph 2 /mnt/NAS/Synology_Drive May 20 10:07:16 stormforce sudo[1377]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:16 stormforce kernel: CIFS: Attempting to mount //192.168.1.114/Triumph 2 May 20 10:07:17 stormforce systemd[1]: systemd-hostnamed.service: Succeeded. May 20 10:07:20 stormforce volumio[1360]: ....................................++++ May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 20 10:07:21 stormforce volumio[1132]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 20 10:07:21 stormforce volumio[1132]: info: Adding plugin bluetooth to MyMusic Plugins May 20 10:07:21 stormforce volumio[1132]: info: Adding plugin multiroom to MyMusic Plugins May 20 10:07:21 stormforce volumio[1132]: info: Adding plugin metavolumio to MyMusic Plugins May 20 10:07:22 stormforce sudo[1377]: pam_unix(sudo:session): session closed for user root May 20 10:07:22 stormforce kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 20 10:07:22 stormforce kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 20 10:07:22 stormforce volumio[1132]: info: Adding plugin cd_controller to MyMusic Plugins May 20 10:07:22 stormforce volumio[1132]: info: Adding plugin qobuzconnect to MyMusic Plugins May 20 10:07:22 stormforce volumio[1132]: info: Adding plugin smart_inputs to MyMusic Plugins May 20 10:07:22 stormforce volumio[1132]: info: Adding plugin tidalconnect to MyMusic Plugins May 20 10:07:22 stormforce volumio[1132]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 20 10:07:24 stormforce volumio[1132]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 20 10:07:24 stormforce volumio[1132]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 20 10:07:24 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 10:07:24 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 10:07:24 stormforce volumio[1132]: info: Starting MyVolumio Remote Streaming Endpoints May 20 10:07:24 stormforce volumio[1132]: info: MyVolumio not started May 20 10:07:24 stormforce volumio[1132]: info: Initializing device activation check May 20 10:07:24 stormforce volumio[1132]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 20 10:07:24 stormforce volumio[1132]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 20 10:07:25 stormforce volumio[1132]: info: Starting Streaming Service Transparent Proxy May 20 10:07:25 stormforce volumio[1132]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 20 10:07:25 stormforce volumio[1132]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 20 10:07:25 stormforce volumio[1132]: info: Streaming services startup May 20 10:07:25 stormforce volumio[1132]: info: Starting Streaming Daemon May 20 10:07:25 stormforce sudo[1405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 20 10:07:25 stormforce sudo[1405]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:25 stormforce volumio[1132]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 20 10:07:25 stormforce sudo[1405]: pam_unix(sudo:session): session closed for user root May 20 10:07:25 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetState May 20 10:07:25 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:25 stormforce volumio[1132]: error: Cannot start Volumio Streaming Daemon May 20 10:07:25 stormforce volumio[1132]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 20 10:07:25 stormforce volumio[1132]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 20 10:07:25 stormforce volumio[1132]: info: Cannot mount NAS Synology_Drive at system boot, trial number 2 ,retrying in 5 seconds May 20 10:07:26 stormforce systemd[1]: systemd-timedated.service: Succeeded. May 20 10:07:27 stormforce volumio[1132]: STREAMING PROXY: Starting server on port 3245 May 20 10:07:27 stormforce volumio[1132]: Node JS runtime: 14 May 20 10:07:27 stormforce sudo[1415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 20 10:07:27 stormforce sudo[1415]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:27 stormforce sudo[1415]: pam_unix(sudo:session): session closed for user root May 20 10:07:27 stormforce sudo[1417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 20 10:07:27 stormforce sudo[1417]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:27 stormforce sudo[1417]: pam_unix(sudo:session): session closed for user root May 20 10:07:27 stormforce volumio[1132]: verbose: New Socket.io Connection to 192.168.1.94 from 192.168.1.125 UA: Mozilla/5.0 (Linux; Android 10; SM-G965F Build/QP1A.190711.020; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 20 10:07:27 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 20 10:07:28 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 20 10:07:28 stormforce sudo[1421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 20 10:07:28 stormforce sudo[1421]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:28 stormforce sudo[1421]: pam_unix(sudo:session): session closed for user root May 20 10:07:28 stormforce sudo[1423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 20 10:07:28 stormforce sudo[1423]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:28 stormforce sudo[1423]: pam_unix(sudo:session): session closed for user root May 20 10:07:28 stormforce volumio[1132]: verbose: New Socket.io Connection to 192.168.1.94 from 192.168.1.125 UA: Mozilla/5.0 (Linux; Android 10; SM-G965F Build/QP1A.190711.020; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 20 10:07:28 stormforce volumio[1360]: ............................................................++++ May 20 10:07:28 stormforce volumio[1360]: e is 65537 (0x010001) May 20 10:07:28 stormforce volumio[1360]: writing RSA key May 20 10:07:28 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 20 10:07:28 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 20 10:07:29 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetVisibleSources May 20 10:07:29 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 10:07:29 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetState May 20 10:07:29 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:29 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 20 10:07:29 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetQueue May 20 10:07:29 stormforce volumio[1132]: info: CoreStateMachine::getQueue May 20 10:07:29 stormforce volumio[1132]: info: CorePlayQueue::getQueue May 20 10:07:29 stormforce volumio[1132]: info: Listing playlists May 20 10:07:29 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 20 10:07:29 stormforce volumio[1132]: info: Received Get System Info May 20 10:07:29 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 20 10:07:29 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 20 10:07:29 stormforce volumio[1132]: info: Discovery: Getting this device information May 20 10:07:29 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetState May 20 10:07:29 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:29 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 20 10:07:29 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetState May 20 10:07:29 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:29 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 20 10:07:29 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 20 10:07:30 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 20 10:07:30 stormforce volumio[1132]: info: Received Get System Info May 20 10:07:30 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 20 10:07:30 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 20 10:07:30 stormforce volumio[1132]: info: Discovery: Getting this device information May 20 10:07:30 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetState May 20 10:07:30 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:30 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 20 10:07:30 stormforce sudo[1459]: 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.114/Triumph 2 /mnt/NAS/Synology_Drive May 20 10:07:30 stormforce sudo[1459]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:30 stormforce kernel: CIFS: Attempting to mount //192.168.1.114/Triumph 2 May 20 10:07:31 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 20 10:07:37 stormforce sudo[1459]: pam_unix(sudo:session): session closed for user root May 20 10:07:37 stormforce volumio[1132]: info: Cannot mount NAS Synology_Drive at system boot, trial number 3 ,retrying in 5 seconds May 20 10:07:37 stormforce kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 20 10:07:37 stormforce kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 20 10:07:38 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 20 10:07:39 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 20 10:07:39 stormforce volumio[1132]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 20 10:07:39 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetState May 20 10:07:39 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:41 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 10:07:41 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 10:07:41 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 20 10:07:41 stormforce volumio-remote-updater[625]: No test mode May 20 10:07:41 stormforce volumio-remote-updater[625]: No alpha test mode May 20 10:07:41 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 20 10:07:41 stormforce volumio[1132]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Various Fixes for QOBUZ Connect
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Qobuz Connect Ready
    • \n
\n","title":"Update v3.812","updateavailable":true} May 20 10:07:41 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 20 10:07:42 stormforce sudo[1488]: 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.114/Triumph 2 /mnt/NAS/Synology_Drive May 20 10:07:42 stormforce sudo[1488]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:42 stormforce kernel: CIFS: Attempting to mount //192.168.1.114/Triumph 2 May 20 10:07:42 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 20 10:07:42 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 20 10:07:42 stormforce volumio[1132]: info: Discovery: Getting this device information May 20 10:07:42 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetState May 20 10:07:42 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:42 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 20 10:07:43 stormforce volumio[1132]: info: CoreCommandRouter::volumioPlay May 20 10:07:43 stormforce volumio[1132]: info: CoreStateMachine::play index undefined May 20 10:07:43 stormforce volumio[1132]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 10:07:43 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:43 stormforce volumio[1132]: info: CoreStateMachine::startPlaybackTimer May 20 10:07:43 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:43 stormforce volumio[1132]: info: [1747732063661] ControllerUPNPBrowser::clearAddPlayTrack May 20 10:07:43 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand stop May 20 10:07:43 stormforce volumio[1132]: info: sendMpdCommand stop took 3 milliseconds May 20 10:07:43 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand clear May 20 10:07:43 stormforce volumio[1132]: info: May 20 10:07:43 stormforce volumio[1132]: ---------------------------- MPD announces system playlist update May 20 10:07:43 stormforce volumio[1132]: info: Ignoring MPD Status Update May 20 10:07:43 stormforce volumio[1132]: info: sendMpdCommand clear took 4 milliseconds May 20 10:07:43 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.37:52100/Music/F1229713.wav" May 20 10:07:43 stormforce volumio[1132]: info: May 20 10:07:43 stormforce volumio[1132]: ---------------------------- MPD announces system playlist update May 20 10:07:43 stormforce volumio[1132]: info: Ignoring MPD Status Update May 20 10:07:43 stormforce volumio[1132]: info: May 20 10:07:43 stormforce volumio[1132]: ---------------------------- MPD announces system playlist update May 20 10:07:43 stormforce volumio[1132]: info: Ignoring MPD Status Update May 20 10:07:43 stormforce volumio[1132]: error: updateQueue error: null May 20 10:07:43 stormforce volumio[1132]: info: ------------------------------ 12ms May 20 10:07:43 stormforce volumio[1132]: error: updateQueue error: null May 20 10:07:43 stormforce volumio[1132]: error: updateQueue error: null May 20 10:07:43 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.37:52100/Music/F1229713.wav" May 20 10:07:43 stormforce volumio[1132]: info: ------------------------------ 66ms May 20 10:07:43 stormforce volumio[1132]: info: ------------------------------ 64ms May 20 10:07:43 stormforce volumio[1132]: info: May 20 10:07:43 stormforce volumio[1132]: ---------------------------- MPD announces system playlist update May 20 10:07:43 stormforce volumio[1132]: info: Ignoring MPD Status Update May 20 10:07:43 stormforce volumio[1132]: info: sendMpdCommand add "http://192.168.1.37:52100/Music/F1229713.wav" took 4 milliseconds May 20 10:07:43 stormforce volumio[1132]: info: CoreStateMachine::setConsumeUpdateService mpd May 20 10:07:43 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand play May 20 10:07:43 stormforce volumio[1132]: info: May 20 10:07:43 stormforce volumio[1132]: ---------------------------- MPD announces system playlist update May 20 10:07:43 stormforce volumio[1132]: info: Ignoring MPD Status Update May 20 10:07:43 stormforce volumio[1132]: info: May 20 10:07:43 stormforce volumio[1132]: ---------------------------- MPD announces system playlist update May 20 10:07:43 stormforce volumio[1132]: info: Ignoring MPD Status Update May 20 10:07:43 stormforce volumio[1132]: info: ------------------------------ 13ms May 20 10:07:43 stormforce volumio[1132]: info: sendMpdCommand play took 9 milliseconds May 20 10:07:43 stormforce volumio[1132]: info: ------------------------------ 9ms May 20 10:07:43 stormforce volumio[1132]: info: ------------------------------ 6ms May 20 10:07:43 stormforce volumio[1132]: info: May 20 10:07:43 stormforce volumio[1132]: ---------------------------- MPD announces state update: player May 20 10:07:43 stormforce volumio[1132]: info: ControllerMpd::getState May 20 10:07:43 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand status May 20 10:07:43 stormforce volumio[1132]: info: May 20 10:07:43 stormforce volumio[1132]: ---------------------------- MPD announces state update: player May 20 10:07:43 stormforce volumio[1132]: info: ControllerMpd::getState May 20 10:07:43 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand status May 20 10:07:43 stormforce volumio[1132]: info: May 20 10:07:43 stormforce volumio[1132]: ---------------------------- MPD announces state update: player May 20 10:07:43 stormforce volumio[1132]: info: ControllerMpd::getState May 20 10:07:43 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand status May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetBrowseSources May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 10:07:44 stormforce volumio[1132]: info: May 20 10:07:44 stormforce volumio[1132]: ---------------------------- MPD announces state update: player May 20 10:07:44 stormforce volumio[1132]: info: sendMpdCommand status took 467 milliseconds May 20 10:07:44 stormforce volumio[1132]: info: ControllerMpd::getState May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand status May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::parseState May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 10:07:44 stormforce volumio[1132]: info: sendMpdCommand status took 471 milliseconds May 20 10:07:44 stormforce volumio[1132]: info: sendMpdCommand status took 467 milliseconds May 20 10:07:44 stormforce volumio[1132]: info: sendMpdCommand status took 8 milliseconds May 20 10:07:44 stormforce volumio[1132]: info: sendMpdCommand playlistinfo took 5 milliseconds May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::parseState May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::parseState May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::parseState May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::parseTrackInfo May 20 10:07:44 stormforce volumio[1132]: info: ControllerMpd::pushState May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::servicePushState May 20 10:07:44 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:44 stormforce volumio[1132]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":159,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F1229713.wav","artist":null,"album":null,"uri":"http://192.168.1.37:52100/Music/F1229713.wav","trackType":"wav"} May 20 10:07:44 stormforce volumio[1132]: verbose: CURRENT POSITION 0 May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::syncState stateService play May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::syncState currentStatus stop May 20 10:07:44 stormforce volumio[1132]: info: ------------------------------ 498ms May 20 10:07:44 stormforce volumio[1132]: info: May 20 10:07:44 stormforce volumio[1132]: ---------------------------- MPD announces state update: player May 20 10:07:44 stormforce volumio[1132]: info: ControllerMpd::getState May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand status May 20 10:07:44 stormforce volumio[1132]: info: May 20 10:07:44 stormforce volumio[1132]: ---------------------------- MPD announces state update: player May 20 10:07:44 stormforce volumio[1132]: info: ControllerMpd::getState May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand status May 20 10:07:44 stormforce volumio[1132]: info: sendMpdCommand playlistinfo took 26 milliseconds May 20 10:07:44 stormforce volumio[1132]: info: sendMpdCommand playlistinfo took 25 milliseconds May 20 10:07:44 stormforce volumio[1132]: info: sendMpdCommand playlistinfo took 24 milliseconds May 20 10:07:44 stormforce volumio[1132]: info: sendMpdCommand status took 8 milliseconds May 20 10:07:44 stormforce volumio[1132]: info: sendMpdCommand status took 6 milliseconds May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::parseTrackInfo May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::parseTrackInfo May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::parseTrackInfo May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::parseState May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::parseState May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 10:07:44 stormforce volumio[1132]: info: ControllerMpd::pushState May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::servicePushState May 20 10:07:44 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:44 stormforce volumio[1132]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":159,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F1229713.wav","artist":null,"album":null,"uri":"http://192.168.1.37:52100/Music/F1229713.wav","trackType":"wav"} May 20 10:07:44 stormforce volumio[1132]: verbose: CURRENT POSITION 0 May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::syncState stateService play May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::syncState currentStatus play May 20 10:07:44 stormforce volumio[1132]: info: Received an update from plugin. extracting info from payload May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::pushState May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::volumioPushState May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::pushState May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::volumioPushState May 20 10:07:44 stormforce volumio[1132]: info: ControllerMpd::pushState May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::servicePushState May 20 10:07:44 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:44 stormforce volumio[1132]: verbose: STATE SERVICE {"status":"play","position":0,"seek":160,"duration":159,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F1229713.wav","artist":null,"album":null,"uri":"http://192.168.1.37:52100/Music/F1229713.wav","trackType":"wav"} May 20 10:07:44 stormforce volumio[1132]: verbose: CURRENT POSITION 0 May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::syncState stateService play May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::syncState currentStatus play May 20 10:07:44 stormforce volumio[1132]: info: Received an update from plugin. extracting info from payload May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::pushState May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::volumioPushState May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::pushState May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::volumioPushState May 20 10:07:44 stormforce volumio[1132]: info: ControllerMpd::pushState May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::servicePushState May 20 10:07:44 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:44 stormforce volumio[1132]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":159,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F1229713.wav","artist":null,"album":null,"uri":"http://192.168.1.37:52100/Music/F1229713.wav","trackType":"wav"} May 20 10:07:44 stormforce volumio[1132]: verbose: CURRENT POSITION 0 May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::syncState stateService play May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::syncState currentStatus play May 20 10:07:44 stormforce volumio[1132]: info: Received an update from plugin. extracting info from payload May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::pushState May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::volumioPushState May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::pushState May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::volumioPushState May 20 10:07:44 stormforce volumio[1132]: info: ------------------------------ 617ms May 20 10:07:44 stormforce volumio[1132]: info: ------------------------------ 614ms May 20 10:07:44 stormforce volumio[1132]: info: ------------------------------ 158ms May 20 10:07:44 stormforce volumio[1132]: info: sendMpdCommand playlistinfo took 112 milliseconds May 20 10:07:44 stormforce volumio[1132]: info: sendMpdCommand playlistinfo took 112 milliseconds May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::parseTrackInfo May 20 10:07:44 stormforce volumio[1132]: verbose: ControllerMpd::parseTrackInfo May 20 10:07:44 stormforce volumio[1132]: info: ControllerMpd::pushState May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::servicePushState May 20 10:07:44 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:44 stormforce volumio[1132]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":159,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F1229713.wav","artist":null,"album":null,"uri":"http://192.168.1.37:52100/Music/F1229713.wav","trackType":"wav"} May 20 10:07:44 stormforce volumio[1132]: verbose: CURRENT POSITION 0 May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::syncState stateService play May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::syncState currentStatus play May 20 10:07:44 stormforce volumio[1132]: info: Received an update from plugin. extracting info from payload May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::pushState May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::volumioPushState May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::pushState May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::volumioPushState May 20 10:07:44 stormforce volumio[1132]: info: ControllerMpd::pushState May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::servicePushState May 20 10:07:44 stormforce volumio[1132]: info: CorePlayQueue::getTrack 0 May 20 10:07:44 stormforce volumio[1132]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":159,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F1229713.wav","artist":null,"album":null,"uri":"http://192.168.1.37:52100/Music/F1229713.wav","trackType":"wav"} May 20 10:07:44 stormforce volumio[1132]: verbose: CURRENT POSITION 0 May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::syncState stateService play May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::syncState currentStatus play May 20 10:07:44 stormforce volumio[1132]: info: Received an update from plugin. extracting info from payload May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::pushState May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::volumioPushState May 20 10:07:44 stormforce volumio[1132]: info: CoreStateMachine::pushState May 20 10:07:44 stormforce volumio[1132]: info: CoreCommandRouter::volumioPushState May 20 10:07:44 stormforce volumio[1132]: info: ------------------------------ 193ms May 20 10:07:44 stormforce volumio[1132]: info: ------------------------------ 192ms May 20 10:07:45 stormforce volumio[1132]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 20 10:07:45 stormforce volumio[1132]: info: Completed starting MyVolumio Plugin May 20 10:07:45 stormforce volumio[1132]: [Metrics] CommandRouter: 44s 461.33ms May 20 10:07:45 stormforce volumio[1132]: info: CoreCommandRouter::volumiosetStartupVolume May 20 10:07:45 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 10:07:45 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 10:07:45 stormforce volumio[1132]: info: CoreCommandRouter::Close All Modals sent May 20 10:07:45 stormforce volumio[1132]: info: CoreCommandRouter::Close All Modals sent May 20 10:07:45 stormforce volumio[1132]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 20 10:07:45 stormforce volumio[1132]: aplay: main:828: audio open error: Device or resource busy May 20 10:07:46 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 20 10:07:46 stormforce volumio[1132]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 20 10:07:46 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 20 10:07:46 stormforce volumio[1132]: info: Received Get System Version May 20 10:07:46 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 10:07:46 stormforce volumio[1132]: info: Received Get System Info May 20 10:07:46 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 20 10:07:46 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 20 10:07:46 stormforce volumio[1132]: info: Discovery: Getting this device information May 20 10:07:46 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetState May 20 10:07:46 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 20 10:07:46 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 20 10:07:46 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 20 10:07:46 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 20 10:07:48 stormforce sudo[1488]: pam_unix(sudo:session): session closed for user root May 20 10:07:48 stormforce volumio[1132]: info: Cannot mount NAS Synology_Drive at system boot, trial number 4 ,retrying in 5 seconds May 20 10:07:48 stormforce kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 20 10:07:48 stormforce kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 20 10:07:52 stormforce ntpd[788]: Soliciting pool server 162.159.200.123 May 20 10:07:52 stormforce volumio[1132]: info: BOOT COMPLETED May 20 10:07:53 stormforce sudo[1582]: 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.114/Triumph 2 /mnt/NAS/Synology_Drive May 20 10:07:53 stormforce sudo[1582]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 10:07:53 stormforce ntpd[788]: Soliciting pool server 80.87.128.222 May 20 10:07:53 stormforce kernel: CIFS: Attempting to mount //192.168.1.114/Triumph 2 May 20 10:07:54 stormforce ntpd[788]: Soliciting pool server 81.179.123.60 May 20 10:07:54 stormforce ntpd[788]: Soliciting pool server 213.5.132.231 May 20 10:07:54 stormforce ntpd[788]: Soliciting pool server 217.154.60.177 May 20 10:07:54 stormforce ntpd[788]: Soliciting pool server 149.22.188.7 May 20 10:07:55 stormforce ntpd[788]: Soliciting pool server 131.111.8.60 May 20 10:07:55 stormforce ntpd[788]: Soliciting pool server 162.159.200.1 May 20 10:07:55 stormforce ntpd[788]: Soliciting pool server 129.250.35.251 May 20 10:07:56 stormforce ntpd[788]: Soliciting pool server 132.226.210.133 May 20 10:07:56 stormforce ntpd[788]: Soliciting pool server 51.89.151.183 May 20 10:07:56 stormforce ntpd[788]: Soliciting pool server 63.135.73.82 May 20 10:07:57 stormforce ntpd[788]: Soliciting pool server 178.215.228.24 May 20 10:07:57 stormforce ntpd[788]: Soliciting pool server 88.80.189.24 May 20 10:07:57 stormforce ntpd[788]: Soliciting pool server 77.68.81.77 May 20 10:07:58 stormforce ntpd[788]: Soliciting pool server 2a00:fd80:aaaa:ffff::eeee:ff3 May 20 10:07:58 stormforce ntpd[788]: Soliciting pool server 95.131.83.40 May 20 10:07:58 stormforce ntpd[788]: Soliciting pool server 193.57.144.50 May 20 10:07:59 stormforce ntpd[788]: Soliciting pool server 81.130.79.209 May 20 10:07:59 stormforce ntpd[788]: Soliciting pool server 131.111.8.63 May 20 10:07:59 stormforce sudo[1582]: pam_unix(sudo:session): session closed for user root May 20 10:07:59 stormforce volumio[1132]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 20 10:07:59 stormforce kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 20 10:07:59 stormforce kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 20 10:08:01 stormforce ntpd[788]: receive: Unexpected origin timestamp 0xebd6c4f0.7c6814d6 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xebd6c4f1.951f6e84 May 20 10:08:01 stormforce ntpd[788]: receive: Unexpected origin timestamp 0xebd6c4f0.7c666e0f does not match aorg 0000000000.00000000 from server@129.250.35.251 xmt 0xebd6c4f1.9537f8a6 May 20 10:08:01 stormforce ntpd[788]: receive: Unexpected origin timestamp 0xebd6c4f0.7c55d963 does not match aorg 0000000000.00000000 from server@193.57.144.50 xmt 0xebd6c4f1.955c39ec May 20 10:08:01 stormforce ntpd[788]: receive: Unexpected origin timestamp 0xebd6c4f0.7c61d2d1 does not match aorg 0000000000.00000000 from server@88.80.189.24 xmt 0xebd6c4f1.9594ad07 May 20 10:08:01 stormforce ntpd[788]: receive: Unexpected origin timestamp 0xebd6c4f0.7c6981c1 does not match aorg 0000000000.00000000 from server@149.22.188.7 xmt 0xebd6c4f1.957480e8 May 20 10:08:01 stormforce ntpd[788]: receive: Unexpected origin timestamp 0xebd6c4f0.7c6af679 does not match aorg 0000000000.00000000 from server@213.5.132.231 xmt 0xebd6c4f1.95e6041c May 20 10:08:01 stormforce ntpd[788]: receive: Unexpected origin timestamp 0xebd6c4f0.7c6031b1 does not match aorg 0000000000.00000000 from server@77.68.81.77 xmt 0xebd6c4f1.961bfdae May 20 10:08:01 stormforce ntpd[788]: receive: Unexpected origin timestamp 0xebd6c4f0.7c6361fc does not match aorg 0000000000.00000000 from server@63.135.73.82 xmt 0xebd6c4f1.97219a04 May 20 10:08:01 stormforce ntpd[788]: receive: Unexpected origin timestamp 0xebd6c4f0.7c64ef93 does not match aorg 0000000000.00000000 from server@131.111.8.60 xmt 0xebd6c4f1.9639e68d May 20 10:08:01 stormforce ntpd[788]: receive: Unexpected origin timestamp 0xebd6c4f0.7c5e220c does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xebd6c4f1.96dab43e May 20 10:08:13 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 20 10:08:13 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 20 10:08:13 stormforce volumio[1132]: info: Discovery: Getting this device information May 20 10:08:13 stormforce volumio[1132]: info: CoreCommandRouter::volumioGetState May 20 10:08:13 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 20 10:08:37 stormforce volumio[1132]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 20 10:08:37 stormforce volumio[1132]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 10:08:37 stormforce volumio[1132]: TypeError: Cannot read property 'length' of undefined May 20 10:08:37 stormforce volumio[1132]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 20 10:08:37 stormforce volumio[1132]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 20 10:08:37 stormforce volumio[1132]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 20 10:08:37 stormforce volumio[1132]: at Parser.emit (events.js:315:20) May 20 10:08:37 stormforce volumio[1132]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 20 10:08:37 stormforce volumio[1132]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 20 10:08:37 stormforce volumio[1132]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 20 10:08:37 stormforce volumio[1132]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 20 10:08:37 stormforce volumio[1132]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 20 10:08:37 stormforce volumio[1132]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 20 10:08:37 stormforce volumio[1132]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 20 10:08:37 stormforce volumio[1132]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 20 10:08:37 stormforce volumio[1132]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 20 10:08:37 stormforce volumio[1132]: at IncomingMessage.emit (events.js:327:22) May 20 10:08:37 stormforce volumio[1132]: at endReadableNT (internal/streams/readable.js:1327:12) May 20 10:08:37 stormforce volumio[1132]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 20 10:08:37 stormforce volumio[1132]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 10:08:37 stormforce sudo[1757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-20 10:07 May 20 10:08:37 stormforce sudo[1757]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"