-- Logs begin at Thu 2019-02-14 11:12:03 CET, end at Fri 2024-04-12 11:05:55 CEST. --
Apr 12 11:04:00 volumio wireless.js[285]: SUCCESSFULLY SET NEW REGDOMAIN: DE
Apr 12 11:04:00 volumio wireless.js[285]: Start wireless flow
Apr 12 11:04:00 volumio wireless.js[285]: Stopped hotspot (if there)..
Apr 12 11:04:00 volumio bthelper[525]: Changing power off succeeded
Apr 12 11:04:00 volumio sudo[623]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Apr 12 11:04:00 volumio sudo[623]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:00 volumio sudo[623]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:00 volumio sudo[625]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Apr 12 11:04:00 volumio sudo[625]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:00 volumio bthelper[525]: [CHG] Controller B8:27:EB:04:2C:B8 Class: 0x0000041c
Apr 12 11:04:00 volumio bthelper[525]: Changing power on succeeded
Apr 12 11:04:00 volumio bthelper[525]: [CHG] Controller B8:27:EB:04:2C:B8 Powered: yes
Apr 12 11:04:00 volumio sudo[625]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:00 volumio wireless.js[285]: DHCP IP
Apr 12 11:04:01 volumio wireless.js[285]: Start ap
Apr 12 11:04:01 volumio wpa_supplicant[637]: Successfully initialized wpa_supplicant
Apr 12 11:04:01 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Apr 12 11:04:01 volumio sudo[638]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd
Apr 12 11:04:01 volumio sudo[638]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:01 volumio dhcpcd[639]: dev: loaded udev
Apr 12 11:04:01 volumio kernel: 8021q: 802.1Q VLAN Support v1.8
Apr 12 11:04:01 volumio dhcpcd[639]: wlan0: connected to Access Point `'
Apr 12 11:04:01 volumio dhcpcd[639]: no interfaces have a carrier
Apr 12 11:04:01 volumio dhcpcd[663]: wlan0: waiting for carrier
Apr 12 11:04:01 volumio dhcpcd[639]: forked to background, child pid 663
Apr 12 11:04:01 volumio sudo[638]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:02 volumio wireless.js[285]: trying...
Apr 12 11:04:02 volumio wpa_supplicant[643]: wlan0: Trying to associate with SSID 'Zuhause'
Apr 12 11:04:02 volumio sudo[665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 12 11:04:02 volumio sudo[665]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:02 volumio sudo[665]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:02 volumio wpa_supplicant[643]: wlan0: Associated with 72:a7:41:9a:ce:92
Apr 12 11:04:02 volumio wpa_supplicant[643]: wlan0: CTRL-EVENT-CONNECTED - Connection to 72:a7:41:9a:ce:92 completed [id=0 id_str=]
Apr 12 11:04:02 volumio dhcpcd[663]: wlan0: carrier acquired
Apr 12 11:04:02 volumio dhcpcd[663]: wlan0: connected to Access Point `Zuhause'
Apr 12 11:04:02 volumio wpa_supplicant[643]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Apr 12 11:04:02 volumio wpa_supplicant[643]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE
Apr 12 11:04:02 volumio dhcpcd[663]: DUID 00:01:00:01:2d:69:1e:54:b8:27:eb:fb:d3:47
Apr 12 11:04:02 volumio dhcpcd[663]: wlan0: IAID eb:fb:d3:47
Apr 12 11:04:02 volumio dhcpcd[663]: wlan0: adding address fe80::5184:c678:a0a4:71d2
Apr 12 11:04:02 volumio dhcpcd[663]: ipv6_addaddr1: Permission denied
Apr 12 11:04:03 volumio wireless.js[285]: trying...
Apr 12 11:04:03 volumio sudo[669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 12 11:04:03 volumio sudo[669]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:03 volumio sudo[669]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:03 volumio dhcpcd[663]: wlan0: soliciting an IPv6 router
Apr 12 11:04:03 volumio dhcpcd[663]: wlan0: soliciting a DHCP lease
Apr 12 11:04:03 volumio dhcpcd[663]: wlan0: offered 192.168.178.131 from 192.168.178.1
Apr 12 11:04:03 volumio dhcpcd[663]: wlan0: probing address 192.168.178.131/24
Apr 12 11:04:04 volumio wireless.js[285]: trying...
Apr 12 11:04:04 volumio sudo[672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 12 11:04:04 volumio sudo[672]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:04 volumio sudo[672]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:05 volumio wireless.js[285]: trying...
Apr 12 11:04:05 volumio sudo[675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 12 11:04:05 volumio sudo[675]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:05 volumio sudo[675]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:06 volumio wireless.js[285]: trying...
Apr 12 11:04:06 volumio sudo[678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 12 11:04:06 volumio sudo[678]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:06 volumio sudo[678]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:07 volumio wireless.js[285]: trying...
Apr 12 11:04:07 volumio sudo[681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 12 11:04:07 volumio sudo[681]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:07 volumio sudo[681]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:08 volumio wireless.js[285]: trying...
Apr 12 11:04:08 volumio sudo[684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 12 11:04:08 volumio sudo[684]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:08 volumio sudo[684]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:08 volumio volumio-remote-updater[293]: [2024-04-12 11:04:08] [info] asio async_connect error: system:111 (Connection refused)
Apr 12 11:04:08 volumio volumio-remote-updater[293]: [2024-04-12 11:04:08] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Apr 12 11:04:08 volumio volumio-remote-updater[293]: [2024-04-12 11:04:08] [error] handle_connect error: Underlying Transport Error
Apr 12 11:04:08 volumio dhcpcd[663]: wlan0: leased 192.168.178.131 for 86400 seconds
Apr 12 11:04:08 volumio avahi-daemon[309]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.131.
Apr 12 11:04:08 volumio avahi-daemon[309]: New relevant interface wlan0.IPv4 for mDNS.
Apr 12 11:04:08 volumio avahi-daemon[309]: Registering new address record for 192.168.178.131 on wlan0.IPv4.
Apr 12 11:04:08 volumio dhcpcd[663]: wlan0: adding route to 192.168.178.0/24
Apr 12 11:04:08 volumio dhcpcd[663]: wlan0: adding default route via 192.168.178.1
Apr 12 11:04:09 volumio wireless.js[285]: trying...
Apr 12 11:04:09 volumio sudo[708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 12 11:04:09 volumio sudo[708]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:09 volumio sudo[708]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:09 volumio wireless.js[285]: Connected to: ----Zuhause
Apr 12 11:04:09 volumio wireless.js[285]: ----
Apr 12 11:04:09 volumio sudo[711]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 12 11:04:09 volumio sudo[711]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:09 volumio sudo[711]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:09 volumio wireless.js[285]: ... joined AP, wlan0 IPv4 is 192.168.178.131, ipV6 is undefined
Apr 12 11:04:09 volumio wireless.js[285]: It's done! AP
Apr 12 11:04:09 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Apr 12 11:04:09 volumio systemd[1]: Started Wireless Services.
Apr 12 11:04:09 volumio systemd[1]: Started Volumio Backend Module.
Apr 12 11:04:09 volumio systemd[1]: Started Volumio Cpu Tweaker.
Apr 12 11:04:09 volumio volumio-cpu-tweak[717]: Setting RT Priority for mpd
Apr 12 11:04:09 volumio volumio-cpu-tweak[717]: pid 35's current scheduling policy: SCHED_OTHER
Apr 12 11:04:09 volumio volumio-cpu-tweak[717]: pid 35's current scheduling priority: 0
Apr 12 11:04:09 volumio volumio-cpu-tweak[717]: Not enough cores to set MPD affinity
Apr 12 11:04:09 volumio volumio-cpu-tweak[717]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input
Apr 12 11:04:09 volumio volumio-cpu-tweak[717]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance
Apr 12 11:04:09 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded.
Apr 12 11:04:10 volumio ntpd[461]: Listen normally on 3 wlan0 192.168.178.131:123
Apr 12 11:04:10 volumio ntpd[461]: new interface(s) found: waking up resolver
Apr 12 11:04:13 volumio systemd[1]: Started Samba NMB Daemon.
Apr 12 11:04:13 volumio nmbd[459]: [2024/04/12 11:04:13.409207, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Apr 12 11:04:13 volumio nmbd[459]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Apr 12 11:04:13 volumio systemd[1]: Starting Samba Winbind Daemon...
Apr 12 11:04:13 volumio nmbd[459]: [2024/04/12 11:04:13.524426, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Apr 12 11:04:13 volumio nmbd[459]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.131 for name WORKGROUP<1d>.
Apr 12 11:04:13 volumio nmbd[459]: This response was from IP 192.168.178.20, reporting an IP address of 192.168.178.20.
Apr 12 11:04:15 volumio winbindd[732]: [2024/04/12 11:04:15.934911, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache)
Apr 12 11:04:15 volumio winbindd[732]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Apr 12 11:04:16 volumio systemd[1]: Started Samba Winbind Daemon.
Apr 12 11:04:16 volumio winbindd[732]: [2024/04/12 11:04:16.031080, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Apr 12 11:04:16 volumio winbindd[732]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
Apr 12 11:04:16 volumio systemd[1]: Starting Samba SMB Daemon...
Apr 12 11:04:18 volumio volumio-remote-updater[293]: [2024-04-12 11:04:18] [info] asio async_connect error: system:111 (Connection refused)
Apr 12 11:04:18 volumio volumio-remote-updater[293]: [2024-04-12 11:04:18] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Apr 12 11:04:18 volumio volumio-remote-updater[293]: [2024-04-12 11:04:18] [error] handle_connect error: Underlying Transport Error
Apr 12 11:04:19 volumio systemd[1]: Started Samba SMB Daemon.
Apr 12 11:04:19 volumio systemd[1]: Reached target Multi-User System.
Apr 12 11:04:19 volumio smbd[737]: [2024/04/12 11:04:19.762798, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Apr 12 11:04:19 volumio smbd[737]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Apr 12 11:04:19 volumio systemd[1]: Reached target Graphical Interface.
Apr 12 11:04:19 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
Apr 12 11:04:20 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Apr 12 11:04:20 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
Apr 12 11:04:20 volumio systemd[1]: Startup finished in 13.625s (kernel) + 1min 10.386s (userspace) = 1min 24.012s.
Apr 12 11:04:22 volumio volumio[716]: info: -------------------------------------------
Apr 12 11:04:22 volumio volumio[716]: info: ----- Volumio3 ----
Apr 12 11:04:22 volumio volumio[716]: info: -------------------------------------------
Apr 12 11:04:22 volumio volumio[716]: info: ----- System startup ----
Apr 12 11:04:22 volumio volumio[716]: info: -------------------------------------------
Apr 12 11:04:26 volumio volumio[716]: info: MYVOLUMIO Environment detected
Apr 12 11:04:26 volumio volumio[716]: info: Plugin folders cleanup
Apr 12 11:04:26 volumio volumio[716]: info: Scanning into folder /volumio/app/plugins/
Apr 12 11:04:26 volumio volumio[716]: info: Scanning category audio_interface
Apr 12 11:04:26 volumio volumio[716]: info: Scanning category miscellanea
Apr 12 11:04:26 volumio volumio[716]: info: Scanning category music_service
Apr 12 11:04:26 volumio volumio[716]: info: Scanning category plugins.json
Apr 12 11:04:26 volumio volumio[716]: info: Scanning category system_controller
Apr 12 11:04:26 volumio volumio[716]: info: Scanning category user_interface
Apr 12 11:04:26 volumio volumio[716]: info: Scanning into folder /data/plugins/
Apr 12 11:04:26 volumio volumio[716]: info: Plugin folders cleanup completed
Apr 12 11:04:26 volumio volumio[716]: info: -------------------------------------------
Apr 12 11:04:26 volumio volumio[716]: info: ----- Core plugins startup ----
Apr 12 11:04:26 volumio volumio[716]: info: -------------------------------------------
Apr 12 11:04:26 volumio volumio[716]: info: Loading plugins from folder /volumio/app/plugins/
Apr 12 11:04:26 volumio volumio[716]: info: Adding plugin upnp to MyMusic Plugins
Apr 12 11:04:26 volumio volumio[716]: info: Adding plugin airplay_emulation to MyMusic Plugins
Apr 12 11:04:26 volumio volumio[716]: info: Adding plugin upnp_browser to MyMusic Plugins
Apr 12 11:04:26 volumio volumio[716]: info: Loading plugins from folder /data/plugins/
Apr 12 11:04:26 volumio volumio[716]: info: Loading plugin "system"...
Apr 12 11:04:27 volumio volumio[716]: info: Loading plugin "appearance"...
Apr 12 11:04:27 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
Apr 12 11:04:28 volumio volumio-remote-updater[293]: [2024-04-12 11:04:28] [connect] Successful connection
Apr 12 11:04:32 volumio volumio[716]: info: Loading plugin "network"...
Apr 12 11:04:32 volumio volumio[716]: info: Refreshing Cached IP Addresses
Apr 12 11:04:32 volumio volumio[716]: info: Loading plugin "services"...
Apr 12 11:04:32 volumio sudo[762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 12 11:04:32 volumio sudo[762]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:32 volumio volumio[716]: info: Loading plugin "alsa_controller"...
Apr 12 11:04:32 volumio sudo[764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 12 11:04:32 volumio sudo[764]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:32 volumio sudo[762]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:32 volumio sudo[764]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:32 volumio sudo[766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Apr 12 11:04:32 volumio sudo[766]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:33 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 12 11:04:33 volumio volumio[716]: info: Loading plugin "wizard"...
Apr 12 11:04:33 volumio volumio[716]: info: Loading plugin "networkfs"...
Apr 12 11:04:33 volumio volumio[716]: info: Starting Udev Watcher for removable devices
Apr 12 11:04:33 volumio sudo[775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Ronald,password=Franziska1!#,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.110/Musik /mnt/NAS/Musik
Apr 12 11:04:33 volumio sudo[775]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:33 volumio volumio[716]: info: Ignoring mount for partition: boot
Apr 12 11:04:33 volumio volumio[716]: info: Ignoring mount for partition: volumio
Apr 12 11:04:33 volumio volumio[716]: info: Ignoring mount for partition: volumio_data
Apr 12 11:04:33 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 12 11:04:33 volumio volumio[716]: info: Loading plugin "volumio_command_line_client"...
Apr 12 11:04:33 volumio sudo[766]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:33 volumio volumio[716]: info: Loading plugin "upnp"...
Apr 12 11:04:33 volumio volumio[716]: info: [1712912673976] Starting Upmpd Daemon
Apr 12 11:04:33 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 12 11:04:33 volumio volumio[716]: info: Loading plugin "my_music"...
Apr 12 11:04:34 volumio volumio[716]: info: Loading plugin "mpd"...
Apr 12 11:04:34 volumio kernel: Key type cifs.spnego registered
Apr 12 11:04:34 volumio kernel: Key type cifs.idmap registered
Apr 12 11:04:34 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount.
Apr 12 11:04:34 volumio kernel: CIFS: Attempting to mount \\192.168.178.110\Musik
Apr 12 11:04:35 volumio sudo[775]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:36 volumio volumio[716]: info: Loading plugin "upnp_browser"...
Apr 12 11:04:38 volumio volumio[716]: info: Loading plugin "alarm-clock"...
Apr 12 11:04:39 volumio volumio[716]: info: Loading plugin "airplay_emulation"...
Apr 12 11:04:39 volumio volumio[716]: info: Starting Shairport Sync
Apr 12 11:04:39 volumio volumio[716]: info: Loading plugin "last_100"...
Apr 12 11:04:39 volumio volumio[716]: info: Loading plugin "webradio"...
Apr 12 11:04:40 volumio volumio[716]: info: Loading plugin "i2s_dacs"...
Apr 12 11:04:40 volumio volumio[716]: info: Loading plugin "volumiodiscovery"...
Apr 12 11:04:40 volumio volumio[716]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 12 11:04:40 volumio node[716]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 12 11:04:40 volumio volumio[716]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 12 11:04:40 volumio node[716]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 12 11:04:40 volumio volumio[716]: *** WARNING *** For more information see
Apr 12 11:04:40 volumio node[716]: *** WARNING *** For more information see
Apr 12 11:04:40 volumio volumio[716]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 12 11:04:40 volumio volumio[716]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 12 11:04:40 volumio volumio[716]: *** WARNING *** For more information see
Apr 12 11:04:40 volumio node[716]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 12 11:04:40 volumio node[716]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 12 11:04:40 volumio node[716]: *** WARNING *** For more information see
Apr 12 11:04:40 volumio volumio[716]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 12 11:04:40 volumio volumio[716]: info: Discovery: Started advertising with name: Volumio
Apr 12 11:04:40 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 12 11:04:40 volumio volumio[716]: info: Loading plugin "outputs"...
Apr 12 11:04:40 volumio volumio[716]: info: Loading plugin "albumart"...
Apr 12 11:04:40 volumio volumio[716]: info: Plugin example_plugin is not enabled
Apr 12 11:04:40 volumio volumio[716]: info: Loading plugin "inputs"...
Apr 12 11:04:40 volumio volumio[716]: info: Loading plugin "updater_comm"...
Apr 12 11:04:42 volumio volumio[716]: info: Plugin mpdemulation is not enabled
Apr 12 11:04:42 volumio volumio[716]: info: Loading plugin "rest_api"...
Apr 12 11:04:42 volumio volumio[716]: info: Loading plugin "websocket"...
Apr 12 11:04:42 volumio volumio[716]: info: Loading i18n strings for locale de
Apr 12 11:04:42 volumio volumio[716]: Updating browse sources language
Apr 12 11:04:42 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 12 11:04:43 volumio volumio-remote-updater[293]: [2024-04-12 11:04:43] [connect] Successful connection
Apr 12 11:04:43 volumio volumio[716]: Forking 1 albumart workers
Apr 12 11:04:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 11:04:45 volumio volumio[716]: info: CoreCommandRouter::initPlayerControls
Apr 12 11:04:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:04:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:04:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:04:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:04:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:04:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:04:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:04:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:04:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 11:04:45 volumio volumio[716]: Express server listening on port 3000
Apr 12 11:04:45 volumio volumio[716]: [Metrics] WebUI: 26s 544.25ms
Apr 12 11:04:45 volumio volumio[716]: info: CoreStateMachine::resetVolumioState
Apr 12 11:04:45 volumio volumio[716]: info: CoreStateMachine::getcurrentVolume
Apr 12 11:04:45 volumio volumio[716]: info: CoreCommandRouter::volumioRetrievevolume
Apr 12 11:04:45 volumio volumio[716]: info: CoreStateMachine::pushState
Apr 12 11:04:45 volumio volumio[716]: info: CorePlayQueue::getTrack 0
Apr 12 11:04:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 12 11:04:45 volumio volumio[716]: info: CoreCommandRouter::volumioPushState
Apr 12 11:04:45 volumio sudo[856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 12 11:04:45 volumio sudo[856]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:46 volumio sudo[858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 12 11:04:46 volumio sudo[858]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:46 volumio sudo[856]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:46 volumio sudo[858]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:47 volumio volumio[716]: verbose: New Socket.io Connection to 192.168.178.131 from 192.168.178.138 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 1
Apr 12 11:04:47 volumio volumio[716]: verbose: New Socket.io Connection to 192.168.178.131 from 192.168.178.138 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 2
Apr 12 11:04:47 volumio volumio[716]: verbose: New Socket.io Connection to 192.168.178.131 from 192.168.178.138 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 3
Apr 12 11:04:47 volumio volumio[716]: verbose: New Socket.io Connection to 192.168.178.131 from 192.168.178.138 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 4
Apr 12 11:04:47 volumio volumio-remote-updater[293]: [2024-04-12 11:04:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712912683 101
Apr 12 11:04:47 volumio volumio[716]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 5
Apr 12 11:04:47 volumio volumio[716]: verbose: New Socket.io Connection to 192.168.178.131 from 192.168.178.138 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 6
Apr 12 11:04:48 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 12 11:04:48 volumio volumio[716]: info: Reloading queue from file
Apr 12 11:04:48 volumio volumio[716]: info: CoreStateMachine::setRepeat true single undefined
Apr 12 11:04:48 volumio volumio[716]: info: CoreStateMachine::pushState
Apr 12 11:04:48 volumio volumio[716]: info: CorePlayQueue::getTrack 0
Apr 12 11:04:48 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 12 11:04:48 volumio volumio[716]: info: CoreCommandRouter::volumioPushState
Apr 12 11:04:48 volumio volumio[716]: info: CoreStateMachine::setRandom null
Apr 12 11:04:48 volumio volumio[716]: info: CoreStateMachine::pushState
Apr 12 11:04:48 volumio volumio[716]: info: CorePlayQueue::getTrack 0
Apr 12 11:04:48 volumio volumio[716]: info: CoreCommandRouter::volumioPushState
Apr 12 11:04:48 volumio volumio[716]: info: Setting Device type: Raspberry PI
Apr 12 11:04:49 volumio sudo[869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Apr 12 11:04:49 volumio volumio[716]: info: Discovery: adding 5237f6d9-96be-45e1-a774-5473eefcf987
Apr 12 11:04:49 volumio volumio[716]: info: Discovery: Found device Volumio
Apr 12 11:04:49 volumio volumio[716]: info: CoreCommandRouter::volumioGetState
Apr 12 11:04:49 volumio sudo[869]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:49 volumio volumio[716]: info: CorePlayQueue::getTrack 0
Apr 12 11:04:49 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Apr 12 11:04:49 volumio sudo[869]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:49 volumio volumio[716]: info: Upmpdcli Daemon Started
Apr 12 11:04:49 volumio volumio[716]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 12 11:04:50 volumio sudo[873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 12 11:04:50 volumio sudo[873]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:50 volumio sudo[875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 12 11:04:50 volumio sudo[873]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:50 volumio sudo[875]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:04:50 volumio sudo[875]: pam_unix(sudo:session): session closed for user root
Apr 12 11:04:50 volumio volumio[716]: verbose: New Socket.io Connection to 192.168.178.131 from 192.168.178.138 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 7
Apr 12 11:04:50 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Apr 12 11:04:50 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 12 11:04:50 volumio volumio[716]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 12 11:04:50 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 12 11:04:51 volumio volumio[716]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 12 11:04:51 volumio volumio[716]: info: CoreCommandRouter::volumioGetState
Apr 12 11:04:51 volumio volumio[716]: info: CorePlayQueue::getTrack 0
Apr 12 11:04:51 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 12 11:04:51 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 12 11:04:51 volumio volumio[716]: info: Received Get System Info
Apr 12 11:04:51 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 12 11:04:51 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 12 11:04:51 volumio volumio[716]: info: Discovery: Getting this device information
Apr 12 11:04:51 volumio volumio[716]: info: CoreCommandRouter::volumioGetState
Apr 12 11:04:51 volumio volumio[716]: info: CorePlayQueue::getTrack 0
Apr 12 11:04:51 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 12 11:04:51 volumio volumio[716]: info: CoreCommandRouter::volumioGetState
Apr 12 11:04:51 volumio volumio[716]: info: CorePlayQueue::getTrack 0
Apr 12 11:04:51 volumio volumio[716]: info: Listing playlists
Apr 12 11:04:51 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Apr 12 11:04:51 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 12 11:04:51 volumio volumio[716]: Starting albumart workers
Apr 12 11:04:52 volumio ntpd[461]: Soliciting pool server 193.203.3.171
Apr 12 11:04:52 volumio ntpd[461]: Soliciting pool server 195.201.137.97
Apr 12 11:04:52 volumio ntpd[461]: Soliciting pool server 193.203.3.170
Apr 12 11:04:52 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Apr 12 11:04:52 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 12 11:04:52 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 12 11:04:52 volumio volumio[716]: info: Getting Alsa Cards List without I2S DAC
Apr 12 11:04:52 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber
Apr 12 11:04:52 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 12 11:04:53 volumio ntpd[461]: Soliciting pool server 194.59.207.235
Apr 12 11:04:53 volumio ntpd[461]: Soliciting pool server 185.248.189.10
Apr 12 11:04:53 volumio ntpd[461]: Soliciting pool server 173.249.33.207
Apr 12 11:04:53 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 11:04:53 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 11:04:53 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 11:04:53 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 11:04:53 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 11:04:53 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 11:04:53 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 11:04:53 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Apr 12 11:04:53 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Apr 12 11:04:54 volumio ntpd[461]: Soliciting pool server 185.232.69.65
Apr 12 11:04:54 volumio ntpd[461]: Soliciting pool server 162.159.200.123
Apr 12 11:04:54 volumio ntpd[461]: Soliciting pool server 88.218.226.91
Apr 12 11:04:54 volumio volumio[716]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 12 11:04:54 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 12 11:04:54 volumio volumio[716]: info: Received Get System Info
Apr 12 11:04:54 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 12 11:04:54 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 12 11:04:54 volumio volumio[716]: info: Discovery: Getting this device information
Apr 12 11:04:54 volumio volumio[716]: info: CoreCommandRouter::volumioGetState
Apr 12 11:04:54 volumio volumio[716]: info: CorePlayQueue::getTrack 0
Apr 12 11:04:54 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 12 11:04:54 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 12 11:04:54 volumio volumio[716]: info: Received Get System Info
Apr 12 11:04:54 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 12 11:04:54 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 12 11:04:54 volumio volumio[716]: info: Discovery: Getting this device information
Apr 12 11:04:54 volumio volumio[716]: info: CoreCommandRouter::volumioGetState
Apr 12 11:04:54 volumio volumio[716]: info: CorePlayQueue::getTrack 0
Apr 12 11:04:54 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 12 11:04:54 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Apr 12 11:04:55 volumio ntpd[461]: Soliciting pool server 128.127.67.142
Apr 12 11:04:55 volumio ntpd[461]: Soliciting pool server 178.215.228.24
Apr 12 11:04:55 volumio ntpd[461]: Soliciting pool server 75.119.140.230
Apr 12 11:04:55 volumio ntpd[461]: Soliciting pool server 129.70.132.33
Apr 12 11:04:55 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Apr 12 11:04:56 volumio ntpd[461]: Soliciting pool server 80.153.195.191
Apr 12 11:04:56 volumio ntpd[461]: Soliciting pool server 2001:418:3ff::1:53
Apr 12 11:04:57 volumio ntpd[461]: Soliciting pool server 88.198.226.248
Apr 12 11:04:58 volumio ntpd[461]: Soliciting pool server 62.225.132.250
Apr 12 11:05:25 volumio ntpd[461]: receive: Unexpected origin timestamp 0xe9c377bb.01fa687c does not match aorg 0000000000.00000000 from server@185.232.69.65 xmt 0xe9c377d5.37ea0e26
Apr 12 11:05:25 volumio ntpd[461]: receive: Unexpected origin timestamp 0xe9c377bb.0205b035 does not match aorg 0000000000.00000000 from server@194.59.207.235 xmt 0xe9c377d5.37e854f1
Apr 12 11:05:25 volumio ntpd[461]: receive: Unexpected origin timestamp 0xe9c377bb.0216af10 does not match aorg 0000000000.00000000 from server@193.203.3.171 xmt 0xe9c377d5.37f58fed
Apr 12 11:05:25 volumio ntpd[461]: receive: Unexpected origin timestamp 0xe9c377bb.020b89df does not match aorg 0000000000.00000000 from server@193.203.3.170 xmt 0xe9c377d5.37ff026d
Apr 12 11:05:25 volumio ntpd[461]: receive: Unexpected origin timestamp 0xe9c377bb.01d7d1a0 does not match aorg 0000000000.00000000 from server@80.153.195.191 xmt 0xe9c377d5.384e764a
Apr 12 11:05:25 volumio ntpd[461]: receive: Unexpected origin timestamp 0xe9c377bb.01e2c440 does not match aorg 0000000000.00000000 from server@129.70.132.33 xmt 0xe9c377d5.3866be3f
Apr 12 11:05:25 volumio ntpd[461]: receive: Unexpected origin timestamp 0xe9c377bb.02001ad6 does not match aorg 0000000000.00000000 from server@185.248.189.10 xmt 0xe9c377d5.38c3e7dd
Apr 12 11:05:25 volumio ntpd[461]: receive: Unexpected origin timestamp 0xe9c377bb.01ebf367 does not match aorg 0000000000.00000000 from server@75.119.140.230 xmt 0xe9c377d5.38788262
Apr 12 11:05:25 volumio volumio[716]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 12 11:05:28 volumio volumio[716]: info: CoreCommandRouter::volumioGetState
Apr 12 11:05:28 volumio volumio[716]: info: CorePlayQueue::getTrack 0
Apr 12 11:05:28 volumio volumio[716]: info: CoreCommandRouter::volumioPlay
Apr 12 11:05:28 volumio volumio[716]: info: CoreStateMachine::play index undefined
Apr 12 11:05:28 volumio volumio[716]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 12 11:05:28 volumio volumio[716]: info: CorePlayQueue::getTrack 0
Apr 12 11:05:28 volumio volumio[716]: info: CoreStateMachine::startPlaybackTimer
Apr 12 11:05:28 volumio volumio[716]: info: CorePlayQueue::getTrack 0
Apr 12 11:05:28 volumio volumio[716]: verbose: ControllerMpd::clearAddPlayTracks NAS/Musik/Two Steps from Hell (2022)/Live - An Epic Music Experience/06 Fire Nation (Live).mp3
Apr 12 11:05:28 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand stop
Apr 12 11:05:34 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 12 11:05:34 volumio volumio[716]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Apr 12 11:05:34 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Apr 12 11:05:34 volumio volumio[716]: info: Received Get System Version
Apr 12 11:05:34 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 12 11:05:34 volumio volumio[716]: info: Received Get System Info
Apr 12 11:05:34 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 12 11:05:34 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 12 11:05:34 volumio volumio[716]: info: Discovery: Getting this device information
Apr 12 11:05:34 volumio volumio[716]: info: CoreCommandRouter::volumioGetState
Apr 12 11:05:34 volumio volumio[716]: info: CorePlayQueue::getTrack 0
Apr 12 11:05:34 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 12 11:05:35 volumio volumio[716]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 12 11:05:37 volumio volumio[716]: info: CoreCommandRouter::volumioPlay
Apr 12 11:05:37 volumio volumio[716]: info: CoreStateMachine::play index undefined
Apr 12 11:05:37 volumio volumio[716]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 12 11:05:37 volumio volumio[716]: info: CorePlayQueue::getTrack 0
Apr 12 11:05:37 volumio volumio[716]: info: CoreStateMachine::startPlaybackTimer
Apr 12 11:05:37 volumio volumio[716]: info: CorePlayQueue::getTrack 0
Apr 12 11:05:37 volumio volumio[716]: verbose: ControllerMpd::clearAddPlayTracks NAS/Musik/Two Steps from Hell (2022)/Live - An Epic Music Experience/06 Fire Nation (Live).mp3
Apr 12 11:05:37 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand stop
Apr 12 11:05:38 volumio volumio[716]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion
Apr 12 11:05:38 volumio volumio[716]: info: Preparing to generate the ALSA configuration file
Apr 12 11:05:39 volumio volumio[716]: info: Asound.conf file unchanged, so no further update is needed
Apr 12 11:05:39 volumio volumio[716]: info: Output device has changed, restarting MPD
Apr 12 11:05:39 volumio volumio[716]: info: Output device has changed, restarting Shairport Sync
Apr 12 11:05:39 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:39 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 12 11:05:39 volumio sudo[897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 12 11:05:39 volumio sudo[897]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:05:39 volumio sudo[897]: pam_unix(sudo:session): session closed for user root
Apr 12 11:05:39 volumio sudo[899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 12 11:05:39 volumio sudo[899]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:05:39 volumio volumio[716]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 12 11:05:39 volumio volumio[716]: info: ___________ START PLUGINS ___________
Apr 12 11:05:39 volumio systemd[1]: Listening on mpd.socket.
Apr 12 11:05:39 volumio systemd[1]: Starting Music Player Daemon...
Apr 12 11:05:39 volumio volumio[716]: info: ControllerMpd::onStart: Initializing MPD
Apr 12 11:05:39 volumio volumio[716]: info: Creating MPD Configuration file
Apr 12 11:05:39 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 11:05:39 volumio volumio[716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 12 11:05:39 volumio volumio[716]: info: [1712912739927] CoreMusicLibrary::Adding element Medienserver
Apr 12 11:05:39 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 12 11:05:40 volumio sudo[908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 12 11:05:40 volumio sudo[908]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:05:40 volumio sudo[908]: pam_unix(sudo:session): session closed for user root
Apr 12 11:05:40 volumio sudo[910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 12 11:05:40 volumio sudo[910]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:05:40 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:40 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Apr 12 11:05:40 volumio systemd[1]: mpd.service: Succeeded.
Apr 12 11:05:40 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 12 11:05:40 volumio systemd[1]: Stopped Music Player Daemon.
Apr 12 11:05:40 volumio systemd[1]: Starting Music Player Daemon...
Apr 12 11:05:40 volumio volumio[716]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 12 11:05:40 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:40 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 12 11:05:41 volumio volumio[716]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 12 11:05:41 volumio volumio[716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 12 11:05:41 volumio volumio[716]: info: [1712912741184] CoreMusicLibrary::Adding element Last_100
Apr 12 11:05:41 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 12 11:05:41 volumio volumio[716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 12 11:05:41 volumio volumio[716]: info: [1712912741220] CoreMusicLibrary::Adding element Webradio
Apr 12 11:05:41 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 12 11:05:41 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 12 11:05:41 volumio volumio[716]: info: Volumio Calling Home
Apr 12 11:05:43 volumio volumio[716]: info: MPD Permissions set
Apr 12 11:05:43 volumio volumio[716]: info: MPD Permissions set
Apr 12 11:05:43 volumio volumio[716]: info: Volumio called home
Apr 12 11:05:43 volumio volumio[716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8
Apr 12 11:05:43 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 12 11:05:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 11:05:47 volumio volumio[716]: info: Starting Shairport Sync
Apr 12 11:05:47 volumio volumio[716]: info: Starting Shairport Sync
Apr 12 11:05:47 volumio volumio[716]: info: Starting Shairport Sync
Apr 12 11:05:47 volumio volumio[716]: info: CoreCommandRouter::volumioGetQueue
Apr 12 11:05:47 volumio volumio[716]: info: CoreStateMachine::getQueue
Apr 12 11:05:47 volumio volumio[716]: info: CorePlayQueue::getQueue
Apr 12 11:05:47 volumio sudo[932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 12 11:05:47 volumio sudo[934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 12 11:05:47 volumio sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:05:47 volumio sudo[934]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:05:47 volumio sudo[936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 12 11:05:48 volumio sudo[936]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 11:05:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 12 11:05:48 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 12 11:05:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 12 11:05:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 12 11:05:48 volumio sudo[932]: pam_unix(sudo:session): session closed for user root
Apr 12 11:05:48 volumio sudo[934]: pam_unix(sudo:session): session closed for user root
Apr 12 11:05:48 volumio sudo[936]: pam_unix(sudo:session): session closed for user root
Apr 12 11:05:49 volumio volumio[716]: info: Shairport-Sync Started
Apr 12 11:05:50 volumio volumio[716]: Error adding Membership: Error: addMembership EINVAL
Apr 12 11:05:50 volumio volumio[716]: info: Shairport-Sync Started
Apr 12 11:05:50 volumio volumio[716]: info: Shairport-Sync Started
Apr 12 11:05:50 volumio volumio[716]: info: Preload queue cleared
Apr 12 11:05:50 volumio volumio[716]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 12 11:05:50 volumio volumio[716]: info: CoreStateMachine::ClearQueue
Apr 12 11:05:50 volumio volumio[716]: info: CoreStateMachine::stop
Apr 12 11:05:50 volumio volumio[716]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 12 11:05:50 volumio volumio[716]: info: CorePlayQueue::clearPlayQueue
Apr 12 11:05:50 volumio volumio[716]: info: CorePlayQueue::saveQueue
Apr 12 11:05:50 volumio volumio[716]: info: CoreCommandRouter::volumioPushQueue
Apr 12 11:05:50 volumio volumio[716]: info: CoreStateMachine::addQueueItems
Apr 12 11:05:50 volumio volumio[716]: info: CorePlayQueue::addQueueItems
Apr 12 11:05:50 volumio volumio[716]: info: Preload queue cleared
Apr 12 11:05:50 volumio volumio[716]: info: Adding Item to queue: music-library/NAS/Musik/Two Steps from Hell (2022)/Live - An Epic Music Experience/06 Fire Nation (Live).mp3
Apr 12 11:05:50 volumio volumio[716]: info: Exploding uri music-library/NAS/Musik/Two Steps from Hell (2022)/Live - An Epic Music Experience/06 Fire Nation (Live).mp3 in service mpd
Apr 12 11:05:50 volumio volumio[716]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 12 11:05:50 volumio volumio[716]: TypeError: Cannot read property 'then' of undefined
Apr 12 11:05:50 volumio volumio[716]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19)
Apr 12 11:05:50 volumio volumio[716]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21)
Apr 12 11:05:50 volumio volumio[716]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16)
Apr 12 11:05:50 volumio volumio[716]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24)
Apr 12 11:05:50 volumio volumio[716]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19)
Apr 12 11:05:50 volumio volumio[716]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30)
Apr 12 11:05:50 volumio volumio[716]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25)
Apr 12 11:05:50 volumio volumio[716]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:501:23)
Apr 12 11:05:50 volumio volumio[716]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17)
Apr 12 11:05:50 volumio volumio[716]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33)
Apr 12 11:05:50 volumio volumio[716]: at Socket.emit (events.js:315:20)
Apr 12 11:05:50 volumio volumio[716]: at /volumio/node_modules/socket.io/lib/socket.js:503:12
Apr 12 11:05:50 volumio volumio[716]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
Apr 12 11:05:50 volumio volumio[716]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 12 11:05:54 volumio mpd[914]: Apr 12 11:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 12 11:05:55 volumio systemd[1]: Started Music Player Daemon.
Apr 12 11:05:55 volumio sudo[899]: pam_unix(sudo:session): session closed for user root
Apr 12 11:05:55 volumio sudo[910]: pam_unix(sudo:session): session closed for user root
Apr 12 11:05:55 volumio sudo[954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-12 11:04
Apr 12 11:05:55 volumio sudo[954]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET"
VOLUMIO_VERSION="3.631"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"