-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Wed 2025-05-28 10:36:10 UTC. -- May 28 10:35:51 wizard-iii-brw ntpd[689]: receive: Unexpected origin timestamp 0xea92eda3.288b7958 does not match aorg 0000000000.00000000 from server@178.239.19.63 xmt 0xebe16587.7fdbd333 May 28 10:35:51 wizard-iii-brw ntpd[689]: receive: Unexpected origin timestamp 0xea92eda3.28852bc2 does not match aorg 0000000000.00000000 from server@80.60.67.39 xmt 0xebe16587.7feb1f51 May 28 10:35:51 wizard-iii-brw ntpd[689]: receive: Unexpected origin timestamp 0xea92eda3.288dec60 does not match aorg 0000000000.00000000 from server@86.80.166.233 xmt 0xebe16587.7f94c97e May 28 10:35:51 wizard-iii-brw ntpd[689]: receive: Unexpected origin timestamp 0xea92eda3.28819c2b does not match aorg 0000000000.00000000 from server@178.239.19.56 xmt 0xebe16587.8041b3a4 May 28 10:35:51 wizard-iii-brw systemd[1]: Starting Daily man-db regeneration... May 28 10:35:51 wizard-iii-brw systemd[1]: Starting Daily apt download activities... May 28 10:35:51 wizard-iii-brw ntpd[689]: receive: Unexpected origin timestamp 0xea92eda3.287c6960 does not match aorg 0000000000.00000000 from server@144.21.38.190 xmt 0xebe16587.8b498ef7 May 28 10:35:51 wizard-iii-brw ntpd[689]: receive: Unexpected origin timestamp 0xea92eda3.28874471 does not match aorg 0000000000.00000000 from server@45.32.4.67 xmt 0xebe16587.893f7795 May 28 10:35:51 wizard-iii-brw volumio[732]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 28 10:35:51 wizard-iii-brw systemd[1]: man-db.service: Succeeded. May 28 10:35:52 wizard-iii-brw systemd[1]: Started Daily man-db regeneration. May 28 10:35:52 wizard-iii-brw volumio[732]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare May 28 10:35:53 wizard-iii-brw volumio[732]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 10:35:53 wizard-iii-brw volumio[732]: TypeError: Cannot read property 'replace' of undefined May 28 10:35:53 wizard-iii-brw volumio[732]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) May 28 10:35:53 wizard-iii-brw volumio[732]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) May 28 10:35:53 wizard-iii-brw volumio[732]: at listOnTimeout (internal/timers.js:554:17) May 28 10:35:53 wizard-iii-brw volumio[732]: at processTimers (internal/timers.js:497:7) May 28 10:35:53 wizard-iii-brw volumio[732]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 10:35:53 wizard-iii-brw sudo[1636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.101/Music /mnt/NAS/Ripnas May 28 10:35:53 wizard-iii-brw sudo[1636]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 10:35:53 wizard-iii-brw kernel: CIFS: Attempting to mount //192.168.178.101/Music May 28 10:35:54 wizard-iii-brw sudo[1649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-28 10:34 May 28 10:35:54 wizard-iii-brw sudo[1649]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 10:35:54 wizard-iii-brw sudo[1649]: pam_unix(sudo:session): session closed for user root May 28 10:35:54 wizard-iii-brw volumio-remote-updater[531]: [2025-05-28 10:35:54] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 28 10:35:54 wizard-iii-brw volumio-remote-updater[531]: [2025-05-28 10:35:54] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 28 10:35:54 wizard-iii-brw systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 28 10:35:54 wizard-iii-brw ntfs-3g[891]: Unmounting /dev/sda1 (MI_harddisk) May 28 10:35:54 wizard-iii-brw kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 28 10:35:54 wizard-iii-brw kernel: CIFS: VFS: cifs_mount failed w/return code = -4 May 28 10:35:54 wizard-iii-brw systemd[1]: media-MI_harddisk.mount: Succeeded. May 28 10:35:54 wizard-iii-brw sudo[1636]: pam_unix(sudo:session): session closed for user root May 28 10:35:54 wizard-iii-brw systemd[1]: volumio.service: Failed with result 'exit-code'. May 28 10:35:54 wizard-iii-brw systemd[1]: Started dynamicswap service. May 28 10:35:54 wizard-iii-brw systemd[1]: dynamicswap.service: Succeeded. May 28 10:35:55 wizard-iii-brw systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 28 10:35:55 wizard-iii-brw systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. May 28 10:35:55 wizard-iii-brw systemd[1]: Started dynamicswap service. May 28 10:35:55 wizard-iii-brw systemd[1]: Stopped Volumio Backend Module. May 28 10:35:55 wizard-iii-brw systemd[1]: Started Volumio Backend Module. May 28 10:35:55 wizard-iii-brw systemd[1]: dynamicswap.service: Succeeded. May 28 10:35:55 wizard-iii-brw systemd[1]: apt-daily.service: Succeeded. May 28 10:35:55 wizard-iii-brw systemd[1]: Started Daily apt download activities. May 28 10:35:55 wizard-iii-brw systemd[1]: Starting Daily apt upgrade and clean activities... May 28 10:35:57 wizard-iii-brw volumio[1666]: info: ------------------------------------------- May 28 10:35:57 wizard-iii-brw volumio[1666]: info: ----- Volumio3 ---- May 28 10:35:57 wizard-iii-brw volumio[1666]: info: ------------------------------------------- May 28 10:35:57 wizard-iii-brw volumio[1666]: info: ----- System startup ---- May 28 10:35:57 wizard-iii-brw volumio[1666]: info: ------------------------------------------- May 28 10:35:57 wizard-iii-brw systemd[1]: apt-daily-upgrade.service: Succeeded. May 28 10:35:57 wizard-iii-brw systemd[1]: Started Daily apt upgrade and clean activities. May 28 10:35:58 wizard-iii-brw volumio[1666]: info: MYVOLUMIO Environment detected May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Plugin folders cleanup May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Scanning into folder /volumio/app/plugins/ May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Scanning category audio_interface May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Scanning category miscellanea May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Scanning category music_service May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Scanning category plugins.json May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Scanning category system_controller May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Scanning category user_interface May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Scanning into folder /data/plugins/ May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Scanning category miscellanea May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Scanning category music_service May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Scanning category user_interface May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Plugin folders cleanup completed May 28 10:35:58 wizard-iii-brw volumio[1666]: info: ------------------------------------------- May 28 10:35:58 wizard-iii-brw volumio[1666]: info: ----- Core plugins startup ---- May 28 10:35:58 wizard-iii-brw volumio[1666]: info: ------------------------------------------- May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Loading plugins from folder /volumio/app/plugins/ May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Adding plugin upnp to MyMusic Plugins May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Adding plugin airplay_emulation to MyMusic Plugins May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Adding plugin upnp_browser to MyMusic Plugins May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Loading plugins from folder /data/plugins/ May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Loading plugin "system"... May 28 10:35:58 wizard-iii-brw volumio[1666]: info: Loading plugin "appearance"... May 28 10:35:59 wizard-iii-brw volumio[1666]: info: Loading plugin "network"... May 28 10:35:59 wizard-iii-brw volumio[1666]: info: Refreshing Cached IP Addresses May 28 10:35:59 wizard-iii-brw volumio-remote-updater[531]: [2025-05-28 10:35:59] [connect] Successful connection May 28 10:35:59 wizard-iii-brw sudo[1766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 10:35:59 wizard-iii-brw sudo[1766]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 10:35:59 wizard-iii-brw volumio[1666]: info: Loading plugin "services"... May 28 10:35:59 wizard-iii-brw volumio[1666]: info: Loading plugin "alsa_controller"... May 28 10:35:59 wizard-iii-brw sudo[1766]: pam_unix(sudo:session): session closed for user root May 28 10:35:59 wizard-iii-brw sudo[1769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 10:35:59 wizard-iii-brw sudo[1769]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 10:35:59 wizard-iii-brw sudo[1769]: pam_unix(sudo:session): session closed for user root May 28 10:35:59 wizard-iii-brw volumio[1666]: info: Loading plugin "wizard"... May 28 10:35:59 wizard-iii-brw volumio[1666]: info: Loading plugin "volumio_command_line_client"... May 28 10:35:59 wizard-iii-brw volumio[1666]: info: Loading plugin "upnp"... May 28 10:35:59 wizard-iii-brw volumio[1666]: info: [1748428559881] Starting Upmpd Daemon May 28 10:35:59 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 10:35:59 wizard-iii-brw volumio[1666]: info: Loading plugin "my_music"... May 28 10:35:59 wizard-iii-brw volumio[1666]: info: Loading plugin "mpd"... May 28 10:36:00 wizard-iii-brw volumio[1666]: info: Creating MPD Configuration file May 28 10:36:00 wizard-iii-brw volumio[1666]: info: Loading plugin "upnp_browser"... May 28 10:36:00 wizard-iii-brw sudo[1778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 10:36:00 wizard-iii-brw sudo[1778]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 10:36:00 wizard-iii-brw sudo[1778]: pam_unix(sudo:session): session closed for user root May 28 10:36:00 wizard-iii-brw sudo[1780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 10:36:00 wizard-iii-brw sudo[1780]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 10:36:00 wizard-iii-brw systemd[1]: Stopping Music Player Daemon... May 28 10:36:00 wizard-iii-brw systemd[1]: mpd.service: Succeeded. May 28 10:36:00 wizard-iii-brw systemd[1]: Stopped Music Player Daemon. May 28 10:36:00 wizard-iii-brw systemd[1]: Starting Music Player Daemon... May 28 10:36:00 wizard-iii-brw volumio[1666]: info: Loading plugin "networkfs"... May 28 10:36:01 wizard-iii-brw sudo[1788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.101/UPnP /mnt/NAS/RIPNAS May 28 10:36:01 wizard-iii-brw sudo[1788]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 10:36:01 wizard-iii-brw sudo[1790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.101/Music /mnt/NAS/Ripnas May 28 10:36:01 wizard-iii-brw sudo[1790]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 10:36:01 wizard-iii-brw kernel: CIFS: Attempting to mount //192.168.178.101/UPnP May 28 10:36:01 wizard-iii-brw kernel: CIFS: Attempting to mount //192.168.178.101/Music May 28 10:36:01 wizard-iii-brw volumio[1666]: info: Starting Udev Watcher for removable devices May 28 10:36:01 wizard-iii-brw sudo[1794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.101/Asset UPnP/Ripnas/music /mnt/NAS/Ripnas May 28 10:36:01 wizard-iii-brw sudo[1794]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 10:36:01 wizard-iii-brw sudo[1800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=nobody,password=nobody,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.32/Volumes/Internal/ /mnt/NAS/MinimServer May 28 10:36:01 wizard-iii-brw sudo[1800]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 10:36:01 wizard-iii-brw kernel: CIFS: Attempting to mount //192.168.178.101/Asset UPnP/Ripnas/music May 28 10:36:01 wizard-iii-brw kernel: CIFS: Attempting to mount //192.168.178.32/Volumes/Internal/ May 28 10:36:01 wizard-iii-brw volumio[1666]: info: Ignoring mount for partition: boot May 28 10:36:01 wizard-iii-brw volumio[1666]: info: Ignoring mount for partition: volumio May 28 10:36:01 wizard-iii-brw volumio[1666]: info: Ignoring mount for partition: volumio_data May 28 10:36:01 wizard-iii-brw volumio[1666]: info: Mounting Device MI_harddisk May 28 10:36:01 wizard-iii-brw sudo[1811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/MI_harddisk -o noatime,dmask=0000,fmask=0000 May 28 10:36:01 wizard-iii-brw sudo[1811]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 10:36:02 wizard-iii-brw mpd[1785]: May 28 10:36 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 28 10:36:02 wizard-iii-brw mpd[1785]: May 28 10:36 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 28 10:36:02 wizard-iii-brw mpd[1785]: May 28 10:36 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 28 10:36:02 wizard-iii-brw systemd[1]: Started Music Player Daemon. May 28 10:36:02 wizard-iii-brw sudo[1780]: pam_unix(sudo:session): session closed for user root May 28 10:36:02 wizard-iii-brw ntfs-3g[1816]: Version 2017.3.23AR.3 integrated FUSE 28 May 28 10:36:02 wizard-iii-brw ntfs-3g[1816]: Mounted /dev/sda1 (Read-Write, label "MI_harddisk", NTFS 3.1) May 28 10:36:02 wizard-iii-brw ntfs-3g[1816]: Cmdline options: rw,noatime,dmask=0000,fmask=0000 May 28 10:36:02 wizard-iii-brw ntfs-3g[1816]: Mount options: allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 May 28 10:36:02 wizard-iii-brw ntfs-3g[1816]: Global ownership and permissions enforced, configuration type 7 May 28 10:36:02 wizard-iii-brw sudo[1811]: pam_unix(sudo:session): session closed for user root May 28 10:36:02 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 10:36:02 wizard-iii-brw volumio[1666]: info: Loading plugin "alarm-clock"... May 28 10:36:03 wizard-iii-brw volumio[1666]: info: Loading plugin "airplay_emulation"... May 28 10:36:03 wizard-iii-brw volumio[1666]: info: Starting Shairport Sync May 28 10:36:03 wizard-iii-brw volumio[1666]: info: Loading plugin "last_100"... May 28 10:36:03 wizard-iii-brw volumio[1666]: info: Loading plugin "webradio"... May 28 10:36:03 wizard-iii-brw volumio[1666]: info: Loading plugin "i2s_dacs"... May 28 10:36:03 wizard-iii-brw volumio[1666]: info: Loading plugin "volumiodiscovery"... May 28 10:36:03 wizard-iii-brw volumio[1666]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 28 10:36:03 wizard-iii-brw volumio[1666]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 10:36:03 wizard-iii-brw volumio[1666]: *** WARNING *** For more information see May 28 10:36:03 wizard-iii-brw node[1666]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 28 10:36:03 wizard-iii-brw volumio[1666]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 28 10:36:03 wizard-iii-brw volumio[1666]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 10:36:03 wizard-iii-brw volumio[1666]: *** WARNING *** For more information see May 28 10:36:03 wizard-iii-brw node[1666]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 10:36:03 wizard-iii-brw node[1666]: *** WARNING *** For more information see May 28 10:36:03 wizard-iii-brw node[1666]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 28 10:36:03 wizard-iii-brw node[1666]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 10:36:03 wizard-iii-brw node[1666]: *** WARNING *** For more information see May 28 10:36:03 wizard-iii-brw volumio[1666]: info: Applying required configuration parameters for plugin volumiodiscovery May 28 10:36:03 wizard-iii-brw volumio[1666]: info: Discovery: Started advertising with name: Wizard III BRW May 28 10:36:03 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 10:36:03 wizard-iii-brw volumio[1666]: info: Loading plugin "spop"... May 28 10:36:04 wizard-iii-brw volumio[1666]: info: Loading plugin "now_playing"... May 28 10:36:04 wizard-iii-brw volumio[1666]: info: Loading plugin "outputs"... May 28 10:36:04 wizard-iii-brw volumio[1666]: info: Loading plugin "albumart"... May 28 10:36:04 wizard-iii-brw volumio[1666]: info: Plugin example_plugin is not enabled May 28 10:36:04 wizard-iii-brw volumio[1666]: info: Loading plugin "inputs"... May 28 10:36:05 wizard-iii-brw volumio[1666]: info: Loading plugin "updater_comm"... May 28 10:36:05 wizard-iii-brw volumio[1666]: info: Plugin mpdemulation is not enabled May 28 10:36:05 wizard-iii-brw volumio[1666]: info: Loading plugin "rest_api"... May 28 10:36:05 wizard-iii-brw volumio[1666]: info: Loading plugin "websocket"... May 28 10:36:05 wizard-iii-brw volumio[1666]: info: Plugin contemporary_advanced is not enabled May 28 10:36:05 wizard-iii-brw volumio[1666]: info: Plugin volspotconnect2 is not enabled May 28 10:36:05 wizard-iii-brw volumio[1666]: info: Loading plugin "touch_display"... May 28 10:36:05 wizard-iii-brw volumio[1666]: Forking 3 albumart workers May 28 10:36:05 wizard-iii-brw volumio[1666]: info: Applying required configuration parameters for plugin touch_display May 28 10:36:05 wizard-iii-brw volumio[1666]: info: Loading i18n strings for locale nl May 28 10:36:05 wizard-iii-brw volumio[1666]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 28 10:36:05 wizard-iii-brw volumio[1666]: Updating browse sources language May 28 10:36:05 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 10:36:05 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 10:36:05 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::initPlayerControls May 28 10:36:05 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:05 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:05 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:05 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:05 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:05 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:05 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:05 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:05 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 10:36:05 wizard-iii-brw volumio[1666]: Express server listening on port 3000 May 28 10:36:05 wizard-iii-brw volumio[1666]: [Metrics] WebUI: 9s 274.50ms May 28 10:36:05 wizard-iii-brw volumio[1666]: info: CoreStateMachine::resetVolumioState May 28 10:36:05 wizard-iii-brw volumio[1666]: info: CoreStateMachine::getcurrentVolume May 28 10:36:06 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::volumioRetrievevolume May 28 10:36:06 wizard-iii-brw volumio[1666]: info: MPD Permissions set May 28 10:36:06 wizard-iii-brw volumio[1666]: verbose: New Socket.io Connection to 192.168.2.116 from 192.168.2.108 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 1 May 28 10:36:06 wizard-iii-brw volumio[1666]: verbose: New Socket.io Connection to localhost: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 Total Clients: 2 May 28 10:36:06 wizard-iii-brw volumio[1666]: verbose: New Socket.io Connection to 192.168.2.116:3000 from 192.168.2.108 UA: Dart/3.5 (dart:io) Total Clients: 3 May 28 10:36:06 wizard-iii-brw volumio[1666]: verbose: New Socket.io Connection to 192.168.2.116 from 192.168.2.108 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 4 May 28 10:36:06 wizard-iii-brw volumio[1666]: verbose: New Socket.io Connection to 192.168.2.116 from 192.168.2.108 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 5 May 28 10:36:06 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 10:36:06 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 10:36:06 wizard-iii-brw volumio[1666]: info: Discovery: Getting this device information May 28 10:36:06 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::volumioGetState May 28 10:36:06 wizard-iii-brw volumio[1666]: info: CorePlayQueue::getTrack 0 May 28 10:36:06 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 10:36:06 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 10:36:06 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 10:36:06 wizard-iii-brw volumio[1666]: info: Discovery: Getting this device information May 28 10:36:06 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::volumioGetState May 28 10:36:06 wizard-iii-brw volumio[1666]: info: CorePlayQueue::getTrack 0 May 28 10:36:06 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 10:36:06 wizard-iii-brw volumio[1666]: Starting albumart workers May 28 10:36:06 wizard-iii-brw volumio[1666]: Starting albumart workers May 28 10:36:06 wizard-iii-brw volumio[1666]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 May 28 10:36:06 wizard-iii-brw volumio[1666]: info: MPD running with PID1785 May 28 10:36:06 wizard-iii-brw volumio[1666]: ,establishing connection May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:07 wizard-iii-brw volumio[1666]: Starting albumart workers May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 10:36:07 wizard-iii-brw volumio[1666]: info: Reloading queue from file May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::volumioGetState May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CorePlayQueue::getTrack 0 May 28 10:36:07 wizard-iii-brw volumio[1666]: error: updateQueue error: null May 28 10:36:07 wizard-iii-brw volumio[1666]: info: VolumeController:: Volume=100 Mute =true May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreStateMachine::pushState May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CorePlayQueue::getTrack 0 May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::volumioPushState May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreStateMachine::updateTrackBlock May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CorePlayQueue::getTrackBlock May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::volumioRetrievevolume May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreStateMachine::setRepeat false single undefined May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreStateMachine::pushState May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CorePlayQueue::getTrack 0 May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::volumioPushState May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreStateMachine::setRandom false May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreStateMachine::pushState May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CorePlayQueue::getTrack 0 May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::volumioPushState May 28 10:36:07 wizard-iii-brw volumio[1666]: info: Setting Device type: Raspberry PI May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CALLMETHOD: user_interface now_playing broadcastPluginInfo undefined May 28 10:36:07 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: now_playing , broadcastPluginInfo May 28 10:36:07 wizard-iii-brw volumio[1666]: error: updateQueue error: null May 28 10:36:08 wizard-iii-brw volumio[1666]: info: Discovery: adding 1f40934b-326b-4838-ac5b-2b547e1617d0 May 28 10:36:08 wizard-iii-brw volumio[1666]: info: mDNS: Found device Wizard III BRW May 28 10:36:08 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::volumioGetState May 28 10:36:08 wizard-iii-brw volumio[1666]: info: CorePlayQueue::getTrack 0 May 28 10:36:08 wizard-iii-brw volumio[1666]: info: VolumeController:: Volume=100 Mute =true May 28 10:36:08 wizard-iii-brw volumio[1666]: info: CoreStateMachine::pushState May 28 10:36:08 wizard-iii-brw volumio[1666]: info: CorePlayQueue::getTrack 0 May 28 10:36:08 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::volumioPushState May 28 10:36:09 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 10:36:09 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 10:36:09 wizard-iii-brw volumio[1666]: info: Discovery: Getting this device information May 28 10:36:09 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::volumioGetState May 28 10:36:09 wizard-iii-brw volumio[1666]: info: CorePlayQueue::getTrack 0 May 28 10:36:09 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 10:36:09 wizard-iii-brw volumio[1666]: verbose: New Socket.io Connection to 192.168.2.116:3000 from 192.168.2.108 UA: Dart/3.5 (dart:io) Total Clients: 7 May 28 10:36:09 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::volumioGetState May 28 10:36:09 wizard-iii-brw volumio[1666]: info: CorePlayQueue::getTrack 0 May 28 10:36:09 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 28 10:36:09 wizard-iii-brw volumio[1666]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 28 10:36:09 wizard-iii-brw volumio[1666]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 10:36:09 wizard-iii-brw volumio[1666]: TypeError: Cannot read property 'has' of undefined May 28 10:36:09 wizard-iii-brw volumio[1666]: at NowPlayingContext.getConfigValue (/data/plugins/user_interface/now_playing/lib/np.js:60:32) May 28 10:36:09 wizard-iii-brw volumio[1666]: at Timeout._onTimeout (/data/plugins/user_interface/now_playing/index.js:681:26) May 28 10:36:09 wizard-iii-brw volumio[1666]: at listOnTimeout (internal/timers.js:554:17) May 28 10:36:09 wizard-iii-brw volumio[1666]: at processTimers (internal/timers.js:497:7) May 28 10:36:09 wizard-iii-brw volumio[1666]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 10:36:10 wizard-iii-brw sudo[1899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-28 10:35 May 28 10:36:10 wizard-iii-brw sudo[1899]: 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="e5f8795c07ba36ff2b02d7ba0a21c7a0f29b897e" VOLUMIO_FE_VERSION="473d8facbed5f9754db84a81b093baace7aa4b64" VOLUMIO_FE3_VERSION="934cddf6d46478e7a53a8d3664cd346f4de7cb34" VOLUMIO_BE_VERSION="359b2717b79cfc72b7d1c918d0fc2885a93bca70" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 24 Jan 2022 10:51:57 AM CET" VOLUMIO_VERSION="3.198" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="74e6a9ad035e912732893c8d6b060b17"