-- Logs begin at Thu 2019-02-14 02:12:04 PST, end at Wed 2025-04-16 17:55:38 MST. --
Apr 16 17:54:01 volumio-test kernel: Key type cifs.spnego registered
Apr 16 17:54:01 volumio-test kernel: Key type cifs.idmap registered
Apr 16 17:54:01 volumio-test 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.
Apr 16 17:54:01 volumio-test kernel: CIFS: Attempting to mount \\192.168.3.99\Music
Apr 16 17:54:03 volumio-test login[503]: pam_unix(login:auth): check pass; user unknown
Apr 16 17:54:04 volumio-test sudo[671]: pam_unix(sudo:session): session closed for user root
Apr 16 17:54:05 volumio-test volumio[577]: info: Ignoring mount for partition: boot
Apr 16 17:54:05 volumio-test volumio[577]: info: Ignoring mount for partition: volumio
Apr 16 17:54:05 volumio-test volumio[577]: info: Ignoring mount for partition: volumio_data
Apr 16 17:54:05 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 16 17:54:05 volumio-test volumio[577]: info: Loading plugin "alarm-clock"...
Apr 16 17:54:06 volumio-test systemd[1]: apt-daily.service: Succeeded.
Apr 16 17:54:06 volumio-test systemd[1]: Started Daily apt download activities.
Apr 16 17:54:06 volumio-test systemd[1]: Starting Daily apt upgrade and clean activities...
Apr 16 17:54:06 volumio-test login[503]: FAILED LOGIN (2) on '/dev/tty1' FOR 'UNKNOWN', Authentication failure
Apr 16 17:54:09 volumio-test volumio-remote-updater[305]: [2025-04-16 17:54:09] [connect] Successful connection
Apr 16 17:54:10 volumio-test volumio[577]: info: Plugin airplay_emulation is not enabled
Apr 16 17:54:10 volumio-test volumio[577]: info: Loading plugin "last_100"...
Apr 16 17:54:10 volumio-test volumio[577]: info: Loading plugin "webradio"...
Apr 16 17:54:12 volumio-test mpd[639]: Apr 16 17:54 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 16 17:54:12 volumio-test volumio[577]: info: Loading plugin "i2s_dacs"...
Apr 16 17:54:13 volumio-test volumio[577]: info: I2S DAC not set, start Auto-detection
Apr 16 17:54:13 volumio-test volumio[577]: info: Loading plugin "volumiodiscovery"...
Apr 16 17:54:13 volumio-test mpd[639]: Apr 16 17:54 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Apr 16 17:54:13 volumio-test mpd[639]: Apr 16 17:54 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Apr 16 17:54:13 volumio-test systemd[1]: Started Music Player Daemon.
Apr 16 17:54:13 volumio-test sudo[634]: pam_unix(sudo:session): session closed for user root
Apr 16 17:54:14 volumio-test volumio[577]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 16 17:54:14 volumio-test node[577]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 16 17:54:14 volumio-test volumio[577]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 16 17:54:14 volumio-test node[577]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 16 17:54:14 volumio-test volumio[577]: *** WARNING *** For more information see
Apr 16 17:54:14 volumio-test volumio[577]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 16 17:54:14 volumio-test volumio[577]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 16 17:54:14 volumio-test volumio[577]: *** WARNING *** For more information see
Apr 16 17:54:14 volumio-test node[577]: *** WARNING *** For more information see
Apr 16 17:54:14 volumio-test node[577]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 16 17:54:14 volumio-test node[577]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 16 17:54:14 volumio-test node[577]: *** WARNING *** For more information see
Apr 16 17:54:14 volumio-test volumio[577]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 16 17:54:14 volumio-test volumio[577]: info: Discovery: Started advertising with name: Volumio-test
Apr 16 17:54:14 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 16 17:54:14 volumio-test volumio[577]: info: Plugin calmradio is not enabled
Apr 16 17:54:14 volumio-test volumio[577]: info: Loading plugin "outputs"...
Apr 16 17:54:14 volumio-test volumio[577]: info: Loading plugin "albumart"...
Apr 16 17:54:15 volumio-test volumio[577]: info: Plugin example_plugin is not enabled
Apr 16 17:54:15 volumio-test volumio[577]: info: Loading plugin "inputs"...
Apr 16 17:54:15 volumio-test volumio[577]: info: Loading plugin "updater_comm"...
Apr 16 17:54:16 volumio-test systemd[1]: apt-daily-upgrade.service: Succeeded.
Apr 16 17:54:16 volumio-test systemd[1]: Started Daily apt upgrade and clean activities.
Apr 16 17:54:19 volumio-test volumio[577]: info: Plugin mpdemulation is not enabled
Apr 16 17:54:19 volumio-test volumio[577]: info: Loading plugin "rest_api"...
Apr 16 17:54:19 volumio-test volumio[577]: info: Loading plugin "websocket"...
Apr 16 17:54:20 volumio-test volumio[577]: info: Loading i18n strings for locale en
Apr 16 17:54:20 volumio-test volumio[577]: Updating browse sources language
Apr 16 17:54:20 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 16 17:54:21 volumio-test volumio[577]: Forking 1 albumart workers
Apr 16 17:54:23 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 16 17:54:23 volumio-test volumio[577]: info: CoreCommandRouter::initPlayerControls
Apr 16 17:54:23 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:23 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:23 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:23 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:23 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:23 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:23 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:23 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:23 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 16 17:54:23 volumio-test volumio[577]: Express server listening on port 3000
Apr 16 17:54:23 volumio-test volumio[577]: [Metrics] WebUI: 67s 27.82ms
Apr 16 17:54:23 volumio-test volumio[577]: info: CoreStateMachine::resetVolumioState
Apr 16 17:54:23 volumio-test volumio[577]: info: CoreStateMachine::getcurrentVolume
Apr 16 17:54:23 volumio-test volumio[577]: info: CoreCommandRouter::volumioRetrievevolume
Apr 16 17:54:24 volumio-test volumio-remote-updater[305]: [2025-04-16 17:54:24] [connect] Successful connection
Apr 16 17:54:24 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 16 17:54:25 volumio-test sudo[810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 16 17:54:25 volumio-test sudo[810]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 16 17:54:25 volumio-test sudo[810]: pam_unix(sudo:session): session closed for user root
Apr 16 17:54:25 volumio-test sudo[812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 16 17:54:25 volumio-test sudo[812]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 16 17:54:25 volumio-test volumio[577]: info: Cannot read play queue from file
Apr 16 17:54:25 volumio-test sudo[812]: pam_unix(sudo:session): session closed for user root
Apr 16 17:54:26 volumio-test volumio[577]: info: MPD Permissions set
Apr 16 17:54:26 volumio-test volumio[577]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Apr 16 17:54:26 volumio-test volumio[577]: wlan0 Interface doesn't support scanning.
Apr 16 17:54:26 volumio-test volumio[577]: info: Cannot use regular scanning, forcing with ap-force
Apr 16 17:54:26 volumio-test sudo[821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Apr 16 17:54:26 volumio-test sudo[821]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 16 17:54:26 volumio-test sudo[821]: pam_unix(sudo:session): session closed for user root
Apr 16 17:54:26 volumio-test volumio[577]: command failed: No such device (-19)
Apr 16 17:54:26 volumio-test volumio[577]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Apr 16 17:54:26 volumio-test volumio[577]: command failed: No such device (-19)
Apr 16 17:54:29 volumio-test volumio[577]: verbose: New Socket.io Connection to 192.168.3.201:3000 from 192.168.3.53 UA: node-XMLHttpRequest Total Clients: 1
Apr 16 17:54:29 volumio-test volumio[577]: verbose: New Socket.io Connection to 192.168.3.201:3000 from 192.168.3.58 UA: node-XMLHttpRequest Total Clients: 2
Apr 16 17:54:29 volumio-test volumio[577]: info: MPD running with PID639
Apr 16 17:54:29 volumio-test volumio[577]: ,establishing connection
Apr 16 17:54:29 volumio-test volumio[577]: info: CoreStateMachine::pushState
Apr 16 17:54:29 volumio-test volumio[577]: info: CorePlayQueue::getTrack 0
Apr 16 17:54:29 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 16 17:54:29 volumio-test volumio[577]: info: CoreCommandRouter::volumioPushState
Apr 16 17:54:29 volumio-test volumio[577]: info: CoreStateMachine::updateTrackBlock
Apr 16 17:54:29 volumio-test volumio[577]: info: CorePlayQueue::getTrackBlock
Apr 16 17:54:29 volumio-test volumio[577]: info: CoreCommandRouter::volumioRetrievevolume
Apr 16 17:54:31 volumio-test volumio[577]: error: MPD error: The expression evaluated to a falsy value:
Apr 16 17:54:31 volumio-test volumio[577]: assert.ok(self.idling)
Apr 16 17:54:31 volumio-test volumio[577]: error: The expression evaluated to a falsy value:
Apr 16 17:54:31 volumio-test volumio[577]: assert.ok(self.idling)
Apr 16 17:54:31 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:31 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:31 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:31 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:31 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:31 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:31 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:31 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:32 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 16 17:54:32 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:32 volumio-test volumio[577]: info: CoreStateMachine::pushState
Apr 16 17:54:32 volumio-test volumio[577]: info: CorePlayQueue::getTrack 0
Apr 16 17:54:32 volumio-test volumio[577]: info: CoreCommandRouter::volumioPushState
Apr 16 17:54:32 volumio-test volumio[577]: info: CoreStateMachine::setRepeat null single undefined
Apr 16 17:54:32 volumio-test volumio[577]: info: CoreStateMachine::pushState
Apr 16 17:54:32 volumio-test volumio[577]: info: CorePlayQueue::getTrack 0
Apr 16 17:54:32 volumio-test volumio[577]: info: CoreCommandRouter::volumioPushState
Apr 16 17:54:33 volumio-test volumio[577]: info: CoreStateMachine::setRandom true
Apr 16 17:54:33 volumio-test volumio[577]: info: CoreStateMachine::pushState
Apr 16 17:54:33 volumio-test volumio[577]: info: CorePlayQueue::getTrack 0
Apr 16 17:54:33 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 16 17:54:33 volumio-test volumio[577]: info: CoreCommandRouter::volumioPushState
Apr 16 17:54:33 volumio-test volumio[577]: info: Setting Device type: Raspberry PI
Apr 16 17:54:33 volumio-test volumio[577]: error: updateQueue error: null
Apr 16 17:54:33 volumio-test volumio[577]: info: CoreCommandRouter::volumioGetState
Apr 16 17:54:33 volumio-test volumio[577]: info: CorePlayQueue::getTrack 0
Apr 16 17:54:33 volumio-test volumio[577]: Starting albumart workers
Apr 16 17:54:34 volumio-test volumio[577]: info: CoreCommandRouter::volumioGetState
Apr 16 17:54:34 volumio-test volumio[577]: info: CorePlayQueue::getTrack 0
Apr 16 17:54:34 volumio-test sudo[843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Apr 16 17:54:34 volumio-test sudo[843]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 16 17:54:34 volumio-test volumio[577]: info: Completed loading Core Plugins
Apr 16 17:54:34 volumio-test volumio[577]: info: Preparing to generate the ALSA configuration file
Apr 16 17:54:35 volumio-test systemd[1]: Started UPnP Renderer front-end to MPD.
Apr 16 17:54:35 volumio-test sudo[843]: pam_unix(sudo:session): session closed for user root
Apr 16 17:54:35 volumio-test volumio[577]: info: Discovery: adding 261857f8-a062-4f55-9ed9-59a40dea908e
Apr 16 17:54:35 volumio-test volumio[577]: info: Discovery: Found device pi52
Apr 16 17:54:36 volumio-test volumio[577]: info: Discovery: Connecting to remote: 192.168.3.52
Apr 16 17:54:36 volumio-test volumio[577]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf
Apr 16 17:54:36 volumio-test volumio[577]: info: Reading ALSA contributions from plugins.
Apr 16 17:54:36 volumio-test volumio[577]: info: Discovery: adding d2a77c9b-2843-4e57-b4d1-69d59cef5803
Apr 16 17:54:36 volumio-test volumio[577]: info: Discovery: Found device Volumio-test
Apr 16 17:54:36 volumio-test volumio[577]: info: CoreCommandRouter::volumioGetState
Apr 16 17:54:36 volumio-test volumio[577]: info: CorePlayQueue::getTrack 0
Apr 16 17:54:36 volumio-test volumio[577]: info: Discovery: adding fa748504-5064-47fe-a439-e952c6e0232b
Apr 16 17:54:36 volumio-test volumio[577]: info: Discovery: Found device pi57
Apr 16 17:54:36 volumio-test volumio[577]: info: Discovery: Connecting to remote: 192.168.3.57
Apr 16 17:54:37 volumio-test volumio[577]: info: Upmpdcli Daemon Started
Apr 16 17:54:38 volumio-test volumio[577]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Apr 16 17:54:39 volumio-test volumio-remote-updater[305]: [2025-04-16 17:54:39] [connect] Successful connection
Apr 16 17:54:45 volumio-test volumio[577]: info: Discovery: Connected to remote: 192.168.3.52
Apr 16 17:54:45 volumio-test volumio[577]: info: Discovery: Connected to remote: 192.168.3.57
Apr 16 17:54:45 volumio-test volumio[577]: Upnp client error: Error: This socket has been ended by the other party
Apr 16 17:54:45 volumio-test volumio[577]: info: Asound.conf file unchanged, so no further update is needed
Apr 16 17:54:46 volumio-test volumio[577]: info: Output device has changed, restarting MPD
Apr 16 17:54:46 volumio-test volumio[577]: info: ___________ START PLUGINS ___________
Apr 16 17:54:46 volumio-test sudo[856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 16 17:54:46 volumio-test sudo[856]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 16 17:54:46 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 16 17:54:46 volumio-test volumio[577]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 16 17:54:46 volumio-test volumio[577]: info: [1744851286545] CoreMusicLibrary::Adding element Media Servers
Apr 16 17:54:46 volumio-test sudo[856]: pam_unix(sudo:session): session closed for user root
Apr 16 17:54:46 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 16 17:54:46 volumio-test sudo[858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 16 17:54:46 volumio-test sudo[858]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 16 17:54:46 volumio-test systemd[1]: Stopping Music Player Daemon...
Apr 16 17:54:47 volumio-test volumio[577]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 16 17:54:47 volumio-test volumio[577]: info: [1744851287025] CoreMusicLibrary::Adding element Last_100
Apr 16 17:54:47 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 16 17:54:47 volumio-test volumio[577]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 16 17:54:47 volumio-test volumio[577]: info: [1744851287079] CoreMusicLibrary::Adding element Webradio
Apr 16 17:54:47 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 16 17:54:47 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 16 17:54:47 volumio-test systemd[1]: mpd.service: Succeeded.
Apr 16 17:54:47 volumio-test systemd[1]: Stopped Music Player Daemon.
Apr 16 17:54:47 volumio-test systemd[1]: Starting Music Player Daemon...
Apr 16 17:54:47 volumio-test volumio[577]: info: Volumio Calling Home
Apr 16 17:54:48 volumio-test volumio[577]: info: Completed starting Core Plugins
Apr 16 17:54:48 volumio-test volumio[577]: info: -------------------------------------------
Apr 16 17:54:48 volumio-test volumio[577]: info: ----- MyVolumio plugins startup ----
Apr 16 17:54:48 volumio-test volumio[577]: info: -------------------------------------------
Apr 16 17:54:48 volumio-test volumio[577]: info: [MyVolumio PluginManager] Fetching plans data....
Apr 16 17:54:50 volumio-test volumio[577]: info: MPD Permissions set
Apr 16 17:54:50 volumio-test volumio[577]: info: Volumio called home
Apr 16 17:54:50 volumio-test systemd[1]: getty@tty1.service: Succeeded.
Apr 16 17:54:51 volumio-test systemd[1]: getty@tty1.service: Service has no hold-off time (RestartSec=0), scheduling restart.
Apr 16 17:54:51 volumio-test systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1.
Apr 16 17:54:51 volumio-test systemd[1]: Stopped Getty on tty1.
Apr 16 17:54:51 volumio-test systemd[1]: Started Getty on tty1.
Apr 16 17:54:51 volumio-test volumio[577]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3
Apr 16 17:54:51 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:51 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:51 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:51 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:51 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:51 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:51 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:51 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:52 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 16 17:54:52 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:54:53 volumio-test volumio[577]: info: CoreCommandRouter::volumioGetState
Apr 16 17:54:53 volumio-test volumio[577]: info: CorePlayQueue::getTrack 0
Apr 16 17:54:54 volumio-test volumio-remote-updater[305]: [2025-04-16 17:54:54] [connect] Successful connection
Apr 16 17:54:54 volumio-test volumio-remote-updater[305]: [2025-04-16 17:54:54] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1744851294 101
Apr 16 17:54:54 volumio-test volumio[577]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Apr 16 17:54:57 volumio-test volumio[577]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Apr 16 17:54:57 volumio-test volumio[577]: info: Adding plugin bluetooth to MyMusic Plugins
Apr 16 17:54:58 volumio-test volumio[577]: info: Adding plugin multiroom to MyMusic Plugins
Apr 16 17:54:58 volumio-test volumio[577]: info: Adding plugin metavolumio to MyMusic Plugins
Apr 16 17:54:58 volumio-test volumio[577]: info: Adding plugin cd_controller to MyMusic Plugins
Apr 16 17:54:58 volumio-test volumio[577]: info: Adding plugin smart_inputs to MyMusic Plugins
Apr 16 17:54:58 volumio-test volumio[577]: info: Adding plugin tidalconnect to MyMusic Plugins
Apr 16 17:54:58 volumio-test volumio[577]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Apr 16 17:55:03 volumio-test mpd[866]: Apr 16 17:55 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 16 17:55:04 volumio-test mpd[866]: Apr 16 17:55 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Apr 16 17:55:04 volumio-test mpd[866]: Apr 16 17:55 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Apr 16 17:55:04 volumio-test systemd[1]: Started Music Player Daemon.
Apr 16 17:55:04 volumio-test sudo[858]: pam_unix(sudo:session): session closed for user root
Apr 16 17:55:10 volumio-test volumio[577]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Apr 16 17:55:10 volumio-test volumio[577]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Apr 16 17:55:10 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 16 17:55:10 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 16 17:55:10 volumio-test volumio[577]: info: Starting MyVolumio Remote Streaming Endpoints
Apr 16 17:55:11 volumio-test volumio[577]: info: MyVolumio login type: Token
Apr 16 17:55:11 volumio-test volumio[577]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Apr 16 17:55:11 volumio-test volumio[577]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Apr 16 17:55:19 volumio-test volumio[577]: info: Starting Streaming Service Transparent Proxy
Apr 16 17:55:19 volumio-test volumio[577]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Apr 16 17:55:19 volumio-test volumio[577]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Apr 16 17:55:19 volumio-test volumio[577]: info: Streaming services startup
Apr 16 17:55:19 volumio-test volumio[577]: info: Starting Streaming Daemon
Apr 16 17:55:20 volumio-test sudo[882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 16 17:55:20 volumio-test sudo[882]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 16 17:55:20 volumio-test volumio[577]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Apr 16 17:55:20 volumio-test sudo[882]: pam_unix(sudo:session): session closed for user root
Apr 16 17:55:21 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 16 17:55:21 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 16 17:55:21 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 16 17:55:22 volumio-test volumio[577]: Upnp client error: Error: This socket has been ended by the other party
Apr 16 17:55:22 volumio-test volumio[577]: Upnp client error: Error: This socket has been ended by the other party
Apr 16 17:55:22 volumio-test volumio[577]: error: Cannot start Volumio Streaming Daemon
Apr 16 17:55:22 volumio-test volumio[577]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 16 17:55:22 volumio-test volumio[577]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 16 17:55:22 volumio-test volumio[577]: Upnp client error: Error: This socket has been ended by the other party
Apr 16 17:55:22 volumio-test volumio[577]: error: updateQueue error: null
Apr 16 17:55:23 volumio-test volumio[577]: error: MyVolumio Custom Token format not valid, refreshing it
Apr 16 17:55:24 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Apr 16 17:55:24 volumio-test volumio-remote-updater[305]: No test mode
Apr 16 17:55:24 volumio-test volumio-remote-updater[305]: No alpha test mode
Apr 16 17:55:25 volumio-test volumio[577]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Improved translations
NEW ADDITIONS
- Update to TIDAL Connect
- Add Install-to-disk option for the new RPi5 HW revisions
","title":"Update v3.799","updateavailable":true}
Apr 16 17:55:25 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 16 17:55:25 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 16 17:55:26 volumio-test volumio[577]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5
Apr 16 17:55:27 volumio-test volumio[577]: info: MyVolumio login type: Token
Apr 16 17:55:28 volumio-test volumio[577]: info: CoreCommandRouter::volumioGetState
Apr 16 17:55:28 volumio-test volumio[577]: info: CorePlayQueue::getTrack 0
Apr 16 17:55:29 volumio-test volumio[577]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Apr 16 17:55:30 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:55:30 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 16 17:55:30 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Apr 16 17:55:31 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Apr 16 17:55:31 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Apr 16 17:55:31 volumio-test volumio[577]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 16 17:55:31 volumio-test volumio[577]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 16 17:55:31 volumio-test kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #574971: comm node: No space for directory leaf checksum. Please run e2fsck -D.
Apr 16 17:55:31 volumio-test kernel: EXT4-fs error (device mmcblk0p3): htree_dirblock_to_tree:1082: inode #574971: comm node: Directory block failed checksum
Apr 16 17:55:31 volumio-test volumio[577]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 16 17:55:31 volumio-test volumio[577]: Error: UNKNOWN: unknown error, scandir '/data/plugins//music_service'
Apr 16 17:55:31 volumio-test volumio[577]: at Object.readdirSync (fs.js:1021:3)
Apr 16 17:55:31 volumio-test volumio[577]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1634:35)
Apr 16 17:55:31 volumio-test volumio[577]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1514:29)
Apr 16 17:55:31 volumio-test volumio[577]: at myVolumio.identifyUserProperties (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:42317)
Apr 16 17:55:31 volumio-test volumio[577]: at Timeout._onTimeout (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:2508)
Apr 16 17:55:31 volumio-test volumio[577]: at listOnTimeout (internal/timers.js:554:17)
Apr 16 17:55:31 volumio-test volumio[577]: at processTimers (internal/timers.js:497:7) {
Apr 16 17:55:31 volumio-test volumio[577]: errno: -74,
Apr 16 17:55:31 volumio-test volumio[577]: syscall: 'scandir',
Apr 16 17:55:31 volumio-test volumio[577]: code: 'UNKNOWN',
Apr 16 17:55:31 volumio-test volumio[577]: path: '/data/plugins//music_service'
Apr 16 17:55:31 volumio-test volumio[577]: }
Apr 16 17:55:31 volumio-test volumio[577]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 16 17:55:36 volumio-test volumio[577]: STREAMING PROXY: Starting server on port 3245
Apr 16 17:55:36 volumio-test volumio[577]: Node JS runtime: 14
Apr 16 17:55:38 volumio-test sudo[904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-16 17:54
Apr 16 17:55:38 volumio-test sudo[904]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST"
VOLUMIO_VERSION="3.569"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"