-- Logs begin at Thu 2019-02-14 20:12:00 AEST, end at Tue 2025-03-11 15:28:38 AEST. -- Mar 11 15:28:18 volumio systemd-timedated[922]: Changed local time to Tue Mar 11 15:28:18 2025 Mar 11 15:28:18 volumio sudo[920]: pam_unix(sudo:session): session closed for user root Mar 11 15:28:18 volumio volumio-time-update[554]: volumio-time-update-util: System time updated successfully. Mar 11 15:28:18 volumio systemd[1]: Started Volumio Time Update Utility. Mar 11 15:28:18 volumio systemd[1]: Starting Daily apt download activities... Mar 11 15:28:18 volumio kernel: sched: RT throttling activated Mar 11 15:28:19 volumio ser2net[802]: Could not open device /dev/ttyS0 for port 3000: No such file or directory Mar 11 15:28:19 volumio nmbd[742]: [2025/03/11 15:28:19.864738, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 11 15:28:19 volumio nmbd[742]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 11 15:28:19 volumio systemd[1]: Started Samba NMB Daemon. Mar 11 15:28:19 volumio systemd[1]: Starting Samba Winbind Daemon... Mar 11 15:28:20 volumio mpd[814]: Mar 11 15:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 11 15:28:20 volumio systemd[1]: Started Music Player Daemon. Mar 11 15:28:20 volumio mpd_monitor.sh[561]: MPD restarted due to no mpc output. Mar 11 15:28:21 volumio winbindd[944]: [2025/03/11 15:28:21.076862, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 11 15:28:21 volumio winbindd[944]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 11 15:28:21 volumio winbindd[944]: [2025/03/11 15:28:21.111991, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 11 15:28:21 volumio systemd[1]: Started Samba Winbind Daemon. Mar 11 15:28:21 volumio winbindd[944]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 11 15:28:21 volumio systemd[1]: Starting Samba SMB Daemon... Mar 11 15:28:22 volumio smbd[952]: [2025/03/11 15:28:22.166927, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 11 15:28:22 volumio smbd[952]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 11 15:28:22 volumio systemd[1]: Started Samba SMB Daemon. Mar 11 15:28:22 volumio systemd[1]: Reached target Multi-User System. Mar 11 15:28:22 volumio systemd[1]: Reached target Graphical Interface. Mar 11 15:28:22 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 11 15:28:22 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 11 15:28:22 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 11 15:28:23 volumio systemd[1]: apt-daily.service: Succeeded. Mar 11 15:28:23 volumio systemd[1]: Started Daily apt download activities. Mar 11 15:28:23 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Mar 11 15:28:24 volumio volumio[890]: info: ------------------------------------------- Mar 11 15:28:24 volumio volumio[890]: info: ----- Volumio3 ---- Mar 11 15:28:24 volumio volumio[890]: info: ------------------------------------------- Mar 11 15:28:24 volumio volumio[890]: info: ----- System startup ---- Mar 11 15:28:24 volumio volumio[890]: info: ------------------------------------------- Mar 11 15:28:24 volumio volumio-remote-updater[552]: [2025-03-11 15:28:24] [connect] Successful connection Mar 11 15:28:24 volumio volumio-remote-updater[552]: [2025-03-11 15:28:24] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Mar 11 15:28:24 volumio volumio-remote-updater[552]: [2025-03-11 15:28:24] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Mar 11 15:28:24 volumio volumio-remote-updater[552]: [2025-03-11 15:28:24] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 11 15:28:24 volumio ser2net[802]: Could not open device /dev/ttyS0 for port 3000: No such file or directory Mar 11 15:28:26 volumio volumio[890]: info: MYVOLUMIO Environment detected Mar 11 15:28:26 volumio volumio[890]: info: Plugin folders cleanup Mar 11 15:28:26 volumio volumio[890]: info: Scanning into folder /volumio/app/plugins/ Mar 11 15:28:26 volumio volumio[890]: info: Scanning category audio_interface Mar 11 15:28:26 volumio volumio[890]: info: Scanning category miscellanea Mar 11 15:28:26 volumio volumio[890]: info: Scanning category music_service Mar 11 15:28:26 volumio volumio[890]: info: Scanning category plugins.json Mar 11 15:28:26 volumio volumio[890]: info: Scanning category system_controller Mar 11 15:28:26 volumio volumio[890]: info: Scanning category user_interface Mar 11 15:28:26 volumio volumio[890]: info: Scanning into folder /data/plugins/ Mar 11 15:28:26 volumio volumio[890]: info: Scanning category music_service Mar 11 15:28:26 volumio volumio[890]: info: Scanning category system_controller Mar 11 15:28:26 volumio volumio[890]: info: Plugin folders cleanup completed Mar 11 15:28:26 volumio volumio[890]: info: ------------------------------------------- Mar 11 15:28:26 volumio volumio[890]: info: ----- Core plugins startup ---- Mar 11 15:28:26 volumio volumio[890]: info: ------------------------------------------- Mar 11 15:28:26 volumio volumio[890]: info: Loading plugins from folder /volumio/app/plugins/ Mar 11 15:28:26 volumio volumio[890]: info: Adding plugin upnp to MyMusic Plugins Mar 11 15:28:26 volumio volumio[890]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 11 15:28:26 volumio volumio[890]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 11 15:28:26 volumio volumio[890]: info: Loading plugins from folder /data/plugins/ Mar 11 15:28:26 volumio volumio[890]: info: Loading plugin "system"... Mar 11 15:28:26 volumio volumio[890]: info: Loading plugin "appearance"... Mar 11 15:28:27 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Mar 11 15:28:27 volumio systemd[1]: Started Daily apt upgrade and clean activities. Mar 11 15:28:27 volumio systemd[1]: Startup finished in 15.734s (kernel) + 25.244s (userspace) = 40.978s. Mar 11 15:28:29 volumio volumio[890]: info: Loading plugin "network"... Mar 11 15:28:29 volumio volumio[890]: info: Refreshing Cached IP Addresses Mar 11 15:28:29 volumio sudo[1052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 11 15:28:29 volumio sudo[1052]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 15:28:29 volumio sudo[1052]: pam_unix(sudo:session): session closed for user root Mar 11 15:28:29 volumio volumio[890]: info: Loading plugin "services"... Mar 11 15:28:29 volumio sudo[1054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 15:28:29 volumio sudo[1054]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 15:28:29 volumio volumio[890]: info: Loading plugin "alsa_controller"... Mar 11 15:28:29 volumio sudo[1054]: pam_unix(sudo:session): session closed for user root Mar 11 15:28:29 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 11 15:28:29 volumio volumio[890]: info: Loading plugin "wizard"... Mar 11 15:28:29 volumio volumio[890]: info: Loading plugin "networkfs"... Mar 11 15:28:30 volumio volumio[890]: info: Starting Udev Watcher for removable devices Mar 11 15:28:30 volumio sudo[1077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=nLtrGSkffXgB,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0,cache=none //10.0.10.2/Media\\Music /mnt/NAS/deathstar Mar 11 15:28:30 volumio sudo[1077]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 15:28:30 volumio volumio[890]: info: Ignoring mount for partition: boot Mar 11 15:28:30 volumio volumio[890]: info: Ignoring mount for partition: volumio Mar 11 15:28:30 volumio volumio[890]: info: Ignoring mount for partition: volumio_data Mar 11 15:28:30 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 11 15:28:30 volumio volumio[890]: info: Loading plugin "volumio_command_line_client"... Mar 11 15:28:30 volumio volumio[890]: info: Loading plugin "upnp"... Mar 11 15:28:30 volumio volumio[890]: info: [1741670910214] Starting Upmpd Daemon Mar 11 15:28:30 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 11 15:28:30 volumio volumio[890]: info: Loading plugin "my_music"... Mar 11 15:28:30 volumio volumio[890]: info: Loading plugin "mpd"... Mar 11 15:28:30 volumio kernel: Key type cifs.spnego registered Mar 11 15:28:30 volumio kernel: Key type cifs.idmap registered Mar 11 15:28:30 volumio kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Mar 11 15:28:30 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Mar 11 15:28:30 volumio kernel: CIFS: Attempting to mount //10.0.10.2/Media/Music Mar 11 15:28:31 volumio volumio[890]: info: Loading plugin "upnp_browser"... Mar 11 15:28:31 volumio volumio[890]: info: Loading plugin "alarm-clock"... Mar 11 15:28:32 volumio volumio[890]: info: Loading plugin "airplay_emulation"... Mar 11 15:28:32 volumio volumio[890]: info: Starting Shairport Sync Mar 11 15:28:32 volumio volumio[890]: info: Loading plugin "last_100"... Mar 11 15:28:32 volumio volumio[890]: info: Loading plugin "webradio"... Mar 11 15:28:32 volumio volumio[890]: info: Loading plugin "i2s_dacs"... Mar 11 15:28:32 volumio volumio[890]: info: I2S DAC not set, start Auto-detection Mar 11 15:28:32 volumio volumio[890]: info: Loading plugin "volumiodiscovery"... Mar 11 15:28:32 volumio volumio[890]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 11 15:28:32 volumio volumio[890]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 11 15:28:32 volumio volumio[890]: *** WARNING *** For more information see Mar 11 15:28:32 volumio node[890]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 11 15:28:32 volumio volumio[890]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 11 15:28:32 volumio volumio[890]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 11 15:28:32 volumio volumio[890]: *** WARNING *** For more information see Mar 11 15:28:32 volumio node[890]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 11 15:28:32 volumio node[890]: *** WARNING *** For more information see Mar 11 15:28:32 volumio node[890]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 11 15:28:32 volumio node[890]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 11 15:28:32 volumio node[890]: *** WARNING *** For more information see Mar 11 15:28:32 volumio volumio[890]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 11 15:28:32 volumio volumio[890]: info: Discovery: Started advertising with name: Volumio Mar 11 15:28:33 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 11 15:28:33 volumio volumio[890]: info: Loading plugin "calmradio"... Mar 11 15:28:33 volumio ser2net[802]: Could not open device /dev/ttyS0 for port 3000: No such file or directory Mar 11 15:28:33 volumio ser2net[802]: Could not open device /dev/ttyS0 for port 3000: No such file or directory Mar 11 15:28:34 volumio volumio-remote-updater[552]: [2025-03-11 15:28:34] [connect] Successful connection Mar 11 15:28:34 volumio volumio-remote-updater[552]: [2025-03-11 15:28:34] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Mar 11 15:28:34 volumio volumio-remote-updater[552]: [2025-03-11 15:28:34] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Mar 11 15:28:34 volumio volumio-remote-updater[552]: [2025-03-11 15:28:34] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 11 15:28:34 volumio ser2net[802]: Could not open device /dev/ttyS0 for port 3000: No such file or directory Mar 11 15:28:35 volumio volumio[890]: info: Loading plugin "autostart"... Mar 11 15:28:35 volumio volumio[890]: info: Applying required configuration parameters for plugin autostart Mar 11 15:28:35 volumio volumio[890]: info: AutoStart - onVolumioStart - read config.json Mar 11 15:28:35 volumio volumio[890]: info: Loading plugin "outputs"... Mar 11 15:28:35 volumio volumio[890]: info: Loading plugin "albumart"... Mar 11 15:28:35 volumio volumio[890]: info: Plugin example_plugin is not enabled Mar 11 15:28:35 volumio volumio[890]: info: Loading plugin "inputs"... Mar 11 15:28:36 volumio volumio[890]: info: Loading plugin "updater_comm"... Mar 11 15:28:36 volumio volumio[890]: info: Plugin mpdemulation is not enabled Mar 11 15:28:36 volumio volumio[890]: info: Loading plugin "rest_api"... Mar 11 15:28:36 volumio volumio[890]: info: Loading plugin "websocket"... Mar 11 15:28:36 volumio volumio[890]: info: Starting Socket.io Server version 2.3.0 Mar 11 15:28:36 volumio volumio[890]: info: Loading i18n strings for locale en Mar 11 15:28:36 volumio volumio[890]: Updating browse sources language Mar 11 15:28:36 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 15:28:36 volumio volumio[890]: Forking 3 albumart workers Mar 11 15:28:37 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 15:28:37 volumio volumio[890]: info: CoreCommandRouter::initPlayerControls Mar 11 15:28:37 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 15:28:37 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 15:28:37 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 15:28:37 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 15:28:37 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 15:28:37 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 15:28:37 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 15:28:37 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 15:28:37 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 15:28:37 volumio volumio[890]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 11 15:28:37 volumio volumio[890]: Error: listen EADDRINUSE: address already in use :::3000 Mar 11 15:28:37 volumio volumio[890]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Mar 11 15:28:37 volumio volumio[890]: at listenInCluster (net.js:1366:12) Mar 11 15:28:37 volumio volumio[890]: at Server.listen (net.js:1452:7) Mar 11 15:28:37 volumio volumio[890]: at Function.listen (/volumio/node_modules/express/lib/application.js:618:24) Mar 11 15:28:37 volumio volumio[890]: at Object. (/volumio/index.js:29:29) Mar 11 15:28:37 volumio volumio[890]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Mar 11 15:28:37 volumio volumio[890]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Mar 11 15:28:37 volumio volumio[890]: at Module.load (internal/modules/cjs/loader.js:928:32) Mar 11 15:28:37 volumio volumio[890]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Mar 11 15:28:37 volumio volumio[890]: at Function.executeUserEntryPoint [as runMain] (internal/modules/run_main.js:72:12) { Mar 11 15:28:37 volumio volumio[890]: code: 'EADDRINUSE', Mar 11 15:28:37 volumio volumio[890]: errno: -98, Mar 11 15:28:37 volumio volumio[890]: syscall: 'listen', Mar 11 15:28:37 volumio volumio[890]: address: '::', Mar 11 15:28:37 volumio volumio[890]: port: 3000 Mar 11 15:28:37 volumio volumio[890]: } Mar 11 15:28:37 volumio volumio[890]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 11 15:28:37 volumio systemd[1]: systemd-fsckd.service: Succeeded. Mar 11 15:28:38 volumio volumio[890]: Starting albumart workers Mar 11 15:28:38 volumio volumio[890]: Starting albumart workers Mar 11 15:28:38 volumio volumio[890]: Starting albumart workers Mar 11 15:28:38 volumio sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-11 15:27 Mar 11 15:28:38 volumio sudo[1133]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"