-- Logs begin at Sat 2024-10-26 17:13:45 EEST, end at Sun 2025-04-27 20:49:07 EEST. -- Apr 27 20:48:24 minidsp-shd ntpd[850]: receive: Unexpected origin timestamp 0xeac777be.e7141f88 does not match aorg 0000000000.00000000 from server@94.237.33.206 xmt 0xebb8ec68.1776b90e Apr 27 20:48:24 minidsp-shd ntpd[850]: receive: Unexpected origin timestamp 0xeac777be.e7219433 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xebb8ec68.176f02f4 Apr 27 20:48:24 minidsp-shd ntpd[850]: receive: Unexpected origin timestamp 0xeac777be.e71d2fbd does not match aorg 0000000000.00000000 from server@37.27.186.231 xmt 0xebb8ec68.17896f7a Apr 27 20:48:24 minidsp-shd ntpd[850]: receive: Unexpected origin timestamp 0xeac777be.e72075ed does not match aorg 0000000000.00000000 from server@65.21.242.181 xmt 0xebb8ec68.178e2b40 Apr 27 20:48:24 minidsp-shd ntpd[850]: receive: Unexpected origin timestamp 0xeac777be.e71bc671 does not match aorg 0000000000.00000000 from server@65.109.9.242 xmt 0xebb8ec68.1783ff04 Apr 27 20:48:24 minidsp-shd ntpd[850]: receive: Unexpected origin timestamp 0xeac777be.e71f6cb5 does not match aorg 0000000000.00000000 from server@195.148.70.12 xmt 0xebb8ec68.17d239fa Apr 27 20:48:24 minidsp-shd systemd[1]: Starting Daily apt download activities... Apr 27 20:48:26 minidsp-shd volumio[856]: info: Loading plugin "airplay_emulation"... Apr 27 20:48:26 minidsp-shd volumio[856]: info: Starting Shairport Sync Apr 27 20:48:26 minidsp-shd volumio[856]: info: Loading plugin "cd_controller"... Apr 27 20:48:26 minidsp-shd volumio[856]: info: Loading plugin "last_100"... Apr 27 20:48:26 minidsp-shd volumio[856]: info: Loading plugin "raat"... Apr 27 20:48:26 minidsp-shd volumio[856]: info: RAAT Plugin loaded Apr 27 20:48:26 minidsp-shd volumio[856]: info: Adding restartRAATSocket REST API Endpoint Apr 27 20:48:26 minidsp-shd volumio[856]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Apr 27 20:48:26 minidsp-shd volumio[856]: info: Loading plugin "streaming_services"... Apr 27 20:48:27 minidsp-shd systemd[1]: apt-daily.service: Succeeded. Apr 27 20:48:27 minidsp-shd systemd[1]: Started Daily apt download activities. Apr 27 20:48:27 minidsp-shd systemd[1]: Starting Daily apt upgrade and clean activities... Apr 27 20:48:28 minidsp-shd volumio[856]: info: Starting Streaming Service Transparent Proxy Apr 27 20:48:28 minidsp-shd volumio[856]: info: Loading plugin "tidalconnect"... Apr 27 20:48:29 minidsp-shd volumio[856]: info: Loading plugin "webradio"... Apr 27 20:48:29 minidsp-shd volumio[856]: info: Loading plugin "i2s_dacs"... Apr 27 20:48:29 minidsp-shd volumio[856]: info: I2S DAC not set, start Auto-detection Apr 27 20:48:29 minidsp-shd volumio[856]: info: Loading plugin "volumiodiscovery"... Apr 27 20:48:29 minidsp-shd volumio[856]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 27 20:48:29 minidsp-shd node[856]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 27 20:48:29 minidsp-shd volumio[856]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 27 20:48:29 minidsp-shd node[856]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 27 20:48:29 minidsp-shd volumio[856]: *** WARNING *** For more information see Apr 27 20:48:29 minidsp-shd node[856]: *** WARNING *** For more information see Apr 27 20:48:29 minidsp-shd volumio[856]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 27 20:48:29 minidsp-shd node[856]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 27 20:48:29 minidsp-shd volumio[856]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 27 20:48:29 minidsp-shd node[856]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 27 20:48:29 minidsp-shd volumio[856]: *** WARNING *** For more information see Apr 27 20:48:29 minidsp-shd node[856]: *** WARNING *** For more information see Apr 27 20:48:29 minidsp-shd volumio[856]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 27 20:48:29 minidsp-shd volumio[856]: info: Discovery: Started advertising with name: miniDSP SHD Apr 27 20:48:29 minidsp-shd volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 27 20:48:29 minidsp-shd volumio[856]: info: Loading plugin "spop"... Apr 27 20:48:30 minidsp-shd systemd[1]: apt-daily-upgrade.service: Succeeded. Apr 27 20:48:30 minidsp-shd systemd[1]: Started Daily apt upgrade and clean activities. Apr 27 20:48:30 minidsp-shd volumio[856]: STREAMING PROXY: Starting server on port 3245 Apr 27 20:48:30 minidsp-shd volumio[856]: Node JS runtime: 14 Apr 27 20:48:31 minidsp-shd volumio[856]: info: Loading plugin "multiroom"... Apr 27 20:48:34 minidsp-shd volumio[856]: info: Applying required configuration parameters for plugin multiroom Apr 27 20:48:34 minidsp-shd sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 27 20:48:34 minidsp-shd sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:48:34 minidsp-shd sudo[1024]: pam_unix(sudo:session): session closed for user root Apr 27 20:48:34 minidsp-shd volumio[856]: info: MRS: MultiRoom plugin initialized Apr 27 20:48:34 minidsp-shd volumio[856]: info: MRS: STOPPING SNAPCLIENT Apr 27 20:48:34 minidsp-shd volumio[856]: info: MRS: Snap server stop Apr 27 20:48:34 minidsp-shd sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 27 20:48:34 minidsp-shd sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:48:34 minidsp-shd volumio[856]: info: MRS: STOPPING volumioStreaming Apr 27 20:48:34 minidsp-shd sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 27 20:48:34 minidsp-shd sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:48:34 minidsp-shd sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 27 20:48:34 minidsp-shd sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:48:34 minidsp-shd sudo[1040]: pam_unix(sudo:session): session closed for user root Apr 27 20:48:34 minidsp-shd volumio[856]: info: Loading plugin "outputs"... Apr 27 20:48:34 minidsp-shd volumio[856]: info: Loading plugin "albumart"... Apr 27 20:48:34 minidsp-shd sudo[1049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 27 20:48:34 minidsp-shd sudo[1049]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:48:34 minidsp-shd sudo[1049]: pam_unix(sudo:session): session closed for user root Apr 27 20:48:34 minidsp-shd sudo[1043]: pam_unix(sudo:session): session closed for user root Apr 27 20:48:34 minidsp-shd volumio[856]: info: Loading plugin "ms_surface_dial"... Apr 27 20:48:34 minidsp-shd sudo[1046]: pam_unix(sudo:session): session closed for user root Apr 27 20:48:34 minidsp-shd volumio[856]: Forking 3 albumart workers Apr 27 20:48:35 minidsp-shd volumio[856]: info: Applying required configuration parameters for plugin ms_surface_dial Apr 27 20:48:35 minidsp-shd volumio[856]: info: Plugin example_plugin is not enabled Apr 27 20:48:35 minidsp-shd volumio[856]: info: Loading plugin "hi_res_audio"... Apr 27 20:48:36 minidsp-shd volumio[856]: Starting albumart workers Apr 27 20:48:36 minidsp-shd volumio[856]: Starting albumart workers Apr 27 20:48:36 minidsp-shd volumio[856]: Starting albumart workers Apr 27 20:48:36 minidsp-shd sudo[906]: pam_unix(sudo:session): session closed for user root Apr 27 20:48:37 minidsp-shd volumio-remote-updater[472]: [2025-04-27 20:48:37] [connect] Successful connection Apr 27 20:48:37 minidsp-shd volumio[856]: info: Applying required configuration parameters for plugin hi_res_audio Apr 27 20:48:37 minidsp-shd volumio[856]: info: Loading plugin "inputs"... Apr 27 20:48:38 minidsp-shd volumio[856]: info: Loading plugin "qobuz"... Apr 27 20:48:40 minidsp-shd volumio[856]: info: Loading plugin "tidal"... Apr 27 20:48:42 minidsp-shd volumio[856]: info: Loading plugin "oem_helper"... Apr 27 20:48:43 minidsp-shd volumio[856]: info: Applying required configuration parameters for plugin oem_helper Apr 27 20:48:43 minidsp-shd volumio[856]: info: Loading plugin "updater_comm"... Apr 27 20:48:43 minidsp-shd volumio[856]: info: Plugin mpdemulation is not enabled Apr 27 20:48:43 minidsp-shd volumio[856]: info: Loading plugin "rest_api"... Apr 27 20:48:43 minidsp-shd volumio[856]: info: Loading plugin "websocket"... Apr 27 20:48:43 minidsp-shd volumio[856]: info: Loading plugin "motherearthradio"... Apr 27 20:48:44 minidsp-shd volumio[856]: info: Applying required configuration parameters for plugin motherearthradio Apr 27 20:48:44 minidsp-shd volumio[856]: info: [1745776124335] [MotherEarth] API delay: 5 Apr 27 20:48:44 minidsp-shd volumio[856]: info: Loading plugin "personal_radio"... Apr 27 20:48:45 minidsp-shd volumio[856]: info: ControllerPersonalRadio::constructor Apr 27 20:48:45 minidsp-shd volumio[856]: info: Loading i18n strings for locale fi Apr 27 20:48:45 minidsp-shd volumio[856]: Updating browse sources language Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::initPlayerControls Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 20:48:45 minidsp-shd volumio[856]: Express server listening on port 3000 Apr 27 20:48:45 minidsp-shd volumio[856]: [Metrics] WebUI: 28s 998.91ms Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreStateMachine::resetVolumioState Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreStateMachine::getcurrentVolume Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::volumioRetrievevolume Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreStateMachine::pushState Apr 27 20:48:45 minidsp-shd volumio[856]: info: CorePlayQueue::getTrack 0 Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::volumioPushState Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 20:48:45 minidsp-shd sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 20:48:45 minidsp-shd sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:48:45 minidsp-shd sudo[1108]: pam_unix(sudo:session): session closed for user root Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Apr 27 20:48:45 minidsp-shd volumio[856]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Apr 27 20:48:45 minidsp-shd sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 20:48:45 minidsp-shd sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:48:45 minidsp-shd volumio[856]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 27 20:48:45 minidsp-shd sudo[1111]: pam_unix(sudo:session): session closed for user root Apr 27 20:48:45 minidsp-shd volumio[856]: info: Completed loading Core Plugins Apr 27 20:48:45 minidsp-shd volumio[856]: info: Preparing to generate the ALSA configuration file Apr 27 20:48:45 minidsp-shd volumio[856]: info: MRS: Removed streaming files Apr 27 20:48:45 minidsp-shd volumio[856]: info: MRS: volumioStreaming STOPPED Apr 27 20:48:45 minidsp-shd volumio[856]: info: MRS: SNAPSERVER STOPPED Apr 27 20:48:45 minidsp-shd volumio[856]: info: MRS: SNAPCLIENT STOPPED Apr 27 20:48:45 minidsp-shd volumio[856]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 27 20:48:45 minidsp-shd volumio[856]: wlan0 Failed to read scan data : Resource temporarily unavailable Apr 27 20:48:45 minidsp-shd volumio[856]: info: Cannot use regular scanning, forcing with ap-force Apr 27 20:48:45 minidsp-shd sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 27 20:48:45 minidsp-shd sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:48:52 minidsp-shd volumio-remote-updater[472]: [2025-04-27 20:48:52] [connect] Successful connection Apr 27 20:49:05 minidsp-shd dbus-daemon[464]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30018ms) Apr 27 20:49:06 minidsp-shd sudo[1114]: pam_unix(sudo:session): session closed for user root Apr 27 20:49:06 minidsp-shd volumio[856]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 27 20:49:06 minidsp-shd volumio[856]: info: Reading ALSA contributions from plugins. Apr 27 20:49:06 minidsp-shd volumio[856]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 20:49:06 minidsp-shd volumio[856]: Error: write EPIPE Apr 27 20:49:06 minidsp-shd volumio[856]: at afterWriteDispatched (internal/stream_base_commons.js:156:25) Apr 27 20:49:06 minidsp-shd volumio[856]: at writeGeneric (internal/stream_base_commons.js:147:3) Apr 27 20:49:06 minidsp-shd volumio[856]: at Socket._writeGeneric (net.js:798:11) Apr 27 20:49:06 minidsp-shd volumio[856]: at Socket._write (net.js:810:8) Apr 27 20:49:06 minidsp-shd volumio[856]: at writeOrBuffer (internal/streams/writable.js:358:12) Apr 27 20:49:06 minidsp-shd volumio[856]: at Socket.Writable.write (internal/streams/writable.js:303:10) Apr 27 20:49:06 minidsp-shd volumio[856]: at /volumio/app/plugins/miscellanea/ms_surface_dial/node_modules/dbus-next/lib/handshake.js:84:16 Apr 27 20:49:06 minidsp-shd volumio[856]: at Socket.readable (/volumio/app/plugins/miscellanea/ms_surface_dial/node_modules/dbus-next/lib/readline.js:12:11) Apr 27 20:49:06 minidsp-shd volumio[856]: at Socket.emit (events.js:400:28) Apr 27 20:49:06 minidsp-shd volumio[856]: at emitReadable_ (internal/streams/readable.js:555:12) Apr 27 20:49:06 minidsp-shd volumio[856]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 27 20:49:06 minidsp-shd volumio[856]: errno: -32, Apr 27 20:49:06 minidsp-shd volumio[856]: code: 'EPIPE', Apr 27 20:49:06 minidsp-shd volumio[856]: syscall: 'write' Apr 27 20:49:06 minidsp-shd volumio[856]: } Apr 27 20:49:06 minidsp-shd volumio[856]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 20:49:07 minidsp-shd sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-27 20:48 Apr 27 20:49:07 minidsp-shd sudo[1160]: 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="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:00:28 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_DEVICENAME="NanoPi Neo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="29ccfe8a9cff19911db4b736879ac117"