-- Logs begin at Thu 2019-02-14 10:11:58 WET, end at Mon 2025-06-09 18:21:05 WEST. -- Jun 09 18:20:13 volumio volumio[1198]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/80s80s/volumio/buster/armhf Jun 09 18:20:16 volumio volumio[1198]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/80s80s/volumio/buster/armhf Jun 09 18:20:16 volumio volumio[1198]: info: Folder /tmp/plugins removed Jun 09 18:20:16 volumio volumio[1198]: info: Check plugin dependencies Jun 09 18:20:16 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 18:20:16 volumio volumio[1198]: info: Checking if plugin already exists Jun 09 18:20:16 volumio volumio[1198]: info: Rename folder Jun 09 18:20:16 volumio volumio[1198]: info: Folder /tmp/downloaded_plugin.zip removed Jun 09 18:20:16 volumio volumio[1198]: info: Move to category Jun 09 18:20:16 volumio volumio[1198]: info: Checking if install.sh is present Jun 09 18:20:16 volumio volumio[1198]: info: Executing install.sh Jun 09 18:20:16 volumio sudo[6605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/80s80s/install.sh Jun 09 18:20:16 volumio sudo[6605]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:16 volumio sudo[6605]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:16 volumio volumio[1198]: info: Installing 80s80s radio Dependencies Jun 09 18:20:16 volumio volumio[1198]: Plugin install end detected on script Jun 09 18:20:16 volumio volumio[1198]: info: Install script completed Jun 09 18:20:16 volumio volumio[1198]: info: Adding reference to registry Jun 09 18:20:16 volumio volumio[1198]: info: Done installing plugin. Jun 09 18:20:16 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 09 18:20:16 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 09 18:20:16 volumio volumio[1198]: info: Folder /tmp/plugins removed Jun 09 18:20:16 volumio volumio[1198]: info: Folder /tmp/downloaded_plugin.zip removed Jun 09 18:20:16 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 09 18:20:16 volumio volumio[1198]: info: Folder /data/temp removed Jun 09 18:20:16 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 09 18:20:16 volumio volumio[1198]: info: FusionDsp - Jun 09 18:20:17 volumio volumio[1198]: info: FusionDsp - undefined Jun 09 18:20:17 volumio volumio[1198]: info: FusionDsp - Jun 09 18:20:17 volumio volumio[1198]: info: FusionDsp - undefined Jun 09 18:20:18 volumio volumio[1198]: info: Enabling plugin 80s80s Jun 09 18:20:18 volumio volumio[1198]: info: Loading plugin "80s80s"... Jun 09 18:20:18 volumio volumio[1198]: info: [1749489618679] [80s80s] API delay: 30 Jun 09 18:20:18 volumio volumio[1198]: info: PLUGIN START: 80s80s Jun 09 18:20:18 volumio volumio[1198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 18:20:18 volumio volumio[1198]: info: [1749489618684] CoreMusicLibrary::Adding element 80s80s Radio Jun 09 18:20:18 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 18:20:18 volumio volumio[1198]: Cannot find translation for source 80s80s Radio Jun 09 18:20:18 volumio volumio[1198]: info: Done. Jun 09 18:20:19 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 09 18:20:19 volumio volumio[1198]: info: FusionDsp - Jun 09 18:20:19 volumio volumio[1198]: info: FusionDsp - undefined Jun 09 18:20:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 09 18:20:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 09 18:20:22 volumio volumio[1198]: info: Discovery: Getting this device information Jun 09 18:20:22 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:22 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 09 18:20:25 volumio volumio[1198]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/mpdoutput/volumio/buster/armhf Jun 09 18:20:27 volumio volumio[1198]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/mpdoutput/volumio/buster/armhf Jun 09 18:20:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 09 18:20:27 volumio sudo[6635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 18:20:27 volumio sudo[6635]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:27 volumio systemd[1]: Stopping Music Player Daemon... Jun 09 18:20:27 volumio volumio[1198]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 09 18:20:27 volumio volumio[1198]: info: Check plugin dependencies Jun 09 18:20:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 18:20:27 volumio volumio[1198]: info: Rename folder Jun 09 18:20:27 volumio systemd[1]: mpd.service: Succeeded. Jun 09 18:20:27 volumio systemd[1]: Stopped Music Player Daemon. Jun 09 18:20:27 volumio systemd[1]: Starting Music Player Daemon... Jun 09 18:20:27 volumio volumio[1198]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 09 18:20:27 volumio volumio[1198]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 09 18:20:27 volumio volumio[1198]: info: Folder /tmp/downloaded_plugin.zip removed Jun 09 18:20:27 volumio volumio[1198]: info: Move to category Jun 09 18:20:27 volumio sudo[6645]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 09 18:20:27 volumio sudo[6645]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:27 volumio sudo[6645]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:27 volumio volumio[1198]: info: Checking if install.sh is present Jun 09 18:20:27 volumio volumio[1198]: info: Executing install.sh Jun 09 18:20:27 volumio sudo[6663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/mpdoutput/install.sh Jun 09 18:20:27 volumio sudo[6663]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:27 volumio volumio[1198]: info: Installing mpd http output Jun 09 18:20:27 volumio volumio[1198]: Plugin install end detected on script Jun 09 18:20:27 volumio sudo[6663]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:27 volumio volumio[1198]: info: Install script completed Jun 09 18:20:27 volumio volumio[1198]: info: Done installing plugin. Jun 09 18:20:27 volumio volumio[1198]: info: Enabling plugin mpdoutput Jun 09 18:20:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 09 18:20:27 volumio volumio[1198]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.61","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":true,"enabled":true,"active":false},{"prettyName":"Mpd http & Icecast output","name":"mpdoutput","category":"audio_interface","version":"1.0.5","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.4","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Backup & Restore Data","name":"backup_restore","category":"system_controller","version":"0.7.4","icon":"fa-floppy-o","isManuallyInstalled":false,"enabled":true,"active":true}] Jun 09 18:20:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 09 18:20:27 volumio volumio[1198]: info: Folder /tmp/plugins removed Jun 09 18:20:27 volumio volumio[1198]: info: Folder /tmp/downloaded_plugin.zip removed Jun 09 18:20:27 volumio volumio[1198]: info: Folder /data/temp removed Jun 09 18:20:27 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 09 18:20:27 volumio volumio[1198]: info: FusionDsp - Jun 09 18:20:27 volumio volumio[1198]: info: FusionDsp - undefined Jun 09 18:20:28 volumio mpd[6658]: Jun 09 18:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 09 18:20:29 volumio systemd[1]: Started Music Player Daemon. Jun 09 18:20:29 volumio sudo[6635]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:29 volumio volumio[1198]: error: updateQueue error: null Jun 09 18:20:31 volumio volumio[1198]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Jun 09 18:20:35 volumio volumio[1198]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Jun 09 18:20:35 volumio volumio[1198]: info: FusionDsp - Stopping FusionDsp service Jun 09 18:20:35 volumio volumio[1198]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 18:20:35 volumio volumio[1198]: TypeError: Cannot read property 'stop' of null Jun 09 18:20:35 volumio volumio[1198]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Jun 09 18:20:35 volumio volumio[1198]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jun 09 18:20:35 volumio volumio[1198]: at /volumio/app/pluginmanager.js:956:12 Jun 09 18:20:35 volumio volumio[1198]: at ChildProcess.exithandler (child_process.js:374:7) Jun 09 18:20:35 volumio volumio[1198]: at ChildProcess.emit (events.js:400:28) Jun 09 18:20:35 volumio volumio[1198]: at maybeClose (internal/child_process.js:1088:16) Jun 09 18:20:35 volumio volumio[1198]: at Process.ChildProcess._handle.onexit (internal/child_process.js:296:5) Jun 09 18:20:35 volumio volumio[1198]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 18:20:36 volumio sudo[6703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-09 18:19 Jun 09 18:20:36 volumio sudo[6703]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:36 volumio sudo[6703]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:37 volumio vtcs[5935]: [2025-06-09 18:20:37.070] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Jun 09 18:20:37 volumio volumio-remote-updater[591]: [2025-06-09 18:20:37] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 09 18:20:37 volumio volumio-remote-updater[591]: [2025-06-09 18:20:37] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 09 18:20:37 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 09 18:20:37 volumio systemd[1]: media-VortexBox_Backup.mount: Succeeded. Jun 09 18:20:37 volumio ntfs-3g[1509]: Unmounting /dev/sda1 (VortexBox Backup) Jun 09 18:20:37 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 09 18:20:37 volumio systemd[1]: Started dynamicswap service. Jun 09 18:20:37 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 09 18:20:37 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 09 18:20:37 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jun 09 18:20:37 volumio systemd[1]: Started dynamicswap service. Jun 09 18:20:37 volumio systemd[1]: Stopped Volumio Backend Module. Jun 09 18:20:37 volumio systemd[1]: Started Volumio Backend Module. Jun 09 18:20:37 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 09 18:20:38 volumio volumio[6727]: info: ------------------------------------------- Jun 09 18:20:38 volumio volumio[6727]: info: ----- Volumio3 ---- Jun 09 18:20:38 volumio volumio[6727]: info: ------------------------------------------- Jun 09 18:20:38 volumio volumio[6727]: info: ----- System startup ---- Jun 09 18:20:38 volumio volumio[6727]: info: ------------------------------------------- Jun 09 18:20:39 volumio volumio[6727]: info: MYVOLUMIO Environment detected Jun 09 18:20:39 volumio volumio[6727]: info: Plugin folders cleanup Jun 09 18:20:39 volumio volumio[6727]: info: Scanning into folder /volumio/app/plugins/ Jun 09 18:20:39 volumio volumio[6727]: info: Scanning category audio_interface Jun 09 18:20:39 volumio volumio[6727]: info: Scanning category miscellanea Jun 09 18:20:39 volumio volumio[6727]: info: Scanning category music_service Jun 09 18:20:39 volumio volumio[6727]: info: Scanning category plugins.json Jun 09 18:20:39 volumio volumio[6727]: info: Scanning category system_controller Jun 09 18:20:39 volumio volumio[6727]: info: Scanning category user_interface Jun 09 18:20:39 volumio volumio[6727]: info: Scanning into folder /data/plugins/ Jun 09 18:20:39 volumio volumio[6727]: info: Scanning category audio_interface Jun 09 18:20:39 volumio volumio[6727]: info: Scanning category music_service Jun 09 18:20:39 volumio volumio[6727]: info: Scanning category system_controller Jun 09 18:20:39 volumio volumio[6727]: info: Plugin folders cleanup completed Jun 09 18:20:39 volumio volumio[6727]: info: ------------------------------------------- Jun 09 18:20:39 volumio volumio[6727]: info: ----- Core plugins startup ---- Jun 09 18:20:39 volumio volumio[6727]: info: ------------------------------------------- Jun 09 18:20:39 volumio volumio[6727]: info: Loading plugins from folder /volumio/app/plugins/ Jun 09 18:20:39 volumio volumio[6727]: info: Adding plugin upnp to MyMusic Plugins Jun 09 18:20:39 volumio volumio[6727]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 09 18:20:39 volumio volumio[6727]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 09 18:20:39 volumio volumio[6727]: info: Loading plugins from folder /data/plugins/ Jun 09 18:20:39 volumio volumio[6727]: info: Loading plugin "system"... Jun 09 18:20:39 volumio volumio[6727]: info: Loading plugin "appearance"... Jun 09 18:20:40 volumio volumio[6727]: info: Loading plugin "network"... Jun 09 18:20:40 volumio volumio[6727]: info: Refreshing Cached IP Addresses Jun 09 18:20:40 volumio sudo[6757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 09 18:20:40 volumio sudo[6759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 09 18:20:40 volumio volumio[6727]: info: Loading plugin "services"... Jun 09 18:20:40 volumio volumio[6727]: info: Loading plugin "alsa_controller"... Jun 09 18:20:40 volumio sudo[6757]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:40 volumio sudo[6759]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:40 volumio sudo[6757]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:40 volumio sudo[6767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 09 18:20:40 volumio sudo[6759]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:40 volumio sudo[6767]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:40 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 18:20:40 volumio volumio[6727]: info: Loading plugin "wizard"... Jun 09 18:20:40 volumio volumio[6727]: info: Loading plugin "networkfs"... Jun 09 18:20:40 volumio volumio[6727]: info: Starting Udev Watcher for removable devices Jun 09 18:20:40 volumio volumio[6727]: info: Ignoring mount for partition: BOOT Jun 09 18:20:40 volumio volumio[6727]: info: Ignoring mount for partition: volumio Jun 09 18:20:40 volumio volumio[6727]: info: Ignoring mount for partition: volumio_data Jun 09 18:20:40 volumio volumio[6727]: info: Mounting Device VortexBox_Backup Jun 09 18:20:40 volumio sudo[6811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/VortexBox_Backup -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 09 18:20:40 volumio sudo[6811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:40 volumio sudo[6767]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:42 volumio volumio-remote-updater[591]: [2025-06-09 18:20:42] [connect] Successful connection Jun 09 18:20:42 volumio ntfs-3g[6822]: Version 2017.3.23AR.3 integrated FUSE 28 Jun 09 18:20:42 volumio ntfs-3g[6822]: Mounted /dev/sda1 (Read-Write, label "VortexBox Backup", NTFS 3.1) Jun 09 18:20:42 volumio ntfs-3g[6822]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 09 18:20:42 volumio ntfs-3g[6822]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Jun 09 18:20:42 volumio ntfs-3g[6822]: Global ownership and permissions enforced, configuration type 7 Jun 09 18:20:42 volumio sudo[6811]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:42 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 18:20:42 volumio volumio[6727]: info: Loading plugin "volumio_command_line_client"... Jun 09 18:20:42 volumio volumio[6727]: info: Loading plugin "upnp"... Jun 09 18:20:42 volumio volumio[6727]: info: [1749489642133] Starting Upmpd Daemon Jun 09 18:20:42 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 18:20:42 volumio volumio[6727]: info: Loading plugin "my_music"... Jun 09 18:20:42 volumio volumio[6727]: info: Loading plugin "mpd"... Jun 09 18:20:42 volumio volumio[6727]: info: Loading plugin "upnp_browser"... Jun 09 18:20:42 volumio volumio[6727]: info: Loading plugin "alarm-clock"... Jun 09 18:20:42 volumio volumio[6727]: info: Loading plugin "airplay_emulation"... Jun 09 18:20:42 volumio volumio[6727]: info: Starting Shairport Sync Jun 09 18:20:42 volumio volumio[6727]: info: Loading plugin "last_100"... Jun 09 18:20:42 volumio volumio[6727]: info: Loading plugin "webradio"... Jun 09 18:20:42 volumio volumio[6727]: info: Loading plugin "i2s_dacs"... Jun 09 18:20:42 volumio volumio[6727]: info: I2S DAC not set, start Auto-detection Jun 09 18:20:42 volumio volumio[6727]: info: Loading plugin "volumiodiscovery"... Jun 09 18:20:42 volumio volumio[6727]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 09 18:20:42 volumio volumio[6727]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 18:20:42 volumio volumio[6727]: *** WARNING *** For more information see Jun 09 18:20:42 volumio volumio[6727]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 09 18:20:42 volumio volumio[6727]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 18:20:42 volumio node[6727]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 09 18:20:42 volumio volumio[6727]: *** WARNING *** For more information see Jun 09 18:20:42 volumio node[6727]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 18:20:42 volumio node[6727]: *** WARNING *** For more information see Jun 09 18:20:42 volumio node[6727]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 09 18:20:42 volumio node[6727]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 18:20:42 volumio node[6727]: *** WARNING *** For more information see Jun 09 18:20:42 volumio volumio[6727]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 09 18:20:42 volumio volumio[6727]: info: Discovery: Started advertising with name: Volumio Jun 09 18:20:42 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 18:20:42 volumio volumio[6727]: info: Loading plugin "outputs"... Jun 09 18:20:42 volumio volumio[6727]: info: Loading plugin "albumart"... Jun 09 18:20:42 volumio volumio[6727]: info: Plugin example_plugin is not enabled Jun 09 18:20:42 volumio volumio[6727]: info: Loading plugin "inputs"... Jun 09 18:20:42 volumio volumio[6727]: info: Loading plugin "updater_comm"... Jun 09 18:20:43 volumio volumio[6727]: info: Plugin mpdemulation is not enabled Jun 09 18:20:43 volumio volumio[6727]: info: Loading plugin "rest_api"... Jun 09 18:20:43 volumio volumio[6727]: info: Loading plugin "websocket"... Jun 09 18:20:43 volumio volumio[6727]: info: Starting Socket.io Server version 2.3.0 Jun 09 18:20:43 volumio volumio[6727]: info: Loading plugin "fusiondsp"... Jun 09 18:20:43 volumio volumio[6727]: Forking 3 albumart workers Jun 09 18:20:43 volumio volumio[6727]: info: Applying required configuration parameters for plugin fusiondsp Jun 09 18:20:43 volumio volumio[6727]: info: Loading plugin "mpdoutput"... Jun 09 18:20:43 volumio volumio[6727]: info: Loading plugin "80s80s"... Jun 09 18:20:43 volumio volumio[6727]: info: Applying required configuration parameters for plugin 80s80s Jun 09 18:20:43 volumio volumio[6727]: info: [1749489643684] [80s80s] API delay: 30 Jun 09 18:20:43 volumio volumio[6727]: info: Loading plugin "backup_restore"... Jun 09 18:20:43 volumio volumio[6727]: info: Applying required configuration parameters for plugin backup_restore Jun 09 18:20:43 volumio volumio[6727]: info: Loading i18n strings for locale fr Jun 09 18:20:43 volumio volumio[6727]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Jun 09 18:20:43 volumio volumio[6727]: Updating browse sources language Jun 09 18:20:43 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 18:20:43 volumio volumio[6727]: Starting albumart workers Jun 09 18:20:43 volumio volumio[6727]: Starting albumart workers Jun 09 18:20:43 volumio volumio[6727]: Starting albumart workers Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::initPlayerControls Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 18:20:44 volumio volumio[6727]: Express server listening on port 3000 Jun 09 18:20:44 volumio volumio[6727]: [Metrics] WebUI: 5s 543.71ms Jun 09 18:20:44 volumio volumio[6727]: info: CoreStateMachine::resetVolumioState Jun 09 18:20:44 volumio volumio[6727]: info: CoreStateMachine::getcurrentVolume Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::volumioRetrievevolume Jun 09 18:20:44 volumio volumio[6727]: info: CoreStateMachine::pushState Jun 09 18:20:44 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::volumioPushState Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 18:20:44 volumio volumio[6727]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 09 18:20:44 volumio volumio[6727]: info: Completed loading Core Plugins Jun 09 18:20:44 volumio volumio[6727]: info: Preparing to generate the ALSA configuration file Jun 09 18:20:44 volumio volumio[6727]: info: Volumio Network Manager: Network status updated: 3 Jun 09 18:20:44 volumio volumio[6727]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 09 18:20:44 volumio volumio[6727]: info: Reading ALSA contributions from plugins. Jun 09 18:20:44 volumio volumio[6727]: verbose: New Socket.io Connection to 192.168.1.106 from 192.168.1.113 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 1 Jun 09 18:20:44 volumio volumio[6727]: verbose: New Socket.io Connection to 192.168.1.107:3000 from 192.168.1.113 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Jun 09 18:20:44 volumio volumio[6727]: verbose: New Socket.io Connection to 192.168.1.106 from 192.168.1.113 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 3 Jun 09 18:20:44 volumio volumio[6727]: verbose: New Socket.io Connection to 192.168.1.106 from 192.168.1.113 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 4 Jun 09 18:20:44 volumio volumio[6727]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 5 Jun 09 18:20:44 volumio volumio[6727]: verbose: New Socket.io Connection to 192.168.1.106 from 192.168.1.113 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 Jun 09 18:20:44 volumio volumio-remote-updater[591]: [2025-06-09 18:20:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749489642 101 Jun 09 18:20:44 volumio volumio[6727]: 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: 7 Jun 09 18:20:44 volumio volumio[6727]: verbose: New Socket.io Connection to 192.168.1.106 from 192.168.1.113 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 8 Jun 09 18:20:44 volumio volumio[6727]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 9 Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 09 18:20:44 volumio volumio[6727]: info: Discovery: Getting this device information Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:44 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 09 18:20:44 volumio volumio[6727]: verbose: New Socket.io Connection to 192.168.1.106 from 192.168.1.113 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 10 Jun 09 18:20:44 volumio volumio[6727]: info: Reloading queue from file Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 09 18:20:44 volumio volumio[6727]: info: CoreStateMachine::setRepeat true single undefined Jun 09 18:20:44 volumio volumio[6727]: info: CoreStateMachine::pushState Jun 09 18:20:44 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::volumioPushState Jun 09 18:20:44 volumio volumio[6727]: info: CoreStateMachine::setRandom false Jun 09 18:20:44 volumio volumio[6727]: info: CoreStateMachine::pushState Jun 09 18:20:44 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::volumioPushState Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::volumioGetVisibleSources Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:44 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:44 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 09 18:20:44 volumio volumio[6727]: info: Received Get System Info Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 09 18:20:44 volumio volumio[6727]: info: Discovery: Getting this device information Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:44 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:44 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:44 volumio volumio[6727]: info: Listing playlists Jun 09 18:20:44 volumio volumio[6727]: verbose: New Socket.io Connection to 192.168.1.106 from 192.168.1.113 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 11 Jun 09 18:20:44 volumio volumio[6727]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.113 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 12 Jun 09 18:20:44 volumio volumio[6727]: info: Setting Device type: Tinkerboard Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:44 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:44 volumio volumio[6727]: info: Asound.conf file written Jun 09 18:20:44 volumio sudo[6891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 09 18:20:44 volumio sudo[6891]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:44 volumio sudo[6891]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:44 volumio volumio[6727]: info: Output device has changed, restarting MPD Jun 09 18:20:44 volumio volumio[6727]: info: Output device has changed, restarting Shairport Sync Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 18:20:44 volumio sudo[6903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 09 18:20:44 volumio sudo[6906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 18:20:44 volumio sudo[6903]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:44 volumio sudo[6903]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:44 volumio sudo[6906]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:44 volumio volumio[6727]: info: ___________ START PLUGINS ___________ Jun 09 18:20:44 volumio volumio[6727]: info: ControllerMpd::onStart: Initializing MPD Jun 09 18:20:44 volumio volumio[6727]: info: Creating MPD Configuration file Jun 09 18:20:44 volumio systemd[1]: Stopping Music Player Daemon... Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 18:20:44 volumio volumio[6727]: info: [1749489644962] CoreMusicLibrary::Adding element Serveurs Média Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 18:20:44 volumio sudo[6923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 09 18:20:44 volumio sudo[6926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 18:20:44 volumio sudo[6923]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:44 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 18:20:45 volumio sudo[6926]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:45 volumio sudo[6923]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 18:20:45 volumio systemd[1]: mpd.service: Succeeded. Jun 09 18:20:45 volumio systemd[1]: Stopped Music Player Daemon. Jun 09 18:20:45 volumio systemd[1]: Starting Music Player Daemon... Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 18:20:45 volumio volumio[6727]: info: [1749489645066] CoreMusicLibrary::Adding element Last_100 Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 18:20:45 volumio volumio[6727]: info: [1749489645069] CoreMusicLibrary::Adding element Webradio Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 18:20:45 volumio volumio[6727]: info: Initializing BBC Radios Jun 09 18:20:45 volumio sudo[6944]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: Loading i18n strings for locale fr Jun 09 18:20:45 volumio sudo[6944]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:45 volumio volumio[6727]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: FusionDsp - mixtype--------------------- None Jun 09 18:20:45 volumio sudo[6944]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 18:20:45 volumio volumio[6727]: info: [1749489645137] CoreMusicLibrary::Adding element 80s80s Radio Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 18:20:45 volumio volumio[6727]: Cannot find translation for source 80s80s Radio Jun 09 18:20:45 volumio volumio[6727]: info: Volumio Calling Home Jun 09 18:20:45 volumio volumio[6727]: info: Preparing to generate the ALSA configuration file Jun 09 18:20:45 volumio volumio[6727]: info: Discovery: adding e7df10cc-a9f3-4788-b231-c51d468cd691 Jun 09 18:20:45 volumio volumio[6727]: info: Discovery: Found device Volumio Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:45 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:45 volumio volumio[6727]: info: Discovery: this is already registered, e7df10cc-a9f3-4788-b231-c51d468cd691 Jun 09 18:20:45 volumio volumio[6727]: info: Discovery: Found device Volumio Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:45 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:45 volumio volumio[6727]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 09 18:20:45 volumio volumio[6727]: info: Reading ALSA contributions from plugins. Jun 09 18:20:45 volumio volumio[6727]: info: Received Get System Info Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 09 18:20:45 volumio volumio[6727]: info: Discovery: Getting this device information Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:45 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:45 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::volumioGetVisibleSources Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:45 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:45 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::volumioGetQueue Jun 09 18:20:45 volumio volumio[6727]: info: CoreStateMachine::getQueue Jun 09 18:20:45 volumio volumio[6727]: info: CorePlayQueue::getQueue Jun 09 18:20:45 volumio volumio[6727]: info: Listing playlists Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 09 18:20:45 volumio volumio[6727]: info: Received Get System Info Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 09 18:20:45 volumio volumio[6727]: info: Discovery: Getting this device information Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:45 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:45 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 09 18:20:45 volumio volumio[6727]: info: MPD Permissions set Jun 09 18:20:45 volumio volumio[6727]: info: MPD Permissions set Jun 09 18:20:45 volumio volumio[6727]: info: Volumio called home Jun 09 18:20:45 volumio volumio[6727]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 09 18:20:45 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:45 volumio volumio[6727]: info: FusionDsp - Jun 09 18:20:45 volumio volumio[6727]: info: FusionDsp - Jun 09 18:20:45 volumio volumio[6727]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.113 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 14 Jun 09 18:20:45 volumio volumio[6727]: info: FusionDsp - undefined Jun 09 18:20:45 volumio volumio[6727]: info: FusionDsp - undefined Jun 09 18:20:46 volumio volumio[6727]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 09 18:20:46 volumio volumio[6727]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 09 18:20:46 volumio volumio[6727]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 09 18:20:46 volumio volumio[6727]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 09 18:20:46 volumio volumio[6727]: info: Starting Shairport Sync Jun 09 18:20:46 volumio volumio[6727]: info: Starting Shairport Sync Jun 09 18:20:46 volumio volumio[6727]: info: Starting Shairport Sync Jun 09 18:20:46 volumio sudo[6982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 09 18:20:46 volumio sudo[6984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 09 18:20:46 volumio mpd[6968]: Jun 09 18:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 09 18:20:46 volumio sudo[6982]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:46 volumio sudo[6984]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:46 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 09 18:20:46 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 09 18:20:46 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 09 18:20:46 volumio sudo[6992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 09 18:20:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 09 18:20:46 volumio sudo[6992]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:46 volumio sudo[6982]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 09 18:20:46 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 09 18:20:46 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 09 18:20:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 09 18:20:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 09 18:20:46 volumio sudo[6984]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:46 volumio sudo[6992]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:46 volumio volumio[6727]: info: Asound.conf file unchanged, so no further update is needed Jun 09 18:20:46 volumio volumio[6727]: info: Output device has changed, restarting MPD Jun 09 18:20:46 volumio volumio[6727]: info: Output device has changed, restarting Shairport Sync Jun 09 18:20:46 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:46 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 18:20:46 volumio sudo[7017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 09 18:20:46 volumio sudo[7017]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:46 volumio sudo[7017]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:46 volumio sudo[7019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 18:20:46 volumio sudo[7019]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:46 volumio volumio[6727]: info: Shairport-Sync Started Jun 09 18:20:46 volumio volumio[6727]: Error adding Membership: Error: addMembership EINVAL Jun 09 18:20:46 volumio volumio[6727]: info: Shairport-Sync Started Jun 09 18:20:46 volumio volumio[6727]: info: Shairport-Sync Started Jun 09 18:20:46 volumio volumio[6727]: info: FusionDsp - Jun 09 18:20:46 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 09 18:20:46 volumio systemd[1]: mpd.service: Succeeded. Jun 09 18:20:46 volumio systemd[1]: Stopped Music Player Daemon. Jun 09 18:20:46 volumio systemd[1]: Starting Music Player Daemon... Jun 09 18:20:46 volumio sudo[7036]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 09 18:20:46 volumio sudo[7036]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:46 volumio sudo[7036]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:46 volumio volumio[6727]: info: FusionDsp - Jun 09 18:20:46 volumio volumio[6727]: info: FusionDsp - Jun 09 18:20:46 volumio volumio[6727]: info: FusionDsp - Jun 09 18:20:46 volumio volumio[6727]: info: MPD Permissions set Jun 09 18:20:46 volumio volumio[6727]: info: FusionDsp - undefined Jun 09 18:20:46 volumio volumio[6727]: info: FusionDsp - undefined Jun 09 18:20:46 volumio volumio[6727]: info: FusionDsp - undefined Jun 09 18:20:46 volumio volumio[6727]: info: FusionDsp - undefined Jun 09 18:20:47 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:47 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:47 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:47 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:47 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:47 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:47 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:47 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:47 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 18:20:47 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 09 18:20:47 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:47 volumio volumio[6727]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.113 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 15 Jun 09 18:20:47 volumio volumio[6727]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 09 18:20:47 volumio volumio[6727]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jun 09 18:20:47 volumio volumio[6727]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 09 18:20:47 volumio volumio[6727]: info: camilladsp service started and running in background, instance 1 Jun 09 18:20:47 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 18:20:47 volumio volumio[6727]: cannot open device 'volumioHw': Device or resource busy Jun 09 18:20:47 volumio volumio[6727]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 09 18:20:47 volumio volumio[6727]: cannot open device 'volumioHw': Device or resource busy Jun 09 18:20:47 volumio volumio[6727]: info: FusionDsp loaded Jun 09 18:20:47 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 09 18:20:47 volumio volumio[6727]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 09 18:20:47 volumio volumio[6727]: info: Adding Signal Path Element [object Object] Jun 09 18:20:47 volumio volumio[6727]: info: Adding fusiondspeq DSP Signal Path Element Jun 09 18:20:47 volumio volumio[6727]: info: FusionDsp - ---- installed callbackRead Jun 09 18:20:47 volumio volumio[6727]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 09 18:20:47 volumio sudo[7069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 09 18:20:47 volumio sudo[7069]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:47 volumio volumio[6727]: info: Starting Shairport Sync Jun 09 18:20:47 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:47 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:47 volumio volumio[6727]: info: CoreCommandRouter::volumioPause Jun 09 18:20:47 volumio volumio[6727]: info: CoreStateMachine::pause Jun 09 18:20:47 volumio volumio[6727]: info: mpdhttpout --- Volumio set on pause Jun 09 18:20:47 volumio volumio[6727]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Jun 09 18:20:47 volumio volumio[6727]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Jun 09 18:20:47 volumio systemd[1]: Started FusionDsp Daemon. Jun 09 18:20:47 volumio sudo[7077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 09 18:20:47 volumio sudo[7069]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:47 volumio sudo[7077]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:47 volumio volumio[6727]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 09 18:20:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 09 18:20:47 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 09 18:20:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 09 18:20:47 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 09 18:20:47 volumio sudo[7077]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:47 volumio volumio[6727]: info: Shairport-Sync Started Jun 09 18:20:47 volumio volumio[6727]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.113 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 17 Jun 09 18:20:47 volumio volumio[6727]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.113 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 18 Jun 09 18:20:47 volumio mpd[7045]: Jun 09 18:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 09 18:20:47 volumio volumio[6727]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.113 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 19 Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 09 18:20:48 volumio volumio[6727]: info: Discovery: Getting this device information Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:48 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 09 18:20:48 volumio volumio[6727]: info: Discovery: Getting this device information Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:48 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 09 18:20:48 volumio volumio[6727]: verbose: New Socket.io Connection to 192.168.1.107:3000 from 192.168.1.113 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 20 Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:48 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 09 18:20:48 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 09 18:20:48 volumio systemd[1]: Started Music Player Daemon. Jun 09 18:20:48 volumio sudo[7019]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:48 volumio sudo[6926]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:48 volumio sudo[6906]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:49 volumio volumio[6727]: info: Completed starting Core Plugins Jun 09 18:20:49 volumio volumio[6727]: info: ------------------------------------------- Jun 09 18:20:49 volumio volumio[6727]: info: ----- MyVolumio plugins startup ---- Jun 09 18:20:49 volumio volumio[6727]: info: ------------------------------------------- Jun 09 18:20:49 volumio volumio[6727]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 09 18:20:49 volumio volumio[6727]: error: MPD error: The expression evaluated to a falsy value: Jun 09 18:20:49 volumio volumio[6727]: assert.ok(self.idling) Jun 09 18:20:49 volumio volumio[6727]: error: The expression evaluated to a falsy value: Jun 09 18:20:49 volumio volumio[6727]: assert.ok(self.idling) Jun 09 18:20:49 volumio volumio[6727]: error: MPD error: The expression evaluated to a falsy value: Jun 09 18:20:49 volumio volumio[6727]: assert.ok(self.idling) Jun 09 18:20:49 volumio volumio[6727]: error: The expression evaluated to a falsy value: Jun 09 18:20:49 volumio volumio[6727]: assert.ok(self.idling) Jun 09 18:20:49 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:49 volumio volumio[6727]: info: MPD running with PID7045 Jun 09 18:20:49 volumio volumio[6727]: ,establishing connection Jun 09 18:20:49 volumio volumio[6727]: error: updateQueue error: null Jun 09 18:20:49 volumio volumio[6727]: error: updateQueue error: null Jun 09 18:20:49 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 09 18:20:49 volumio sudo[7108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 18:20:49 volumio sudo[7108]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:49 volumio systemd[1]: Stopping Music Player Daemon... Jun 09 18:20:49 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:49 volumio systemd[1]: mpd.service: Succeeded. Jun 09 18:20:49 volumio systemd[1]: Stopped Music Player Daemon. Jun 09 18:20:49 volumio systemd[1]: Starting Music Player Daemon... Jun 09 18:20:49 volumio sudo[7114]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 09 18:20:49 volumio sudo[7114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:49 volumio sudo[7114]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:50 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:50 volumio mpd[7123]: Jun 09 18:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 09 18:20:50 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:51 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:51 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:51 volumio systemd[1]: Started Music Player Daemon. Jun 09 18:20:51 volumio sudo[7108]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:51 volumio volumio[6727]: error: updateQueue error: null Jun 09 18:20:52 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:52 volumio sudo[7144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 09 18:20:52 volumio sudo[7144]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:52 volumio sudo[7144]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:52 volumio sudo[7147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 09 18:20:52 volumio sudo[7151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 09 18:20:52 volumio sudo[7147]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:52 volumio sudo[7151]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:52 volumio sudo[7147]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:52 volumio sudo[7151]: pam_unix(sudo:session): session closed for user root Jun 09 18:20:52 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 09 18:20:52 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 09 18:20:52 volumio volumio[6727]: info: Discovery: Getting this device information Jun 09 18:20:52 volumio volumio[6727]: info: CoreCommandRouter::volumioGetState Jun 09 18:20:52 volumio volumio[6727]: info: CorePlayQueue::getTrack 0 Jun 09 18:20:52 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 09 18:20:52 volumio volumio[6727]: info: Upmpdcli Daemon Started Jun 09 18:20:52 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:53 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:53 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:54 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:54 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 09 18:20:54 volumio volumio[6727]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/bandcamp/volumio/buster/armhf Jun 09 18:20:57 volumio volumio[6727]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/bandcamp/volumio/buster/armhf Jun 09 18:20:57 volumio volumio[6727]: info: Folder /tmp/plugins removed Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 09 18:20:58 volumio volumio[6727]: info: Adding plugin bluetooth to MyMusic Plugins Jun 09 18:20:58 volumio volumio[6727]: info: Adding plugin multiroom to MyMusic Plugins Jun 09 18:20:58 volumio volumio[6727]: info: Adding plugin metavolumio to MyMusic Plugins Jun 09 18:20:58 volumio volumio[6727]: info: Adding plugin cd_controller to MyMusic Plugins Jun 09 18:20:58 volumio volumio[6727]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 09 18:20:58 volumio volumio[6727]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 09 18:20:58 volumio volumio[6727]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 09 18:20:59 volumio volumio[6727]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 09 18:20:59 volumio volumio[6727]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 09 18:20:59 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 18:20:59 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 18:20:59 volumio volumio[6727]: info: Starting MyVolumio Remote Streaming Endpoints Jun 09 18:20:59 volumio volumio[6727]: info: MyVolumio login type: Token Jun 09 18:20:59 volumio volumio[6727]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 09 18:20:59 volumio volumio[6727]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 09 18:20:59 volumio volumio[6727]: info: Starting Streaming Service Transparent Proxy Jun 09 18:20:59 volumio volumio[6727]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 09 18:20:59 volumio volumio[6727]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 09 18:20:59 volumio volumio[6727]: info: Streaming services startup Jun 09 18:20:59 volumio volumio[6727]: info: Starting Streaming Daemon Jun 09 18:20:59 volumio sudo[7241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 09 18:20:59 volumio sudo[7241]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:20:59 volumio volumio[6727]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 09 18:20:59 volumio sudo[7241]: pam_unix(sudo:session): session closed for user root Jun 09 18:21:00 volumio volumio[6727]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/RoonBridge/volumio/buster/armhf Jun 09 18:21:00 volumio volumio[6727]: error: Cannot start Volumio Streaming Daemon Jun 09 18:21:00 volumio volumio[6727]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 09 18:21:00 volumio volumio[6727]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 09 18:21:00 volumio volumio[6727]: info: Check plugin dependencies Jun 09 18:21:00 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 18:21:00 volumio volumio[6727]: info: Checking if plugin already exists Jun 09 18:21:00 volumio volumio[6727]: info: Rename folder Jun 09 18:21:00 volumio volumio[6727]: info: Move to category Jun 09 18:21:00 volumio volumio[6727]: info: Folder /tmp/downloaded_plugin.zip removed Jun 09 18:21:00 volumio volumio[6727]: STREAMING PROXY: Starting server on port 3245 Jun 09 18:21:00 volumio volumio[6727]: Node JS runtime: 14 Jun 09 18:21:01 volumio volumio[6727]: info: Checking if install.sh is present Jun 09 18:21:01 volumio volumio[6727]: info: Executing install.sh Jun 09 18:21:01 volumio sudo[7285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/bandcamp/install.sh Jun 09 18:21:01 volumio sudo[7285]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 18:21:01 volumio sudo[7285]: pam_unix(sudo:session): session closed for user root Jun 09 18:21:01 volumio volumio[6727]: info: Install script completed Jun 09 18:21:01 volumio volumio[6727]: info: Adding reference to registry Jun 09 18:21:01 volumio volumio[6727]: info: Done installing plugin. Jun 09 18:21:01 volumio volumio[6727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 09 18:21:02 volumio volumio[6727]: info: tallend Jun 09 18:21:02 volumio volumio[6727]: info: Folder /tmp/plugins removed Jun 09 18:21:02 volumio volumio[6727]: info: Folder /tmp/downloaded_plugin.zip removed Jun 09 18:21:02 volumio volumio[6727]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 09 18:21:02 volumio volumio[6727]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 09 18:21:02 volumio volumio[6727]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/RoonBridge/volumio/buster/armhf Jun 09 18:21:02 volumio volumio[6727]: info: Folder /data/temp removed Jun 09 18:21:02 volumio volumio[6727]: info: Folder /tmp/plugins removed Jun 09 18:21:02 volumio volumio[6727]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 09 18:21:02 volumio volumio[6727]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 09 18:21:02 volumio volumio[6727]: info: FusionDsp - Jun 09 18:21:02 volumio volumio[6727]: info: FusionDsp - Jun 09 18:21:03 volumio volumio[6727]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/RoonBridge/volumio/buster/armhf Jun 09 18:21:03 volumio volumio[6727]: info: FusionDsp - undefined Jun 09 18:21:03 volumio volumio[6727]: info: FusionDsp - undefined Jun 09 18:21:03 volumio volumio[6727]: info: FusionDsp - Jun 09 18:21:03 volumio volumio[6727]: info: FusionDsp - Jun 09 18:21:03 volumio volumio[6727]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 09 18:21:03 volumio volumio[6727]: info: An error occurred installing the plugin. Rolling back config Jun 09 18:21:03 volumio volumio[6727]: info: Plugin folders cleanup Jun 09 18:21:03 volumio volumio[6727]: info: Scanning into folder /volumio/app/plugins/ Jun 09 18:21:03 volumio volumio[6727]: info: Scanning category audio_interface Jun 09 18:21:03 volumio volumio[6727]: info: Scanning category miscellanea Jun 09 18:21:03 volumio volumio[6727]: info: Scanning category music_service Jun 09 18:21:03 volumio volumio[6727]: info: Scanning category plugins.json Jun 09 18:21:03 volumio volumio[6727]: info: Scanning category system_controller Jun 09 18:21:03 volumio volumio[6727]: info: Scanning category user_interface Jun 09 18:21:03 volumio volumio[6727]: info: Scanning into folder /data/plugins/ Jun 09 18:21:03 volumio volumio[6727]: info: Scanning category audio_interface Jun 09 18:21:03 volumio volumio[6727]: info: Scanning category music_service Jun 09 18:21:03 volumio volumio[6727]: info: Scanning category system_controller Jun 09 18:21:03 volumio volumio[6727]: info: Plugin folders cleanup completed Jun 09 18:21:03 volumio volumio[6727]: info: Error: Error Jun 09 18:21:03 volumio volumio[6727]: info: FusionDsp - undefined Jun 09 18:21:03 volumio volumio[6727]: info: FusionDsp - undefined Jun 09 18:21:03 volumio volumio[6727]: info: Folder /tmp/plugins removed Jun 09 18:21:04 volumio volumio[6727]: info: Folder /tmp/downloaded_plugin.zip removed Jun 09 18:21:04 volumio volumio[6727]: info: Folder /tmp/downloaded_plugin.zip removed Jun 09 18:21:04 volumio volumio[6727]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/RoonBridge/volumio/buster/armhf Jun 09 18:21:04 volumio volumio[6727]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/RoonBridge/volumio/buster/armhf Jun 09 18:21:04 volumio volumio[6727]: info: Folder /tmp/plugins removed Jun 09 18:21:05 volumio volumio[6727]: info: Folder /data/temp removed Jun 09 18:21:05 volumio volumio[6727]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 18:21:05 volumio volumio[6727]: Error: Unable to resolve or reject the same promise twice Jun 09 18:21:05 volumio volumio[6727]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 09 18:21:05 volumio volumio[6727]: at /volumio/app/pluginmanager.js:1087:11 Jun 09 18:21:05 volumio volumio[6727]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Jun 09 18:21:05 volumio volumio[6727]: at FSReqCallback.oncomplete (fs.js:180:23) Jun 09 18:21:05 volumio volumio[6727]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 18:21:05 volumio sudo[7312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-09 18:20 Jun 09 18:21:05 volumio sudo[7312]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:53:51 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="53825f995260683aee752aed6b29f24e"