-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sun 2024-11-24 21:20:18 CET. -- Nov 24 21:19:57 volumio systemd-timedated[949]: Changed local time to Sun Nov 24 20:19:57 2024 Nov 24 21:19:57 volumio sudo[935]: pam_unix(sudo:session): session closed for user root Nov 24 21:19:57 volumio volumio-time-update[620]: volumio-time-update-util: System time updated successfully. Nov 24 21:19:57 volumio systemd[1]: Started Volumio Time Update Utility. Nov 24 21:19:57 volumio systemd[1]: Starting Daily apt download activities... Nov 24 21:19:57 volumio iwconfig[960]: Error for wireless request "Set Power Management" (8B2C) : Nov 24 21:19:57 volumio iwconfig[960]: SET failed on device wlan0 ; Invalid argument. Nov 24 21:19:57 volumio systemd[1]: wireless.service: Succeeded. Nov 24 21:19:57 volumio systemd[1]: Started Wireless Services. Nov 24 21:19:57 volumio systemd[1]: Started Volumio Backend Module. Nov 24 21:19:57 volumio systemd[1]: Started Volumio Cpu Tweaker. Nov 24 21:19:57 volumio systemd[1]: Started First Start. Nov 24 21:19:57 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Nov 24 21:19:57 volumio systemd[1]: Reached target Host and Network Name Lookups. Nov 24 21:19:57 volumio firststart.sh[965]: Volumio first start configuration script Nov 24 21:19:57 volumio firststart.sh[965]: Configuring unconfigured packages Nov 24 21:19:57 volumio volumio-cpu-tweak[964]: Setting RT Priority for mpd Nov 24 21:19:57 volumio volumio-cpu-tweak[964]: Setting MPD Affinity Nov 24 21:19:57 volumio volumio-cpu-tweak[964]: pid 831's current affinity mask: f Nov 24 21:19:57 volumio volumio-cpu-tweak[964]: pid 831's new affinity mask: 3 Nov 24 21:19:57 volumio volumio-cpu-tweak[964]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Nov 24 21:19:57 volumio volumio-cpu-tweak[964]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Nov 24 21:19:57 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Nov 24 21:19:57 volumio firststart.sh[965]: Creating /var/log/samba/cores folder Nov 24 21:19:57 volumio firststart.sh[965]: Creating /boot/userconfig.txt Nov 24 21:19:57 volumio firststart.sh[965]: Removing default SSH host keys Nov 24 21:19:57 volumio firststart.sh[965]: removed '/etc/ssh/ssh_host_ecdsa_key' Nov 24 21:19:57 volumio firststart.sh[965]: removed '/etc/ssh/ssh_host_ecdsa_key.pub' Nov 24 21:19:57 volumio firststart.sh[965]: removed '/etc/ssh/ssh_host_ed25519_key' Nov 24 21:19:57 volumio firststart.sh[965]: removed '/etc/ssh/ssh_host_ed25519_key.pub' Nov 24 21:19:57 volumio firststart.sh[965]: removed '/etc/ssh/ssh_host_rsa_key' Nov 24 21:19:57 volumio firststart.sh[965]: removed '/etc/ssh/ssh_host_rsa_key.pub' Nov 24 21:19:57 volumio firststart.sh[965]: Generating SSH host keys Nov 24 21:19:58 volumio systemd[1]: Started Samba NMB Daemon. Nov 24 21:19:58 volumio nmbd[766]: [2024/11/24 20:19:58.371329, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 24 21:19:58 volumio nmbd[766]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 24 21:19:58 volumio systemd[1]: Starting Samba Winbind Daemon... Nov 24 21:19:58 volumio systemd[1]: apt-daily.service: Succeeded. Nov 24 21:19:58 volumio systemd[1]: Started Daily apt download activities. Nov 24 21:19:58 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Nov 24 21:19:58 volumio ntpd[807]: Listen normally on 3 eth0 192.168.88.100:123 Nov 24 21:19:58 volumio ntpd[807]: Listen normally on 4 wlan0 192.168.211.1:123 Nov 24 21:19:58 volumio ntpd[807]: new interface(s) found: waking up resolver Nov 24 21:19:58 volumio winbindd[1030]: [2024/11/24 20:19:58.954054, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 24 21:19:58 volumio winbindd[1030]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 24 21:19:59 volumio winbindd[1030]: [2024/11/24 20:19:59.355621, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 24 21:19:59 volumio winbindd[1030]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 24 21:19:59 volumio systemd[1]: Started Samba Winbind Daemon. Nov 24 21:19:59 volumio systemd[1]: Starting Samba SMB Daemon... Nov 24 21:19:59 volumio firststart.sh[965]: debconf: unable to initialize frontend: Dialog Nov 24 21:19:59 volumio firststart.sh[965]: debconf: (TERM is not set, so the dialog frontend is not usable.) Nov 24 21:19:59 volumio firststart.sh[965]: debconf: falling back to frontend: Readline Nov 24 21:19:59 volumio firststart.sh[965]: debconf: unable to initialize frontend: Readline Nov 24 21:19:59 volumio firststart.sh[965]: debconf: (This frontend requires a controlling tty.) Nov 24 21:19:59 volumio firststart.sh[965]: debconf: falling back to frontend: Teletype Nov 24 21:19:59 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Nov 24 21:19:59 volumio systemd[1]: Started Daily apt upgrade and clean activities. Nov 24 21:20:01 volumio smbd[1087]: [2024/11/24 20:20:01.388345, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 24 21:20:01 volumio systemd[1]: Started Samba SMB Daemon. Nov 24 21:20:01 volumio smbd[1087]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 24 21:20:01 volumio systemd[1]: Reached target Multi-User System. Nov 24 21:20:01 volumio systemd[1]: Reached target Graphical Interface. Nov 24 21:20:01 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 24 21:20:01 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 24 21:20:01 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 24 21:20:01 volumio systemd[1]: Startup finished in 23.930s (kernel) + 21.312s (userspace) = 45.242s. Nov 24 21:20:02 volumio firststart.sh[965]: Creating SSH2 RSA key; this may take some time ... Nov 24 21:20:02 volumio firststart.sh[965]: 2048 SHA256:2t0GjoYX1O0WsesF8xurvxba9N1Vxl4LRIx1fyUrwMU root@volumio (RSA) Nov 24 21:20:02 volumio volumio[963]: info: ------------------------------------------- Nov 24 21:20:02 volumio volumio[963]: info: ----- Volumio3 ---- Nov 24 21:20:02 volumio volumio[963]: info: ------------------------------------------- Nov 24 21:20:02 volumio volumio[963]: info: ----- System startup ---- Nov 24 21:20:02 volumio volumio[963]: info: ------------------------------------------- Nov 24 21:20:03 volumio volumio-remote-updater[657]: [2024-11-24 20:20:03] [connect] Successful connection Nov 24 21:20:03 volumio volumio[963]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio Nov 24 21:20:03 volumio volumio[963]: info: MYVOLUMIO Environment detected Nov 24 21:20:03 volumio firststart.sh[965]: Creating SSH2 DSA key; this may take some time ... Nov 24 21:20:03 volumio firststart.sh[965]: 1024 SHA256:byqc/Mrix9F+mdxcH0YnzJbCWJY1406NfSi5MLHhEVQ root@volumio (DSA) Nov 24 21:20:03 volumio firststart.sh[965]: Creating SSH2 ECDSA key; this may take some time ... Nov 24 21:20:03 volumio volumio[963]: info: Plugin folders cleanup Nov 24 21:20:03 volumio volumio[963]: info: Scanning into folder /volumio/app/plugins/ Nov 24 21:20:03 volumio volumio[963]: info: Scanning category audio_interface Nov 24 21:20:03 volumio volumio[963]: info: Scanning category miscellanea Nov 24 21:20:03 volumio volumio[963]: info: Scanning category music_service Nov 24 21:20:03 volumio volumio[963]: info: Scanning category plugins.json Nov 24 21:20:03 volumio volumio[963]: info: Scanning category system_controller Nov 24 21:20:03 volumio firststart.sh[965]: 256 SHA256:Ri9wvm2PONxc7PV5MTrEztKk3XZ4RquhfTMqhyuRhGE root@volumio (ECDSA) Nov 24 21:20:03 volumio volumio[963]: info: Scanning category user_interface Nov 24 21:20:03 volumio volumio[963]: info: Scanning into folder /data/plugins/ Nov 24 21:20:03 volumio volumio[963]: info: Plugin folders cleanup completed Nov 24 21:20:03 volumio volumio[963]: info: ------------------------------------------- Nov 24 21:20:03 volumio volumio[963]: info: ----- Core plugins startup ---- Nov 24 21:20:03 volumio volumio[963]: info: ------------------------------------------- Nov 24 21:20:03 volumio volumio[963]: info: Loading plugins from folder /volumio/app/plugins/ Nov 24 21:20:03 volumio volumio[963]: info: Adding plugin upnp to MyMusic Plugins Nov 24 21:20:03 volumio volumio[963]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 24 21:20:03 volumio volumio[963]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 24 21:20:03 volumio volumio[963]: info: Loading plugins from folder /data/plugins/ Nov 24 21:20:03 volumio volumio[963]: info: Loading plugin "system"... Nov 24 21:20:03 volumio volumio[963]: info: No id defined. Creating one Nov 24 21:20:03 volumio volumio[963]: info: Loading plugin "appearance"... Nov 24 21:20:05 volumio systemd[1]: Reloading. Nov 24 21:20:05 volumio volumio[963]: info: Loading plugin "network"... Nov 24 21:20:05 volumio volumio[963]: info: Refreshing Cached IP Addresses Nov 24 21:20:06 volumio sudo[1270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 21:20:06 volumio sudo[1270]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:06 volumio sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 21:20:06 volumio sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:06 volumio sudo[1270]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:06 volumio sudo[1272]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:06 volumio volumio[963]: info: Loading plugin "services"... Nov 24 21:20:06 volumio volumio[963]: info: Loading plugin "alsa_controller"... Nov 24 21:20:06 volumio sudo[1281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 24 21:20:06 volumio sudo[1281]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:06 volumio volumio[963]: warn: The ALSA output card is not set, defaulting to card [object Object] Nov 24 21:20:06 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 24 21:20:06 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 24 21:20:06 volumio volumio[963]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out Nov 24 21:20:06 volumio volumio[963]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 24 21:20:06 volumio volumio[963]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 24 21:20:06 volumio volumio[963]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 24 21:20:06 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 24 21:20:06 volumio volumio[963]: info: Loading plugin "wizard"... Nov 24 21:20:06 volumio volumio[963]: info: Loading plugin "networkfs"... Nov 24 21:20:06 volumio volumio[963]: info: Starting Udev Watcher for removable devices Nov 24 21:20:06 volumio volumio[963]: info: Ignoring mount for partition: boot Nov 24 21:20:06 volumio volumio[963]: info: Ignoring mount for partition: volumio Nov 24 21:20:06 volumio volumio[963]: info: Ignoring mount for partition: volumio_data Nov 24 21:20:06 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 24 21:20:06 volumio volumio[963]: info: Loading plugin "volumio_command_line_client"... Nov 24 21:20:06 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 24 21:20:06 volumio volumio[963]: info: Loading plugin "upnp"... Nov 24 21:20:06 volumio volumio[963]: info: [1732479606549] Starting Upmpd Daemon Nov 24 21:20:06 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 24 21:20:06 volumio volumio[963]: info: Loading plugin "my_music"... Nov 24 21:20:06 volumio volumio[963]: info: Loading plugin "mpd"... Nov 24 21:20:06 volumio firststart.sh[965]: rescue-ssh.target is a disabled or a static unit, not starting it. Nov 24 21:20:07 volumio sudo[1281]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:07 volumio volumio[963]: info: Loading plugin "upnp_browser"... Nov 24 21:20:07 volumio firststart.sh[965]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Nov 24 21:20:07 volumio firststart.sh[965]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Nov 24 21:20:07 volumio systemd[1]: Reloading. Nov 24 21:20:07 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 24 21:20:07 volumio volumio[963]: info: Loading plugin "alarm-clock"... Nov 24 21:20:08 volumio volumio[963]: info: Loading plugin "airplay_emulation"... Nov 24 21:20:08 volumio volumio[963]: info: Starting Shairport Sync Nov 24 21:20:08 volumio volumio[963]: info: Loading plugin "last_100"... Nov 24 21:20:08 volumio volumio[963]: info: Loading plugin "webradio"... Nov 24 21:20:08 volumio volumio[963]: info: Loading plugin "i2s_dacs"... Nov 24 21:20:08 volumio volumio[963]: info: I2S DAC not set, start Auto-detection Nov 24 21:20:08 volumio volumio[963]: info: Loading plugin "volumiodiscovery"... Nov 24 21:20:08 volumio volumio[963]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 24 21:20:08 volumio volumio[963]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 24 21:20:08 volumio volumio[963]: *** WARNING *** For more information see Nov 24 21:20:08 volumio volumio[963]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 24 21:20:08 volumio volumio[963]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 24 21:20:08 volumio node[963]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 24 21:20:08 volumio volumio[963]: *** WARNING *** For more information see Nov 24 21:20:08 volumio node[963]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 24 21:20:08 volumio node[963]: *** WARNING *** For more information see Nov 24 21:20:08 volumio node[963]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 24 21:20:08 volumio node[963]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 24 21:20:08 volumio node[963]: *** WARNING *** For more information see Nov 24 21:20:08 volumio volumio[963]: info: Discovery: Started advertising with name: Volumio Nov 24 21:20:08 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 24 21:20:08 volumio volumio[963]: info: Loading plugin "outputs"... Nov 24 21:20:08 volumio volumio[963]: info: Loading plugin "albumart"... Nov 24 21:20:08 volumio volumio[963]: info: Plugin example_plugin is not enabled Nov 24 21:20:08 volumio volumio[963]: info: Loading plugin "inputs"... Nov 24 21:20:08 volumio volumio[963]: info: Loading plugin "updater_comm"... Nov 24 21:20:08 volumio firststart.sh[965]: Enabling SSH for first boot Nov 24 21:20:08 volumio volumio[963]: Forking 3 albumart workers Nov 24 21:20:08 volumio volumio[963]: info: Plugin mpdemulation is not enabled Nov 24 21:20:08 volumio volumio[963]: info: Loading plugin "rest_api"... Nov 24 21:20:08 volumio systemd[1]: Starting OpenBSD Secure Shell server... Nov 24 21:20:08 volumio volumio[963]: info: Loading plugin "websocket"... Nov 24 21:20:08 volumio volumio[963]: info: Starting Socket.io Server version 2.3.0 Nov 24 21:20:09 volumio volumio[963]: info: Loading i18n strings for locale en Nov 24 21:20:09 volumio volumio[963]: Updating browse sources language Nov 24 21:20:09 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 21:20:09 volumio sshd[1376]: Server listening on 0.0.0.0 port 22. Nov 24 21:20:09 volumio sshd[1376]: Server listening on :: port 22. Nov 24 21:20:09 volumio systemd[1]: Started OpenBSD Secure Shell server. Nov 24 21:20:09 volumio firststart.sh[965]: Disabling firststart service Nov 24 21:20:09 volumio firststart.sh[965]: Removed /etc/systemd/system/multi-user.target.wants/firststart.service. Nov 24 21:20:09 volumio systemd[1]: Reloading. Nov 24 21:20:09 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:20:09 volumio volumio[963]: info: CoreCommandRouter::initPlayerControls Nov 24 21:20:09 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 24 21:20:09 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:09 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:09 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:09 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:09 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:09 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:09 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:09 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:09 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:20:09 volumio volumio[963]: Express server listening on port 3000 Nov 24 21:20:09 volumio volumio[963]: [Metrics] WebUI: 8s 840.02ms Nov 24 21:20:09 volumio volumio[963]: info: CoreStateMachine::resetVolumioState Nov 24 21:20:09 volumio volumio[963]: info: CoreStateMachine::getcurrentVolume Nov 24 21:20:09 volumio volumio[963]: info: CoreCommandRouter::volumioRetrievevolume Nov 24 21:20:09 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:20:09 volumio volumio[963]: info: Cannot read play queue from file Nov 24 21:20:10 volumio volumio[963]: info: Volumio Network Manager: Network status updated: 1 Nov 24 21:20:10 volumio volumio[963]: verbose: New Socket.io Connection to 192.168.88.100 from 192.168.88.115 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 24 21:20:10 volumio volumio[963]: verbose: New Socket.io Connection to volumio.local from 192.168.88.115 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Nov 24 21:20:10 volumio firststart.sh[965]: Finalizing Nov 24 21:20:10 volumio volumio[963]: verbose: New Socket.io Connection to 192.168.88.100 from 192.168.88.115 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 24 21:20:10 volumio volumio[963]: verbose: New Socket.io Connection to 192.168.88.100 from 192.168.88.115 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 24 21:20:10 volumio volumio[963]: verbose: New Socket.io Connection to 192.168.88.100 from 192.168.88.115 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 24 21:20:10 volumio volumio[963]: info: VolumeController:: Volume=86 Mute =false Nov 24 21:20:10 volumio volumio[963]: info: CoreStateMachine::pushState Nov 24 21:20:10 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:10 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 21:20:10 volumio volumio[963]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:10 volumio volumio[963]: info: CoreStateMachine::updateTrackBlock Nov 24 21:20:10 volumio volumio[963]: info: CorePlayQueue::getTrackBlock Nov 24 21:20:10 volumio volumio[963]: info: CoreCommandRouter::volumioRetrievevolume Nov 24 21:20:10 volumio volumio[963]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 24 21:20:10 volumio volumio[963]: info: CoreStateMachine::getcurrentVolume Nov 24 21:20:10 volumio volumio[963]: info: CoreCommandRouter::volumioRetrievevolume Nov 24 21:20:10 volumio volumio[963]: Starting albumart workers Nov 24 21:20:10 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:10 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:10 volumio volumio[963]: Starting albumart workers Nov 24 21:20:10 volumio volumio[963]: info: CoreCommandRouter::volumioGetQueue Nov 24 21:20:10 volumio volumio[963]: info: CoreStateMachine::getQueue Nov 24 21:20:10 volumio volumio[963]: info: CorePlayQueue::getQueue Nov 24 21:20:10 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Nov 24 21:20:10 volumio volumio[963]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Nov 24 21:20:10 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Nov 24 21:20:10 volumio volumio[963]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Nov 24 21:20:10 volumio volumio[963]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Nov 24 21:20:10 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Nov 24 21:20:10 volumio volumio[963]: info: Setting Device type: Raspberry PI Nov 24 21:20:10 volumio volumio[963]: info: VolumeController:: Volume=86 Mute =false Nov 24 21:20:10 volumio volumio[963]: info: CoreStateMachine::pushState Nov 24 21:20:10 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:10 volumio volumio[963]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:10 volumio volumio[963]: info: VolumeController:: Volume=86 Mute =false Nov 24 21:20:10 volumio volumio[963]: info: CoreStateMachine::pushState Nov 24 21:20:10 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:10 volumio volumio[963]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:10 volumio volumio[963]: info: CoreStateMachine::updateTrackBlock Nov 24 21:20:10 volumio volumio[963]: info: CorePlayQueue::getTrackBlock Nov 24 21:20:10 volumio volumio[963]: info: CoreCommandRouter::volumioRetrievevolume Nov 24 21:20:10 volumio volumio[963]: Starting albumart workers Nov 24 21:20:10 volumio volumio[963]: info: Discovery: adding 277fd80f-0ca5-49ec-8700-4b5c59633139 Nov 24 21:20:10 volumio volumio[963]: info: Discovery: Found device Volumio Nov 24 21:20:10 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:10 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:10 volumio volumio[963]: info: Discovery: this is already registered, 277fd80f-0ca5-49ec-8700-4b5c59633139 Nov 24 21:20:10 volumio volumio[963]: info: Discovery: Found device Volumio Nov 24 21:20:10 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:10 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:10 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:11 volumio volumio[963]: info: VolumeController:: Volume=86 Mute =false Nov 24 21:20:11 volumio volumio[963]: info: CoreStateMachine::pushState Nov 24 21:20:11 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:11 volumio volumio[963]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:12 volumio systemd[1]: firststart.service: Succeeded. Nov 24 21:20:12 volumio volumio[963]: verbose: New Socket.io Connection to 192.168.88.100 from 192.168.88.115 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 24 21:20:12 volumio volumio[963]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 24 21:20:12 volumio volumio[963]: info: Received Get System Version Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 24 21:20:12 volumio volumio[963]: info: Received Get System Info Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 21:20:12 volumio volumio[963]: info: Discovery: Getting this device information Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:12 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::volumioGetVisibleSources Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:12 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 24 21:20:12 volumio volumio[963]: info: Received Get System Info Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 21:20:12 volumio volumio[963]: info: Discovery: Getting this device information Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:12 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:12 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:12 volumio volumio[963]: info: Listing playlists Nov 24 21:20:12 volumio volumio[963]: info: Completed loading Core Plugins Nov 24 21:20:12 volumio volumio[963]: info: Preparing to generate the ALSA configuration file Nov 24 21:20:12 volumio volumio[963]: info: Asound.conf file written Nov 24 21:20:12 volumio sudo[1437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 24 21:20:12 volumio sudo[1437]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:12 volumio sudo[1437]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:12 volumio volumio[963]: No state is present for card b1 Nov 24 21:20:12 volumio volumio[963]: /usr/share/alsa/init/bcm2835:14: control element not found Nov 24 21:20:12 volumio volumio[963]: No state is present for card b1 Nov 24 21:20:12 volumio volumio[963]: No state is present for card Headphones Nov 24 21:20:12 volumio volumio[963]: /usr/share/alsa/init/bcm2835:20: control element not found Nov 24 21:20:12 volumio volumio[963]: No state is present for card Headphones Nov 24 21:20:12 volumio volumio[963]: info: Output device has changed, restarting MPD Nov 24 21:20:12 volumio volumio[963]: info: Output device has changed, restarting Shairport Sync Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:20:12 volumio sudo[1442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 21:20:12 volumio sudo[1442]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:12 volumio sudo[1444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 21:20:12 volumio sudo[1444]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:12 volumio systemd[1]: Stopping Music Player Daemon... Nov 24 21:20:12 volumio volumio[963]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 21:20:12 volumio volumio[963]: info: ___________ START PLUGINS ___________ Nov 24 21:20:12 volumio volumio[963]: info: Setting Hotspot Unique name on first start: Volumio-60E93 Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Nov 24 21:20:12 volumio volumio[963]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::Reload Ui Nov 24 21:20:12 volumio volumio[963]: info: ControllerMpd::onStart: Initializing MPD Nov 24 21:20:12 volumio volumio[963]: info: Creating MPD Configuration file Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 24 21:20:12 volumio volumio[963]: info: [1732479612695] CoreMusicLibrary::Adding element Media Servers Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:20:12 volumio sudo[1442]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:12 volumio sudo[1453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 21:20:12 volumio sudo[1451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 21:20:12 volumio sudo[1451]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:12 volumio sudo[1453]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:12 volumio sudo[1451]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:12 volumio volumio[963]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:20:12 volumio volumio[963]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 24 21:20:12 volumio volumio[963]: info: [1732479612870] CoreMusicLibrary::Adding element Last_100 Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 24 21:20:12 volumio volumio[963]: info: [1732479612874] CoreMusicLibrary::Adding element Webradio Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 24 21:20:12 volumio volumio[963]: info: Initializing BBC Radios Nov 24 21:20:12 volumio systemd[1]: mpd.service: Succeeded. Nov 24 21:20:12 volumio systemd[1]: Stopped Music Player Daemon. Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 24 21:20:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:20:12 volumio volumio[963]: info: Volumio Calling Home Nov 24 21:20:13 volumio systemd[1]: Starting Music Player Daemon... Nov 24 21:20:13 volumio sudo[1468]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 24 21:20:13 volumio sudo[1468]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:13 volumio sudo[1468]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:13 volumio volumio[963]: info: MPD Permissions set Nov 24 21:20:13 volumio volumio[963]: info: MPD Permissions set Nov 24 21:20:13 volumio volumio[963]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 24 21:20:13 volumio volumio[963]: info: Volumio called home Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:13 volumio volumio[963]: info: Starting Shairport Sync Nov 24 21:20:13 volumio volumio[963]: info: Starting Shairport Sync Nov 24 21:20:13 volumio sudo[1487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 21:20:13 volumio sudo[1487]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:13 volumio volumio[963]: info: Starting Shairport Sync Nov 24 21:20:13 volumio sudo[1489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 21:20:13 volumio sudo[1489]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 24 21:20:13 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 24 21:20:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 24 21:20:13 volumio sudo[1492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 21:20:13 volumio sudo[1492]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:13 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:13 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 24 21:20:13 volumio sudo[1487]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:13 volumio shairport-sync[1494]: free(): double free detected in tcache 2 Nov 24 21:20:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 24 21:20:13 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=6/ABRT Nov 24 21:20:13 volumio systemd[1]: shairport-sync.service: Failed with result 'signal'. Nov 24 21:20:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 24 21:20:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 24 21:20:13 volumio sudo[1497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Nov 24 21:20:13 volumio sudo[1497]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:13 volumio sudo[1492]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:13 volumio sudo[1489]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:13 volumio sudo[1497]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:13 volumio volumio[963]: info: Shairport-Sync Started Nov 24 21:20:13 volumio volumio[963]: Error adding Membership: Error: addMembership EINVAL Nov 24 21:20:13 volumio volumio[963]: info: Shairport-Sync Started Nov 24 21:20:13 volumio volumio[963]: info: Shairport-Sync Started Nov 24 21:20:13 volumio volumio[963]: info: Permissions for /etc/hostapd/hostapd.conf Nov 24 21:20:14 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Nov 24 21:20:14 volumio volumio[963]: info: Discovery: Restarting Advertising due to device name change Nov 24 21:20:14 volumio sudo[1503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Nov 24 21:20:14 volumio sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:14 volumio sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 21:20:14 volumio sudo[1508]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:14 volumio sudo[1508]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:14 volumio sudo[1510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 21:20:14 volumio sudo[1510]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:14 volumio systemd[1]: Starting Wireless Services... Nov 24 21:20:14 volumio sudo[1510]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:14 volumio volumio[963]: verbose: New Socket.io Connection to 192.168.88.100 from 192.168.88.115 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Nov 24 21:20:14 volumio volumio[963]: info: CoreCommandRouter::volumioGetVisibleSources Nov 24 21:20:14 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 21:20:14 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Nov 24 21:20:14 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Nov 24 21:20:14 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Nov 24 21:20:14 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Nov 24 21:20:14 volumio volumio[963]: info: Received Get System Info Nov 24 21:20:14 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 21:20:14 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 21:20:14 volumio volumio[963]: info: Discovery: Getting this device information Nov 24 21:20:14 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:14 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:14 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 21:20:14 volumio volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 21:20:14 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 24 21:20:14 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 24 21:20:14 volumio wireless.js[1506]: Cleaning previous... Nov 24 21:20:14 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Nov 24 21:20:15 volumio volumio[963]: info: Discovery: A device disappeared from network Nov 24 21:20:15 volumio volumio[963]: info: Discovery: Device volumio disappeared from network Nov 24 21:20:15 volumio volumio[963]: info: Discovery: A device disappeared from network Nov 24 21:20:15 volumio mpd[1477]: Nov 24 20:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 24 21:20:15 volumio systemd[1]: Started Music Player Daemon. Nov 24 21:20:15 volumio sudo[1444]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:15 volumio sudo[1453]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:15 volumio volumio[963]: info: Completed starting Core Plugins Nov 24 21:20:15 volumio volumio[963]: info: ------------------------------------------- Nov 24 21:20:15 volumio volumio[963]: info: ----- MyVolumio plugins startup ---- Nov 24 21:20:15 volumio volumio[963]: info: ------------------------------------------- Nov 24 21:20:15 volumio volumio[963]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 24 21:20:15 volumio volumio[963]: info: MPD running with PID1477 Nov 24 21:20:15 volumio volumio[963]: ,establishing connection Nov 24 21:20:15 volumio volumio[963]: error: MPD error: The expression evaluated to a falsy value: Nov 24 21:20:15 volumio volumio[963]: assert.ok(self.idling) Nov 24 21:20:15 volumio volumio[963]: error: The expression evaluated to a falsy value: Nov 24 21:20:15 volumio volumio[963]: assert.ok(self.idling) Nov 24 21:20:15 volumio volumio[963]: error: MPD error: The expression evaluated to a falsy value: Nov 24 21:20:15 volumio volumio[963]: assert.ok(self.idling) Nov 24 21:20:15 volumio volumio[963]: error: The expression evaluated to a falsy value: Nov 24 21:20:15 volumio volumio[963]: assert.ok(self.idling) Nov 24 21:20:15 volumio volumio[963]: error: updateQueue error: null Nov 24 21:20:15 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Nov 24 21:20:15 volumio volumio[963]: info: Loading i18n strings for locale en Nov 24 21:20:15 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Nov 24 21:20:15 volumio volumio[963]: info: Setting timezone to Europe/Ljubljana Nov 24 21:20:15 volumio systemd[1]: hostapd.service: Succeeded. Nov 24 21:20:15 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Nov 24 21:20:15 volumio sudo[1530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Nov 24 21:20:15 volumio sudo[1530]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:15 volumio sudo[1530]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:15 volumio sudo[1535]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 24 21:20:15 volumio sudo[1534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Ljubljana /etc/localtime Nov 24 21:20:15 volumio sudo[1534]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:15 volumio sudo[1535]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:15 volumio avahi-daemon[622]: Withdrawing address record for 192.168.211.1 on wlan0. Nov 24 21:20:15 volumio avahi-daemon[622]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Nov 24 21:20:15 volumio sudo[1534]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:15 volumio sudo[1535]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:15 volumio avahi-daemon[622]: Interface wlan0.IPv4 no longer relevant for mDNS. Nov 24 21:20:15 volumio sudo[1538]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 24 21:20:15 volumio sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:15 volumio sudo[1540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Nov 24 21:20:15 volumio sudo[1540]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:15 volumio sudo[1540]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:16 volumio sudo[1538]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:16 volumio sudo[1544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Ljubljana Nov 24 21:20:16 volumio sudo[1544]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:16 volumio sudo[1544]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:16 volumio volumio[963]: Terminated Nov 24 21:20:16 volumio volumio[963]: info: Could not set timezone, retrying Nov 24 21:20:16 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 21:20:16 volumio volumio[963]: Updating browse sources language Nov 24 21:20:16 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 21:20:16 volumio wireless.js[1506]: Stopped aP Nov 24 21:20:16 volumio systemd[1]: systemd-fsckd.service: Succeeded. Nov 24 21:20:16 volumio sudo[1551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 24 21:20:16 volumio sudo[1551]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:16 volumio sudo[1551]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:16 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 24 21:20:16 volumio sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Nov 24 21:20:16 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:16 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:16 volumio sudo[1574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 24 21:20:16 volumio sudo[1574]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:16 volumio sudo[1574]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:16 volumio sudo[1576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Nov 24 21:20:16 volumio sudo[1576]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:16 volumio sudo[1581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 21:20:16 volumio sudo[1581]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:16 volumio sudo[1581]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:16 volumio sudo[1583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 21:20:16 volumio sudo[1583]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:16 volumio sudo[1583]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:16 volumio sudo[1587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 24 21:20:16 volumio sudo[1587]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:16 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Nov 24 21:20:16 volumio sudo[1587]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:16 volumio volumio[963]: info: Upmpdcli Daemon Started Nov 24 21:20:16 volumio sudo[1576]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:16 volumio wireless.js[1506]: SETTING APPROPRIATE REG DOMAIN: SI Nov 24 21:20:16 volumio sudo[1609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 24 21:20:16 volumio sudo[1609]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:16 volumio sudo[1609]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:16 volumio volumio[1589]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 24 21:20:16 volumio sudo[1611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set SI Nov 24 21:20:16 volumio sudo[1611]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:16 volumio sudo[1611]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:16 volumio wireless.js[1506]: SUCCESSFULLY SET NEW REGDOMAIN: SI Nov 24 21:20:16 volumio sudo[1618]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 24 21:20:16 volumio sudo[1618]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:16 volumio sudo[1618]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:17 volumio sudo[1620]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 24 21:20:17 volumio sudo[1620]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:17 volumio sudo[1620]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:17 volumio sudo[1623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Ljubljana Nov 24 21:20:17 volumio sudo[1623]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:17 volumio avahi-daemon[622]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Nov 24 21:20:17 volumio avahi-daemon[622]: New relevant interface wlan0.IPv4 for mDNS. Nov 24 21:20:17 volumio avahi-daemon[622]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Nov 24 21:20:17 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 24 21:20:17 volumio systemd-timedated[949]: Failed to set time zone: Permission denied Nov 24 21:20:17 volumio sudo[1623]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:17 volumio volumio[963]: Failed to set time zone: Access denied Nov 24 21:20:17 volumio volumio[963]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 21:20:17 volumio volumio[963]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Europe/Ljubljana' Nov 24 21:20:17 volumio volumio[963]: Failed to set time zone: Access denied Nov 24 21:20:17 volumio volumio[963]: at checkExecSyncError (child_process.js:616:11) Nov 24 21:20:17 volumio volumio[963]: at execSync (child_process.js:652:15) Nov 24 21:20:17 volumio volumio[963]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9) Nov 24 21:20:17 volumio volumio[963]: at listOnTimeout (internal/timers.js:554:17) Nov 24 21:20:17 volumio volumio[963]: at processTimers (internal/timers.js:497:7) { Nov 24 21:20:17 volumio volumio[963]: status: 1, Nov 24 21:20:17 volumio volumio[963]: signal: null, Nov 24 21:20:17 volumio volumio[963]: output: [ null, '', 'Failed to set time zone: Access denied\n' ], Nov 24 21:20:17 volumio volumio[963]: pid: 1622, Nov 24 21:20:17 volumio volumio[963]: stdout: '', Nov 24 21:20:17 volumio volumio[963]: stderr: 'Failed to set time zone: Access denied\n' Nov 24 21:20:17 volumio volumio[963]: } Nov 24 21:20:17 volumio volumio[963]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 21:20:17 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Nov 24 21:20:17 volumio hostapd[1629]: Configuration file: /etc/hostapd/hostapd.conf Nov 24 21:20:17 volumio hostapd[1629]: wlan0: Could not connect to kernel driver Nov 24 21:20:17 volumio hostapd[1629]: Using interface wlan0 with hwaddr b8:27:eb:7c:ef:1b and ssid "Volumio-60E93" Nov 24 21:20:17 volumio hostapd[1629]: wlan0: interface state UNINITIALIZED->ENABLED Nov 24 21:20:17 volumio hostapd[1629]: wlan0: AP-ENABLED Nov 24 21:20:17 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Nov 24 21:20:17 volumio iwconfig[1640]: Error for wireless request "Set Power Management" (8B2C) : Nov 24 21:20:17 volumio iwconfig[1640]: SET failed on device wlan0 ; Invalid argument. Nov 24 21:20:17 volumio systemd[1]: wireless.service: Succeeded. Nov 24 21:20:17 volumio systemd[1]: Started Wireless Services. Nov 24 21:20:17 volumio sudo[1503]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:18 volumio volumio-remote-updater[657]: [2024-11-24 20:20:18] [connect] Successful connection Nov 24 21:20:18 volumio sudo[1642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-24 21:19 Nov 24 21:20:18 volumio sudo[1642]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"