-- Logs begin at Thu 2019-02-14 11:12:01 CET, end at Wed 2025-12-10 23:15:00 CET. -- Dec 10 23:14:00 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Dec 10 23:14:00 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Dec 10 23:14:00 volumio volumio[764]: info: Refreshing Cached IP Addresses Dec 10 23:14:00 volumio sudo[3171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 10 23:14:00 volumio sudo[3171]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:00 volumio sudo[3173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 10 23:14:00 volumio sudo[3173]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:00 volumio sudo[3173]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:00 volumio sudo[3171]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:00 volumio sudo[3176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 23:14:00 volumio sudo[3176]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:00 volumio systemd[1]: upmpdcli.service: Succeeded. Dec 10 23:14:00 volumio sudo[3176]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:01 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Dec 10 23:14:01 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Dec 10 23:14:01 volumio volumio[764]: info: Refreshing Cached IP Addresses Dec 10 23:14:01 volumio sudo[3185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 10 23:14:01 volumio sudo[3185]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:01 volumio sudo[3187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 10 23:14:01 volumio sudo[3185]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:01 volumio sudo[3187]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:01 volumio sudo[3187]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:01 volumio volumio[764]: error: Cannot kill upmpdcli Error: Command failed: /usr/bin/sudo /usr/bin/killall upmpdcli Dec 10 23:14:01 volumio volumio[764]: upmpdcli: no process found Dec 10 23:14:01 volumio sudo[3190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 23:14:01 volumio sudo[3190]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:01 volumio sudo[3190]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:10 volumio sudo[3197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 10 23:14:10 volumio sudo[3197]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:10 volumio sudo[3197]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:10 volumio sudo[3199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 23:14:10 volumio sudo[3199]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:10 volumio sudo[3199]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:10 volumio sudo[3204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 10 23:14:10 volumio sudo[3204]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:10 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Dec 10 23:14:10 volumio sudo[3204]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:10 volumio volumio[764]: info: Upmpdcli Daemon Started Dec 10 23:14:11 volumio volumio[3207]: writing RSA key Dec 10 23:14:11 volumio sudo[3238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 10 23:14:11 volumio sudo[3238]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:11 volumio sudo[3238]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:11 volumio sudo[3240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 23:14:11 volumio sudo[3240]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:11 volumio sudo[3243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 10 23:14:11 volumio sudo[3243]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:11 volumio sudo[3240]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:11 volumio sudo[3243]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:11 volumio volumio[764]: info: Upmpdcli Daemon Started Dec 10 23:14:17 volumio ntpd[729]: Soliciting pool server 94.130.23.46 Dec 10 23:14:18 volumio ntpd[729]: Soliciting pool server 79.133.44.140 Dec 10 23:14:19 volumio ntpd[729]: Soliciting pool server 5.45.97.204 Dec 10 23:14:21 volumio ntpd[729]: Soliciting pool server 185.13.148.71 Dec 10 23:14:21 volumio ntpd[729]: Soliciting pool server 45.145.40.190 Dec 10 23:14:22 volumio ntpd[729]: Soliciting pool server 162.19.170.154 Dec 10 23:14:22 volumio ntpd[729]: Soliciting pool server 85.215.166.214 Dec 10 23:14:22 volumio ntpd[729]: Soliciting pool server 139.162.152.20 Dec 10 23:14:36 volumio volumio[764]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 10 23:14:36 volumio volumio-remote-updater[2583]: No test mode Dec 10 23:14:36 volumio volumio-remote-updater[2583]: No alpha test mode Dec 10 23:14:36 volumio volumio[764]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n\n

NEW ADDITIONS

\n\n","title":"Update v3.874","updateavailable":true} Dec 10 23:14:36 volumio volumio[764]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 23:14:36 volumio volumio[764]: SyntaxError: /data/configuration/plugins.json: Unexpected token Dec 10 23:14:36 volumio volumio[764]: in JSON at position 0 Dec 10 23:14:36 volumio volumio[764]: at JSON.parse () Dec 10 23:14:36 volumio volumio[764]: at Object.readFileSync (/volumio/node_modules/jsonfile/index.js:69:17) Dec 10 23:14:36 volumio volumio[764]: at PluginManager.getAllPlugNames (/volumio/app/pluginmanager.js:568:21) Dec 10 23:14:36 volumio volumio[764]: at PluginManager.getPluginsMatrix (/volumio/app/pluginmanager.js:592:26) Dec 10 23:14:36 volumio volumio[764]: at PluginManager.listPluginsBrokenByNewVersion (/volumio/app/pluginmanager.js:1224:22) Dec 10 23:14:36 volumio volumio[764]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:951:60) Dec 10 23:14:36 volumio volumio[764]: at Socket.emit (events.js:315:20) Dec 10 23:14:36 volumio volumio[764]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 10 23:14:36 volumio volumio[764]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 10 23:14:36 volumio volumio[764]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 23:14:37 volumio volumio[764]: /bin/sh: 1: Syntax error: Unterminated quoted string Dec 10 23:14:37 volumio volumio[764]: child_process.js:655 Dec 10 23:14:37 volumio volumio[764]: throw err; Dec 10 23:14:37 volumio volumio[764]: ^ Dec 10 23:14:37 volumio volumio[764]: Error: Command failed: /usr/bin/node /volumio/crashreport.js "/data/configuration/plugins.json: Unexpected token Dec 10 23:14:37 volumio volumio[764]: in JSON at position 0" Dec 10 23:14:37 volumio volumio[764]: /bin/sh: 1: Syntax error: Unterminated quoted string Dec 10 23:14:37 volumio volumio[764]: at checkExecSyncError (child_process.js:616:11) Dec 10 23:14:37 volumio volumio[764]: at execSync (child_process.js:652:15) Dec 10 23:14:37 volumio volumio[764]: at process. (/volumio/index.js:87:3) Dec 10 23:14:37 volumio volumio[764]: at process.emit (events.js:315:20) Dec 10 23:14:37 volumio volumio[764]: at process._fatalException (internal/process/execution.js:163:25) { Dec 10 23:14:37 volumio volumio[764]: status: 2, Dec 10 23:14:37 volumio volumio[764]: signal: null, Dec 10 23:14:37 volumio volumio[764]: output: [ Dec 10 23:14:37 volumio volumio[764]: null, Dec 10 23:14:37 volumio volumio[764]: Buffer(0) [Uint8Array] [], Dec 10 23:14:37 volumio volumio[764]: Buffer(53) [Uint8Array] [ Dec 10 23:14:37 volumio volumio[764]: 47, 98, 105, 110, 47, 115, 104, 58, 32, 49, Dec 10 23:14:37 volumio volumio[764]: 58, 32, 83, 121, 110, 116, 97, 120, 32, 101, Dec 10 23:14:37 volumio volumio[764]: 114, 114, 111, 114, 58, 32, 85, 110, 116, 101, Dec 10 23:14:37 volumio volumio[764]: 114, 109, 105, 110, 97, 116, 101, 100, 32, 113, Dec 10 23:14:37 volumio volumio[764]: 117, 111, 116, 101, 100, 32, 115, 116, 114, 105, Dec 10 23:14:37 volumio volumio[764]: 110, 103, 10 Dec 10 23:14:37 volumio volumio[764]: ] Dec 10 23:14:37 volumio volumio[764]: ], Dec 10 23:14:37 volumio volumio[764]: pid: 3305, Dec 10 23:14:37 volumio volumio[764]: stdout: Buffer(0) [Uint8Array] [], Dec 10 23:14:37 volumio volumio[764]: stderr: Buffer(53) [Uint8Array] [ Dec 10 23:14:37 volumio volumio[764]: 47, 98, 105, 110, 47, 115, 104, 58, 32, 49, Dec 10 23:14:37 volumio volumio[764]: 58, 32, 83, 121, 110, 116, 97, 120, 32, 101, Dec 10 23:14:37 volumio volumio[764]: 114, 114, 111, 114, 58, 32, 85, 110, 116, 101, Dec 10 23:14:37 volumio volumio[764]: 114, 109, 105, 110, 97, 116, 101, 100, 32, 113, Dec 10 23:14:37 volumio volumio[764]: 117, 111, 116, 101, 100, 32, 115, 116, 114, 105, Dec 10 23:14:37 volumio volumio[764]: 110, 103, 10 Dec 10 23:14:37 volumio volumio[764]: ] Dec 10 23:14:37 volumio volumio[764]: } Dec 10 23:14:37 volumio volumio-remote-updater[2583]: [2025-12-10 23:14:37] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 10 23:14:37 volumio volumio-remote-updater[2583]: [2025-12-10 23:14:37] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 10 23:14:37 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=7/NOTRUNNING Dec 10 23:14:37 volumio ntfs-3g[852]: Unmounting /dev/sda1 (MUSIC) Dec 10 23:14:37 volumio systemd[1]: media-MUSIC.mount: Succeeded. Dec 10 23:14:37 volumio systemd[2485]: media-MUSIC.mount: Succeeded. Dec 10 23:14:37 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 10 23:14:37 volumio systemd[1]: Started dynamicswap service. Dec 10 23:14:37 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 10 23:14:37 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Dec 10 23:14:37 volumio systemd[1]: Stopped Volumio Backend Module. Dec 10 23:14:37 volumio systemd[1]: Started Volumio Backend Module. Dec 10 23:14:37 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 10 23:14:42 volumio volumio-remote-updater[2583]: [2025-12-10 23:14:42] [connect] Successful connection Dec 10 23:14:42 volumio volumio[3313]: info: ------------------------------------------- Dec 10 23:14:42 volumio volumio[3313]: info: ----- Volumio3 ---- Dec 10 23:14:42 volumio volumio[3313]: info: ------------------------------------------- Dec 10 23:14:42 volumio volumio[3313]: info: ----- System startup ---- Dec 10 23:14:42 volumio volumio[3313]: info: ------------------------------------------- Dec 10 23:14:44 volumio volumio[3313]: info: MYVOLUMIO Environment detected Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin music_service/airplay_emulation. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin music_service/inputs. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin music_service/mpd. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin music_service/webradio. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin music_service/example_plugin. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin music_service/last_100. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin music_service/upnp_browser. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin audio_interface/outputs. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin audio_interface/upnp. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin audio_interface/alsa_controller. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin miscellanea/alarm-clock. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin miscellanea/albumart. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin miscellanea/appearance. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin miscellanea/wizard. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin miscellanea/my_music. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin user_interface/websocket. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin user_interface/mpdemulation. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin user_interface/rest_api. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin system_controller/updater_comm. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin system_controller/network. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin system_controller/networkfs. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin system_controller/services. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin system_controller/system. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin system_controller/i2s_dacs. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin system_controller/volumiodiscovery. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Found new core plugin system_controller/volumio_command_line_client. Adding it Dec 10 23:14:44 volumio volumio[3313]: info: Plugin folders cleanup Dec 10 23:14:44 volumio volumio[3313]: info: Scanning into folder /volumio/app/plugins/ Dec 10 23:14:44 volumio volumio[3313]: info: Scanning category audio_interface Dec 10 23:14:44 volumio volumio[3313]: info: Scanning category miscellanea Dec 10 23:14:44 volumio volumio[3313]: info: Scanning category music_service Dec 10 23:14:44 volumio volumio[3313]: info: Scanning category plugins.json Dec 10 23:14:44 volumio volumio[3313]: info: Scanning category system_controller Dec 10 23:14:44 volumio volumio[3313]: info: Scanning category user_interface Dec 10 23:14:44 volumio volumio[3313]: info: Scanning into folder /data/plugins/ Dec 10 23:14:44 volumio volumio[3313]: info: Scanning category system_hardware Dec 10 23:14:44 volumio volumio[3313]: info: Scanning category user_interface Dec 10 23:14:44 volumio volumio[3313]: info: Plugin folders cleanup completed Dec 10 23:14:44 volumio volumio[3313]: info: ------------------------------------------- Dec 10 23:14:44 volumio volumio[3313]: info: ----- Core plugins startup ---- Dec 10 23:14:44 volumio volumio[3313]: info: ------------------------------------------- Dec 10 23:14:44 volumio volumio[3313]: info: Loading plugins from folder /volumio/app/plugins/ Dec 10 23:14:44 volumio volumio[3313]: info: Adding plugin upnp to MyMusic Plugins Dec 10 23:14:44 volumio volumio[3313]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 10 23:14:44 volumio volumio[3313]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 10 23:14:44 volumio volumio[3313]: info: Loading plugins from folder /data/plugins/ Dec 10 23:14:44 volumio volumio[3313]: info: Loading plugin "system"... Dec 10 23:14:44 volumio volumio[3313]: info: No id defined. Creating one Dec 10 23:14:44 volumio volumio[3313]: info: Loading plugin "appearance"... Dec 10 23:14:47 volumio volumio[3313]: info: Loading plugin "network"... Dec 10 23:14:47 volumio volumio[3313]: info: Refreshing Cached IP Addresses Dec 10 23:14:47 volumio sudo[3340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 10 23:14:47 volumio sudo[3340]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:47 volumio volumio[3313]: info: Loading plugin "services"... Dec 10 23:14:47 volumio sudo[3340]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:47 volumio volumio[3313]: info: Loading plugin "alsa_controller"... Dec 10 23:14:47 volumio sudo[3342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 23:14:47 volumio sudo[3345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 10 23:14:47 volumio sudo[3345]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:47 volumio sudo[3342]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:47 volumio sudo[3345]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:47 volumio sudo[3342]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:47 volumio volumio[3313]: warn: The ALSA output card is not set, defaulting to card [object Object] Dec 10 23:14:47 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 23:14:47 volumio volumio[3313]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out Dec 10 23:14:47 volumio volumio[3313]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 10 23:14:47 volumio volumio[3313]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 10 23:14:47 volumio volumio[3313]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 10 23:14:48 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 23:14:48 volumio volumio[3313]: info: Loading plugin "wizard"... Dec 10 23:14:48 volumio volumio[3313]: info: Loading plugin "networkfs"... Dec 10 23:14:48 volumio volumio[3313]: info: Starting Udev Watcher for removable devices Dec 10 23:14:48 volumio volumio[3313]: info: Ignoring mount for partition: boot Dec 10 23:14:48 volumio volumio[3313]: info: Ignoring mount for partition: volumio Dec 10 23:14:48 volumio volumio[3313]: info: Ignoring mount for partition: volumio_data Dec 10 23:14:48 volumio volumio[3313]: info: Mounting Device MUSIC Dec 10 23:14:48 volumio sudo[3368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/MUSIC -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 10 23:14:48 volumio sudo[3368]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:49 volumio ntfs-3g[3372]: Version 2017.3.23AR.3 integrated FUSE 28 Dec 10 23:14:49 volumio ntfs-3g[3372]: Mounted /dev/sda1 (Read-Write, label "MUSIC", NTFS 3.1) Dec 10 23:14:49 volumio ntfs-3g[3372]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 10 23:14:49 volumio ntfs-3g[3372]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Dec 10 23:14:49 volumio ntfs-3g[3372]: Global ownership and permissions enforced, configuration type 7 Dec 10 23:14:49 volumio sudo[3368]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:49 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 10 23:14:49 volumio volumio[3313]: info: Loading plugin "volumio_command_line_client"... Dec 10 23:14:49 volumio volumio[3313]: info: Loading plugin "upnp"... Dec 10 23:14:49 volumio volumio[3313]: info: [1765404889165] Starting Upmpd Daemon Dec 10 23:14:49 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 10 23:14:49 volumio volumio[3313]: info: Loading plugin "my_music"... Dec 10 23:14:49 volumio volumio[3313]: info: Loading plugin "mpd"... Dec 10 23:14:50 volumio volumio[3313]: info: Loading plugin "upnp_browser"... Dec 10 23:14:50 volumio volumio[3313]: info: Loading plugin "alarm-clock"... Dec 10 23:14:51 volumio volumio[3313]: info: Loading plugin "airplay_emulation"... Dec 10 23:14:51 volumio volumio[3313]: info: Starting Shairport Sync Dec 10 23:14:51 volumio volumio[3313]: info: Loading plugin "last_100"... Dec 10 23:14:51 volumio volumio[3313]: info: Loading plugin "webradio"... Dec 10 23:14:51 volumio volumio[3313]: info: Loading plugin "i2s_dacs"... Dec 10 23:14:51 volumio volumio[3313]: info: I2S DAC not set, start Auto-detection Dec 10 23:14:51 volumio volumio[3313]: info: Loading plugin "volumiodiscovery"... Dec 10 23:14:51 volumio volumio[3313]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 10 23:14:51 volumio volumio[3313]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 10 23:14:51 volumio node[3313]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 10 23:14:51 volumio volumio[3313]: *** WARNING *** For more information see Dec 10 23:14:51 volumio volumio[3313]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 10 23:14:51 volumio volumio[3313]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 10 23:14:51 volumio volumio[3313]: *** WARNING *** For more information see Dec 10 23:14:51 volumio node[3313]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 10 23:14:51 volumio node[3313]: *** WARNING *** For more information see Dec 10 23:14:51 volumio node[3313]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 10 23:14:51 volumio node[3313]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 10 23:14:51 volumio node[3313]: *** WARNING *** For more information see Dec 10 23:14:51 volumio volumio[3313]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 10 23:14:51 volumio volumio[3313]: info: Discovery: Started advertising with name: undefined Dec 10 23:14:51 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 10 23:14:51 volumio volumio[3313]: info: Plugin gpio-buttons is not enabled Dec 10 23:14:51 volumio volumio[3313]: info: Plugin peppy_screensaver is not enabled Dec 10 23:14:51 volumio volumio[3313]: info: Loading plugin "outputs"... Dec 10 23:14:51 volumio volumio[3313]: info: Loading plugin "albumart"... Dec 10 23:14:52 volumio volumio[3313]: info: Plugin example_plugin is not enabled Dec 10 23:14:52 volumio volumio[3313]: info: Loading plugin "inputs"... Dec 10 23:14:52 volumio volumio[3313]: info: Loading plugin "updater_comm"... Dec 10 23:14:52 volumio volumio[3313]: info: Plugin mpdemulation is not enabled Dec 10 23:14:52 volumio volumio[3313]: info: Loading plugin "rest_api"... Dec 10 23:14:52 volumio volumio[3313]: info: Loading plugin "websocket"... Dec 10 23:14:52 volumio volumio[3313]: info: Starting Socket.io Server version 2.3.0 Dec 10 23:14:52 volumio volumio[3313]: info: Plugin rotaryencoder2 is not enabled Dec 10 23:14:52 volumio volumio[3313]: info: Plugin touch_display is not enabled Dec 10 23:14:52 volumio volumio[3313]: info: Loading i18n strings for locale de Dec 10 23:14:52 volumio volumio[3313]: Updating browse sources language Dec 10 23:14:52 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 23:14:52 volumio volumio[3313]: Forking 3 albumart workers Dec 10 23:14:53 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 23:14:53 volumio volumio[3313]: info: CoreCommandRouter::initPlayerControls Dec 10 23:14:53 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:14:53 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:14:53 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:14:53 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:14:53 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:14:53 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:14:53 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:14:53 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 23:14:53 volumio volumio[3313]: Express server listening on port 3000 Dec 10 23:14:53 volumio volumio[3313]: [Metrics] WebUI: 12s 756.02ms Dec 10 23:14:53 volumio volumio[3313]: info: CoreStateMachine::resetVolumioState Dec 10 23:14:53 volumio volumio[3313]: info: CoreStateMachine::getcurrentVolume Dec 10 23:14:53 volumio volumio[3313]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 23:14:53 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 23:14:53 volumio volumio[3313]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 10 23:14:53 volumio volumio[3313]: info: CoreStateMachine::getcurrentVolume Dec 10 23:14:53 volumio volumio[3313]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 23:14:53 volumio volumio[3313]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 10 23:14:53 volumio volumio[3313]: wlan0 Interface doesn't support scanning. Dec 10 23:14:53 volumio volumio[3313]: info: Cannot use regular scanning, forcing with ap-force Dec 10 23:14:53 volumio sudo[3409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 10 23:14:53 volumio sudo[3409]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:54 volumio sudo[3409]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:54 volumio volumio[3313]: command failed: No such device (-19) Dec 10 23:14:54 volumio volumio[3313]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 10 23:14:54 volumio volumio[3313]: command failed: No such device (-19) Dec 10 23:14:54 volumio volumio[3313]: verbose: New Socket.io Connection to 192.168.100.44 from 192.168.100.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:145.0) Gecko/20100101 Firefox/145.0 Engine version: 3 Transport: polling Total Clients: 1 Dec 10 23:14:54 volumio volumio[3313]: verbose: New Socket.io Connection to 192.168.100.44 from 192.168.100.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:145.0) Gecko/20100101 Firefox/145.0 Engine version: 3 Transport: polling Total Clients: 2 Dec 10 23:14:54 volumio volumio[3313]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 10 23:14:54 volumio volumio-remote-updater[2583]: [2025-12-10 23:14:54] [connect] Successful connection Dec 10 23:14:54 volumio volumio[3313]: verbose: New Socket.io Connection to 192.168.100.44 from 192.168.100.58 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 Dec 10 23:14:54 volumio volumio[3313]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 10 23:14:54 volumio volumio[3313]: verbose: New Socket.io Connection to 192.168.100.44 from 192.168.100.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:145.0) Gecko/20100101 Firefox/145.0 Engine version: 3 Transport: polling Total Clients: 6 Dec 10 23:14:54 volumio volumio[3313]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 10 23:14:54 volumio volumio[3313]: verbose: New Socket.io Connection to 192.168.100.44 from 192.168.100.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:145.0) Gecko/20100101 Firefox/145.0 Engine version: 3 Transport: polling Total Clients: 8 Dec 10 23:14:54 volumio volumio[3313]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 10 23:14:55 volumio volumio[3313]: info: Scanning new location : "USB/MUSIC" Dec 10 23:14:55 volumio volumio[3313]: info: Reloading queue from file Dec 10 23:14:55 volumio volumio-remote-updater[2583]: [2025-12-10 23:14:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765404894 101 Dec 10 23:14:55 volumio volumio[3313]: 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: 10 Dec 10 23:14:55 volumio volumio[3313]: info: VolumeController:: Volume=94 Mute =false Dec 10 23:14:55 volumio volumio[3313]: info: CoreStateMachine::pushState Dec 10 23:14:55 volumio volumio[3313]: info: CorePlayQueue::getTrack 0 Dec 10 23:14:55 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 23:14:55 volumio volumio[3313]: info: CoreCommandRouter::volumioPushState Dec 10 23:14:55 volumio volumio[3313]: info: CoreStateMachine::updateTrackBlock Dec 10 23:14:55 volumio volumio[3313]: info: CorePlayQueue::getTrackBlock Dec 10 23:14:55 volumio volumio[3313]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 23:14:55 volumio volumio[3313]: info: CoreStateMachine::setRepeat null single undefined Dec 10 23:14:55 volumio volumio[3313]: info: CoreStateMachine::pushState Dec 10 23:14:55 volumio volumio[3313]: info: CorePlayQueue::getTrack 0 Dec 10 23:14:55 volumio volumio[3313]: info: CoreCommandRouter::volumioPushState Dec 10 23:14:55 volumio volumio[3313]: info: CoreStateMachine::setRandom null Dec 10 23:14:55 volumio volumio[3313]: info: CoreStateMachine::pushState Dec 10 23:14:55 volumio volumio[3313]: info: CorePlayQueue::getTrack 0 Dec 10 23:14:55 volumio volumio[3313]: info: CoreCommandRouter::volumioPushState Dec 10 23:14:55 volumio volumio[3313]: info: Setting Device type: Raspberry PI Dec 10 23:14:55 volumio volumio[3313]: Starting albumart workers Dec 10 23:14:55 volumio volumio[3313]: info: VolumeController:: Volume=94 Mute =false Dec 10 23:14:55 volumio volumio[3313]: info: CoreStateMachine::pushState Dec 10 23:14:55 volumio volumio[3313]: info: CorePlayQueue::getTrack 0 Dec 10 23:14:55 volumio volumio[3313]: info: CoreCommandRouter::volumioPushState Dec 10 23:14:55 volumio volumio[3313]: info: CoreStateMachine::updateTrackBlock Dec 10 23:14:55 volumio volumio[3313]: info: CorePlayQueue::getTrackBlock Dec 10 23:14:55 volumio volumio[3313]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 23:14:55 volumio volumio[3313]: Starting albumart workers Dec 10 23:14:55 volumio volumio[3313]: Starting albumart workers Dec 10 23:14:56 volumio volumio[3313]: info: VolumeController:: Volume=94 Mute =false Dec 10 23:14:56 volumio volumio[3313]: info: CoreStateMachine::pushState Dec 10 23:14:56 volumio volumio[3313]: info: CorePlayQueue::getTrack 0 Dec 10 23:14:56 volumio volumio[3313]: info: CoreCommandRouter::volumioPushState Dec 10 23:14:56 volumio volumio[3313]: info: VolumeController:: Volume=94 Mute =false Dec 10 23:14:56 volumio volumio[3313]: info: CoreStateMachine::pushState Dec 10 23:14:56 volumio volumio[3313]: info: CorePlayQueue::getTrack 0 Dec 10 23:14:56 volumio volumio[3313]: info: CoreCommandRouter::volumioPushState Dec 10 23:14:56 volumio volumio[3313]: info: Completed loading Core Plugins Dec 10 23:14:56 volumio volumio[3313]: info: Preparing to generate the ALSA configuration file Dec 10 23:14:56 volumio volumio[3313]: info: Asound.conf file written Dec 10 23:14:56 volumio sudo[3427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 10 23:14:56 volumio sudo[3427]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:56 volumio sudo[3427]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:56 volumio volumio[3313]: info: Output device has changed, restarting MPD Dec 10 23:14:57 volumio volumio[3313]: info: Output device has changed, restarting Shairport Sync Dec 10 23:14:57 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:14:57 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 23:14:57 volumio sudo[3434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 23:14:57 volumio sudo[3434]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:57 volumio sudo[3434]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:57 volumio sudo[3435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 23:14:57 volumio sudo[3435]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:57 volumio volumio[3313]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 23:14:57 volumio volumio[3313]: info: ___________ START PLUGINS ___________ Dec 10 23:14:57 volumio volumio[3313]: info: ControllerMpd::onStart: Initializing MPD Dec 10 23:14:57 volumio volumio[3313]: info: Creating MPD Configuration file Dec 10 23:14:57 volumio systemd[1]: Stopping Music Player Daemon... Dec 10 23:14:57 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 23:14:57 volumio volumio[3313]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 23:14:57 volumio volumio[3313]: info: [1765404897608] CoreMusicLibrary::Adding element Medienserver Dec 10 23:14:57 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 23:14:57 volumio sudo[3442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 23:14:57 volumio sudo[3442]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:57 volumio sudo[3442]: pam_unix(sudo:session): session closed for user root Dec 10 23:14:57 volumio sudo[3444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 23:14:57 volumio sudo[3444]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 23:14:57 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:14:57 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 23:14:58 volumio volumio[3313]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 23:14:58 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 23:14:58 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 23:14:58 volumio systemd[1]: mpd.service: Succeeded. Dec 10 23:14:58 volumio systemd[1]: Stopped Music Player Daemon. Dec 10 23:14:58 volumio volumio[3313]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 23:14:58 volumio volumio[3313]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 23:14:58 volumio volumio[3313]: info: [1765404898144] CoreMusicLibrary::Adding element Last_100 Dec 10 23:14:58 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 23:14:58 volumio systemd[1]: Starting Music Player Daemon... Dec 10 23:14:58 volumio volumio[3313]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 23:14:58 volumio volumio[3313]: info: [1765404898171] CoreMusicLibrary::Adding element Webradio Dec 10 23:14:58 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 23:14:58 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 23:14:58 volumio volumio[3313]: info: Initializing BBC Radios Dec 10 23:14:58 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 23:14:58 volumio volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 23:14:58 volumio volumio[3313]: info: Volumio Calling Home Dec 10 23:14:59 volumio volumio[3313]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 10 23:14:59 volumio volumio[3313]: info: Discovery: Started advertising with name: undefined Dec 10 23:14:59 volumio volumio[3313]: verbose: New Socket.io Connection to 192.168.100.44 from 192.168.100.52 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:145.0) Gecko/20100101 Firefox/145.0 Engine version: 3 Transport: polling Total Clients: 11 Dec 10 23:14:59 volumio volumio[3313]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Dec 10 23:14:59 volumio volumio[3313]: info: MPD Permissions set Dec 10 23:14:59 volumio volumio[3313]: info: MPD Permissions set Dec 10 23:14:59 volumio volumio[3313]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 23:14:59 volumio volumio[3313]: TypeError: Cannot read property 'charAt' of undefined Dec 10 23:14:59 volumio volumio[3313]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Dec 10 23:14:59 volumio volumio[3313]: at listOnTimeout (internal/timers.js:554:17) Dec 10 23:14:59 volumio volumio[3313]: at processTimers (internal/timers.js:497:7) Dec 10 23:14:59 volumio volumio[3313]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 23:15:00 volumio sudo[3500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-10 23:14 Dec 10 23:15:00 volumio sudo[3500]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="ef589a0572b08c3d1e9a984ec6a655ef3698e415" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="abe72e92bc7de90ab6193cbc17e21ad2750980a2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 28 May 2024 12:17:12 PM CEST" VOLUMIO_VERSION="3.695" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8f37ff40efaaf3752fac55d6a95d61cb"