-- Logs begin at Wed 2024-11-27 09:57:45 CET, end at Thu 2024-11-28 13:55:40 CET. -- Nov 28 13:55:04 volumio systemd-timedated[1176]: Changed local time to Thu Nov 28 13:55:04 2024 Nov 28 13:55:04 volumio sudo[1170]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:04 volumio systemd[1]: Starting Daily apt download activities... Nov 28 13:55:04 volumio systemd[1]: Starting Daily man-db regeneration... Nov 28 13:55:04 volumio volumio-time-update[562]: volumio-time-update-util: System time updated successfully. Nov 28 13:55:04 volumio systemd[1]: Started Volumio Time Update Utility. Nov 28 13:55:04 volumio systemd[1]: man-db.service: Succeeded. Nov 28 13:55:04 volumio systemd[1]: Started Daily man-db regeneration. Nov 28 13:55:04 volumio wireless.js[580]: trying... Nov 28 13:55:04 volumio sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 13:55:04 volumio sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:04 volumio sudo[1202]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:04 volumio nmbd[819]: [2024/11/28 13:55:04.970716, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 28 13:55:04 volumio systemd[1]: Started Samba NMB Daemon. Nov 28 13:55:04 volumio nmbd[819]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 28 13:55:04 volumio systemd[1]: Starting Samba Winbind Daemon... Nov 28 13:55:04 volumio kernel: nf_conntrack: automatic helper assignment is deprecated and it will be removed soon. Use the iptables CT target to attach helpers instead. Nov 28 13:55:05 volumio winbindd[1213]: [2024/11/28 13:55:05.208212, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 28 13:55:05 volumio winbindd[1213]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 28 13:55:05 volumio winbindd[1213]: [2024/11/28 13:55:05.216192, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 28 13:55:05 volumio systemd[1]: Started Samba Winbind Daemon. Nov 28 13:55:05 volumio winbindd[1213]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 28 13:55:05 volumio systemd[1]: Starting Samba SMB Daemon... Nov 28 13:55:05 volumio wireless.js[580]: trying... Nov 28 13:55:05 volumio sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 13:55:05 volumio sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:05 volumio sudo[1240]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:05 volumio systemd[1]: apt-daily.service: Succeeded. Nov 28 13:55:05 volumio systemd[1]: Started Daily apt download activities. Nov 28 13:55:05 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Nov 28 13:55:05 volumio smbd[1232]: [2024/11/28 13:55:05.580606, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 28 13:55:05 volumio systemd[1]: Started Samba SMB Daemon. Nov 28 13:55:05 volumio smbd[1232]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 28 13:55:06 volumio wireless.js[580]: trying... Nov 28 13:55:06 volumio sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 13:55:06 volumio sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:06 volumio sudo[1301]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:06 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Nov 28 13:55:06 volumio systemd[1]: Started Daily apt upgrade and clean activities. Nov 28 13:55:06 volumio volumio-remote-updater[572]: [2024-11-28 13:55:06] [info] asio async_connect error: system:111 (Connection refused) Nov 28 13:55:06 volumio volumio-remote-updater[572]: [2024-11-28 13:55:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 28 13:55:06 volumio volumio-remote-updater[572]: [2024-11-28 13:55:06] [error] handle_connect error: Underlying Transport Error Nov 28 13:55:07 volumio wireless.js[580]: trying... Nov 28 13:55:07 volumio sudo[1325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 13:55:07 volumio sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:07 volumio sudo[1325]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:08 volumio wireless.js[580]: trying... Nov 28 13:55:08 volumio sudo[1335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 13:55:08 volumio sudo[1335]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:08 volumio sudo[1335]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:09 volumio wireless.js[580]: trying... Nov 28 13:55:09 volumio sudo[1345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 13:55:09 volumio sudo[1345]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:09 volumio sudo[1345]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:10 volumio wireless.js[580]: trying... Nov 28 13:55:10 volumio sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 13:55:10 volumio sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:10 volumio sudo[1355]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:11 volumio wireless.js[580]: trying... Nov 28 13:55:11 volumio sudo[1365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 13:55:11 volumio sudo[1365]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:11 volumio sudo[1365]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:12 volumio wireless.js[580]: trying... Nov 28 13:55:12 volumio sudo[1375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 13:55:12 volumio sudo[1375]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:12 volumio sudo[1375]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:13 volumio wireless.js[580]: trying... Nov 28 13:55:13 volumio sudo[1388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 13:55:13 volumio sudo[1388]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:13 volumio sudo[1388]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:14 volumio wireless.js[580]: trying... Nov 28 13:55:14 volumio sudo[1398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 13:55:14 volumio sudo[1398]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:14 volumio sudo[1398]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:15 volumio wireless.js[580]: trying... Nov 28 13:55:15 volumio sudo[1408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 13:55:15 volumio sudo[1408]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:15 volumio sudo[1408]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:16 volumio wireless.js[580]: trying... Nov 28 13:55:16 volumio sudo[1418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 13:55:16 volumio sudo[1418]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:16 volumio sudo[1418]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:17 volumio volumio-remote-updater[572]: [2024-11-28 13:55:17] [info] asio async_connect error: system:111 (Connection refused) Nov 28 13:55:17 volumio volumio-remote-updater[572]: [2024-11-28 13:55:17] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 28 13:55:17 volumio volumio-remote-updater[572]: [2024-11-28 13:55:17] [error] handle_connect error: Underlying Transport Error Nov 28 13:55:17 volumio wireless.js[580]: Overtime, starting plan B Nov 28 13:55:17 volumio iwconfig[1429]: Error for wireless request "Set Power Management" (8B2C) : Nov 28 13:55:17 volumio iwconfig[1429]: SET failed on device wlan0 ; Operation not permitted. Nov 28 13:55:17 volumio systemd[1]: Started Wireless Services. Nov 28 13:55:17 volumio systemd[1]: Started Volumio Backend Module. Nov 28 13:55:17 volumio systemd[1]: Started Volumio Cpu Tweaker. Nov 28 13:55:17 volumio systemd[1]: Started Start Volumio Kiosk. Nov 28 13:55:17 volumio systemd[1]: Reached target Multi-User System. Nov 28 13:55:17 volumio systemd[1]: Reached target Graphical Interface. Nov 28 13:55:17 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 28 13:55:17 volumio startkiosk.sh[1432]: Starting Volumio Kiosk with arguments for cursor display Nov 28 13:55:18 volumio volumio-cpu-tweak[1431]: Setting RT Priority for mpd Nov 28 13:55:18 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 28 13:55:18 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 28 13:55:18 volumio systemd[1]: Startup finished in 14.958s (kernel) + 26.744s (userspace) = 41.703s. Nov 28 13:55:18 volumio volumio-cpu-tweak[1431]: Setting MPD Affinity Nov 28 13:55:18 volumio volumio-cpu-tweak[1431]: pid 877's current affinity mask: f Nov 28 13:55:18 volumio volumio-cpu-tweak[1431]: pid 877's new affinity mask: 3 Nov 28 13:55:18 volumio volumio-cpu-tweak[1431]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Nov 28 13:55:18 volumio volumio-cpu-tweak[1431]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Nov 28 13:55:18 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Nov 28 13:55:18 volumio startkiosk.sh[1432]: X.Org X Server 1.20.4 Nov 28 13:55:18 volumio startkiosk.sh[1432]: X Protocol Version 11, Revision 0 Nov 28 13:55:18 volumio startkiosk.sh[1432]: Build Operating System: Linux 4.19.0-18-armmp-lpae armv7l Debian Nov 28 13:55:18 volumio startkiosk.sh[1432]: Current Operating System: Linux volumio 4.4.71+ #23 SMP Sun Sep 20 12:23:26 CEST 2020 armv7l Nov 28 13:55:18 volumio startkiosk.sh[1432]: Kernel command line: earlyprintk quiet loglevel=0 splash plymouth.ignore-serial-consoles initramfs.clear console=tty1 console=ttyS3,115200n8 rw init=/sbin/init imgpart=UUID=e78aac5d-11ed-4be7-9acd-84b7c2dd1454 imgfile=/volumio_current.sqsh bootpart=UUID=6887-FC6C datapart=UUID=59827d2e-5652-41d6-87c5-d67aec9fc23a uuidconfig=/extlinux/extlinux.conf vt.global_cursor_default=0 bootdelay=5 uboot_version=2017.07-g879646d1d6 root=/dev/mmcblk1p2 Nov 28 13:55:18 volumio startkiosk.sh[1432]: Build Date: 18 December 2021 09:05:36AM Nov 28 13:55:18 volumio startkiosk.sh[1432]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Nov 28 13:55:18 volumio startkiosk.sh[1432]: Current version of pixman: 0.36.0 Nov 28 13:55:18 volumio startkiosk.sh[1432]: Before reporting problems, check http://wiki.x.org Nov 28 13:55:18 volumio startkiosk.sh[1432]: to make sure that you have the latest version. Nov 28 13:55:18 volumio startkiosk.sh[1432]: Markers: (--) probed, (**) from config file, (==) default setting, Nov 28 13:55:18 volumio startkiosk.sh[1432]: (++) from command line, (!!) notice, (II) informational, Nov 28 13:55:18 volumio startkiosk.sh[1432]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Nov 28 13:55:18 volumio startkiosk.sh[1432]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Nov 28 13:55:18 2024 Nov 28 13:55:18 volumio startkiosk.sh[1432]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Nov 28 13:55:18 volumio startkiosk.sh[1432]: gbm: failed to open any driver (search paths /usr/lib/arm-linux-gnueabihf/dri:\$${ORIGIN}/dri:/usr/lib/dri) Nov 28 13:55:18 volumio startkiosk.sh[1432]: gbm: Last dlopen error: /usr/lib/dri/rockchip_dri.so: cannot open shared object file: No such file or directory Nov 28 13:55:18 volumio startkiosk.sh[1432]: failed to load driver: rockchip Nov 28 13:55:18 volumio startkiosk.sh[1432]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Nov 28 13:55:19 volumio volumio[1430]: info: ------------------------------------------- Nov 28 13:55:19 volumio volumio[1430]: info: ----- Volumio3 ---- Nov 28 13:55:19 volumio volumio[1430]: info: ------------------------------------------- Nov 28 13:55:19 volumio volumio[1430]: info: ----- System startup ---- Nov 28 13:55:19 volumio volumio[1430]: info: ------------------------------------------- Nov 28 13:55:20 volumio volumio[1430]: info: MYVOLUMIO Environment detected Nov 28 13:55:20 volumio volumio[1430]: info: Plugin folders cleanup Nov 28 13:55:20 volumio volumio[1430]: info: Scanning into folder /volumio/app/plugins/ Nov 28 13:55:20 volumio volumio[1430]: info: Scanning category audio_interface Nov 28 13:55:20 volumio volumio[1430]: info: Scanning category miscellanea Nov 28 13:55:20 volumio volumio[1430]: info: Scanning category music_service Nov 28 13:55:20 volumio volumio[1430]: info: Scanning category plugins.json Nov 28 13:55:20 volumio volumio[1430]: info: Scanning category system_controller Nov 28 13:55:20 volumio volumio[1430]: info: Scanning category user_interface Nov 28 13:55:20 volumio volumio[1430]: info: Scanning into folder /data/plugins/ Nov 28 13:55:20 volumio volumio[1430]: info: Scanning category music_service Nov 28 13:55:20 volumio volumio[1430]: info: Plugin folders cleanup completed Nov 28 13:55:20 volumio volumio[1430]: info: ------------------------------------------- Nov 28 13:55:20 volumio volumio[1430]: info: ----- Core plugins startup ---- Nov 28 13:55:20 volumio volumio[1430]: info: ------------------------------------------- Nov 28 13:55:20 volumio volumio[1430]: info: Loading plugins from folder /volumio/app/plugins/ Nov 28 13:55:20 volumio volumio[1430]: info: Adding plugin upnp to MyMusic Plugins Nov 28 13:55:20 volumio volumio[1430]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 28 13:55:20 volumio volumio[1430]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 28 13:55:20 volumio volumio[1430]: info: Loading plugins from folder /data/plugins/ Nov 28 13:55:20 volumio volumio[1430]: info: Loading plugin "system"... Nov 28 13:55:20 volumio volumio[1430]: info: Loading plugin "appearance"... Nov 28 13:55:21 volumio volumio[1430]: info: Loading plugin "network"... Nov 28 13:55:21 volumio volumio[1430]: info: Refreshing Cached IP Addresses Nov 28 13:55:21 volumio sudo[1545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 13:55:21 volumio sudo[1547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 13:55:21 volumio volumio[1430]: info: Loading plugin "services"... Nov 28 13:55:21 volumio sudo[1545]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:21 volumio volumio[1430]: info: Loading plugin "alsa_controller"... Nov 28 13:55:21 volumio sudo[1545]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:21 volumio sudo[1547]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:21 volumio sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 28 13:55:21 volumio sudo[1547]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:21 volumio sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:21 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 13:55:21 volumio volumio[1430]: info: Loading plugin "wizard"... Nov 28 13:55:21 volumio volumio[1430]: info: Loading plugin "networkfs"... Nov 28 13:55:21 volumio volumio[1430]: info: Starting Udev Watcher for removable devices Nov 28 13:55:21 volumio sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.178.22:Music /mnt/NAS/Musik Nov 28 13:55:21 volumio sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:21 volumio volumio[1430]: info: Ignoring mount for partition: BOOT Nov 28 13:55:21 volumio volumio[1430]: info: Ignoring mount for partition: volumio Nov 28 13:55:21 volumio volumio[1430]: info: Ignoring mount for partition: volumio_data Nov 28 13:55:21 volumio volumio[1430]: error: Cannot associate FS Label, not mounting Nov 28 13:55:21 volumio volumio[1430]: info: Mounting Device My_Music Nov 28 13:55:21 volumio sudo[1610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/My_Music -o noatime Nov 28 13:55:21 volumio sudo[1610]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:22 volumio mount.exfat[1616]: volume was not unmounted cleanly Nov 28 13:55:22 volumio sudo[1610]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:22 volumio volumio[1430]: WARN: volume was not unmounted cleanly. Nov 28 13:55:22 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 28 13:55:22 volumio volumio[1430]: info: Loading plugin "volumio_command_line_client"... Nov 28 13:55:22 volumio volumio[1430]: info: Loading plugin "upnp"... Nov 28 13:55:22 volumio volumio[1430]: info: [1732798522379] Starting Upmpd Daemon Nov 28 13:55:22 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 28 13:55:22 volumio volumio[1430]: info: Loading plugin "my_music"... Nov 28 13:55:22 volumio volumio[1430]: info: Loading plugin "mpd"... Nov 28 13:55:22 volumio volumio[1430]: info: Loading plugin "upnp_browser"... Nov 28 13:55:22 volumio volumio[1430]: info: Loading plugin "alarm-clock"... Nov 28 13:55:23 volumio volumio[1430]: info: Loading plugin "airplay_emulation"... Nov 28 13:55:23 volumio volumio[1430]: info: Starting Shairport Sync Nov 28 13:55:23 volumio volumio[1430]: info: Loading plugin "last_100"... Nov 28 13:55:23 volumio volumio[1430]: info: Loading plugin "webradio"... Nov 28 13:55:23 volumio systemd[1]: systemd-fsckd.service: Succeeded. Nov 28 13:55:23 volumio sudo[1561]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:23 volumio volumio[1430]: info: Loading plugin "i2s_dacs"... Nov 28 13:55:23 volumio volumio[1430]: info: I2S DAC not set, start Auto-detection Nov 28 13:55:23 volumio volumio[1430]: info: Loading plugin "volumiodiscovery"... Nov 28 13:55:23 volumio volumio[1430]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 28 13:55:23 volumio volumio[1430]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 13:55:23 volumio volumio[1430]: *** WARNING *** For more information see Nov 28 13:55:23 volumio volumio[1430]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 28 13:55:23 volumio node[1430]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 28 13:55:23 volumio volumio[1430]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 13:55:23 volumio volumio[1430]: *** WARNING *** For more information see Nov 28 13:55:23 volumio node[1430]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 13:55:23 volumio node[1430]: *** WARNING *** For more information see Nov 28 13:55:23 volumio node[1430]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 28 13:55:23 volumio node[1430]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 13:55:23 volumio node[1430]: *** WARNING *** For more information see Nov 28 13:55:23 volumio volumio[1430]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 28 13:55:23 volumio volumio[1430]: info: Discovery: Started advertising with name: Volumio Nov 28 13:55:23 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 28 13:55:23 volumio volumio[1430]: info: Loading plugin "outputs"... Nov 28 13:55:23 volumio volumio[1430]: info: Loading plugin "albumart"... Nov 28 13:55:23 volumio volumio[1430]: info: Plugin example_plugin is not enabled Nov 28 13:55:23 volumio volumio[1430]: info: Loading plugin "inputs"... Nov 28 13:55:23 volumio volumio[1430]: info: Loading plugin "updater_comm"... Nov 28 13:55:23 volumio volumio[1430]: info: Plugin mpdemulation is not enabled Nov 28 13:55:23 volumio volumio[1430]: info: Loading plugin "rest_api"... Nov 28 13:55:23 volumio volumio[1430]: info: Loading plugin "websocket"... Nov 28 13:55:23 volumio volumio[1430]: info: Starting Socket.io Server version 2.3.0 Nov 28 13:55:23 volumio volumio[1430]: info: Loading plugin "lms"... Nov 28 13:55:23 volumio volumio[1430]: Forking 3 albumart workers Nov 28 13:55:23 volumio volumio[1430]: info: Loading i18n strings for locale de Nov 28 13:55:23 volumio volumio[1430]: Updating browse sources language Nov 28 13:55:23 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 13:55:23 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 13:55:23 volumio volumio[1430]: info: CoreCommandRouter::initPlayerControls Nov 28 13:55:23 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:23 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:23 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:23 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:23 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:23 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:23 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:23 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:23 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 13:55:23 volumio volumio[1430]: Express server listening on port 3000 Nov 28 13:55:23 volumio volumio[1430]: [Metrics] WebUI: 4s 486.25ms Nov 28 13:55:23 volumio volumio[1430]: info: CoreStateMachine::resetVolumioState Nov 28 13:55:23 volumio volumio[1430]: info: CoreStateMachine::getcurrentVolume Nov 28 13:55:23 volumio volumio[1430]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 13:55:23 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 13:55:23 volumio volumio[1430]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 28 13:55:23 volumio volumio[1430]: info: Completed loading Core Plugins Nov 28 13:55:23 volumio volumio[1430]: info: Preparing to generate the ALSA configuration file Nov 28 13:55:23 volumio volumio[1430]: info: Volumio Network Manager: Network status updated: 1 Nov 28 13:55:24 volumio volumio[1430]: info: Reloading queue from file Nov 28 13:55:24 volumio volumio[1430]: info: Setting Device type: Tinkerboard Nov 28 13:55:24 volumio volumio[1430]: info: CoreStateMachine::setRepeat true single undefined Nov 28 13:55:24 volumio volumio[1430]: info: CoreStateMachine::pushState Nov 28 13:55:24 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::volumioPushState Nov 28 13:55:24 volumio volumio[1430]: info: CoreStateMachine::setRandom false Nov 28 13:55:24 volumio volumio[1430]: info: CoreStateMachine::pushState Nov 28 13:55:24 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::volumioPushState Nov 28 13:55:24 volumio volumio[1430]: info: VolumeController:: Volume=100 Mute =false Nov 28 13:55:24 volumio volumio[1430]: info: CoreStateMachine::pushState Nov 28 13:55:24 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::volumioPushState Nov 28 13:55:24 volumio volumio[1430]: info: CoreStateMachine::updateTrackBlock Nov 28 13:55:24 volumio volumio[1430]: info: CorePlayQueue::getTrackBlock Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 13:55:24 volumio volumio[1430]: Starting albumart workers Nov 28 13:55:24 volumio volumio[1430]: info: Asound.conf file written Nov 28 13:55:24 volumio volumio[1430]: Starting albumart workers Nov 28 13:55:24 volumio volumio[1430]: Starting albumart workers Nov 28 13:55:24 volumio sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 28 13:55:24 volumio sudo[1675]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:24 volumio sudo[1675]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:24 volumio volumio[1430]: info: Output device has changed, restarting MPD Nov 28 13:55:24 volumio volumio[1430]: info: Output device has changed, restarting Shairport Sync Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 13:55:24 volumio sudo[1689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 28 13:55:24 volumio sudo[1688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 28 13:55:24 volumio sudo[1689]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:24 volumio volumio[1430]: info: ___________ START PLUGINS ___________ Nov 28 13:55:24 volumio sudo[1688]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:24 volumio volumio[1430]: info: ControllerMpd::onStart: Initializing MPD Nov 28 13:55:24 volumio volumio[1430]: info: Creating MPD Configuration file Nov 28 13:55:24 volumio sudo[1689]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:24 volumio systemd[1]: Stopping Music Player Daemon... Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 28 13:55:24 volumio volumio[1430]: info: [1732798524595] CoreMusicLibrary::Adding element Medienserver Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 13:55:24 volumio sudo[1713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 28 13:55:24 volumio sudo[1713]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 13:55:24 volumio sudo[1714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 28 13:55:24 volumio sudo[1714]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 28 13:55:24 volumio volumio[1430]: info: [1732798524833] CoreMusicLibrary::Adding element Last_100 Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 28 13:55:24 volumio volumio[1430]: info: [1732798524837] CoreMusicLibrary::Adding element Webradio Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 13:55:24 volumio sudo[1714]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 13:55:24 volumio volumio[1430]: info: Initializing BBC Radios Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 13:55:24 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 13:55:24 volumio volumio[1430]: info: Volumio Calling Home Nov 28 13:55:24 volumio sudo[1772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 28 13:55:24 volumio sudo[1772]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:25 volumio systemd[1]: Stopping Logitech Media Server Daemon... Nov 28 13:55:25 volumio squeezeboxserver[820]: [24-11-27 09:57:57.3802] Slim::Utils::Firmware::downloadAsyncError (574) Warning: Firmware: Failed to download http://update.slimdevices.com/update/firmware/8.2.0/fab4.version (403 Forbidden), will try again in 10 minutes. Nov 28 13:55:25 volumio squeezeboxserver[820]: [24-11-28 13:55:05.5963] Slim::Formats::XML::gotErrorViaHTTP (343) Error: getting https://www.mysqueezebox.com/api/sounds/v1/opml Nov 28 13:55:25 volumio squeezeboxserver[820]: Couldn't resolve IP address for: www.mysqueezebox.com Nov 28 13:55:25 volumio squeezeboxserver[820]: [24-11-28 13:55:05.5971] Slim::Plugin::Sounds::Plugin::_gotSoundsError (82) Error: Unable to cache Sounds & Effects menu from SN: Couldn't resolve IP address for: www.mysqueezebox.com Nov 28 13:55:25 volumio squeezeboxserver[820]: [24-11-28 13:55:07.5949] Slim::Networking::SqueezeNetwork::_error (431) Unable to login to SN: Couldn't resolve IP address for: www.mysqueezebox.com Nov 28 13:55:25 volumio squeezeboxserver[820]: [24-11-28 13:55:07.5961] Slim::Networking::SqueezeNetwork::_init_error (156) Unable to login to mysqueezebox.com, sync is disabled: Couldn't resolve IP address for: www.mysqueezebox.com (https://www.mysqueezebox.com) Nov 28 13:55:25 volumio squeezeboxserver[820]: [24-11-28 13:55:07.5974] Slim::Networking::SqueezeNetwork::_init_error (172) mysqueezebox.com sync init failed: Couldn't resolve IP address for: www.mysqueezebox.com, will retry in 199800 (https://www.mysqueezebox.com) Nov 28 13:55:25 volumio systemd[1]: mpd.service: Succeeded. Nov 28 13:55:25 volumio systemd[1]: Stopped Music Player Daemon. Nov 28 13:55:25 volumio systemd[1]: Starting Music Player Daemon... Nov 28 13:55:25 volumio systemd[1]: logitechmediaserver.service: Succeeded. Nov 28 13:55:25 volumio systemd[1]: Stopped Logitech Media Server Daemon. Nov 28 13:55:25 volumio systemd[1]: Starting Logitech Media Server Daemon... Nov 28 13:55:25 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Nov 28 13:55:25 volumio volumio[1430]: info: Volumio called home Nov 28 13:55:25 volumio volumio[1430]: info: MPD Permissions set Nov 28 13:55:25 volumio sudo[1783]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 28 13:55:25 volumio systemd[1]: Started Logitech Media Server Daemon. Nov 28 13:55:25 volumio sudo[1772]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:25 volumio sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:25 volumio sudo[1783]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:25 volumio volumio[1430]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 13:55:25 volumio volumio[1430]: info: VolumeController:: Volume=100 Mute =false Nov 28 13:55:25 volumio volumio[1430]: info: CoreStateMachine::pushState Nov 28 13:55:25 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::volumioPushState Nov 28 13:55:25 volumio volumio[1430]: info: logitechmediaserver started Nov 28 13:55:25 volumio volumio[1430]: info: Discovery: adding fe27e5b1-c736-452a-8156-9fea9a0f6e6f Nov 28 13:55:25 volumio volumio[1430]: info: Discovery: Found device Volumio Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:25 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:25 volumio volumio[1430]: info: Starting Shairport Sync Nov 28 13:55:25 volumio volumio[1430]: info: Starting Shairport Sync Nov 28 13:55:25 volumio volumio[1430]: info: Starting Shairport Sync Nov 28 13:55:25 volumio sudo[1816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 28 13:55:25 volumio sudo[1816]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:25 volumio sudo[1818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 28 13:55:25 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:25 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:25 volumio sudo[1818]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 28 13:55:25 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 28 13:55:25 volumio sudo[1821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 28 13:55:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 28 13:55:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 28 13:55:25 volumio sudo[1816]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 28 13:55:25 volumio sudo[1821]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:25 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 28 13:55:25 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 28 13:55:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 28 13:55:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 28 13:55:25 volumio sudo[1818]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 28 13:55:25 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 28 13:55:25 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 28 13:55:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 28 13:55:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 28 13:55:25 volumio sudo[1821]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:25 volumio volumio[1430]: info: Shairport-Sync Started Nov 28 13:55:25 volumio volumio[1430]: Error adding Membership: Error: addMembership EINVAL Nov 28 13:55:25 volumio volumio[1430]: info: Shairport-Sync Started Nov 28 13:55:25 volumio volumio[1430]: info: Shairport-Sync Started Nov 28 13:55:26 volumio mpd[1807]: Nov 28 13:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 28 13:55:27 volumio volumio-remote-updater[572]: [2024-11-28 13:55:27] [connect] Successful connection Nov 28 13:55:27 volumio volumio-remote-updater[572]: [2024-11-28 13:55:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732798527 101 Nov 28 13:55:27 volumio volumio[1430]: 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: 2 Nov 28 13:55:30 volumio squeezeboxserver[1791]: [24-11-28 13:55:27.3814] main::init (390) Starting Logitech Media Server (v8.2.0, 1627922070, Tue Aug 3 11:39:20 CEST 2021) perl 5.028001 - arm-linux-gnueabihf-thread-multi-64int Nov 28 13:55:30 volumio squeezeboxserver[1791]: [24-11-28 13:55:29.4385] Slim::Formats::XML::gotErrorViaHTTP (343) Error: getting https://www.mysqueezebox.com/api/sounds/v1/opml Nov 28 13:55:30 volumio squeezeboxserver[1791]: Couldn't resolve IP address for: www.mysqueezebox.com Nov 28 13:55:30 volumio squeezeboxserver[1791]: [24-11-28 13:55:29.4401] Slim::Plugin::Sounds::Plugin::_gotSoundsError (82) Error: Unable to cache Sounds & Effects menu from SN: Couldn't resolve IP address for: www.mysqueezebox.com Nov 28 13:55:30 volumio squeezeboxserver[1791]: [24-11-28 13:55:29.5082] Slim::Networking::SqueezeNetwork::_error (431) Unable to login to SN: Couldn't resolve IP address for: www.mysqueezebox.com Nov 28 13:55:30 volumio squeezeboxserver[1791]: [24-11-28 13:55:29.5089] Slim::Networking::SqueezeNetwork::_init_error (156) Unable to login to mysqueezebox.com, sync is disabled: Couldn't resolve IP address for: www.mysqueezebox.com (https://www.mysqueezebox.com) Nov 28 13:55:30 volumio squeezeboxserver[1791]: [24-11-28 13:55:29.5099] Slim::Networking::SqueezeNetwork::_init_error (172) mysqueezebox.com sync init failed: Couldn't resolve IP address for: www.mysqueezebox.com, will retry in 200100 (https://www.mysqueezebox.com) Nov 28 13:55:30 volumio volumio[1430]: verbose: New Socket.io Connection to 192.168.178.21 from 192.168.178.34 UA: Mozilla/5.0 (iPad; CPU OS 12_5_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/12.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 3 Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:30 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 28 13:55:30 volumio volumio[1430]: info: Received Get System Info Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 13:55:30 volumio volumio[1430]: info: Discovery: Getting this device information Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:30 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:30 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:30 volumio volumio[1430]: info: Listing playlists Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 28 13:55:30 volumio volumio[1430]: info: Received Get System Info Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 13:55:30 volumio volumio[1430]: info: Discovery: Getting this device information Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:30 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:30 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 28 13:55:30 volumio volumio[1430]: info: Received Get System Info Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 13:55:30 volumio volumio[1430]: info: Discovery: Getting this device information Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:30 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 28 13:55:30 volumio volumio[1430]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 28 13:55:30 volumio volumio[1430]: info: Received Get System Version Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 13:55:30 volumio volumio[1430]: info: Received Get System Info Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 13:55:30 volumio volumio[1430]: info: Discovery: Getting this device information Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:30 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:30 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:30 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 28 13:55:30 volumio volumio[1430]: info: Received Get System Info Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 13:55:30 volumio volumio[1430]: info: Discovery: Getting this device information Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:30 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:30 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:30 volumio volumio[1430]: info: Listing playlists Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::volumioGetBrowseSources Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::volumioGetBrowseSources Nov 28 13:55:30 volumio volumio[1430]: info: CoreCommandRouter::volumioGetBrowseSources Nov 28 13:55:32 volumio sudo[2016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 13:55:32 volumio sudo[2018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 13:55:32 volumio sudo[2018]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:32 volumio sudo[2019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 28 13:55:32 volumio sudo[2016]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:32 volumio sudo[2018]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:32 volumio sudo[2016]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:32 volumio sudo[2019]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:32 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Nov 28 13:55:32 volumio sudo[2019]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:32 volumio volumio[1430]: info: Upmpdcli Daemon Started Nov 28 13:55:32 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 28 13:55:33 volumio volumio[1430]: 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: 4 Nov 28 13:55:33 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 28 13:55:33 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Nov 28 13:55:33 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Nov 28 13:55:33 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 28 13:55:34 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 13:55:34 volumio systemd[1]: systemd-timedated.service: Succeeded. Nov 28 13:55:35 volumio volumio[1430]: 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 Nov 28 13:55:35 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 28 13:55:35 volumio systemd[1]: mpd.service: Succeeded. Nov 28 13:55:35 volumio systemd[1]: Stopped Music Player Daemon. Nov 28 13:55:35 volumio systemd[1]: Starting Music Player Daemon... Nov 28 13:55:35 volumio sudo[2067]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 28 13:55:35 volumio sudo[2067]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 13:55:35 volumio sudo[2067]: pam_unix(sudo:session): session closed for user root Nov 28 13:55:36 volumio mpd[2076]: Nov 28 13:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 28 13:55:36 volumio volumio[1430]: 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: 6 Nov 28 13:55:36 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 28 13:55:36 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 13:55:36 volumio volumio[1430]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 13:55:36 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 13:55:36 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:36 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:36 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 28 13:55:37 volumio volumio[1430]: info: Received Get System Info Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 13:55:37 volumio volumio[1430]: info: Discovery: Getting this device information Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:37 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:37 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:37 volumio volumio[1430]: info: Listing playlists Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:37 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:37 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:37 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 28 13:55:37 volumio volumio[1430]: info: Received Get System Info Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 13:55:37 volumio volumio[1430]: info: Discovery: Getting this device information Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:37 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:37 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:37 volumio volumio[1430]: info: Listing playlists Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 28 13:55:37 volumio volumio[1430]: info: Received Get System Info Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 13:55:37 volumio volumio[1430]: info: Discovery: Getting this device information Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:37 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:37 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 13:55:38 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Nov 28 13:55:38 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 28 13:55:38 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 28 13:55:38 volumio volumio[1430]: info: Received Get System Info Nov 28 13:55:38 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 13:55:38 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 13:55:38 volumio volumio[1430]: info: Discovery: Getting this device information Nov 28 13:55:38 volumio volumio[1430]: info: CoreCommandRouter::volumioGetState Nov 28 13:55:38 volumio volumio[1430]: info: CorePlayQueue::getTrack 0 Nov 28 13:55:38 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 13:55:39 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Nov 28 13:55:40 volumio volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Nov 28 13:55:40 volumio volumio[1430]: verbose: ControllerMpd::sendMpdCommand update Nov 28 13:55:40 volumio volumio[1430]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 13:55:40 volumio volumio[1430]: TypeError: Cannot read property 'then' of undefined Nov 28 13:55:40 volumio volumio[1430]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Nov 28 13:55:40 volumio volumio[1430]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1899:15) Nov 28 13:55:40 volumio volumio[1430]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1097:32) Nov 28 13:55:40 volumio volumio[1430]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1085:26) Nov 28 13:55:40 volumio volumio[1430]: at Socket.emit (events.js:400:28) Nov 28 13:55:40 volumio volumio[1430]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 28 13:55:40 volumio volumio[1430]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Nov 28 13:55:40 volumio volumio[1430]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 13:55:40 volumio sudo[2086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-28 13:54 Nov 28 13:55:40 volumio sudo[2086]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:11:38 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="932fc3546f7cc8faa2b4d349036752dd"