-- Logs begin at Thu 2019-02-14 12:11:59 EET, end at Sat 2025-11-01 13:09:05 EET. -- Nov 01 13:08:10 volumio volumio[992]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Nov 01 13:08:10 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Nov 01 13:08:11 volumio sudo[20356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Nov 01 13:08:11 volumio sudo[20356]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:11 volumio sudo[20356]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:11 volumio volumio[992]: info: Permissions for /etc/hostapd/hostapd.conf Nov 01 13:08:11 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Nov 01 13:08:11 volumio volumio[992]: info: Discovery: Restarting Advertising due to device name change Nov 01 13:08:11 volumio sudo[20359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Nov 01 13:08:11 volumio sudo[20359]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:11 volumio systemd[1]: wireless.service: Killing process 19683 (wpa_supplicant) with signal SIGKILL. Nov 01 13:08:11 volumio systemd[1]: wireless.service: Killing process 19708 (dhcpcd) with signal SIGKILL. Nov 01 13:08:11 volumio systemd[1]: Stopping Wireless Services... Nov 01 13:08:11 volumio systemd[1]: wireless.service: Succeeded. Nov 01 13:08:11 volumio systemd[1]: Stopped Wireless Services. Nov 01 13:08:11 volumio systemd[1]: Starting Wireless Services... Nov 01 13:08:12 volumio wireless.js[20361]: Cleaning previous... Nov 01 13:08:12 volumio sudo[20375]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 01 13:08:12 volumio sudo[20375]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:12 volumio avahi-daemon[592]: Withdrawing address record for 192.168.178.55 on wlan0. Nov 01 13:08:12 volumio avahi-daemon[592]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.55. Nov 01 13:08:12 volumio avahi-daemon[592]: Interface wlan0.IPv4 no longer relevant for mDNS. Nov 01 13:08:12 volumio sudo[20375]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:12 volumio volumio[992]: info: Discovery: A device disappeared from network Nov 01 13:08:12 volumio volumio[992]: info: Discovery: Device volumio disappeared from network Nov 01 13:08:12 volumio sudo[20378]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 01 13:08:12 volumio sudo[20378]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:12 volumio sudo[20378]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:12 volumio wireless.js[20361]: Stopped aP Nov 01 13:08:12 volumio sudo[20385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 01 13:08:12 volumio sudo[20385]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:12 volumio sudo[20385]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:12 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 01 13:08:12 volumio sudo[20387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Nov 01 13:08:12 volumio sudo[20387]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:12 volumio sudo[20387]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:12 volumio sudo[20394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 01 13:08:12 volumio sudo[20394]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:12 volumio sudo[20394]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:12 volumio sudo[20397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Nov 01 13:08:12 volumio sudo[20397]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:13 volumio sudo[20397]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:13 volumio wireless.js[20361]: SETTING APPROPRIATE REG DOMAIN: GR Nov 01 13:08:13 volumio sudo[20402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 01 13:08:13 volumio sudo[20402]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:13 volumio sudo[20402]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:13 volumio sudo[20404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set GR Nov 01 13:08:13 volumio sudo[20404]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:13 volumio sudo[20404]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:13 volumio wireless.js[20361]: SUCCESSFULLY SET NEW REGDOMAIN: GR Nov 01 13:08:13 volumio wireless.js[20361]: Start wireless flow Nov 01 13:08:13 volumio wireless.js[20361]: Stopped hotspot (if there).. Nov 01 13:08:13 volumio sudo[20409]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 01 13:08:13 volumio sudo[20409]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:13 volumio sudo[20409]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:13 volumio sudo[20411]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 01 13:08:13 volumio sudo[20411]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:14 volumio sudo[20411]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:14 volumio wireless.js[20361]: DHCP IP Nov 01 13:08:14 volumio wireless.js[20361]: Start ap Nov 01 13:08:14 volumio wpa_supplicant[20413]: Successfully initialized wpa_supplicant Nov 01 13:08:14 volumio sudo[20414]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Nov 01 13:08:14 volumio sudo[20414]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:14 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 01 13:08:14 volumio dhcpcd[20415]: main: control_open: Connection refused Nov 01 13:08:14 volumio dhcpcd[20415]: dev: loaded udev Nov 01 13:08:14 volumio dhcpcd-run-hooks[20445]: wlan0: starting wpa_supplicant Nov 01 13:08:14 volumio dhcpcd-run-hooks[20450]: wlan0: failed to start wpa_supplicant Nov 01 13:08:14 volumio dhcpcd-run-hooks[20451]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Nov 01 13:08:14 volumio dhcpcd[20415]: wlan0: connected to Access Point `' Nov 01 13:08:14 volumio dhcpcd[20415]: no interfaces have a carrier Nov 01 13:08:14 volumio dhcpcd[20415]: forked to background, child pid 20474 Nov 01 13:08:14 volumio dhcpcd[20474]: eth0: waiting for carrier Nov 01 13:08:14 volumio dhcpcd[20474]: wlan0: waiting for carrier Nov 01 13:08:14 volumio sudo[20414]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:14 volumio wpa_supplicant[20449]: wlan0: Trying to associate with SSID 'FRITZ!Box' Nov 01 13:08:15 volumio wireless.js[20361]: trying... Nov 01 13:08:15 volumio sudo[20476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 01 13:08:15 volumio sudo[20476]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:15 volumio sudo[20476]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:16 volumio ntpd[980]: Deleting interface #5 wlan0, 192.168.178.55#123, interface stats: received=58, sent=59, dropped=0, active_time=196 secs Nov 01 13:08:16 volumio ntpd[980]: 192.168.178.1 local addr 192.168.178.55 -> Nov 01 13:08:16 volumio ntpd[980]: 195.167.30.249 local addr 192.168.178.55 -> Nov 01 13:08:16 volumio ntpd[980]: 147.52.190.11 local addr 192.168.178.55 -> Nov 01 13:08:16 volumio ntpd[980]: 83.212.108.245 local addr 192.168.178.55 -> Nov 01 13:08:16 volumio ntpd[980]: 162.159.200.123 local addr 192.168.178.55 -> Nov 01 13:08:16 volumio ntpd[980]: 194.177.210.54 local addr 192.168.178.55 -> Nov 01 13:08:16 volumio ntpd[980]: 83.212.109.72 local addr 192.168.178.55 -> Nov 01 13:08:16 volumio ntpd[980]: 162.159.200.1 local addr 192.168.178.55 -> Nov 01 13:08:16 volumio ntpd[980]: 91.217.155.60 local addr 192.168.178.55 -> Nov 01 13:08:16 volumio ntpd[980]: 44.32.63.52 local addr 192.168.178.55 -> Nov 01 13:08:16 volumio volumio[992]: info: Discovery: Started advertising with name: Volumio Nov 01 13:08:16 volumio wireless.js[20361]: trying... Nov 01 13:08:16 volumio sudo[20480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 01 13:08:16 volumio sudo[20480]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:16 volumio sudo[20480]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:17 volumio wireless.js[20361]: trying... Nov 01 13:08:17 volumio sudo[20483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 01 13:08:17 volumio sudo[20483]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:17 volumio sudo[20483]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:17 volumio volumio[992]: info: Volumio Network Manager: Network status updated: 0 Nov 01 13:08:17 volumio dhcpcd[20474]: wlan0: carrier acquired Nov 01 13:08:17 volumio dhcpcd[20474]: wlan0: connected to Access Point `FRITZ!Box' Nov 01 13:08:17 volumio wpa_supplicant[20449]: wlan0: Associated with b0:f2:08:7d:83:e6 Nov 01 13:08:17 volumio wpa_supplicant[20449]: wlan0: CTRL-EVENT-CONNECTED - Connection to b0:f2:08:7d:83:e6 completed [id=0 id_str=] Nov 01 13:08:17 volumio wpa_supplicant[20449]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 01 13:08:17 volumio wpa_supplicant[20449]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=GR Nov 01 13:08:17 volumio dhcpcd[20474]: DUID 00:01:00:01:30:17:4e:d6:b8:27:eb:db:1d:a9 Nov 01 13:08:17 volumio dhcpcd[20474]: wlan0: IAID eb:8e:48:fc Nov 01 13:08:17 volumio dhcpcd[20474]: wlan0: adding address fe80::ba27:ebff:fe8e:48fc Nov 01 13:08:17 volumio dhcpcd[20474]: ipv6_addaddr1: Permission denied Nov 01 13:08:17 volumio dhcpcd[20474]: wlan0: carrier lost Nov 01 13:08:17 volumio dhcpcd[20474]: wlan0: carrier acquired Nov 01 13:08:17 volumio dhcpcd[20474]: wlan0: IAID eb:8e:48:fc Nov 01 13:08:18 volumio wireless.js[20361]: trying... Nov 01 13:08:18 volumio sudo[20524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 01 13:08:18 volumio sudo[20524]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:18 volumio sudo[20524]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:18 volumio dhcpcd[20474]: wlan0: soliciting an IPv6 router Nov 01 13:08:18 volumio dhcpcd[20474]: wlan0: rebinding lease of 192.168.178.55 Nov 01 13:08:19 volumio wireless.js[20361]: trying... Nov 01 13:08:19 volumio sudo[20527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 01 13:08:19 volumio sudo[20527]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:19 volumio sudo[20527]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:20 volumio wireless.js[20361]: trying... Nov 01 13:08:20 volumio sudo[20530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 01 13:08:20 volumio sudo[20530]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:20 volumio sudo[20530]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:21 volumio wireless.js[20361]: trying... Nov 01 13:08:21 volumio sudo[20533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 01 13:08:21 volumio sudo[20533]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:21 volumio sudo[20533]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:22 volumio dhcpcd[20474]: wlan0: carrier lost Nov 01 13:08:22 volumio wpa_supplicant[20449]: wlan0: Associated with e0:28:6d:72:0f:00 Nov 01 13:08:22 volumio wpa_supplicant[20449]: wlan0: CTRL-EVENT-CONNECTED - Connection to e0:28:6d:72:0f:00 completed [id=0 id_str=] Nov 01 13:08:22 volumio wpa_supplicant[20449]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 01 13:08:22 volumio wireless.js[20361]: trying... Nov 01 13:08:22 volumio sudo[20558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 01 13:08:22 volumio sudo[20558]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:22 volumio sudo[20558]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:22 volumio ntpd[980]: ntpd exiting on signal 15 (Terminated) Nov 01 13:08:22 volumio systemd[1]: Stopping Network Time Service... Nov 01 13:08:22 volumio systemd[1]: ntp.service: Succeeded. Nov 01 13:08:22 volumio systemd[1]: Stopped Network Time Service. Nov 01 13:08:22 volumio systemd[1]: Starting Network Time Service... Nov 01 13:08:22 volumio ntpd[20581]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 01 13:08:22 volumio ntpd[20581]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Nov 01 13:08:22 volumio systemd[1]: Started Network Time Service. Nov 01 13:08:22 volumio dhcpcd[20474]: wlan0: carrier acquired Nov 01 13:08:22 volumio ntpd[20587]: proto: precision = 0.729 usec (-20) Nov 01 13:08:22 volumio ntpd[20587]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 01 13:08:22 volumio ntpd[20587]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 01 13:08:22 volumio ntpd[20587]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 309 days ago Nov 01 13:08:22 volumio ntpd[20587]: Listen and drop on 0 v6wildcard [::]:123 Nov 01 13:08:22 volumio ntpd[20587]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 01 13:08:22 volumio ntpd[20587]: Listen normally on 2 lo 127.0.0.1:123 Nov 01 13:08:22 volumio ntpd[20587]: Listening on routing socket on fd #19 for interface updates Nov 01 13:08:22 volumio ntpd[20587]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Nov 01 13:08:22 volumio ntpd[20587]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Nov 01 13:08:22 volumio dhcpcd[20474]: wlan0: IAID eb:8e:48:fc Nov 01 13:08:22 volumio dhcpcd[20474]: wlan0: rebinding lease of 192.168.178.55 Nov 01 13:08:22 volumio dhcpcd[20474]: wlan0: probing address 192.168.178.55/24 Nov 01 13:08:22 volumio dhcpcd[20474]: wlan0: soliciting an IPv6 router Nov 01 13:08:23 volumio wireless.js[20361]: trying... Nov 01 13:08:23 volumio sudo[20591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 01 13:08:23 volumio sudo[20591]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:23 volumio sudo[20591]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:23 volumio ntpd[20587]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 01 13:08:24 volumio wireless.js[20361]: trying... Nov 01 13:08:24 volumio sudo[20594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 01 13:08:24 volumio sudo[20594]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:24 volumio sudo[20594]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:24 volumio ntpd[20587]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 01 13:08:25 volumio wireless.js[20361]: trying... Nov 01 13:08:25 volumio sudo[20600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 01 13:08:25 volumio sudo[20600]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:25 volumio sudo[20600]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:25 volumio volumio[992]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 13:08:25 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 01 13:08:25 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 13:08:25 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 01 13:08:25 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 13:08:25 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 13:08:25 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 13:08:25 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 01 13:08:25 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 01 13:08:25 volumio ntpd[20587]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 01 13:08:26 volumio wireless.js[20361]: trying... Nov 01 13:08:26 volumio sudo[20607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 01 13:08:26 volumio sudo[20607]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:26 volumio sudo[20607]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:26 volumio ntpd[20587]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 01 13:08:27 volumio wireless.js[20361]: trying... Nov 01 13:08:27 volumio sudo[20610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 01 13:08:27 volumio sudo[20610]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:27 volumio sudo[20610]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:28 volumio dhcpcd[20474]: wlan0: leased 192.168.178.55 for 864000 seconds Nov 01 13:08:28 volumio avahi-daemon[592]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.55. Nov 01 13:08:28 volumio avahi-daemon[592]: New relevant interface wlan0.IPv4 for mDNS. Nov 01 13:08:28 volumio dhcpcd[20474]: wlan0: adding route to 192.168.178.0/24 Nov 01 13:08:28 volumio avahi-daemon[592]: Registering new address record for 192.168.178.55 on wlan0.IPv4. Nov 01 13:08:28 volumio dhcpcd[20474]: wlan0: adding default route via 192.168.178.1 Nov 01 13:08:28 volumio wireless.js[20361]: trying... Nov 01 13:08:28 volumio sudo[20633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 01 13:08:28 volumio sudo[20633]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:28 volumio sudo[20633]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:28 volumio wireless.js[20361]: Connected to: ----FRITZ!Box Nov 01 13:08:28 volumio wireless.js[20361]: ---- Nov 01 13:08:28 volumio sudo[20646]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 13:08:28 volumio sudo[20646]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:28 volumio sudo[20646]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:28 volumio wireless.js[20361]: ... joined AP, wlan0 IPv4 is 192.168.178.55, ipV6 is undefined Nov 01 13:08:28 volumio wireless.js[20361]: It's done! AP Nov 01 13:08:28 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 01 13:08:28 volumio systemd[1]: Started Wireless Services. Nov 01 13:08:28 volumio sudo[20359]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:28 volumio ntpd[20587]: ntpd exiting on signal 15 (Terminated) Nov 01 13:08:28 volumio systemd[1]: Stopping Network Time Service... Nov 01 13:08:28 volumio systemd[1]: ntp.service: Succeeded. Nov 01 13:08:28 volumio systemd[1]: Stopped Network Time Service. Nov 01 13:08:28 volumio systemd[1]: Starting Network Time Service... Nov 01 13:08:28 volumio ntpd[20669]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 01 13:08:28 volumio ntpd[20669]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Nov 01 13:08:28 volumio systemd[1]: Started Network Time Service. Nov 01 13:08:28 volumio ntpd[20675]: proto: precision = 0.677 usec (-20) Nov 01 13:08:28 volumio ntpd[20675]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 01 13:08:28 volumio ntpd[20675]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 01 13:08:28 volumio ntpd[20675]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 309 days ago Nov 01 13:08:28 volumio ntpd[20675]: Listen and drop on 0 v6wildcard [::]:123 Nov 01 13:08:28 volumio ntpd[20675]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 01 13:08:28 volumio ntpd[20675]: Listen normally on 2 lo 127.0.0.1:123 Nov 01 13:08:28 volumio ntpd[20675]: Listen normally on 3 wlan0 192.168.178.55:123 Nov 01 13:08:28 volumio ntpd[20675]: Listening on routing socket on fd #20 for interface updates Nov 01 13:08:28 volumio ntpd[20675]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Nov 01 13:08:28 volumio ntpd[20675]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Nov 01 13:08:28 volumio volumio[992]: info: CoreCommandRouter::volumioPlay Nov 01 13:08:28 volumio volumio[992]: info: CoreStateMachine::play index undefined Nov 01 13:08:28 volumio volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 13:08:28 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Nov 01 13:08:28 volumio volumio[992]: info: CoreStateMachine::startPlaybackTimer Nov 01 13:08:28 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Nov 01 13:08:28 volumio volumio[992]: info: [1761995308587] ControllerUPNPBrowser::resume Nov 01 13:08:28 volumio volumio[992]: verbose: ControllerMpd::sendMpdCommand play Nov 01 13:08:28 volumio volumio[992]: info: sendMpdCommand play took 6 milliseconds Nov 01 13:08:28 volumio volumio[992]: info: Nov 01 13:08:28 volumio volumio[992]: ---------------------------- MPD announces state update: player Nov 01 13:08:28 volumio volumio[992]: info: ControllerMpd::getState Nov 01 13:08:28 volumio volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 01 13:08:28 volumio volumio[992]: info: sendMpdCommand status took 3 milliseconds Nov 01 13:08:28 volumio volumio[992]: verbose: ControllerMpd::parseState Nov 01 13:08:28 volumio volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 13:08:28 volumio volumio[992]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 01 13:08:28 volumio volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 01 13:08:28 volumio volumio[992]: info: ControllerMpd::pushState Nov 01 13:08:28 volumio volumio[992]: info: CoreCommandRouter::servicePushState Nov 01 13:08:28 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Nov 01 13:08:28 volumio volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1626355,"duration":12084,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"DANCEFLOOR DISCO MIX","artist":"DJ Kosta","album":"DANCEFLOOR DISCO MIX ( Live Set By DJ Kosta )","uri":"http://192.168.178.48:50002/m/MP3/13905.mp3","trackType":"mp3"} Nov 01 13:08:28 volumio volumio[992]: verbose: CURRENT POSITION 0 Nov 01 13:08:28 volumio volumio[992]: info: CoreStateMachine::syncState stateService play Nov 01 13:08:28 volumio volumio[992]: info: CoreStateMachine::syncState currentStatus pause Nov 01 13:08:28 volumio volumio[992]: info: CoreStateMachine::pushState Nov 01 13:08:28 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Nov 01 13:08:28 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 13:08:28 volumio volumio[992]: info: CoreCommandRouter::volumioPushState Nov 01 13:08:28 volumio volumio[992]: info: ------------------------------ 48ms Nov 01 13:08:28 volumio volumio[992]: info: CoreCommandRouter::volumioGetQueue Nov 01 13:08:28 volumio volumio[992]: info: CoreStateMachine::getQueue Nov 01 13:08:28 volumio volumio[992]: info: CorePlayQueue::getQueue Nov 01 13:08:28 volumio volumio[992]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 01 13:08:29 volumio volumio[992]: info: Discovery: adding 73349c99-d839-4bb8-9ae1-d1e014e9dade Nov 01 13:08:29 volumio volumio[992]: info: Discovery: Found device Volumio Nov 01 13:08:29 volumio volumio[992]: info: CoreCommandRouter::volumioGetState Nov 01 13:08:29 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Nov 01 13:08:29 volumio ntpd[20675]: Soliciting pool server 193.93.164.195 Nov 01 13:08:30 volumio ntpd[20675]: Soliciting pool server 162.159.200.1 Nov 01 13:08:30 volumio ntpd[20675]: Soliciting pool server 193.239.214.226 Nov 01 13:08:31 volumio ntpd[20675]: Soliciting pool server 185.201.254.21 Nov 01 13:08:31 volumio ntpd[20675]: Soliciting pool server 147.52.190.11 Nov 01 13:08:32 volumio ntpd[20675]: Soliciting pool server 162.159.200.123 Nov 01 13:08:32 volumio ntpd[20675]: Soliciting pool server 81.2.191.34 Nov 01 13:08:32 volumio ntpd[20675]: Soliciting pool server 193.239.214.227 Nov 01 13:08:32 volumio ntpd[20675]: Soliciting pool server 193.239.214.227 Nov 01 13:08:33 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Nov 01 13:08:33 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Nov 01 13:08:33 volumio sudo[20690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 13:08:33 volumio sudo[20690]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:33 volumio sudo[20690]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:33 volumio ntpd[20675]: Soliciting pool server 44.32.63.52 Nov 01 13:08:33 volumio ntpd[20675]: Soliciting pool server 83.212.108.245 Nov 01 13:08:33 volumio sudo[20693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 01 13:08:33 volumio sudo[20693]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:33 volumio sudo[20693]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:34 volumio ntpd[20675]: Soliciting pool server 2a06:5780:82:c0c::c0c Nov 01 13:08:37 volumio volumio[992]: info: Volumio Network Manager: Network status updated: 2 Nov 01 13:08:38 volumio volumio[992]: info: CoreCommandRouter::volumioPause Nov 01 13:08:38 volumio volumio[992]: info: CoreStateMachine::pause Nov 01 13:08:38 volumio volumio[992]: info: CoreStateMachine::stPlaybackTimer Nov 01 13:08:38 volumio volumio[992]: info: CoreStateMachine::servicePause Nov 01 13:08:38 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Nov 01 13:08:38 volumio volumio[992]: info: CoreCommandRouter::servicePause Nov 01 13:08:38 volumio volumio[992]: info: [1761995318004] ControllerUPNPBrowser::pause Nov 01 13:08:38 volumio volumio[992]: verbose: ControllerMpd::sendMpdCommand pause Nov 01 13:08:38 volumio volumio[992]: info: Nov 01 13:08:38 volumio volumio[992]: ---------------------------- MPD announces state update: player Nov 01 13:08:38 volumio volumio[992]: info: sendMpdCommand pause took 3 milliseconds Nov 01 13:08:38 volumio volumio[992]: info: ControllerMpd::getState Nov 01 13:08:38 volumio volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 01 13:08:38 volumio volumio[992]: info: sendMpdCommand status took 1 milliseconds Nov 01 13:08:38 volumio volumio[992]: verbose: ControllerMpd::parseState Nov 01 13:08:38 volumio volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 13:08:38 volumio volumio[992]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 01 13:08:38 volumio volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 01 13:08:38 volumio volumio[992]: info: ControllerMpd::pushState Nov 01 13:08:38 volumio volumio[992]: info: CoreCommandRouter::servicePushState Nov 01 13:08:38 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Nov 01 13:08:38 volumio volumio[992]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1635732,"duration":12084,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"DANCEFLOOR DISCO MIX","artist":"DJ Kosta","album":"DANCEFLOOR DISCO MIX ( Live Set By DJ Kosta )","uri":"http://192.168.178.48:50002/m/MP3/13905.mp3","trackType":"mp3"} Nov 01 13:08:38 volumio volumio[992]: verbose: CURRENT POSITION 0 Nov 01 13:08:38 volumio volumio[992]: info: CoreStateMachine::syncState stateService pause Nov 01 13:08:38 volumio volumio[992]: info: CoreStateMachine::syncState currentStatus pause Nov 01 13:08:38 volumio volumio[992]: info: CoreStateMachine::pushState Nov 01 13:08:38 volumio volumio[992]: info: CorePlayQueue::getTrack 0 Nov 01 13:08:38 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 13:08:38 volumio volumio[992]: info: CoreCommandRouter::volumioPushState Nov 01 13:08:38 volumio volumio[992]: info: CoreStateMachine::stPlaybackTimer Nov 01 13:08:38 volumio volumio[992]: info: ------------------------------ 21ms Nov 01 13:08:38 volumio volumio[992]: info: CoreCommandRouter::volumioGetQueue Nov 01 13:08:38 volumio volumio[992]: info: CoreStateMachine::getQueue Nov 01 13:08:38 volumio volumio[992]: info: CorePlayQueue::getQueue Nov 01 13:08:38 volumio volumio[992]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 01 13:08:38 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Nov 01 13:08:38 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Nov 01 13:08:38 volumio volumio[992]: info: Refreshing Cached IP Addresses Nov 01 13:08:38 volumio sudo[20714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Nov 01 13:08:38 volumio sudo[20714]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:38 volumio sudo[20714]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:38 volumio volumio[992]: error: Upnp client error: Error: This socket has been ended by the other party Nov 01 13:08:38 volumio sudo[20716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 13:08:38 volumio sudo[20716]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:38 volumio sudo[20719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 13:08:38 volumio sudo[20719]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:38 volumio sudo[20716]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:38 volumio sudo[20719]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:41 volumio volumio[992]: info: CoreCommandRouter::volumioGetQueue Nov 01 13:08:41 volumio volumio[992]: info: CoreStateMachine::getQueue Nov 01 13:08:41 volumio volumio[992]: info: CorePlayQueue::getQueue Nov 01 13:08:45 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 01 13:08:45 volumio volumio[992]: info: Preload queue cleared Nov 01 13:08:48 volumio sudo[20741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 13:08:48 volumio sudo[20741]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:48 volumio sudo[20741]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:48 volumio sudo[20743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 13:08:48 volumio sudo[20743]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:48 volumio sudo[20743]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:48 volumio sudo[20747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 01 13:08:48 volumio sudo[20747]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 13:08:48 volumio sudo[20747]: pam_unix(sudo:session): session closed for user root Nov 01 13:08:48 volumio volumio[992]: info: Upmpdcli Daemon Started Nov 01 13:08:49 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 01 13:08:49 volumio volumio[992]: info: Preload queue cleared Nov 01 13:08:51 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 01 13:08:51 volumio volumio[992]: info: Preload queue cleared Nov 01 13:08:53 volumio nmbd[769]: [2025/11/01 13:08:53.043656, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 01 13:08:53 volumio nmbd[769]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.55 for name WORKGROUP<1d>. Nov 01 13:08:53 volumio nmbd[769]: This response was from IP 192.168.178.28, reporting an IP address of 192.168.178.28. Nov 01 13:08:54 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 01 13:08:55 volumio volumio[992]: info: Preload queue cleared Nov 01 13:09:01 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 01 13:09:01 volumio volumio[992]: info: Preload queue cleared Nov 01 13:09:04 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 01 13:09:04 volumio volumio[992]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 13:09:04 volumio volumio[992]: TypeError: Cannot read property 'length' of undefined Nov 01 13:09:04 volumio volumio[992]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Nov 01 13:09:04 volumio volumio[992]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Nov 01 13:09:04 volumio volumio[992]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Nov 01 13:09:04 volumio volumio[992]: at Parser.emit (events.js:315:20) Nov 01 13:09:04 volumio volumio[992]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Nov 01 13:09:04 volumio volumio[992]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Nov 01 13:09:04 volumio volumio[992]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Nov 01 13:09:04 volumio volumio[992]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Nov 01 13:09:04 volumio volumio[992]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Nov 01 13:09:04 volumio volumio[992]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Nov 01 13:09:04 volumio volumio[992]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Nov 01 13:09:04 volumio volumio[992]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Nov 01 13:09:04 volumio volumio[992]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Nov 01 13:09:04 volumio volumio[992]: at IncomingMessage.emit (events.js:327:22) Nov 01 13:09:04 volumio volumio[992]: at endReadableNT (internal/streams/readable.js:1327:12) Nov 01 13:09:04 volumio volumio[992]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 01 13:09:04 volumio volumio[992]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 13:09:05 volumio sudo[20776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 13:08 Nov 01 13:09:05 volumio sudo[20776]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"