-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Thu 2025-06-05 14:13:57 UTC. -- Jun 05 14:12:09 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jun 05 14:12:09 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jun 05 14:12:10 volumio volumio[812]: info: CoreCommandRouter::volumioSeek Jun 05 14:12:10 volumio volumio[812]: info: CoreStateMachine::seek Jun 05 14:12:10 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jun 05 14:12:10 volumio volumio[812]: info: TRACKBLOCK {"uri":"music-library/NAS/TrueNAS/Music/Nine Inch Nails/Pretty Hate Machine/Nine Inch Nails - Pretty Hate Machine [01] - Head Like A Hole.mp3","service":"mpd","name":"1 - Head Like A Hole","artist":"Nine Inch Nails","album":"Pretty Hate Machine","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=940&web=Nine%20Inch%20Nails/Pretty%20Hate%20Machine/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FMusic%2FNine%20Inch%20Nails%2FPretty%20Hate%20Machine&metadata=false","duration":300,"trackType":"mp3","samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2} Jun 05 14:12:10 volumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Jun 05 14:12:10 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jun 05 14:12:10 volumio volumio[812]: info: ControllerMpd::seek Jun 05 14:12:10 volumio volumio[812]: info: CoreStateMachine::pushState Jun 05 14:12:10 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jun 05 14:12:10 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 14:12:10 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jun 05 14:12:19 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 14:12:19 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 14:12:19 volumio volumio[812]: info: Discovery: Getting this device information Jun 05 14:12:19 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jun 05 14:12:19 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jun 05 14:12:19 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 14:12:19 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jun 05 14:12:19 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jun 05 14:12:19 volumio volumio[812]: info: Listing playlists Jun 05 14:12:19 volumio volumio[812]: info: Listing playlists Jun 05 14:12:29 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 05 14:12:29 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jun 05 14:12:29 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jun 05 14:12:32 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 05 14:12:32 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 05 14:12:38 volumio volumio[812]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 14:12:38 volumio volumio[812]: info: FusionDsp - Rock-FusionDsp Jun 05 14:12:38 volumio volumio[812]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 05 14:12:39 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jun 05 14:12:39 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jun 05 14:12:39 volumio volumio[812]: info: Listing playlists Jun 05 14:12:39 volumio volumio[812]: info: Listing playlists Jun 05 14:12:49 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 14:12:49 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 14:12:49 volumio volumio[812]: info: Discovery: Getting this device information Jun 05 14:12:49 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jun 05 14:12:49 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jun 05 14:12:49 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 14:12:49 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jun 05 14:12:49 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jun 05 14:12:59 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jun 05 14:12:59 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jun 05 14:12:59 volumio volumio[812]: info: Listing playlists Jun 05 14:12:59 volumio volumio[812]: info: Listing playlists Jun 05 14:13:09 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jun 05 14:13:09 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:14 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 05 14:13:14 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 05 14:13:19 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 14:13:19 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 14:13:19 volumio volumio[812]: info: Discovery: Getting this device information Jun 05 14:13:19 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jun 05 14:13:19 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:19 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 14:13:19 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jun 05 14:13:19 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:19 volumio volumio[812]: info: Listing playlists Jun 05 14:13:19 volumio volumio[812]: info: Listing playlists Jun 05 14:13:19 volumio volumio[812]: info: Enabling plugin fusiondsp Jun 05 14:13:19 volumio volumio[812]: info: Loading plugin "fusiondsp"... Jun 05 14:13:19 volumio volumio[812]: info: Applying required configuration parameters for plugin fusiondsp Jun 05 14:13:19 volumio volumio[812]: info: Preparing to generate the ALSA configuration file Jun 05 14:13:19 volumio volumio[812]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 05 14:13:19 volumio volumio[812]: info: Reading ALSA contributions from plugins. Jun 05 14:13:19 volumio volumio[812]: info: Asound.conf file unchanged, so no further update is needed Jun 05 14:13:19 volumio volumio[812]: info: Output device has changed, restarting MPD Jun 05 14:13:20 volumio sudo[6119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 05 14:13:20 volumio sudo[6119]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:13:20 volumio sudo[6119]: pam_unix(sudo:session): session closed for user root Jun 05 14:13:20 volumio volumio[812]: info: PLUGIN START: fusiondsp Jun 05 14:13:20 volumio volumio[812]: info: Loading i18n strings for locale en Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:20 volumio volumio[812]: info: FusionDsp - mixtype--------------------- Hardware Jun 05 14:13:20 volumio volumio[812]: info: Preparing to generate the ALSA configuration file Jun 05 14:13:20 volumio sudo[6122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 05 14:13:20 volumio sudo[6122]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:13:20 volumio volumio[812]: info: Done. Jun 05 14:13:20 volumio volumio[812]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 05 14:13:20 volumio volumio[812]: info: Reading ALSA contributions from plugins. Jun 05 14:13:20 volumio volumio[812]: info: MPD Permissions set Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:20 volumio systemd[1]: Stopping Music Player Daemon... Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:20 volumio volumio[812]: info: Asound.conf file unchanged, so no further update is needed Jun 05 14:13:20 volumio volumio[812]: info: Output device has changed, restarting MPD Jun 05 14:13:20 volumio sudo[6129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 05 14:13:20 volumio sudo[6129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:13:20 volumio sudo[6129]: pam_unix(sudo:session): session closed for user root Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:20 volumio sudo[6131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 05 14:13:20 volumio sudo[6131]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 14:13:20 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:20 volumio volumio[812]: info: MPD Permissions set Jun 05 14:13:21 volumio volumio[812]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 14:13:21 volumio volumio[812]: TypeError: Cannot read property 'off' of undefined Jun 05 14:13:21 volumio volumio[812]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Jun 05 14:13:21 volumio volumio[812]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jun 05 14:13:21 volumio volumio[812]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1869:8) Jun 05 14:13:21 volumio volumio[812]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1496:29) Jun 05 14:13:21 volumio volumio[812]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1250:47) Jun 05 14:13:21 volumio volumio[812]: at Socket.emit (events.js:315:20) Jun 05 14:13:21 volumio volumio[812]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 05 14:13:21 volumio volumio[812]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 05 14:13:21 volumio volumio[812]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 14:13:21 volumio sudo[6147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-05 14:12 Jun 05 14:13:21 volumio sudo[6147]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:13:21 volumio sudo[6147]: pam_unix(sudo:session): session closed for user root Jun 05 14:13:22 volumio volumio-remote-updater[469]: [2025-06-05 14:13:22] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 05 14:13:22 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 05 14:13:22 volumio volumio-remote-updater[469]: [2025-06-05 14:13:22] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 05 14:13:22 volumio sudo[6122]: pam_unix(sudo:session): session closed for user root Jun 05 14:13:22 volumio sudo[6131]: pam_unix(sudo:session): session closed for user root Jun 05 14:13:22 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 05 14:13:22 volumio systemd[1]: Started dynamicswap service. Jun 05 14:13:22 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 05 14:13:22 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 05 14:13:22 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jun 05 14:13:22 volumio systemd[1]: Started dynamicswap service. Jun 05 14:13:22 volumio systemd[1]: Stopped Volumio Backend Module. Jun 05 14:13:22 volumio systemd[1]: Started Volumio Backend Module. Jun 05 14:13:22 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 05 14:13:24 volumio volumio[6164]: info: ------------------------------------------- Jun 05 14:13:24 volumio volumio[6164]: info: ----- Volumio3 ---- Jun 05 14:13:24 volumio volumio[6164]: info: ------------------------------------------- Jun 05 14:13:24 volumio volumio[6164]: info: ----- System startup ---- Jun 05 14:13:24 volumio volumio[6164]: info: ------------------------------------------- Jun 05 14:13:25 volumio volumio[6164]: info: MYVOLUMIO Environment detected Jun 05 14:13:25 volumio volumio[6164]: info: Plugin folders cleanup Jun 05 14:13:25 volumio volumio[6164]: info: Scanning into folder /volumio/app/plugins/ Jun 05 14:13:25 volumio volumio[6164]: info: Scanning category audio_interface Jun 05 14:13:25 volumio volumio[6164]: info: Scanning category miscellanea Jun 05 14:13:25 volumio volumio[6164]: info: Scanning category music_service Jun 05 14:13:25 volumio volumio[6164]: info: Scanning category plugins.json Jun 05 14:13:25 volumio volumio[6164]: info: Scanning category system_controller Jun 05 14:13:25 volumio volumio[6164]: info: Scanning category user_interface Jun 05 14:13:25 volumio volumio[6164]: info: Scanning into folder /data/plugins/ Jun 05 14:13:25 volumio volumio[6164]: info: Scanning category audio_interface Jun 05 14:13:25 volumio volumio[6164]: info: Plugin folders cleanup completed Jun 05 14:13:25 volumio volumio[6164]: info: ------------------------------------------- Jun 05 14:13:25 volumio volumio[6164]: info: ----- Core plugins startup ---- Jun 05 14:13:25 volumio volumio[6164]: info: ------------------------------------------- Jun 05 14:13:25 volumio volumio[6164]: info: Loading plugins from folder /volumio/app/plugins/ Jun 05 14:13:25 volumio volumio[6164]: info: Adding plugin upnp to MyMusic Plugins Jun 05 14:13:25 volumio volumio[6164]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 05 14:13:25 volumio volumio[6164]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 05 14:13:25 volumio volumio[6164]: info: Loading plugins from folder /data/plugins/ Jun 05 14:13:25 volumio volumio[6164]: info: Loading plugin "system"... Jun 05 14:13:25 volumio volumio[6164]: info: Loading plugin "appearance"... Jun 05 14:13:26 volumio volumio[6164]: info: Loading plugin "network"... Jun 05 14:13:26 volumio volumio[6164]: info: Refreshing Cached IP Addresses Jun 05 14:13:26 volumio sudo[6187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 14:13:26 volumio sudo[6187]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:13:26 volumio sudo[6187]: pam_unix(sudo:session): session closed for user root Jun 05 14:13:26 volumio sudo[6189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 14:13:26 volumio sudo[6189]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:13:26 volumio volumio[6164]: info: Loading plugin "services"... Jun 05 14:13:26 volumio sudo[6189]: pam_unix(sudo:session): session closed for user root Jun 05 14:13:26 volumio volumio[6164]: info: Loading plugin "alsa_controller"... Jun 05 14:13:26 volumio sudo[6193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 05 14:13:26 volumio sudo[6193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:13:26 volumio volumio[6164]: info: Loading plugin "wizard"... Jun 05 14:13:26 volumio volumio[6164]: info: Loading plugin "volumio_command_line_client"... Jun 05 14:13:26 volumio volumio[6164]: info: Plugin upnp is not enabled Jun 05 14:13:26 volumio volumio[6164]: info: Loading plugin "my_music"... Jun 05 14:13:26 volumio volumio[6164]: info: Loading plugin "mpd"... Jun 05 14:13:27 volumio volumio-remote-updater[469]: [2025-06-05 14:13:27] [connect] Successful connection Jun 05 14:13:27 volumio volumio[6164]: info: Creating MPD Configuration file Jun 05 14:13:27 volumio volumio[6164]: info: Loading plugin "upnp_browser"... Jun 05 14:13:27 volumio sudo[6200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 05 14:13:27 volumio sudo[6200]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:13:27 volumio sudo[6200]: pam_unix(sudo:session): session closed for user root Jun 05 14:13:27 volumio sudo[6202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 05 14:13:27 volumio sudo[6202]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:13:27 volumio volumio[6164]: info: Loading plugin "networkfs"... Jun 05 14:13:28 volumio volumio[6164]: info: Cannot mount NAS TrueNAS at system boot, trial number 1 ,retrying in 5 seconds Jun 05 14:13:28 volumio volumio[6164]: info: Starting Udev Watcher for removable devices Jun 05 14:13:28 volumio volumio[6164]: info: Ignoring mount for partition: boot Jun 05 14:13:28 volumio volumio[6164]: info: Ignoring mount for partition: volumio Jun 05 14:13:28 volumio volumio[6164]: info: Ignoring mount for partition: volumio_data Jun 05 14:13:28 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 05 14:13:28 volumio volumio[6164]: info: Loading plugin "alarm-clock"... Jun 05 14:13:28 volumio volumio[6164]: info: Plugin airplay_emulation is not enabled Jun 05 14:13:28 volumio volumio[6164]: info: Loading plugin "last_100"... Jun 05 14:13:28 volumio volumio[6164]: info: Loading plugin "webradio"... Jun 05 14:13:28 volumio volumio[6164]: info: Loading plugin "i2s_dacs"... Jun 05 14:13:28 volumio volumio[6164]: info: Loading plugin "volumiodiscovery"... Jun 05 14:13:28 volumio volumio[6164]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 05 14:13:28 volumio volumio[6164]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 05 14:13:28 volumio volumio[6164]: *** WARNING *** For more information see Jun 05 14:13:28 volumio volumio[6164]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 05 14:13:28 volumio volumio[6164]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 05 14:13:28 volumio volumio[6164]: *** WARNING *** For more information see Jun 05 14:13:28 volumio node[6164]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 05 14:13:28 volumio node[6164]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 05 14:13:28 volumio node[6164]: *** WARNING *** For more information see Jun 05 14:13:28 volumio node[6164]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 05 14:13:28 volumio node[6164]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 05 14:13:28 volumio node[6164]: *** WARNING *** For more information see Jun 05 14:13:28 volumio volumio[6164]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 05 14:13:28 volumio volumio[6164]: info: Discovery: Started advertising with name: Volumio Jun 05 14:13:28 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 05 14:13:28 volumio volumio[6164]: info: Loading plugin "outputs"... Jun 05 14:13:28 volumio volumio[6164]: info: Loading plugin "albumart"... Jun 05 14:13:28 volumio volumio[6164]: info: Plugin example_plugin is not enabled Jun 05 14:13:28 volumio volumio[6164]: info: Loading plugin "inputs"... Jun 05 14:13:28 volumio volumio[6164]: info: Loading plugin "updater_comm"... Jun 05 14:13:29 volumio volumio[6164]: info: Plugin mpdemulation is not enabled Jun 05 14:13:29 volumio volumio[6164]: info: Loading plugin "rest_api"... Jun 05 14:13:29 volumio volumio[6164]: info: Loading plugin "websocket"... Jun 05 14:13:29 volumio volumio[6164]: info: Loading plugin "fusiondsp"... Jun 05 14:13:29 volumio volumio[6164]: Forking 3 albumart workers Jun 05 14:13:29 volumio volumio[6164]: info: Applying required configuration parameters for plugin fusiondsp Jun 05 14:13:29 volumio volumio[6164]: info: Loading i18n strings for locale en Jun 05 14:13:29 volumio volumio[6164]: Updating browse sources language Jun 05 14:13:29 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 14:13:29 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 14:13:29 volumio volumio[6164]: info: CoreCommandRouter::initPlayerControls Jun 05 14:13:29 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:29 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:29 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:29 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:29 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:29 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:29 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:29 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 14:13:29 volumio volumio[6164]: Express server listening on port 3000 Jun 05 14:13:29 volumio volumio[6164]: [Metrics] WebUI: 5s 796.70ms Jun 05 14:13:29 volumio volumio[6164]: info: CoreStateMachine::resetVolumioState Jun 05 14:13:29 volumio volumio[6164]: info: CoreStateMachine::getcurrentVolume Jun 05 14:13:29 volumio volumio[6164]: info: CoreCommandRouter::volumioRetrievevolume Jun 05 14:13:29 volumio volumio[6164]: info: MPD Permissions set Jun 05 14:13:29 volumio volumio[6164]: verbose: New Socket.io Connection to 192.168.50.141 from 192.168.50.108 UA: Mozilla/5.0 (Linux; Android 15; SM-S916U Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Total Clients: 1 Jun 05 14:13:29 volumio volumio[6164]: verbose: New Socket.io Connection to 192.168.50.141:3000 from 192.168.50.108 UA: Dart/3.5 (dart:io) Total Clients: 2 Jun 05 14:13:29 volumio volumio-remote-updater[469]: [2025-06-05 14:13:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749132807 101 Jun 05 14:13:30 volumio volumio[6164]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::volumioGetState Jun 05 14:13:30 volumio volumio[6164]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:30 volumio volumio[6164]: Starting albumart workers Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:13:30 volumio volumio[6164]: Starting albumart workers Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 14:13:30 volumio volumio[6164]: info: Reloading queue from file Jun 05 14:13:30 volumio volumio[6164]: Starting albumart workers Jun 05 14:13:30 volumio volumio[6164]: info: CoreStateMachine::setRepeat null single undefined Jun 05 14:13:30 volumio volumio[6164]: info: CoreStateMachine::pushState Jun 05 14:13:30 volumio volumio[6164]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::volumioPushState Jun 05 14:13:30 volumio volumio[6164]: info: CoreStateMachine::setRandom null Jun 05 14:13:30 volumio volumio[6164]: info: CoreStateMachine::pushState Jun 05 14:13:30 volumio volumio[6164]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::volumioPushState Jun 05 14:13:30 volumio volumio[6164]: info: Setting Device type: Raspberry PI Jun 05 14:13:30 volumio volumio[6164]: info: VolumeController:: Volume=94 Mute =false Jun 05 14:13:30 volumio volumio[6164]: info: CoreStateMachine::pushState Jun 05 14:13:30 volumio volumio[6164]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::volumioPushState Jun 05 14:13:30 volumio volumio[6164]: info: CoreStateMachine::updateTrackBlock Jun 05 14:13:30 volumio volumio[6164]: info: CorePlayQueue::getTrackBlock Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::volumioRetrievevolume Jun 05 14:13:30 volumio volumio[6164]: verbose: New Socket.io Connection to 192.168.50.141 from 192.168.50.108 UA: Mozilla/5.0 (Linux; Android 15; SM-S916U Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Total Clients: 4 Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 14:13:30 volumio volumio[6164]: info: Discovery: Getting this device information Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::volumioGetState Jun 05 14:13:30 volumio volumio[6164]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 14:13:30 volumio volumio[6164]: info: VolumeController:: Volume=94 Mute =false Jun 05 14:13:30 volumio volumio[6164]: info: CoreStateMachine::pushState Jun 05 14:13:30 volumio volumio[6164]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::volumioPushState Jun 05 14:13:30 volumio volumio[6164]: info: Discovery: adding 2dbdac5d-8ebb-49c0-9fee-9fdda9b73a87 Jun 05 14:13:30 volumio volumio[6164]: info: mDNS: Found device Volumio Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::volumioGetState Jun 05 14:13:30 volumio volumio[6164]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::volumioGetState Jun 05 14:13:30 volumio volumio[6164]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:30 volumio volumio[6164]: verbose: New Socket.io Connection to 192.168.50.141:3000 from 192.168.50.108 UA: Dart/3.5 (dart:io) Total Clients: 5 Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 05 14:13:30 volumio volumio[6164]: info: Listing playlists Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::volumioGetQueue Jun 05 14:13:30 volumio volumio[6164]: info: CoreStateMachine::getQueue Jun 05 14:13:30 volumio volumio[6164]: info: CorePlayQueue::getQueue Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::volumioGetVisibleSources Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::volumioGetState Jun 05 14:13:30 volumio volumio[6164]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 05 14:13:30 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 05 14:13:30 volumio sudo[6193]: pam_unix(sudo:session): session closed for user root Jun 05 14:13:33 volumio volumio[6164]: info: Cannot mount NAS TrueNAS at system boot, trial number 2 ,retrying in 5 seconds Jun 05 14:13:34 volumio volumio[6164]: info: CoreCommandRouter::volumioPlay Jun 05 14:13:34 volumio volumio[6164]: verbose: UNSET VOLATILE Jun 05 14:13:34 volumio volumio[6164]: info: CoreStateMachine::play index undefined Jun 05 14:13:34 volumio volumio[6164]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 05 14:13:34 volumio volumio[6164]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:34 volumio volumio[6164]: info: CoreStateMachine::startPlaybackTimer Jun 05 14:13:34 volumio volumio[6164]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:34 volumio volumio[6164]: verbose: ControllerMpd::clearAddPlayTracks NAS/TrueNAS/Music/Nine Inch Nails/Pretty Hate Machine/Nine Inch Nails - Pretty Hate Machine [01] - Head Like A Hole.mp3 Jun 05 14:13:34 volumio volumio[6164]: verbose: ControllerMpd::sendMpdCommand stop Jun 05 14:13:38 volumio volumio[6164]: info: Cannot mount NAS TrueNAS at system boot, trial number 3 ,retrying in 5 seconds Jun 05 14:13:39 volumio volumio[6164]: info: CoreCommandRouter::volumioGetState Jun 05 14:13:39 volumio volumio[6164]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:39 volumio volumio[6164]: info: Listing playlists Jun 05 14:13:39 volumio volumio[6164]: info: Listing playlists Jun 05 14:13:43 volumio volumio[6164]: info: Cannot mount NAS TrueNAS at system boot, trial number 4 ,retrying in 5 seconds Jun 05 14:13:48 volumio volumio[6164]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jun 05 14:13:48 volumio volumio[6164]: info: CoreCommandRouter::volumioPlay Jun 05 14:13:48 volumio volumio[6164]: verbose: UNSET VOLATILE Jun 05 14:13:48 volumio volumio[6164]: info: CoreStateMachine::play index undefined Jun 05 14:13:48 volumio volumio[6164]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 05 14:13:48 volumio volumio[6164]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:48 volumio volumio[6164]: info: CoreStateMachine::startPlaybackTimer Jun 05 14:13:48 volumio volumio[6164]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:48 volumio volumio[6164]: verbose: ControllerMpd::clearAddPlayTracks NAS/TrueNAS/Music/Nine Inch Nails/Pretty Hate Machine/Nine Inch Nails - Pretty Hate Machine [01] - Head Like A Hole.mp3 Jun 05 14:13:48 volumio volumio[6164]: verbose: ControllerMpd::sendMpdCommand stop Jun 05 14:13:49 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 14:13:49 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 14:13:49 volumio volumio[6164]: info: Discovery: Getting this device information Jun 05 14:13:49 volumio volumio[6164]: info: CoreCommandRouter::volumioGetState Jun 05 14:13:49 volumio volumio[6164]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:49 volumio volumio[6164]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 14:13:49 volumio volumio[6164]: info: CoreCommandRouter::volumioGetState Jun 05 14:13:49 volumio volumio[6164]: info: CorePlayQueue::getTrack 0 Jun 05 14:13:57 volumio volumio[6164]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 05 14:13:57 volumio volumio[6164]: info: CoreStateMachine::ClearQueue Jun 05 14:13:57 volumio volumio[6164]: info: CoreStateMachine::stop Jun 05 14:13:57 volumio volumio[6164]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 05 14:13:57 volumio volumio[6164]: verbose: UNSET VOLATILE Jun 05 14:13:57 volumio volumio[6164]: info: CorePlayQueue::clearPlayQueue Jun 05 14:13:57 volumio volumio[6164]: info: CorePlayQueue::saveQueue Jun 05 14:13:57 volumio volumio[6164]: info: CoreCommandRouter::volumioPushState Jun 05 14:13:57 volumio volumio[6164]: info: CoreCommandRouter::volumioPushQueue Jun 05 14:13:57 volumio volumio[6164]: info: CoreStateMachine::addQueueItems Jun 05 14:13:57 volumio volumio[6164]: info: CorePlayQueue::addQueueItems Jun 05 14:13:57 volumio volumio[6164]: info: Adding Item to queue: artists://Nine%20Inch%20Nails/Pretty%20Hate%20Machine Jun 05 14:13:57 volumio volumio[6164]: info: Exploding uri artists://Nine%20Inch%20Nails/Pretty%20Hate%20Machine in service mpd Jun 05 14:13:57 volumio volumio[6164]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 14:13:57 volumio volumio[6164]: TypeError: Cannot read property 'sendCommand' of undefined Jun 05 14:13:57 volumio volumio[6164]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2093:20) Jun 05 14:13:57 volumio volumio[6164]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2153:19) Jun 05 14:13:57 volumio volumio[6164]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1324:16) Jun 05 14:13:57 volumio volumio[6164]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:144:48) Jun 05 14:13:57 volumio volumio[6164]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:243:25) Jun 05 14:13:57 volumio volumio[6164]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:487:23) Jun 05 14:13:57 volumio volumio[6164]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:457:17) Jun 05 14:13:57 volumio volumio[6164]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:113:35) Jun 05 14:13:57 volumio volumio[6164]: at Socket.emit (events.js:315:20) Jun 05 14:13:57 volumio volumio[6164]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 05 14:13:57 volumio volumio[6164]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 05 14:13:57 volumio volumio[6164]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 14:13:57 volumio sudo[6287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-05 14:12 Jun 05 14:13:57 volumio sudo[6287]: 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="3cfa1502993c2310fc263d6ebda1707071fe4cc2" VOLUMIO_FE_VERSION="8efe4f08f83e5de574651b8ed2333629c140d365" VOLUMIO_FE3_VERSION="8f1d07fcde806ea34f52cff908ec76359e14970e" VOLUMIO_BE_VERSION="e249dfa388452e6b8443121dc9b4cabca459c531" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 09 Jun 2022 05:55:51 PM CEST" VOLUMIO_VERSION="3.301" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6a8974e587cd4bab189d231f79b65462"