Unifi AP-HD-In Wall and BlueSound

Answered

Comments

5 comments

  • Official comment
    Sam R.

    Hello Erik,

    I'm sorry to hear you have been experiencing issues with your players. To help us look into this further for you can you please forward this diagnostic log directly from your player by going to Help > Send Support Request in the BluOS app.

    Once the log has been sent, a member of our support crew will investigate this further for you and work towards a solution as soon as possible.

    Regards,
    Sam R.

  • Erik Haga

    To help you identify the problem, here is the diag from one of the FLEX 2I: 

    shares
    
    wifiscan
    	signal: -53.00 dBm
    	last seen: 6550 ms ago
    	SSID: Test_DMZPlaystation
    	DS Parameter set: channel 1
    	signal: -83.00 dBm
    	last seen: 6530 ms ago
    	SSID: NETGEAR99
    	DS Parameter set: channel 1
    	signal: -53.00 dBm
    	last seen: 6540 ms ago
    	SSID: 1397 Livet Min
    	DS Parameter set: channel 1
    	Country: NO	Environment: Indoor/Outdoor
    	signal: -73.00 dBm
    	last seen: 1710 ms ago
    	SSID: 1397 Livet Min
    	DS Parameter set: channel 6
    	Country: NO	Environment: Indoor/Outdoor
    	signal: -74.00 dBm
    	last seen: 6030 ms ago
    	SSID: Test_DMZPlaystation
    	DS Parameter set: channel 6
    	signal: -75.00 dBm
    	last seen: 5940 ms ago
    	SSID: Konami26
    	DS Parameter set: channel 8
    	signal: -65.00 dBm
    	last seen: 5480 ms ago
    	SSID: 1397 Livet Min
    	DS Parameter set: channel 11
    	Country: NO	Environment: Indoor/Outdoor
    	signal: -66.00 dBm
    	last seen: 5470 ms ago
    	SSID: Test_DMZPlaystation
    	DS Parameter set: channel 11
    	signal: -75.00 dBm
    	last seen: 4920 ms ago
    	SSID: 
    	DS Parameter set: channel 36
    	signal: -74.00 dBm
    	last seen: 5020 ms ago
    	SSID: 1397 Livet Min
    	DS Parameter set: channel 36
    	Country: NO	Environment: Indoor/Outdoor
    	signal: -74.00 dBm
    	last seen: 5020 ms ago
    	SSID: Test_DMZPlaystation
    	DS Parameter set: channel 36
    	signal: -58.00 dBm
    	last seen: 4510 ms ago
    	SSID: 
    	DS Parameter set: channel 44
    	signal: -58.00 dBm
    	last seen: 4510 ms ago
    	SSID: 1397 Livet Min
    	DS Parameter set: channel 44
    	Country: NO	Environment: Indoor/Outdoor
    	signal: -58.00 dBm
    	last seen: 4500 ms ago
    	SSID: Test_DMZPlaystation
    	DS Parameter set: channel 44
    	signal: -41.00 dBm
    	last seen: 4350 ms ago
    	SSID: 
    	DS Parameter set: channel 48
    	signal: -46.00 dBm
    	last seen: 4340 ms ago
    	SSID: 1397 Livet Min
    	DS Parameter set: channel 48
    	Country: NO	Environment: Indoor/Outdoor
    	signal: -45.00 dBm
    	last seen: 4340 ms ago
    	SSID: Test_DMZPlaystation
    	DS Parameter set: channel 48
    	signal: -84.00 dBm
    	last seen: 6350 ms ago
    	SSID: EWaade
    	DS Parameter set: channel 4
    	signal: -91.00 dBm
    	last seen: 4170 ms ago
    	SSID: Konami26-5GHz
    	Country: NO	Environment: Indoor/Outdoor
    	signal: -86.00 dBm
    	last seen: 5610 ms ago
    	SSID: tuppri
    	DS Parameter set: channel 11
    	Country: us	Environment: Indoor/Outdoor
    	signal: -75.00 dBm
    	last seen: 6040 ms ago
    	SSID: Test_DMZPlaystation
    	DS Parameter set: channel 6
    	signal: -74.00 dBm
    	last seen: 6150 ms ago
    	SSID: 1397 Livet Min
    	DS Parameter set: channel 6
    	Country: NO	Environment: Indoor/Outdoor
    	signal: -86.00 dBm
    	last seen: 6140 ms ago
    	SSID: tuppeline
    	DS Parameter set: channel 6
    	Country: us	Environment: Indoor/Outdoor
    	signal: -82.00 dBm
    	last seen: 6130 ms ago
    	SSID: tuppeline
    	DS Parameter set: channel 6
    	Country: us	Environment: Indoor/Outdoor
    	signal: -77.00 dBm
    	last seen: 6060 ms ago
    	SSID: 
    	DS Parameter set: channel 6
    
    if
    eth0      Link encap:Ethernet  HWaddr 90:56:82:60:40:E2  
              UP BROADCAST MULTICAST  MTU:1500  Metric:1
              RX packets:0 errors:0 dropped:0 overruns:0 frame:0
              TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
              collisions:0 txqueuelen:1000 
              RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)
    
    lo        Link encap:Local Loopback  
              inet addr:127.0.0.1  Mask:255.0.0.0
              UP LOOPBACK RUNNING  MTU:65536  Metric:1
              RX packets:967 errors:0 dropped:0 overruns:0 frame:0
              TX packets:967 errors:0 dropped:0 overruns:0 carrier:0
              collisions:0 txqueuelen:1000 
              RX bytes:162407 (158.6 KiB)  TX bytes:162407 (158.6 KiB)
    
    wlan0     Link encap:Ethernet  HWaddr AC:5D:5C:8B:8B:4B  
              inet addr:10.10.0.112  Bcast:10.10.0.255  Mask:255.255.255.0
              UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
              RX packets:2484 errors:0 dropped:0 overruns:0 frame:0
              TX packets:2173 errors:0 dropped:0 overruns:0 carrier:0
              collisions:0 txqueuelen:1000 
              RX bytes:355108 (346.7 KiB)  TX bytes:1932987 (1.8 MiB)
    
    
    network_serial
    DC:A4:CA:5C:4D:9D
    
    free
                 total       used       free     shared    buffers     cached
    Mem:        249892     153600      96292          0       7468      47644
    -/+ buffers/cache:      98488     151404
    Swap:            0          0          0
    
    df
    Filesystem                Size      Used Available Use% Mounted on
    /dev/mmcblk0p3          779.3M      1.1M    721.8M   0% /tmp/var/data
    
    uptime
     22:43:36 up 10 min,  0 users,  load average: 0.73, 0.76, 0.47
    
    log
    May 11 23:25:52 (none) syslog.info syslogd started: BusyBox v1.26.2
    May 11 23:25:52 (none) user.notice root: Booting upgrade image.
    May 11 23:25:55 (none) daemon.err ifplugd(eth0): started: BusyBox v1.26.2 ()
    May 11 23:25:55 (none) daemon.err ifplugd(eth0): using SIOCETHTOOL detection mode
    May 11 23:25:55 (none) daemon.err ifplugd(eth0): link is down
    May 11 23:25:55 (none) authpriv.warn dropbear[808]: Failed reading '/etc/dropbear/dropbear_dss_host_key', disabling DSS
    May 11 23:25:55 (none) authpriv.info dropbear[809]: Running in background
    May 11 23:26:00 (none) daemon.info avahi-daemon[843]: Found user 'daemon' (UID 2) and group 'daemon' (GID 2).
    May 11 23:26:00 (none) daemon.info avahi-daemon[843]: Successfully dropped root privileges.
    May 11 23:26:00 (none) daemon.info avahi-daemon[843]: avahi-daemon 0.7 starting up.
    May 11 23:26:00 (none) daemon.warn avahi-daemon[843]: WARNING: No NSS support for mDNS detected, consider installing nss-mdns!
    May 11 23:26:00 (none) daemon.warn avahi-daemon[843]: Failed to open /etc/resolv.conf: No such file or directory
    May 11 23:26:00 (none) daemon.info avahi-daemon[843]: Loading service file /etc/avahi/services/musc.service.
    May 11 23:26:00 (none) daemon.info avahi-daemon[843]: Network interface enumeration completed.
    May 11 23:26:00 (none) daemon.info avahi-daemon[843]: Server startup complete. Host name is PULSEFLEX2i-Soverom.local. Local service cookie is 1985283648.
    May 11 23:26:00 (none) daemon.info avahi-daemon[843]: Service "PULSE FLEX 2i - Soverom" (/etc/avahi/services/musc.service) successfully established.
    May 11 23:26:00 (none) user.info sovi-discover: sovi-discover 0.17
    May 11 23:26:00 (none) user.info stage1: BluOS Upgrader 0.35 - Stage 1
    May 11 23:26:00 (none) user.info sovi-discover: Set primary MAC 90:56:82:60:40:E2
    May 11 23:26:00 (none) user.info stage1: Command line OK
    May 11 23:26:00 (none) user.info stage1: Detected model: PULSE FLEX 2i
    May 11 23:26:09 (none) daemon.info : starting pid 866, tty '': '/sbin/getty -L ttymxc0 115200 vt100'
    May 11 23:26:09 (none) user.info stage1: BluOS Upgrader 0.35 - Stage 1
    May 11 23:26:09 (none) user.info stage1: Command line OK
    May 11 23:26:09 (none) user.info stage1: Detected model: PULSE FLEX 2i
    May 11 23:26:09 (none) daemon.err udhcpc[864]: started, v1.26.2
    May 11 23:26:09 (none) daemon.err udhcpc[864]: sending discover
    May 11 23:26:09 (none) user.info stage1: [error] curl: (6) Couldn't resolve host 'upgrade.nadelectronics.com'
    May 11 23:26:09 (none) user.info stage1: [warning] Download failed (status=6): p125/stage2 (retry 0)
    May 11 23:26:12 (none) user.info stage1: [error] curl: (6) Couldn't resolve host 'upgrade.nadelectronics.com'
    May 11 23:26:12 (none) user.info stage1: [warning] Download failed (status=6): p125/stage2 (retry 1)
    May 11 23:26:12 (none) daemon.info avahi-daemon[843]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::ae5d:5cff:fe8b:8b4b.
    May 11 23:26:12 (none) daemon.info avahi-daemon[843]: New relevant interface wlan0.IPv6 for mDNS.
    May 11 23:26:12 (none) daemon.info avahi-daemon[843]: Registering new address record for fe80::ae5d:5cff:fe8b:8b4b on wlan0.*.
    May 11 23:26:13 (none) daemon.err udhcpc[864]: sending discover
    May 11 23:26:13 (none) daemon.err udhcpc[864]: sending select for 10.10.0.112
    May 11 23:26:13 (none) daemon.err udhcpc[864]: lease of 10.10.0.112 obtained, lease time 28800
    May 11 23:26:13 (none) user.info sovi-discover: Adding address 10.10.0.112/10.255.255.255 wlan0
    May 11 23:26:13 (none) daemon.info avahi-daemon[843]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.10.0.112.
    May 11 23:26:13 (none) daemon.info avahi-daemon[843]: New relevant interface wlan0.IPv4 for mDNS.
    May 11 23:26:13 (none) daemon.info avahi-daemon[843]: Registering new address record for 10.10.0.112 on wlan0.IPv4.
    May 11 23:26:13 (none) user.info sovi-discover: Removing address 10.10.0.112/10.255.255.255 wlan0
    May 11 23:26:13 (none) daemon.info avahi-daemon[843]: Withdrawing address record for 10.10.0.112 on wlan0.
    May 11 23:26:13 (none) daemon.info avahi-daemon[843]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.10.0.112.
    May 11 23:26:13 (none) user.info sovi-discover: Adding address 10.10.0.112/10.10.0.255 wlan0
    May 11 23:26:13 (none) daemon.info avahi-daemon[843]: Interface wlan0.IPv4 no longer relevant for mDNS.
    May 11 23:26:13 (none) daemon.info avahi-daemon[843]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.10.0.112.
    May 11 23:26:13 (none) daemon.info avahi-daemon[843]: New relevant interface wlan0.IPv4 for mDNS.
    May 11 23:26:13 (none) daemon.info avahi-daemon[843]: Registering new address record for 10.10.0.112 on wlan0.IPv4.
    May 11 23:26:13 (none) user.info sovi-discover: Removing address 10.10.0.112/10.10.0.255 wlan0
    May 11 23:26:13 (none) daemon.info avahi-daemon[843]: Withdrawing address record for 10.10.0.112 on wlan0.
    May 11 23:26:13 (none) daemon.info avahi-daemon[843]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.10.0.112.
    May 11 23:26:13 (none) user.info sovi-discover: Adding address 10.10.0.112/10.10.0.255 wlan0
    May 11 23:26:13 (none) daemon.info avahi-daemon[843]: Interface wlan0.IPv4 no longer relevant for mDNS.
    May 11 23:26:13 (none) daemon.info avahi-daemon[843]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.10.0.112.
    May 11 23:26:13 (none) daemon.info avahi-daemon[843]: New relevant interface wlan0.IPv4 for mDNS.
    May 11 23:26:13 (none) daemon.info avahi-daemon[843]: Registering new address record for 10.10.0.112 on wlan0.IPv4.
    May 11 23:26:24 (none) user.info stage2: BluOS Internet Upgrader 1.55 - Stage 2
    May 11 23:26:24 (none) user.info stage2: Command line OK
    May 11 23:26:24 (none) user.info stage2: Detected model: PULSE FLEX 2i
    May 11 23:26:24 (none) user.info stage2: upgrade ID=4acb29a06783b33e838a370099541d0b
    May 11 23:26:25 (none) user.info stage2: MD5 matched: stage2_config
    May 11 23:26:25 (none) user.info stage2: Data host: d2pzg9edlivurc.cloudfront.net
    May 11 23:26:25 (none) user.info stage2: Detected PIC32 version: P100-20191115
    May 11 23:26:25 (none) user.info stage2: MD5 matched: sovi_info.xml
    May 11 23:26:25 (none) user.info stage2: Start upgrade from 3.8.3 to 3.8.8
    May 11 23:26:26 (none) user.info stage2: Start rootfs upgrade/verification
    May 11 23:26:28 (none) user.info stage2: MD5 matched: manifest
    May 11 23:27:06 (none) user.info stage2: rootfs manifest comparison complete, 58 files to download
    May 11 23:27:41 (none) user.info stage2: rootfs update OK
    May 11 23:27:43 (none) user.info stage2: Upgrade complete
    May 11 23:27:46 (none) daemon.info : starting pid 1270, tty '': '/etc/rc.d/rcS stop'
    May 11 23:27:46 (none) daemon.info : The system is going down NOW!
    May 11 23:27:46 (none) syslog.info syslogd exiting
    May 13 22:33:28 (none) syslog.info syslogd started: BusyBox v1.26.2
    May 13 22:33:28 (none) user.notice root: Booting main image.
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'tty' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'tty' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'tty' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'tty' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'tty' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'tty' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'uucp' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'uucp' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'uucp' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'kmem' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'lp' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'lp' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'lp' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'lp' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'lp' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'disk' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'floppy' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'disk' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'disk' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'disk' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'disk' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'disk' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'disk' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'disk' unknown
    May 13 22:33:28 (none) daemon.err udevd[164]: lookup_group: specified group 'disk' unknown
    May 13 22:33:31 (none) user.info sovi-hal-qt: start sovi-hal 0.178
    May 13 22:33:31 (none) user.info sovi-hal-qt: No Model Specific Setup for board  "P125"
    May 13 22:33:31 (none) user.info sovi-hal-qt: uuid:  QUuid("{8fcccf76-6a3a-5a97-a8b8-a1d60a9e2f42}")
    May 13 22:33:32 (none) user.info sovi-hal-qt:  creating headphones
    May 13 22:33:32 (none) user.info sovi-hal-qt: start sovi-hal 0.178
    May 13 22:33:32 (none) user.info sovi-hal-qt: No Model Specific Setup for board  "P125"
    May 13 22:33:32 (none) user.info sovi-hal-qt: uuid:  QUuid("{8fcccf76-6a3a-5a97-a8b8-a1d60a9e2f42}")
    May 13 22:33:32 (none) user.info sovi-hal-qt:  creating headphones
    May 13 22:33:32 (none) user.info sovi-hal-qt: start sovi-hal 0.178
    May 13 22:33:32 (none) user.info sovi-hal-qt: No Model Specific Setup for board  "P125"
    May 13 22:33:32 (none) user.info sovi-hal-qt: uuid:  QUuid("{8fcccf76-6a3a-5a97-a8b8-a1d60a9e2f42}")
    May 13 22:33:33 (none) user.info sovi-hal-qt:  creating headphones
    May 13 22:33:33 (none) daemon.err ifplugd(eth0): started: BusyBox v1.26.2 ()
    May 13 22:33:33 (none) daemon.err ifplugd(eth0): using SIOCETHTOOL detection mode
    May 13 22:33:33 (none) daemon.err ifplugd(eth0): link is down
    May 13 22:33:33 (none) authpriv.info dropbear[847]: Running in background
    May 13 22:33:33 (none) daemon.info avahi-daemon[870]: Found user 'daemon' (UID 101) and group 'daemon' (GID 101).
    May 13 22:33:33 (none) daemon.info avahi-daemon[870]: Successfully dropped root privileges.
    May 13 22:33:33 (none) daemon.info avahi-daemon[870]: avahi-daemon 0.7 starting up.
    May 13 22:33:33 (none) daemon.warn avahi-daemon[870]: WARNING: No NSS support for mDNS detected, consider installing nss-mdns!
    May 13 22:33:33 (none) daemon.warn avahi-daemon[870]: Failed to open /etc/resolv.conf: No such file or directory
    May 13 22:33:33 (none) daemon.info avahi-daemon[870]: Loading service file /etc/avahi/services/musc.service.
    May 13 22:33:33 (none) daemon.info avahi-daemon[870]: Loading service file /etc/avahi/services/sovi-mfg.service.
    May 13 22:33:33 (none) daemon.info avahi-daemon[870]: Network interface enumeration completed.
    May 13 22:33:33 (none) daemon.info avahi-daemon[870]: Server startup complete. Host name is PULSEFLEX2i-Soverom.local. Local service cookie is 612409541.
    May 13 22:33:33 (none) daemon.info avahi-daemon[870]: Service "PULSE FLEX 2i - Soverom" (/etc/avahi/services/sovi-mfg.service) successfully established.
    May 13 22:33:33 (none) daemon.info avahi-daemon[870]: Service "PULSE FLEX 2i - Soverom" (/etc/avahi/services/musc.service) successfully established.
    May 13 22:33:33 (none) user.notice rc.dwam83-upgrade(876): dwam83 not found
    May 13 22:33:33 (none) daemon.info : starting pid 887, tty '': '/sbin/getty -L ttymxc0 115200 vt100'
    May 13 22:33:33 (none) user.info sovi-bt: start sovi-bt 0.51
    May 13 22:33:34 (none) user.info sovi-discover: sovi-discover 0.18
    May 13 22:33:34 (none) user.notice root: Starting sovi processes.
    May 13 22:33:34 (none) user.info sovi-discover: Set primary MAC 90:56:82:60:40:E2
    May 13 22:33:34 (none) user.info sovi-hal-qt: error parsing /Volume xml
    May 13 22:33:34 (none) user.info dspout: dspout 1.127.1
    May 13 22:33:34 (none) user.info dspout: kernel: 5 (5.0.0-00746-g39111bac80a4)
    May 13 22:33:34 (none) user.info dspout: RTP: listen on port 11000, fd=4
    May 13 22:33:34 (none) user.info dspout: RTP: listen on /var/data/pcm_rtp_socket01, fd=5
    May 13 22:33:34 (none) user.info dspout: output device: default
    May 13 22:33:34 (none) user.info dspout: pcmsock: /tmp/pcmsock pcmcontrol /tmp/pcmcontrol player 1
    May 13 22:33:34 (none) user.info dspout: Playback device is default
    May 13 22:33:34 (none) user.info dspout: Stream parameters are 44100Hz, S16_LE, 2 channels
    May 13 22:33:34 (none) user.info dspout:     buffer-frames=13230, period-frames=441
    May 13 22:33:34 (none) user.info dspout: [error] ALSA lib control.c:954:(snd_ctl_open_noupdate) Invalid CTL analog
    May 13 22:33:34 (none) user.info dspout: [error] ALSA lib control.c:954:(snd_ctl_open_noupdate) Invalid CTL digital
    May 13 22:33:34 (none) user.info dspout: open_mixer(default) success
    May 13 22:33:34 (none) user.info dspout: [error] ALSA lib control.c:954:(snd_ctl_open_noupdate) Invalid CTL usbsub
    May 13 22:33:34 (none) user.info dspout: Playback device is default
    May 13 22:33:34 (none) user.info dspout: Stream parameters are 44100Hz, S16_LE, 2 channels
    May 13 22:33:34 (none) user.info dspout:     buffer-frames=13230, period-frames=441
    May 13 22:33:34 (none) user.info dspout: [error] ALSA lib control.c:954:(snd_ctl_open_noupdate) Invalid CTL analog
    May 13 22:33:34 (none) user.info dspout: [error] ALSA lib control.c:954:(snd_ctl_open_noupdate) Invalid CTL digital
    May 13 22:33:34 (none) user.info dspout: open_mixer(default) success
    May 13 22:33:34 (none) user.info dspout: [error] ALSA lib control.c:954:(snd_ctl_open_noupdate) Invalid CTL usbsub
    May 13 22:33:34 (none) user.info dspout: [error] /tmp/controller_socket: No such file or directory
    May 13 22:33:34 (none) user.info dspout: Volume: 400 => 0 -> 135 (min=0, max=255)
    May 13 22:33:36 (none) user.info sovi-hal-qt: error parsing /Volume xml
    May 13 22:33:38 (none) user.info sovi-hal-qt: error parsing /Volume xml
    May 13 22:33:40 (none) user.info sovi-hal-qt: error parsing /Volume xml
    May 13 22:33:41 (none) user.info ./cp.pl: I18N::setLocale I18N.pm (122) en(en) -> en => en
    May 13 22:33:41 (none) user.info ./cp.pl: main::_backupFindDriveDevices ./cp.pl (4257) no internal hard drive.
    May 13 22:33:41 (none) user.info ./cp.pl: main:: ./cp.pl (550) cp initial startup
    May 13 22:33:41 (none) user.info ./cp.pl: main:: ./cp.pl (573) PCB rev 2
    May 13 22:33:41 (none) user.info ./cp.pl: main::xover_send_commands ./cp.pl (5335) sending crossover commands: default
    May 13 22:33:41 (none) user.debug ./cp.pl: LED_DDFA::_sovi_hal_command_async LED_DDFA.pm (158) led_mode=5: queue=2
    May 13 22:33:41 (none) user.info ./cp.pl: main:: ./cp.pl (699) player_name file found: PULSE FLEX 2i - Soverom
    May 13 22:33:42 (none) user.info ./cp.pl: main:: ./cp.pl (754) initialized, forking to background
    May 13 22:33:42 (none) user.info ./cp.pl: main::__ANON__ ./cp.pl (5099) couldn't connect to wifi, bringing up AP mode, with WAC
    May 13 22:33:42 (none) user.info sovi-hal-qt: error parsing /Volume xml
    May 13 22:33:44 (none) user.info sovi-hal-qt: error parsing /Volume xml
    May 13 22:33:44 (none) user.info dspout: [error] /tmp/controller_socket: No such file or directory
    May 13 22:33:44 (none) user.info ./ms.pl: I18N::setLocale I18N.pm (122) en(en) -> en => en
    May 13 22:33:44 (none) user.info ./ms.pl: ImportedPlaylists::load ImportedPlaylists.pm (87) no imported_playlists.db file, initializing.
    May 13 22:33:44 (none) user.info ./ms.pl: IndexDB::connect IndexDB.pm (112) Attached /var/data/metadata_flip.sq3 Version 1 Schema 4
    May 13 22:33:44 (none) user.emerg ./ms.pl: main::__ANON__ ./ms.pl (120) Cannot determine local time zone
    May 13 22:33:44 (none) user.emerg ./ms.pl: main::__ANON__ ./ms.pl (120)  at /usr/lib/perl5/site_perl/5.14.2/DateTime/TimeZone/Local.pm line 25
    May 13 22:33:44 (none) user.debug ./ms.pl: Alarms::init Alarms.pm (94) Timezone: UTC
    May 13 22:33:44 (none) user.debug ./ms.pl: Alarms::_schedule Alarms.pm (351) No alarm found for today or tomorrow. Will look again in a day
    May 13 22:33:45 (none) user.info ./ms.pl: BaseEndpoint::init BaseEndpoint.pm (78) Listen on /tmp/var/data/airplay/airplay_req01
    May 13 22:33:45 (none) user.warn ./ms.pl: BaseEndpoint::tellEndpoint BaseEndpoint.pm (319) /tmp/var/data/airplay/airplay_cmd01: Connection refused
    May 13 22:33:45 (none) daemon.info avahi-daemon[870]: Files changed, reloading.
    May 13 22:33:45 (none) daemon.info avahi-daemon[870]: Loading service file /etc/avahi/services/airplay_http.service.
    May 13 22:33:45 (none) daemon.info avahi-daemon[870]: Service "PULSE FLEX 2i - Soverom" (/etc/avahi/services/airplay_http.service) successfully established.
    May 13 22:33:45 (none) daemon.warn avahi-daemon[870]: Failed to open /etc/resolv.conf: No such file or directory
    May 13 22:33:45 (none) user.info ./ms.pl: BaseEndpoint::init BaseEndpoint.pm (78) Listen on /tmp/var/data/raat/raat_req01
    May 13 22:33:45 (none) user.warn ./ms.pl: BaseEndpoint::tellEndpoint BaseEndpoint.pm (319) /tmp/var/data/raat/raat_cmd01: Connection refused
    May 13 22:33:45 (none) user.info ./ms.pl: BaseEndpoint::init BaseEndpoint.pm (78) Listen on /tmp/var/data/spotify/spotify_req01
    May 13 22:33:45 (none) user.warn ./ms.pl: BaseEndpoint::tellEndpoint BaseEndpoint.pm (319) /tmp/var/data/spotify/spotify_cmd01: Connection refused
    May 13 22:33:45 (none) user.info ./ms.pl: Controller::new Controller.pm (139) pcmsock:/tmp/pcmsock pcmcontrol:/tmp/pcmcontrol port:11002
    May 13 22:33:45 (none) user.info ./ms.pl: NPlayer::DDFA Player.pm (3056) DDFA not configured.
    May 13 22:33:45 (none) user.info ./ms.pl: NPlayer::trigger_reset Player.pm (3026) trigger on
    May 13 22:33:45 (none) user.info ./ms.pl: NPlayer::set_volume_ramp Player.pm (2863) log:-90..0 dB
    May 13 22:33:45 (none) user.info ./ms.pl: main:: ./ms.pl (317) PCB rev 2
    May 13 22:33:45 (none) user.info ./ms.pl: HardKeys::refreshIRKeyTableFromFile HardKeys.pm (215) couldn't find irkeymap file, no IR keys initialized
    May 13 22:33:45 (none) user.debug ./ms.pl: LED_DDFA::_sovi_hal_command_async LED_DDFA.pm (158) GetInit?: queue=2
    May 13 22:33:45 (none) user.debug ./ms.pl: LED_DDFA::_sovi_hal_command_async LED_DDFA.pm (158) Source?: queue=3
    May 13 22:33:46 (none) daemon.info avahi-daemon[870]: Files changed, reloading.
    May 13 22:33:46 (none) daemon.info avahi-daemon[870]: Service group file /etc/avahi/services/musc.service changed, reloading.
    May 13 22:33:46 (none) daemon.info avahi-daemon[870]: Service "PULSE FLEX 2i - Soverom" (/etc/avahi/services/musc.service) successfully established.
    May 13 22:33:46 (none) daemon.warn avahi-daemon[870]: Failed to open /etc/resolv.conf: No such file or directory
    May 13 22:33:46 (none) user.info ./ms.pl: NPlayer::__ANON__ Player.pm (581) saving volume level dB: -60, headphones: -60
    May 13 22:33:46 (none) user.info dspout: Command(l=1): S
    May 13 22:33:46 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /BTPropertyChange?type=CSR8675&adapter=true&connected=false&streaming=false
    May 13 22:33:46 (none) user.info dspout: Command(l=5): I5000
    May 13 22:33:46 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Volume?etag=-1&timeout=120
    May 13 22:33:46 (none) user.info dspout: Command(l=1): c
    May 13 22:33:46 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Volume?etag=9b8a5df0c6445bc881dfebfd0d2d0fb9&timeout=120
    May 13 22:33:46 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Headphones?present=0
    May 13 22:33:46 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Services
    May 13 22:33:46 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Playlist?length=1
    May 13 22:33:46 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /RadioBrowse?service=Capture
    May 13 22:33:46 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Presets
    May 13 22:33:46 (none) user.info dspout: Command(l=4): V400
    May 13 22:33:50 (none) user.info sovi-hal-qt: IP Address changed:  "10.1.2.3"
    May 13 22:33:50 (none) user.info sovi-hal-qt: Telling AVR host IP addr changed  "10.1.2.3"
    May 13 22:33:50 (none) user.info sovi-discover: Adding address 10.1.2.3/10.255.255.255 wlan0
    May 13 22:33:50 (none) daemon.info avahi-daemon[870]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.2.3.
    May 13 22:33:50 (none) daemon.info avahi-daemon[870]: New relevant interface wlan0.IPv4 for mDNS.
    May 13 22:33:50 (none) daemon.info avahi-daemon[870]: Registering new address record for 10.1.2.3 on wlan0.IPv4.
    May 13 22:33:50 (none) user.info sovi-discover: SDDP wlan0: SDDP initializing
    May 13 22:33:50 (none) user.info ./cp.pl: WAC::start WAC.pm (86) 
    May 13 22:33:50 (none) daemon.err dnsd[981]: accepting UDP packets on 0.0.0.0:53
    May 13 22:33:50 (none) user.info ./cp.pl: main:: ./cp.pl (767) starting ethernet monitor
    May 13 22:33:50 (none) user.info ./cp.pl: Authorization::_startHttpd Authorization.pm (168) port: 43969
    May 13 22:33:51 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Settings?schemaVersion=25
    May 13 22:33:51 (none) user.debug ./cp.pl: LED_DDFA::_sovi_hal_command_async LED_DDFA.pm (158) led_mode=5: queue=3
    May 13 22:33:51 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Services
    May 13 22:33:51 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /RadioBrowse?service=Capture
    May 13 22:33:52 (none) user.info ./ms.pl: Sources::RadioParadise::__ANON__ Sources/RadioParadise.pm (145) GET: 595 - Connection refused
    May 13 22:33:52 (none) user.info ./ms.pl: Sources::RadioParadise::__ANON__ Sources/RadioParadise.pm (274) failed: Connection refused
    May 13 22:33:52 (none) daemon.info avahi-daemon[870]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::ae5d:5cff:fe8b:8b4b.
    May 13 22:33:52 (none) daemon.info avahi-daemon[870]: New relevant interface wlan0.IPv6 for mDNS.
    May 13 22:33:52 (none) daemon.info avahi-daemon[870]: Registering new address record for fe80::ae5d:5cff:fe8b:8b4b on wlan0.*.
    May 13 22:33:53 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /GetSettings
    May 13 22:33:56 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Settings?schemaVersion=25
    May 13 22:34:22 (none) user.info ./cp.pl: AmazonIoT::checkCreds Sources/AmazonIoT.pm (108) AmazonIoT: Device not provisioned.
    May 13 22:34:34 (none) user.notice rc.stage0: error downloading stage0, waiting 2 minute(s)
    May 13 22:34:52 (none) user.info ./ms.pl: Sources::RadioParadise::__ANON__ Sources/RadioParadise.pm (145) GET: 595 - Connection refused
    May 13 22:34:52 (none) user.info ./ms.pl: Sources::RadioParadise::__ANON__ Sources/RadioParadise.pm (274) failed: Connection refused
    May 13 22:35:18 (none) daemon.info hostapd: wlan0: STA 78:4f:43:77:63:8c IEEE 802.11: authenticated
    May 13 22:35:18 (none) daemon.info hostapd: wlan0: STA 78:4f:43:77:63:8c IEEE 802.11: associated (aid 1)
    May 13 22:35:18 (none) daemon.info hostapd: wlan0: STA 78:4f:43:77:63:8c RADIUS: starting accounting session BC3958430B485C62
    May 13 22:35:23 (none) user.info ./cp.pl: WAC::__ANON__ WAC.pm (94) /auth-setup
    May 13 22:35:23 (none) user.info ./cp.pl: WAC::__ANON__ WAC.pm (110) mfiserver: MFi open: /dev/i2c-2@0x10
    May 13 22:35:23 (none) user.info ./cp.pl: WAC::__ANON__ WAC.pm (110) mfiserver: Using MFi Auth v.3 chip
    May 13 22:35:23 (none) user.info ./cp.pl: I18N::setLocale I18N.pm (122) en-gb(en-gb) -> en-GB => en
    May 13 22:35:23 (none) user.info ./cp.pl: WAC::__ANON__ WAC.pm (129) /config
    May 13 22:35:25 (none) user.info ./cp.pl: I18N::setLocale I18N.pm (122) en-GB,en-US;q=0.9,en;q=0.8,nb;q=0.7(en-GB) -> en-GB => en
    May 13 22:35:26 (none) user.info ./cp.pl: WAC::stop WAC.pm (286) 
    May 13 22:35:27 (none) user.info sovi-discover: Removing address 10.1.2.3/10.255.255.255 wlan0
    May 13 22:35:27 (none) daemon.info avahi-daemon[870]: Interface wlan0.IPv6 no longer relevant for mDNS.
    May 13 22:35:27 (none) daemon.info avahi-daemon[870]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::ae5d:5cff:fe8b:8b4b.
    May 13 22:35:27 (none) user.info sovi-discover: SDDP wlan0: Multicast setting interface 10.1.2.3 failed Cannot assign requested address
    May 13 22:35:27 (none) user.info sovi-discover: SDDP wlan0: Closing multicast socket 7
    May 13 22:35:28 (none) user.info sovi-discover: SDDP wlan0: Closing unicast socket 6
    May 13 22:35:28 (none) daemon.info avahi-daemon[870]: Interface wlan0.IPv4 no longer relevant for mDNS.
    May 13 22:35:28 (none) daemon.info avahi-daemon[870]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.2.3.
    May 13 22:35:28 (none) daemon.info avahi-daemon[870]: Withdrawing address record for fe80::ae5d:5cff:fe8b:8b4b on wlan0.
    May 13 22:35:28 (none) daemon.info avahi-daemon[870]: Withdrawing address record for 10.1.2.3 on wlan0.
    May 13 22:35:30 (none) user.info sovi-hal-qt: IP changed  "0.0.0.0"
    May 13 22:35:30 (none) user.info sovi-hal-qt: Telling AVR host IP addr changed  "0.0.0.0"
    May 13 22:35:30 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: OK
    May 13 22:35:30 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: wpa_state=DISCONNECTED address=ac:5d:5c:8b:8b:4b
    May 13 22:35:30 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-SCAN-STARTED 
    May 13 22:35:31 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
    May 13 22:35:31 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
    May 13 22:35:31 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 0 e0:63:da:53:8b:d3
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 1 e2:63:da:13:8b:d3
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 2 e2:63:da:23:8b:d3
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 3 e2:63:da:13:8a:47
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 4 e2:63:da:23:8a:47
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 5 e0:63:da:53:8a:47
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 6 e0:63:da:53:8d:07
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 7 e2:63:da:13:8d:07
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 8 e2:63:da:23:8d:07
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 9 fe:ec:da:11:bd:2a
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 10 0e:ec:da:11:bd:2a
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 11 e2:63:da:13:8b:d2
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 12 e2:63:da:23:8b:d2
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 13 10:7b:ef:7c:ca:60
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 14 5c:6a:80:31:8a:d2
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 15 a0:e4:cb:2f:bc:01
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 16 10:7b:ef:7c:ca:61
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 17 e2:63:da:13:8a:46
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 18 e0:63:da:53:8a:46
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 19 e2:63:da:23:8a:46
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 20 e2:63:da:13:8d:06
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 21 e2:63:da:23:8d:06
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 22 e0:63:da:53:8d:06
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 23 a4:2b:8c:f4:d8:b8
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-SCAN-RESULTS 
    May 13 22:35:35 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>SME: Trying to authenticate with fe:ec:da:11:bd:2a (SSID='1397 Livet Min' freq=2437 MHz)
    May 13 22:35:36 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>Trying to associate with fe:ec:da:11:bd:2a (SSID='1397 Livet Min' freq=2437 MHz)
    May 13 22:35:36 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>Associated with fe:ec:da:11:bd:2a
    May 13 22:35:36 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
    May 13 22:35:36 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=NO
    May 13 22:35:36 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>WPA: Key negotiation completed with fe:ec:da:11:bd:2a [PTK=CCMP GTK=CCMP]
    May 13 22:35:36 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-CONNECTED - Connection to fe:ec:da:11:bd:2a completed [id=0 id_str=]
    May 13 22:35:36 (none) daemon.err udhcpc[1026]: started, v1.26.2
    May 13 22:35:36 (none) daemon.err udhcpc[1026]: sending discover
    May 13 22:35:36 (none) daemon.err udhcpc[1026]: sending select for 10.10.0.112
    May 13 22:35:37 (none) daemon.err udhcpc[1026]: lease of 10.10.0.112 obtained, lease time 28800
    May 13 22:35:37 (none) user.info sovi-discover: Adding address 10.10.0.112/10.255.255.255 wlan0
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.10.0.112.
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: New relevant interface wlan0.IPv4 for mDNS.
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: Registering new address record for 10.10.0.112 on wlan0.IPv4.
    May 13 22:35:37 (none) user.info sovi-discover: Removing address 10.10.0.112/10.255.255.255 wlan0
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: Withdrawing address record for 10.10.0.112 on wlan0.
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.10.0.112.
    May 13 22:35:37 (none) user.info sovi-discover: Adding address 10.10.0.112/10.10.0.255 wlan0
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: Interface wlan0.IPv4 no longer relevant for mDNS.
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.10.0.112.
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: New relevant interface wlan0.IPv4 for mDNS.
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: Registering new address record for 10.10.0.112 on wlan0.IPv4.
    May 13 22:35:37 (none) user.info sovi-discover: Removing address 10.10.0.112/10.10.0.255 wlan0
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: Withdrawing address record for 10.10.0.112 on wlan0.
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.10.0.112.
    May 13 22:35:37 (none) user.info sovi-discover: Adding address 10.10.0.112/10.10.0.255 wlan0
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: Interface wlan0.IPv4 no longer relevant for mDNS.
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.10.0.112.
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: New relevant interface wlan0.IPv4 for mDNS.
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: Registering new address record for 10.10.0.112 on wlan0.IPv4.
    May 13 22:35:37 (none) user.info sovi-discover: SDDP wlan0: SDDP initializing
    May 13 22:35:37 (none) user.info sovi-discover: SDDP wlan0: Closing multicast socket 7
    May 13 22:35:37 (none) user.info sovi-discover: SDDP wlan0: Closing unicast socket 6
    May 13 22:35:37 (none) daemon.info ipwatchd[1037]: IPwatchD started
    May 13 22:35:37 (none) user.info ./cp.pl: main::__ANON__ ./cp.pl (1029) Connected to 1397 Livet Min: WPA2
    May 13 22:35:37 (none) user.info sovi-hal-qt: IP Address changed:  "10.10.0.112"
    May 13 22:35:37 (none) user.info sovi-hal-qt: Telling AVR host IP addr changed  "10.10.0.112"
    May 13 22:35:37 (none) user.info RAAT: Starting: raat_bluos -c /var/data/raat.json -z 1 -i BluOS:90:56:82:60:40:e2 -d /var/data/raat -b Bluesound -M PULSE FLEX 2i -v 3.8.8 -e -C -U 104 -G 104 -N 1 PULSE FLEX 2i - Soverom
    May 13 22:35:37 (none) user.info sovi-spotify: new name: PULSE FLEX 2i - Soverom
    May 13 22:35:37 (none) user.info sovi-spotify: sovi-spotify 1.23, libspotify_embedded: HEAD-v3.76.27-g015b9211
    May 13 22:35:37 (none) user.info sovi-spotify: Listening on spotify_pcm01
    May 13 22:35:37 (none) user.info sovi-spotify: Listening on spotify_cmd01
    May 13 22:35:37 (none) user.info AirPlay: Starting: chrt -r 1 sovi-airplay -z 1 -i 90:56:82:60:40:e2 -d /var/data/airplay -b Bluesound -M PULSE FLEX 2i -v 3.8.8 -e -N 1 PULSE FLEX 2i - Soverom
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: Files changed, reloading.
    May 13 22:35:37 (none) daemon.info avahi-daemon[870]: Loading service file /etc/avahi/services/spotify-1.service.
    May 13 22:35:37 (none) user.info ./cp.pl: WAC::__ANON__ WAC.pm (210) Restarting for /configured
    May 13 22:35:37 (none) user.info AirPlay: [sovi-airplay] appInitialize[1]: PULSE FLEX 2i - Soverom 1
    May 13 22:35:37 (none) user.info ./ms.pl: Sources::AirPlay::setVolume Sources/AirPlay.pm (147) level=4 dB: -60 -> -30
    May 13 22:35:37 (none) user.info AirPlay: [sovi-airplay] Starting command thread: playerNum=1
    May 13 22:35:37 (none) user.warn sovi-airplay[1045]: *** WARNING *** The program 'sovi-airplay' uses the Apple Bonjour compatibility layer of Avahi.
    May 13 22:35:37 (none) user.warn sovi-airplay[1045]: *** WARNING *** Please fix your application to use the native API of Avahi!
    May 13 22:35:37 (none) user.warn sovi-airplay[1045]: *** WARNING *** For more information see <http://0pointer.de/blog/projects/avahi-compat.html>
    May 13 22:35:37 (none) user.info AirPlay: [sovi-airplay] Listening on airplay_cmd01
    May 13 22:35:37 (none) user.info AirPlay: *** WARNING *** The program 'sovi-airplay' uses the Apple Bonjour compatibility layer of Avahi.
    May 13 22:35:37 (none) user.info AirPlay: *** WARNING *** Please fix your application to use the native API of Avahi!
    May 13 22:35:37 (none) user.info AirPlay: *** WARNING *** For more information see <http://0pointer.de/blog/projects/avahi-compat.html>
    May 13 22:35:37 (none) user.info AirPlay: [AirPlay] Registering Bonjour _airplay._tcp. port 7000
    May 13 22:35:37 (none) user.info AirPlay: [AirPlay] Registering Bonjour 9056826040E2@PULSE FLEX 2i - Soverom._raop._tcp. port 7000
    May 13 22:35:37 (none) user.info AirPlay: [AirPlay] AirPlay servers started
    May 13 22:35:38 (none) daemon.info avahi-daemon[870]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::ae5d:5cff:fe8b:8b4b.
    May 13 22:35:38 (none) daemon.info avahi-daemon[870]: New relevant interface wlan0.IPv6 for mDNS.
    May 13 22:35:38 (none) daemon.info avahi-daemon[870]: Registering new address record for fe80::ae5d:5cff:fe8b:8b4b on wlan0.*.
    May 13 22:35:38 (none) daemon.info avahi-daemon[870]: Service "PULSE FLEX 2i - Soverom" (/etc/avahi/services/spotify-1.service) successfully established.
    May 13 22:35:39 (none) user.info ./cp.pl: WAC::__ANON__ WAC.pm (197) /configured
    May 13 22:35:39 (none) user.info ./cp.pl: WAC::stop WAC.pm (286) 
    May 13 22:35:40 (none) user.info ./cp.pl: main::__ANON__ ./cp.pl (5039) Refreshed AnyEvent::DNS::resolver => 10.10.0.5
    May 13 22:35:40 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /UpdateDNS
    May 13 22:35:40 (none) user.info ./ms.pl: main::_updateDNS ./ms.pl (7071) Refreshed AnyEvent::DNS::resolver => 10.10.0.5
    May 13 22:35:42 (none) user.info RAAT: [server] listening on port 41571
    May 13 22:35:42 (none) user.info RAAT: [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003
    May 13 22:35:42 (none) user.info RAAT: [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:43179
    May 13 22:35:42 (none) user.info RAAT: [discovery] [iface:10.10.0.112] multicast recv socket is bound to 0.0.0.0:9003
    May 13 22:35:42 (none) user.info RAAT: [discovery] [iface:10.10.0.112] multicast send socket is bound to 0.0.0.0:60265
    May 13 22:35:42 (none) user.info RAAT: [discovery] unicast socket is bound to 0.0.0.0:9003
    May 13 22:35:43 (none) user.notice rc.stage0: download OK
    May 13 22:35:44 (none) user.info ./ms.pl: I18N::setLocale I18N.pm (122) en-NO;q=1, nb-NO;q=0.9(en-NO) -> en-NO => en
    May 13 22:35:46 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Volume?etag=9b8a5df0c6445bc881dfebfd0d2d0fb9&timeout=120
    May 13 22:35:46 (none) user.info ./cp.pl: main::configAndStartNTP ./cp.pl (4948) Starting chronyd
    May 13 22:35:46 (none) daemon.info chronyd[1077]: chronyd version 3.4 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH +IPV6 -DEBUG)
    May 13 22:35:49 (none) daemon.info chronyd[1077]: Frequency -27.090 +/- 8.529 ppm read from /var/data/chrony.drift
    May 13 22:35:52 (none) user.emerg ./ms.pl: main::__ANON__ ./ms.pl (120) Can't locate object method "tid" via package "threads" at /usr/lib/perl5/5.14.2/XSLoader.pm line 95.
    May 13 22:35:52 (none) user.emerg ./ms.pl: main::__ANON__ ./ms.pl (120)  at /usr/lib/perl5/5.14.2/XSLoader.pm line 95
    May 13 22:35:52 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 10.10.0.187: /Services?schemaVersion=26
    May 13 22:35:52 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 10.10.0.187: /RadioBrowse?service=Capture
    May 13 22:35:53 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 10.10.0.187: /Playlist?length=1
    May 13 22:35:53 (none) user.info ./ms.pl: I18N::setLocale I18N.pm (122) en-us(en-us) -> en-US => en-US
    May 13 22:35:53 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 10.10.0.187: /Artwork?cacheService=&followRedirects=1&height=600&service=&width=600
    May 13 22:35:53 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 10.10.0.187: /Artwork?album=&artist=&cacheAlbum=&cacheArtist=&cacheService=&followRedirects=1&height=600&service=&width=600
    May 13 22:35:53 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 10.10.0.187: /Services?schemaVersion=26
    May 13 22:35:53 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 10.10.0.187: /RadioBrowse?service=Capture
    May 13 22:35:54 (none) user.info stage0 0.30: start
    May 13 22:35:54 (none) user.info stage0 0.30: starting httpd service
    May 13 22:35:54 (none) user.info stage0 0.30: starting upgrade check timer
    May 13 22:35:54 (none) daemon.info chronyd[1077]: System's initial offset : 11.680814 seconds fast of true (step)
    May 13 22:35:45 (none) user.info ./cp.pl: main::fetchAll ./cp.pl (5204) fetchAll from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching Lastfmuser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching Amazonuser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching Bugsuser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching CalmRadiouser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching CustomChannelsuser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching Deezeruser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching DMHuser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching Idagiouser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching iHRuser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching KKboxuser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching Napsteruser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching Nugsuser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching Primephonicuser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching Qobuzuser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching Qsicuser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching RadioParadiseuser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching RadioComuser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching Slackeruser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching SoundMachineuser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching Tidaluser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching WiMPuser from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching network_serial from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching artfile.bin from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching artfile.db from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching metadata_flip.sq3 from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching metadata_flop.sq3 from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching imported_playlists.db from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching stored_playlists.db from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching resizeall_enable from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching favs.db from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::fetch_files Replicator.pm (294) fetching shares from 10.10.0.127
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: CalmRadiouser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: Bugsuser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: CustomChannelsuser status=404, no rename
    May 13 22:35:45 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Services
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: Deezeruser status=404, no rename
    May 13 22:35:45 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /RadioBrowse?service=Capture
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: Amazonuser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: Lastfmuser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: DMHuser status=404, no rename
    May 13 22:35:45 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 10.10.0.187: /Services?schemaVersion=26
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: Idagiouser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: iHRuser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: KKboxuser status=404, no rename
    May 13 22:35:45 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 10.10.0.187: /RadioBrowse?service=Capture
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: Napsteruser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: Nugsuser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: Primephonicuser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: Qobuzuser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: Qsicuser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: RadioParadiseuser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: RadioComuser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: Slackeruser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: SoundMachineuser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: Tidaluser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: WiMPuser status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: artfile.bin status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: artfile.db status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: metadata_flip.sq3 status=304, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: metadata_flop.sq3 status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: imported_playlists.db status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: network_serial status=412, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: resizeall_enable status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: favs.db status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: stored_playlists.db status=404, no rename
    May 13 22:35:45 (none) user.debug ./cp.pl: Replicator::__ANON__ Replicator.pm (375) term: shares status=404, no rename
    May 13 22:35:49 (none) daemon.info chronyd[1077]: Selected source 10.10.0.127
    May 13 22:35:52 (none) daemon.info chronyd[1077]: Selected source 192.36.143.130
    May 13 22:36:09 (none) daemon.info chronyd[1077]: Selected source 10.10.0.127
    May 13 22:37:01 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /GetSettings
    May 13 22:37:34 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Volume?etag=9b8a5df0c6445bc881dfebfd0d2d0fb9&timeout=120
    May 13 22:37:39 (none) user.info ./ms.pl: I18N::setLocale I18N.pm (122) en-GB(en-GB) -> en-GB => en
    May 13 22:37:40 (none) user.info ./ms.pl: I18N::setLocale I18N.pm (122) en-US(en-US) -> en-US => en-US
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 0 e0:63:da:53:8b:d3
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 1 e2:63:da:13:8b:d3
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 2 e2:63:da:23:8b:d3
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 3 e2:63:da:13:8a:47
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 4 e2:63:da:23:8a:47
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 5 e0:63:da:53:8a:47
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 6 e0:63:da:53:8d:07
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 7 e2:63:da:13:8d:07
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 8 e2:63:da:23:8d:07
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 10 0e:ec:da:11:bd:2a
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 11 e2:63:da:13:8b:d2
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 12 e2:63:da:23:8b:d2
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 13 10:7b:ef:7c:ca:60
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 14 5c:6a:80:31:8a:d2
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 15 a0:e4:cb:2f:bc:01
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 16 10:7b:ef:7c:ca:61
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 17 e2:63:da:13:8a:46
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 18 e0:63:da:53:8a:46
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 19 e2:63:da:23:8a:46
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 20 e2:63:da:13:8d:06
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 21 e2:63:da:23:8d:06
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 22 e0:63:da:53:8d:06
    May 13 22:38:27 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-REMOVED 23 a4:2b:8c:f4:d8:b8
    May 13 22:38:42 (none) user.info stage0 0.30: periodic upgrade check
    May 13 22:38:43 (none) user.emerg stage0 0.30: Can't locate object method "tid" via package "threads" at /usr/lib/perl5/5.14.2/XSLoader.pm line 95.
    May 13 22:38:43 (none) user.emerg stage0 0.30:  at /usr/lib/perl5/5.14.2/XSLoader.pm line 95
    May 13 22:38:43 (none) user.info stage0 0.30: Component              Local          Target
    May 13 22:38:43 (none) user.info stage0 0.30: rootfs              20200320        20200320
    May 13 22:38:43 (none) user.info stage0 0.30: ddfa                    none            none
    May 13 22:38:43 (none) user.info stage0 0.30: bridge                  none            none
    May 13 22:38:43 (none) user.info stage0 0.30: pic32          P100-20191115   P100-20191115
    May 13 22:38:43 (none) user.info stage0 0.30: proxy                      -               -
    May 13 22:39:34 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Volume?etag=9b8a5df0c6445bc881dfebfd0d2d0fb9&timeout=120
    May 13 22:40:21 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /GetSettings
    May 13 22:41:34 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Volume?etag=9b8a5df0c6445bc881dfebfd0d2d0fb9&timeout=120
    May 13 22:42:48 (none) user.info ./cp.pl: I18N::setLocale I18N.pm (122) en-us(en-us) -> en-US => en
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-SCAN-STARTED 
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 24 e2:63:da:23:8a:47
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 25 e0:63:da:53:8a:47
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 26 e2:63:da:13:8a:47
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 27 e0:63:da:53:8d:07
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 28 e2:63:da:13:8d:07
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 29 e2:63:da:23:8d:07
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 30 e2:63:da:23:8d:06
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 31 e0:63:da:53:8d:06
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 32 e2:63:da:13:8d:06
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 33 0e:ec:da:11:bd:2a
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 34 10:7b:ef:7c:ca:60
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 35 5c:6a:80:31:8a:d2
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 36 5c:6a:80:90:d1:5c
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 37 08:9e:08:f9:14:85
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 38 a0:e4:cb:2f:bc:01
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 39 e0:63:da:53:8b:d3
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 40 e2:63:da:13:8b:d3
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 41 e2:63:da:23:8b:d3
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 42 10:7b:ef:7c:ca:61
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 43 e2:63:da:13:8a:46
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 44 e2:63:da:23:8a:46
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 45 e0:63:da:53:8a:46
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-BSS-ADDED 46 a4:2b:8c:f4:d8:b8
    May 13 22:42:57 (none) user.debug ./cp.pl: IW::__ANON__ IW.pm (566) wlan0: <3>CTRL-EVENT-SCAN-RESULTS 
    May 13 22:42:59 (none) user.info RAAT: [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003
    May 13 22:42:59 (none) user.info RAAT: [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:48938
    May 13 22:42:59 (none) user.info RAAT: [discovery] [iface:10.10.0.112] multicast recv socket is bound to 0.0.0.0:9003
    May 13 22:42:59 (none) user.info RAAT: [discovery] [iface:10.10.0.112] multicast send socket is bound to 0.0.0.0:47379
    May 13 22:42:59 (none) user.info RAAT: [discovery] unicast socket is bound to 0.0.0.0:9003
    May 13 22:43:13 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Songs?length=1
    May 13 22:43:29 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Songs?length=1
    May 13 22:43:34 (none) user.info ./ms.pl: main::HttpRequest ./ms.pl (1030) [1] 127.0.0.1: /Volume?etag=9b8a5df0c6445bc881dfebfd0d2d0fb9&timeout=120
    May 13 22:43:35 (none) daemon.info chronyd[1077]: Selected source 185.35.202.197
    
    vardata
           4096 May 13 22:35 upgrade/
             17 May 13 22:35 node_initialized_semaphore
           4096 May 13 22:35 airplay/
           4096 May 13 22:35 raat/
            264 May 13 22:35 raat.json
           4096 May 13 22:35 spotify/
             44 May 13 22:35 udhcpd.leases
            132 May 13 22:35 wpa.conf
             37 May 13 22:33 led_settings.conf
            230 May 13 22:33 hostapd.conf
              8 May 13 22:33 last_volume_level_db
             11 May 13 22:33 dnsd.conf
            190 May 13 22:33 udhcpd.conf
              0 May 13 22:33 pcm_rtp_socket01
              5 May 11 23:38 daily_report_time
              0 May 11 23:28 Initialized
           4096 May 11 23:25 log/
             42 May 11 23:25 chrony.drift
           1696 May 11 23:23 auth-server-cert.pem
           3272 May 11 23:23 auth-server-key.pem
             18 Aug 19  2017 network_serial
         143360 Jan  1  2000 metadata_flip.sq3
             24 Mar  8  1970 player_name
             36 Mar  8  1970 analysis_uuid
           4096 Mar  8  1970 etc/
           4096 Mar  8  1970 samba/
           4096 Mar  8  1970 ci/
             36 Mar  8  1970 uuid
          16384 Mar  8  1970 lost+found/
    
    ntp
    Reference ID    : B923CAC5 (nux.hackeriet.no)
    Stratum         : 3
    Ref time (UTC)  : Wed May 13 22:43:23 2020
    System time     : 0.000181166 seconds fast of NTP time
    Last offset     : +0.000047816 seconds
    RMS offset      : 0.000214511 seconds
    Frequency       : 28.802 ppm slow
    Residual freq   : +0.018 ppm
    Skew            : 1.076 ppm
    Root delay      : 0.006979738 seconds
    Root dispersion : 0.000309988 seconds
    Update interval : 64.9 seconds
    Leap status     : Normal
    210 Number of sources = 5
    MS Name/IP address         Stratum Poll Reach LastRx Last sample               
    ===============================================================================
    ^+ PULSE2Flex-Stua.private       3   6   377    66   +110us[ +150us] +/- 4632us
    ^- tikk.signal.no                2   6   377    13  +4642us[+4688us] +/-   81ms
    ^- 77-95-77-250.bb.cust.hkn>     2   6   377    13  +2728us[+2774us] +/-   46ms
    ^+ time100.stupi.se              1   6   377    13   -136us[  -90us] +/- 5448us
    ^* nux.hackeriet.no              2   6   377    13  +7944ns[  +54us] +/- 3491us
    210 Number of sources = 5
    Name/IP Address            NP  NR  Span  Frequency  Freq Skew  Offset  Std Dev
    ==============================================================================
    PULSE2Flex-Stua.private    33  17   403     -0.274      1.197   +191us   207us
    tikk.signal.no             11   8   458     -0.575      3.343  +4543us   352us
    77-95-77-250.bb.cust.hkn>   7   4   389     +1.711     25.486  +2190us  1329us
    time100.stupi.se           11   5   458     +0.328      1.740   -191us   222us
    nux.hackeriet.no           11   9   458     +0.315      4.378    -25us   518us
    
    dmesg
    [    0.000000] Booting Linux on physical CPU 0x0
    [    0.000000] Linux version 5.0.0-00746-g39111bac80a4 (bluesound@obsidian3) (gcc version 6.3.1 20170404 (Linaro GCC 6.3-2017.05)) #1 SMP Thu Mar 19 11:56:45 EDT 2020
    [    0.000000] CPU: ARMv7 Processor [412fc09a] revision 10 (ARMv7), cr=10c5387d
    [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
    [    0.000000] OF: fdt: Machine model: Bluesound i.MX6 SoloLite P125
    [    0.000000] Memory policy: Data cache writealloc
    [    0.000000] cma: Reserved 16 MiB at 0x8ec00000
    [    0.000000] On node 0 totalpages: 65536
    [    0.000000]   Normal zone: 512 pages used for memmap
    [    0.000000]   Normal zone: 0 pages reserved
    [    0.000000]   Normal zone: 65536 pages, LIFO batch:15
    [    0.000000] percpu: Embedded 16 pages/cpu @(ptrval) s34636 r8192 d22708 u65536
    [    0.000000] pcpu-alloc: s34636 r8192 d22708 u65536 alloc=16*4096
    [    0.000000] pcpu-alloc: [0] 0 
    [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 65024
    [    0.000000] Kernel command line: console=ttymxc0,115200 root=/dev/mmcblk0p2 rootwait ro rootfstype=ext4
    [    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
    [    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
    [    0.000000] Memory: 232484K/262144K available (6144K kernel code, 238K rwdata, 2460K rodata, 1024K init, 318K bss, 13276K reserved, 16384K cma-reserved, 0K highmem)
    [    0.000000] Virtual kernel memory layout:
    [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
    [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
    [    0.000000]     vmalloc : 0x90800000 - 0xff800000   (1776 MB)
    [    0.000000]     lowmem  : 0x80000000 - 0x90000000   ( 256 MB)
    [    0.000000]     pkmap   : 0x7fe00000 - 0x80000000   (   2 MB)
    [    0.000000]     modules : 0x7f000000 - 0x7fe00000   (  14 MB)
    [    0.000000]       .text : 0x(ptrval) - 0x(ptrval)   (7136 kB)
    [    0.000000]       .init : 0x(ptrval) - 0x(ptrval)   (1024 kB)
    [    0.000000]       .data : 0x(ptrval) - 0x(ptrval)   ( 239 kB)
    [    0.000000]        .bss : 0x(ptrval) - 0x(ptrval)   ( 319 kB)
    [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
    [    0.000000] rcu: Hierarchical RCU implementation.
    [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=4 to nr_cpu_ids=1.
    [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
    [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
    [    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
    [    0.000000] L2C-310 erratum 769419 enabled
    [    0.000000] L2C-310 enabling early BRESP for Cortex-A9
    [    0.000000] L2C-310 full line of zeros enabled for Cortex-A9
    [    0.000000] L2C-310 ID prefetch enabled, offset 16 lines
    [    0.000000] L2C-310 dynamic clock gating enabled, standby mode enabled
    [    0.000000] L2C-310 cache controller enabled, 16 ways, 256 kB
    [    0.000000] L2C-310: CACHE_ID 0x410000c8, AUX_CTRL 0x76430001
    [    0.000000] Switching to timer-based delay loop, resolution 41ns
    [    0.000007] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 89478484971ns
    [    0.000025] clocksource: mxc_timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
    [    0.001101] Console: colour dummy device 80x30
    [    0.001133] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=240000)
    [    0.001149] pid_max: default: 32768 minimum: 301
    [    0.001298] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.001312] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.001848] CPU: Testing write buffer coherency: ok
    [    0.001889] CPU0: Spectre v2: using BPIALL workaround
    [    0.002119] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
    [    0.002648] Setting up static identity map for 0x80100000 - 0x80100060
    [    0.002806] rcu: Hierarchical SRCU implementation.
    [    0.003312] smp: Bringing up secondary CPUs ...
    [    0.003324] smp: Brought up 1 node, 1 CPU
    [    0.003334] SMP: Total of 1 processors activated (48.00 BogoMIPS).
    [    0.003343] CPU: All CPU(s) started in SVC mode.
    [    0.004224] devtmpfs: initialized
    [    0.009667] random: get_random_u32 called from bucket_table_alloc+0x140/0x174 with crng_init=0
    [    0.009839] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4
    [    0.010144] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
    [    0.010169] futex hash table entries: 256 (order: 2, 16384 bytes)
    [    0.011007] pinctrl core: initialized pinctrl subsystem
    [    0.011891] NET: Registered protocol family 16
    [    0.013500] DMA: preallocated 256 KiB pool for atomic coherent allocations
    [    0.021227] vdd1p1: supplied by regulator-dummy
    [    0.021754] vdd3p0: supplied by regulator-dummy
    [    0.022276] vdd2p5: supplied by regulator-dummy
    [    0.022793] vddarm: supplied by regulator-dummy
    [    0.023320] vddpu: supplied by regulator-dummy
    [    0.023831] vddsoc: supplied by regulator-dummy
    [    0.032396] hw-breakpoint: found 5 (+1 reserved) breakpoint and 1 watchpoint registers.
    [    0.032409] hw-breakpoint: maximum watchpoint size is 4 bytes.
    [    0.033168] imx6sl-pinctrl 20e0000.iomuxc: Invalid fsl,pins or pins property in node /soc/aips-bus@2000000/iomuxc@20e0000/imx6sl-nad/gpiokeysgrp
    [    0.033249] imx6sl-pinctrl 20e0000.iomuxc: initialized IMX pinctrl driver
    [    0.052407] SCSI subsystem initialized
    [    0.052463] libata version 3.00 loaded.
    [    0.052687] usbcore: registered new interface driver usbfs
    [    0.052741] usbcore: registered new interface driver hub
    [    0.052844] usbcore: registered new device driver usb
    [    0.053780] i2c i2c-0: IMX I2C adapter registered
    [    0.053801] i2c i2c-0: can't use DMA, using PIO instead.
    [    0.054430] i2c i2c-1: IMX I2C adapter registered
    [    0.054451] i2c i2c-1: can't use DMA, using PIO instead.
    [    0.054959] i2c i2c-2: IMX I2C adapter registered
    [    0.054977] i2c i2c-2: can't use DMA, using PIO instead.
    [    0.055244] pps_core: LinuxPPS API ver. 1 registered
    [    0.055255] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
    [    0.055279] PTP clock support registered
    [    0.055516] Advanced Linux Sound Architecture Driver Initialized.
    [    0.056719] Bluetooth: Core ver 2.22
    [    0.056774] NET: Registered protocol family 31
    [    0.056783] Bluetooth: HCI device and connection manager initialized
    [    0.056798] Bluetooth: HCI socket layer initialized
    [    0.056809] Bluetooth: L2CAP socket layer initialized
    [    0.057224] clocksource: Switched to clocksource mxc_timer1
    [    0.057469] FS-Cache: Loaded
    [    0.066270] NET: Registered protocol family 2
    [    0.066864] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes)
    [    0.066899] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.066942] TCP bind hash table entries: 2048 (order: 2, 16384 bytes)
    [    0.066980] TCP: Hash tables configured (established 2048 bind 2048)
    [    0.067068] UDP hash table entries: 256 (order: 1, 8192 bytes)
    [    0.067097] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
    [    0.067436] NET: Registered protocol family 1
    [    0.067937] RPC: Registered named UNIX socket transport module.
    [    0.067950] RPC: Registered udp transport module.
    [    0.067957] RPC: Registered tcp transport module.
    [    0.067963] RPC: Registered tcp NFSv4.1 backchannel transport module.
    [    0.067976] PCI: CLS 0 bytes, default 64
    [    0.068648] hw perfevents: enabled with armv7_cortex_a9 PMU driver, 7 counters available
    [    0.072294] workingset: timestamp_bits=30 max_order=16 bucket_order=0
    [    0.078100] NFS: Registering the id_resolver key type
    [    0.078129] Key type id_resolver registered
    [    0.078137] Key type id_legacy registered
    [    0.078725] fuse init (API version 7.28)
    [    0.088722] NET: Registered protocol family 38
    [    0.089051] io scheduler mq-deadline registered
    [    0.089063] io scheduler kyber registered
    [    0.091933] imx-sdma 20ec000.sdma: Direct firmware load for imx/sdma/sdma-imx6q.bin failed with error -2
    [    0.091954] imx-sdma 20ec000.sdma: external firmware not found, using ROM firmware
    [    0.095485] imx-pgc-pd imx-pgc-power-domain.0: DMA mask not set
    [    0.095560] imx-pgc-pd imx-pgc-power-domain.0: Linked as a consumer to 20dc000.gpc
    [    0.095624] imx-pgc-pd imx-pgc-power-domain.1: DMA mask not set
    [    0.095722] imx-pgc-pd imx-pgc-power-domain.1: Linked as a consumer to regulator.5
    [    0.095779] PU : no governor for states
    [    0.095810] imx-pgc-pd imx-pgc-power-domain.1: Linked as a consumer to 20dc000.gpc
    [    0.095877] imx-pgc-pd imx-pgc-power-domain.2: DMA mask not set
    [    0.096551] imx-pgc-pd imx-pgc-power-domain.2: Linked as a consumer to 20dc000.gpc
    [    0.098472] pfuze100-regulator 1-0008: Full layer: 2, Metal layer: 1
    [    0.099120] pfuze100-regulator 1-0008: FAB: 0, FIN: 0
    [    0.099134] pfuze100-regulator 1-0008: pfuze100 found.
    [    0.116629] 2020000.serial: ttymxc0 at MMIO 0x2020000 (irq = 21, base_baud = 5000000) is a IMX
    [    0.886104] printk: console [ttymxc0] enabled
    [    0.891234] 2034000.serial: ttymxc2 at MMIO 0x2034000 (irq = 25, base_baud = 5000000) is a IMX
    [    0.910730] loop: module loaded
    [    0.968416] nad-nmb-imx6 nmb: PCB rev 2
    [    0.973731] libphy: Fixed MDIO Bus: probed
    [    1.097353] fec 2188000.ethernet: Linked as a consumer to regulator.11
    [    1.170319] random: fast init done
    [    1.174391] libphy: fec_enet_mii_bus: probed
    [    1.179629] usbcore: registered new interface driver asix
    [    1.185084] usbcore: registered new interface driver ax88179_178a
    [    1.191313] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
    [    1.197906] ehci-mxc: Freescale On-Chip EHCI Host driver
    [    1.203407] usbcore: registered new interface driver usb-storage
    [    1.216517] imx_usb 2184200.usb: 2184200.usb supply vbus not found, using dummy regulator
    [    1.224867] imx_usb 2184200.usb: Linked as a consumer to regulator.0
    [    1.235035] ci_hdrc ci_hdrc.1: EHCI Host Controller
    [    1.239990] ci_hdrc ci_hdrc.1: new USB bus registered, assigned bus number 1
    [    1.277263] ci_hdrc ci_hdrc.1: USB 2.0 started, EHCI 1.00
    [    1.283456] hub 1-0:1.0: USB hub found
    [    1.287332] hub 1-0:1.0: 1 port detected
    [    1.293252] snvs_rtc 20cc000.snvs:snvs-rtc-lp: registered as rtc0
    [    1.299659] i2c /dev entries driver
    [    1.303614] IR NEC protocol handler initialized
    [    1.308232] IR RC5(x/sz) protocol handler initialized
    [    1.313290] IR RC6 protocol handler initialized
    [    1.317847] IR JVC protocol handler initialized
    [    1.322380] IR Sony protocol handler initialized
    [    1.326998] IR SANYO protocol handler initialized
    [    1.331723] IR Sharp protocol handler initialized
    [    1.336430] IR MCE Keyboard/mouse protocol handler initialized
    [    1.342281] IR XMP protocol handler initialized
    [    1.346814] ir_imon_decoder: IR iMON protocol handler initialized
    [    1.352926] IR Denon protocol handler initialized
    [    1.357650] IR Metz protocol handler initialized
    [    1.362905] imx2-wdt 20bc000.wdog: timeout 60 sec (nowayout=1)
    [    1.369048] usbcore: registered new interface driver bcm203x
    [    1.374764] usbcore: registered new interface driver btusb
    [    1.380350] usbcore: registered new interface driver ath3k
    [    1.386282] sdhci: Secure Digital Host Controller Interface driver
    [    1.392510] sdhci: Copyright(c) Pierre Ossman
    [    1.396871] sdhci-pltfm: SDHCI platform and OF driver helper
    [    1.404548] sdhci-esdhc-imx 2190000.usdhc: Linked as a consumer to regulator.7
    [    1.411918] sdhci-esdhc-imx 2190000.usdhc: Linked as a consumer to regulator.8
    [    1.419303] mmc0 bounce up to 128 segments into one, max segment size 65536 bytes
    [    1.459959] mmc0: SDHCI controller on 2190000.usdhc [2190000.usdhc] using DMA
    [    1.469536] sdhci-esdhc-imx 2198000.usdhc: Linked as a consumer to regulator.9
    [    1.476911] sdhci-esdhc-imx 2198000.usdhc: Linked as a consumer to regulator.10
    [    1.484450] mmc1 bounce up to 128 segments into one, max segment size 65536 bytes
    [    1.526087] mmc1: SDHCI controller on 2198000.usdhc [2198000.usdhc] using DMA
    [    1.537004] usbcore: registered new interface driver usbhid
    [    1.542661] usbhid: USB HID core driver
    [    1.548685] snd_dummy: probe of snd_dummy.0 failed with error -22
    [    1.561117] mmc0: Switching to 1.8V signalling voltage failed
    [    1.568449] p100-codec 0-004c: hybridflow=P125 rev 2, volume max=3.0, speaker offset=-3.0
    [    1.576714] p100-codec 0-004c: setup amp (speaker out)
    [    1.582174] mmc0: new DDR MMC card at address 0001
    [    1.588313] fsl-ssi-dai 2028000.ssi: No cache defaults, reading back from HW
    [    1.596507] mmcblk0: mmc0:0001 MMC02G 1.79 GiB 
    [    1.601914] mmcblk0boot0: mmc0:0001 MMC02G partition 1 1.00 MiB
    [    1.608598] fsl-ssi-dai 202c000.ssi: No cache defaults, reading back from HW
    [    1.616379] mmcblk0boot1: mmc0:0001 MMC02G partition 2 1.00 MiB
    [    1.622451] mmc1: queuing unknown CIS tuple 0x01 (3 bytes)
    [    1.628530] fsl-ssi-dai 2030000.ssi: No cache defaults, reading back from HW
    [    1.637574] mmcblk0rpmb: mmc0:0001 MMC02G partition 3 128 KiB, chardev (246:0)
    [    1.649618] imx-n110-analog sound_analog: p100-codec <-> 2028000.ssi mapping ok
    [    1.657321] usb 1-1: new high-speed USB device number 2 using ci_hdrc
    [    1.665531] fsl-ssi-dai 2028000.ssi: invalid slot width: 32
    [    1.676138]  mmcblk0: p1 p2 p3
    [    1.682371] mmc1: queuing unknown CIS tuple 0x1a (5 bytes)
    [    1.689733] imx-spdif sound_spdif: snd-soc-dummy-dai <-> 2004000.spdif mapping ok
    [    1.700424] imx-n110-bt sound_bt: codec_bt <-> 202c000.ssi mapping ok
    [    1.708117] mmc1: queuing unknown CIS tuple 0x1b (8 bytes)
    [    1.715541] imx-nad-adc sound_adc: codec_pcm1803 <-> 2030000.ssi mapping ok
    [    1.723015] mmc1: queuing unknown CIS tuple 0x14 (0 bytes)
    [    1.728763] fsl-ssi-dai 2030000.ssi: invalid slot width: 32
    [    1.738508] NET: Registered protocol family 10
    [    1.745872] mmc1: queuing unknown CIS tuple 0x80 (1 bytes)
    [    1.752090] Segment Routing with IPv6
    [    1.755850] NET: Registered protocol family 17
    [    1.760504] mmc1: queuing unknown CIS tuple 0x81 (1 bytes)
    [    1.766158] Key type dns_resolver registered
    [    1.770759] mmc1: queuing unknown CIS tuple 0x82 (1 bytes)
    [    1.776635] cpu cpu0: Linked as a consumer to regulator.4
    [    1.782143] mmc1: new high speed SDIO card at address 0001
    [    1.787746] cpu cpu0: Linked as a consumer to regulator.5
    [    1.793218] cpu cpu0: Linked as a consumer to regulator.6
    [    1.799558] cpu cpu0: running cpufreq without cooling device: 0
    [    1.805549] Registering SWP/SWPB emulation handler
    [    1.819110] snvs_rtc 20cc000.snvs:snvs-rtc-lp: setting system clock to 2020-05-13T22:33:26 UTC (1589409206)
    [    1.829375] SW4: disabling
    [    1.832100] SWBST: disabling
    [    1.835522] VGEN2: disabling
    [    1.838999] VGEN4: disabling
    [    1.842408] VGEN5: disabling
    [    1.845812] VGEN6: disabling
    [    1.849223] ALSA device list:
    [    1.852196]   #0: imx-n110-analog
    [    1.855515]   #1: imx-spdif
    [    1.858351]   #2: imx-n110-bt
    [    1.861323]   #3: imx-nad-adc
    [    1.866552] hub 1-1:1.0: USB hub found
    [    1.870585] hub 1-1:1.0: 4 ports detected
    [    1.888717] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
    [    1.896854] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
    [    1.923737] devtmpfs: mounted
    [    1.932048] Freeing unused kernel memory: 1024K
    [    1.937012] Run /sbin/init as init process
    [    2.380206] nad-pic32 nad-pic32-0: open line discipline on ttymxc2
    [    2.386550] input: nad-pic32-keyboard as /devices/soc0/soc/2000000.aips-bus/2000000.spba-bus/2034000.serial/tty/ttymxc2/nad-pic32-0/input0
    [    2.400728] Registered IR keymap rc-empty
    [    2.404978] rc rc0: nad-pic32-rc as /devices/soc0/soc/2000000.aips-bus/2000000.spba-bus/2034000.serial/tty/ttymxc2/nad-pic32-0/rc0
    [    2.417374] input: nad-pic32-rc as /devices/soc0/soc/2000000.aips-bus/2000000.spba-bus/2034000.serial/tty/ttymxc2/nad-pic32-0/rc0/input1
    [    2.449790] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null)
    [    2.990172] p100-codec 0-004c: setup amp ok
    [    3.385414] EXT4-fs (mmcblk0p3): recovery complete
    [    3.860915] EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: commit=30
    [    3.996904] udevd (165): /proc/165/oom_adj is deprecated, please use /proc/165/oom_score_adj instead.
    [    4.511931] synth uevent: /devices/soc0/soc/2000000.aips-bus/2000000.spba-bus/2034000.serial/tty/ttymxc2/nad-pic32-0/rc0/input1: failed to send uevent
    [    4.511941] input input1: uevent: failed to send synthetic uevent
    [    5.534347] mxs-dcp 20fc000.dcp: mxs_dcp: initialized
    [    5.543443] mxs_dcp: WARNING - not in Trusted or Secure State, Non-volatile Test Key in effect
    [    5.918002] ath10k_sdio mmc1:0001:1: WARNING: ath10k SDIO support is incomplete, don't expect anything to work!
    [    6.074334] ath10k_sdio mmc1:0001:1: qca9377 hw1.1 sdio target 0x05020001 chip_id 0x00000000 sub 0000:0000
    [    6.084093] ath10k_sdio mmc1:0001:1: kconfig debug 0 debugfs 0 tracing 0 dfs 0 testmode 0
    [    6.095767] ath10k_sdio mmc1:0001:1: firmware ver WLAN.TF.1.1.1-00061-QCATFSWPZ-1 api 5 features ignore-otp crc32 7746e551
    [    6.515573] ath10k_sdio mmc1:0001:1: board_file api 1 bmi_id N/A crc32 dfcad459
    [    7.063768] random: sovi-hal-qt: uninitialized urandom read (4 bytes read)
    [    7.577332] random: sovi-hal-qt: uninitialized urandom read (4 bytes read)
    [    7.689351] random: crng init done
    [    8.188971] ath10k_sdio mmc1:0001:1: unsupported HTC service id: 1536
    [    8.215570] ath: EEPROM regdomain: 0x6c
    [    8.215576] ath: EEPROM indicates we should expect a direct regpair map
    [    8.215586] ath: Country alpha2 being used: 00
    [    8.215590] ath: Regpair used: 0x6c
    [    8.837498] SMSC LAN8710/LAN8720 2188000.ethernet-1:00: attached PHY driver [SMSC LAN8710/LAN8720] (mii_bus:phy_addr=2188000.ethernet-1:00, irq=POLL)
    [   22.540111] mmc1: queuing unknown CIS tuple 0x01 (3 bytes)
    [   22.555109] mmc1: queuing unknown CIS tuple 0x1a (5 bytes)
    [   22.564766] mmc1: queuing unknown CIS tuple 0x1b (8 bytes)
    [   22.571946] mmc1: queuing unknown CIS tuple 0x14 (0 bytes)
    [   24.088867] ath10k_sdio mmc1:0001:1: unsupported HTC service id: 1536
    [   24.266811] mmc1: queuing unknown CIS tuple 0x01 (3 bytes)
    [   24.281903] mmc1: queuing unknown CIS tuple 0x1a (5 bytes)
    [   24.291552] mmc1: queuing unknown CIS tuple 0x1b (8 bytes)
    [   24.297820] mmc1: queuing unknown CIS tuple 0x14 (0 bytes)
    [   25.798939] ath10k_sdio mmc1:0001:1: unsupported HTC service id: 1536
    [   26.012342] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
    [  122.343303] mmc1: queuing unknown CIS tuple 0x01 (3 bytes)
    [  122.357960] mmc1: queuing unknown CIS tuple 0x1a (5 bytes)
    [  122.367600] mmc1: queuing unknown CIS tuple 0x1b (8 bytes)
    [  122.374436] mmc1: queuing unknown CIS tuple 0x14 (0 bytes)
    [  123.878885] ath10k_sdio mmc1:0001:1: unsupported HTC service id: 1536
    [  124.053330] mmc1: queuing unknown CIS tuple 0x01 (3 bytes)
    [  124.067877] mmc1: queuing unknown CIS tuple 0x1a (5 bytes)
    [  124.077497] mmc1: queuing unknown CIS tuple 0x1b (8 bytes)
    [  124.084357] mmc1: queuing unknown CIS tuple 0x14 (0 bytes)
    [  125.598923] ath10k_sdio mmc1:0001:1: unsupported HTC service id: 1536
    [  130.592920] wlan0: authenticate with fe:ec:da:11:bd:2a
    [  130.644488] wlan0: send auth to fe:ec:da:11:bd:2a (try 1/3)
    [  132.065437] wlan0: send auth to fe:ec:da:11:bd:2a (try 2/3)
    [  132.075424] wlan0: authenticated
    [  132.087333] wlan0: associate with fe:ec:da:11:bd:2a (try 1/3)
    [  132.102808] wlan0: RX AssocResp from fe:ec:da:11:bd:2a (capab=0x1431 status=0 aid=3)
    [  132.127662] wlan0: associated
    [  132.139739] ath: EEPROM regdomain: 0x8242
    [  132.139745] ath: EEPROM indicates we should expect a country code
    [  132.139749] ath: doing EEPROM country->regdmn map search
    [  132.139755] ath: country maps to regdmn code: 0x37
    [  132.139759] ath: Country alpha2 being used: NO
    [  132.139761] ath: Regpair used: 0x37
    [  132.139766] ath: regdomain 0x8242 dynamically updated by country element
    [  132.212258] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
    [  132.264816] wlan0: Limiting TX power to 20 (20 - 0) dBm as advertised by fe:ec:da:11:bd:2a
    
    name
    PULSE FLEX 2i - Soverom
    
    xml
    <?xml version="1.0" encoding="UTF-8" standalone="yes"?>
    <info>
    	<versions>
    		<brand>Bluesound</brand>
    		<board>P125</board>
    		<name>PULSE FLEX 2i</name>
    		<git>3.8.8</git>
    		<rootfs>20200320</rootfs>
    		<ddfa>none</ddfa>
    		<bridge>none</bridge>
    		<pic32>P100-20191115</pic32>
    	</versions>
    	<settings>
    		<alarms>default</alarms>
    		<alsa>
    			<conf file="asound.conf.tone_vol" usbsubfile="asound.conf.tone_vol_usbsub" usbsubcard="4" fixedrate="48000"/>
    			<eq id="switch" displayName="{A_TONE_CONTROL_LABEL}" icon="/images/settings/ic_eqonoff.png" control="iface=MIXER,name='Tone Fixed Gain'" default="off" type="onoff" />
    			<eq id="treble" displayName="{A_TREBLE}" icon="icon_treble.png" control="iface=MIXER,name='Treble Playback Volume'" default="12" units="dB" type="treble">
    				<dependsOn name="switch" value="ON" />
    			</eq>
    			<eq id="bass"   displayName="{A_BASS}"   icon="icon_bass.png"   control="iface=MIXER,name='Bass Playback Volume'"   default="12" units="dB" type="bass">
    				<dependsOn name="switch" value="ON" />
    			</eq>
    		</alsa>
    		<dspout_args>--mqa-decode-only --has-sub --with-ac3-capture --output-delay 810</dspout_args>
    		<!-- TAS5754M; measured at ~810µs on P125 -->
    		<amp card="imxn110analog">tas5754</amp>
    		<airplay>yes</airplay>
    		<apple_authentication_coprocessor>yes</apple_authentication_coprocessor>
    		<bt>csr8645</bt>
    		<capture>
    			<device id="input0" name="{ANALOG_INPUT}"
    				type="analog"
    				hw="plughw:3,0" format="S24_3LE" channels="2" rate="48000" outputRateLocked="1" rateMin="32000" rateMax="96000"
    				plugFile="/sys/class/gpio/audio_in_detect_plug/value" plugValue="0"
    				typeFile="/sys/class/gpio/audio_in_detect_analog/value" typeValue="0"
    				dynamic="true"/>
    			<device id="input1" name="{OPTICAL_INPUT}"
    				type="spdif"
    				hw="hw:1,0" format="S24_LE" channels="2" rate="1" rateMin="32000" rateMax="192000"
    				plugFile="/sys/class/gpio/audio_in_detect_plug/value" plugValue="0"
    				typeFile="/sys/class/gpio/audio_in_detect_analog/value" typeValue="1"
    				dynamic="true"/>
    			<device id="input2" name="Bluetooth" type="bluetooth" hw="hw:2,0" icon="/images/BluetoothIcon.png" image="/images/BluetoothIconNP.png"/>
    		</capture>
    		<capture_monitor>hw:1,0</capture_monitor>
    		<cdrom>none</cdrom>
    		<crossover>usb</crossover>
    		<ddfa_dev>none</ddfa_dev>
    		<headphone>yes</headphone>
    		<hdd_devpath>none</hdd_devpath>
    		<irinput>yes</irinput>
    		<led-brightness min="0" max="5" default="5" dim="3"/>
    		<pic32_dev>/dev/ttymxc2</pic32_dev>
    		<raat>
    			<signalPath type="amplifier" method="bluos_digital" quality="enhanced"/>
    			<signalPath type="output" method="speakers" quality="lossless"/>
    		</raat>
    		<rs232>none</rs232>
    		<server>no</server>
    		<standby>yes</standby>
    		<can-disable-standby>yes</can-disable-standby>
    		<trigger_in>none</trigger_in>
    		<trigger_out_path>none</trigger_out_path>
    		<volume minLimit="-90" maxLimit="0" minDefault="-90" maxDefault="0" top="0" ramp="log" allowFixed="no" host="no"/>
    		<wifi has2p4GHz="1" has5GHz="1">auto</wifi>
    		<zoneOptions>
    		    <option zoneMaster="true">left</option>
    		    <option zoneMaster="true">right</option>
    		    <option zoneMaster="true">side_left</option>
    		    <option zoneMaster="true">side_right</option>
    		</zoneOptions>
    	</settings>
    </info>
    
    hdparm
    
    kleerstat
    
    wifi
    Connected to fe:ec:da:11:bd:2a (on wlan0)
    	SSID: 1397 Livet Min
    	freq: 2437
    	RX: 1010807 bytes (7125 packets)
    	TX: 1986639 bytes (2048 packets)
    	signal: -74 dBm
    	tx bitrate: 1.0 MBit/s
    
    	bss flags:	short-preamble short-slot-time
    	dtim period:	1
    	beacon int:	100
    
    cdperrors
    
    mounts
    /dev/root / ext4 ro,relatime 0 0
    devtmpfs /dev devtmpfs rw,relatime,size=116240k,nr_inodes=29060,mode=755 0 0
    none /proc proc rw,relatime 0 0
    none /sys sysfs rw,relatime 0 0
    none /proc/sys/debug debugfs rw,relatime 0 0
    none /config configfs rw,relatime 0 0
    tmpfs /tmp tmpfs rw,relatime 0 0
    tmpfs /dev tmpfs rw,relatime,mode=755 0 0
    /dev/mmcblk0p1 /tmp/mnt/upgrade ext4 ro,relatime 0 0
    /dev/mmcblk0p3 /tmp/var/data ext4 rw,noatime,nodiratime,commit=30 0 0
    devpts /dev/pts devpts rw,relatime,mode=600,ptmxmode=000 0 0
    shm /dev/shm tmpfs rw,relatime 0 0
    
    ps
    USER       PID %CPU %MEM    VSZ   RSS TTY      STAT START   TIME COMMAND
    root         1  0.2  0.5   2116  1256 ?        Ss   22:33   0:01 init      
    root         2  0.0  0.0      0     0 ?        S    22:33   0:00 [kthreadd]
    root         3  0.0  0.0      0     0 ?        I<   22:33   0:00 [rcu_gp]
    root         4  0.0  0.0      0     0 ?        I<   22:33   0:00 [rcu_par_gp]
    root         5  0.0  0.0      0     0 ?        I    22:33   0:00 [kworker/0:0-eve]
    root         6  0.1  0.0      0     0 ?        I<   22:33   0:00 [kworker/0:0H-mm]
    root         7  0.1  0.0      0     0 ?        I    22:33   0:01 [kworker/u2:0-at]
    root         8  0.0  0.0      0     0 ?        I<   22:33   0:00 [mm_percpu_wq]
    root         9  0.0  0.0      0     0 ?        S    22:33   0:00 [ksoftirqd/0]
    root        10  0.0  0.0      0     0 ?        I    22:33   0:00 [rcu_sched]
    root        11  0.0  0.0      0     0 ?        S    22:33   0:00 [migration/0]
    root        12  0.0  0.0      0     0 ?        S    22:33   0:00 [cpuhp/0]
    root        13  0.0  0.0      0     0 ?        S    22:33   0:00 [kdevtmpfs]
    root        14  0.0  0.0      0     0 ?        I    22:33   0:00 [kworker/0:1-eve]
    root        15  0.0  0.0      0     0 ?        S    22:33   0:00 [oom_reaper]
    root        16  0.0  0.0      0     0 ?        I<   22:33   0:00 [writeback]
    root        17  0.0  0.0      0     0 ?        S    22:33   0:00 [kcompactd0]
    root        18  0.0  0.0      0     0 ?        I<   22:33   0:00 [crypto]
    root        19  0.0  0.0      0     0 ?        I<   22:33   0:00 [kblockd]
    root        20  0.0  0.0      0     0 ?        S    22:33   0:00 [watchdogd]
    root        21  0.0  0.0      0     0 ?        I<   22:33   0:00 [rpciod]
    root        22  0.0  0.0      0     0 ?        I<   22:33   0:00 [kworker/u3:0]
    root        23  0.0  0.0      0     0 ?        I<   22:33   0:00 [xprtiod]
    root        37  0.0  0.0      0     0 ?        S    22:33   0:00 [kswapd0]
    root        38  0.0  0.0      0     0 ?        I<   22:33   0:00 [nfsiod]
    root        39  0.0  0.0      0     0 ?        I<   22:33   0:00 [cifsiod]
    root        40  0.0  0.0      0     0 ?        I<   22:33   0:00 [cifsoplockd]
    root        98  0.0  0.0      0     0 ?        I<   22:33   0:00 [ci_otg]
    root        99  0.0  0.0      0     0 ?        S    22:33   0:00 [irq/57-mmc0]
    root       100  0.4  0.0      0     0 ?        S    22:33   0:02 [irq/58-mmc1]
    root       101  0.1  0.0      0     0 ?        D    22:33   0:00 [kworker/0:2+eve]
    root       104  0.0  0.0      0     0 ?        I<   22:33   0:00 [p100-codec]
    root       105  0.0  0.0      0     0 ?        I<   22:33   0:00 [mmc_complete]
    root       107  0.0  0.0      0     0 ?        I<   22:33   0:00 [ipv6_addrconf]
    root       108  0.0  0.0      0     0 ?        I<   22:33   0:00 [kworker/0:2H-mm]
    root       109  0.0  0.0      0     0 ?        S    22:33   0:00 [jbd2/mmcblk0p2-]
    root       110  0.0  0.0      0     0 ?        I<   22:33   0:00 [ext4-rsv-conver]
    root       150  0.0  0.0      0     0 ?        S    22:33   0:00 [rc0]
    root       151  0.0  0.0   1672    52 ?        Ss   22:33   0:00 ldattach -s 115200 -8 -n -1 25 /dev/ttymxc2
    root       154  0.0  0.0      0     0 ?        S    22:33   0:00 [jbd2/mmcblk0p1-]
    root       155  0.0  0.0      0     0 ?        I<   22:33   0:00 [ext4-rsv-conver]
    root       157  0.0  0.0      0     0 ?        S    22:33   0:00 [jbd2/mmcblk0p3-]
    root       158  0.0  0.0      0     0 ?        I<   22:33   0:00 [ext4-rsv-conver]
    root       162  0.0  0.4   2372  1088 ?        Ss   22:33   0:00 syslogd -C256
    root       165  0.0  0.4   2016  1236 ?        S<s  22:33   0:00 udevd --daemon
    root       514  0.0  0.0      0     0 ?        S    22:33   0:00 [mxs_dcp_chan/sh]
    root       515  0.0  0.0      0     0 ?        S    22:33   0:00 [mxs_dcp_chan/ae]
    root       581  0.0  0.0      0     0 ?        I<   22:33   0:00 [cfg80211]
    root       640  0.0  0.0      0     0 ?        I<   22:33   0:00 [ath10k_wq]
    root       641  0.0  0.0      0     0 ?        I<   22:33   0:00 [ath10k_aux_wq]
    root       642  0.0  0.0      0     0 ?        I<   22:33   0:00 [ath10k_sdio_wq]
    root       778  0.0  0.0   1984    44 ?        Ss   22:33   0:00 watchdog /dev/watchdog
    root       806  0.0  0.0      0     0 ?        I    22:33   0:00 [kworker/u2:3-ph]
    102        827  0.0  0.6   2736  1528 ?        Ss   22:33   0:00 dbus-daemon --system
    root       828  0.3  4.4  41220 11152 ?        Sl   22:33   0:02 sovi-hal-qt
    root       845  0.0  0.0   2116    48 ?        Ss   22:33   0:00 ifplugd -i eth0 -I -r /etc/ifplugd.action
    root       847  0.0  0.5   2280  1436 ?        Ss   22:33   0:00 dropbear -r /var/data/etc/dropbear_rsa_host_key
    daemon     870  0.0  0.9   3168  2444 ?        S    22:33   0:00 avahi-daemon: running [PULSEFLEX2i-Soverom.local]
    root       871  0.0  0.3   3484   780 ?        S    22:33   0:00 avahi-browse-sovi
    root       875  0.0  2.4  11220  6000 ?        S    22:33   0:00 sovi-mfg
    root       885  0.0  3.8  38264  9512 ?        Sl   22:33   0:00 sovi-bt
    root       886  0.0  0.6   2260  1736 ?        S    22:33   0:00 /bin/bash /etc/rc.d/rc.sovi
    root       887  0.0  0.0   2120   204 ttymxc0  Ss+  22:33   0:00 /sbin/getty -L ttymxc0 115200 vt100
    root       900  0.0  0.9   3648  2332 ?        Sl   22:33   0:00 sovi-discover --sddp /tmp/sddp.conf
    root       911  0.0  0.7   2280  1824 ?        S    22:33   0:00 /bin/bash /etc/rc.d/rc.stage0
    root       928  8.9  1.4  18036  3624 ?        Sl   22:33   0:54 dspout -d --mqa-decode-only --has-sub --with-ac3-capture --output-delay 810
    root       931  1.4 16.4  45028 40992 ?        Sl   22:33   0:08 perl ./ms.pl -z1 -d /var/data
    root       939  7.6 10.4  31752 26040 ?        Sl   22:33   0:46 perl ./cp.pl -c /var/data
    root       994  0.0  0.2   3416   620 ?        S    22:35   0:00 mfiserver
    root      1008  0.0  0.9   4620  2472 ?        Ss   22:35   0:00 /usr/bin/wpa_supplicant -i wlan0 -c /var/data/wpa.conf -C /tmp -D nl80211 -B -P /var/run/wpa_supplicant.wlan0
    root      1037  0.0  2.2   6368  5696 ?        Ss   22:35   0:00 /usr/sbin/ipwatchd -c /etc/ipwatchd.conf
    root      1040  0.0  0.0   2116    68 ?        Ss   22:35   0:00 /sbin/udhcpc -h PULSEFLEX2i-Soverom -i wlan0 -p /var/run/udhcpc.wlan0 -S -V BluOS 3.8.8 -b
    root      1041  0.6  0.8   4148  2212 ?        S    22:35   0:03 sovi-spotify -n PULSE FLEX 2i - Soverom -i 90:56:82:60:40:e2 -d /var/data/spotify -c /var/data/Spotifyuser -v P125 -P 30125 -b Bluesound -m PULSE FLEX 2i -z 1 -s
    raat      1043  0.0  0.7  27720  1780 ?        Sl   22:35   0:00 raat_bluos -c /var/data/raat.json -z 1 -i BluOS:90:56:82:60:40:e2 -d /var/data/raat -b Bluesound -M PULSE FLEX 2i -v 3.8.8 -e -C -U 104 -G 104 -N 1 PULSE FLEX 2i - Soverom
    root      1045  0.0  1.1  55332  2948 ?        Sl   22:35   0:00 sovi-airplay -z 1 -i 90:56:82:60:40:e2 -d /var/data/airplay -b Bluesound -M PULSE FLEX 2i -v 3.8.8 -e -N 1 PULSE FLEX 2i - Soverom
    root      1077  0.0  0.8  11060  2228 ?        S    22:35   0:00 /usr/sbin/chronyd -r -f /tmp/ntp.conf
    root      1080  0.2  5.5  16056 13780 ?        Sl   22:35   0:01 /usr/bin/perl /var/data/upgrade/stage0/stage0
    root      1081  0.0  0.0   1984   204 ?        S    22:35   0:00 sleep 3600
    root      1329  0.1  0.0      0     0 ?        I    22:42   0:00 [kworker/u2:1-ph]
    root      1366  0.0  0.0   1984   200 ?        S    22:43   0:00 sleep 30
    root      1368  0.0  0.0      0     0 ?        Z    22:43   0:00 [perl] <defunct>
    root      1372  8.0  0.0      0     0 ?        Z    22:43   0:00 [sh] <defunct>
    root      1375  1.0  0.0      0     0 ?        Z    22:43   0:00 [sh] <defunct>
    root      1383  0.0  0.0      0     0 ?        Z    22:43   0:00 [perl] <defunct>
    root      1392  0.0  0.0      0     0 ?        Z    22:43   0:00 [sh] <defunct>
    root      1406  1.0  0.0      0     0 ?        Z    22:43   0:00 [sh] <defunct>
    root      1414  0.0  0.0      0     0 ?        Z    22:43   0:00 [perl] <defunct>
    root      1435  1.0  0.0      0     0 ?        Z    22:43   0:00 [sh] <defunct>
    root      1448  3.0  0.0      0     0 ?        Z    22:43   0:00 [sh] <defunct>
    root      1531  0.0  0.0      0     0 ?        Z    22:43   0:00 [sh] <defunct>
    root      1540  0.0  0.0      0     0 ?        Z    22:43   0:00 [sh] <defunct>
    root      1546  0.0  0.0      0     0 ?        Z    22:43   0:00 [perl] <defunct>
    root      1548  0.0  0.0      0     0 ?        Z    22:43   0:00 [sh] <defunct>
    root      1550  0.0  0.0      0     0 ?        Z    22:43   0:00 [perl] <defunct>
    root      1552  0.0  0.0      0     0 ?        Z    22:43   0:00 [perl] <defunct>
    root      1554  0.0  0.0      0     0 ?        Z    22:43   0:00 [sh] <defunct>
    root      1556  0.0  0.0      0     0 ?        Z    22:43   0:00 [perl] <defunct>
    root      1558  0.0  0.6   2236  1720 ?        S    22:43   0:00 sh -c /dev/root / ext4 ro,relatime 0 0?devtmpfs /dev devtmpfs rw,relatime,size=116240k,nr_inodes=29060,mode=755 0 0?none /proc proc rw,relatime 0 0?none /sys sysfs rw,relatime 0 0?none /proc/sys/debug debugfs rw,relatime 0 0?none /config configfs rw,relatime 0 0?tmpfs /tmp tmpfs rw,relatime 0 0?tmpfs /dev tmpfs rw,relatime,mode=755 0 0?/dev/mmcblk0p1 /tmp/mnt/upgrade ext4 ro,relatime 0 0?/dev/mmcblk0p3 /tmp/var/data ext4 rw,noatime,nodiratime,commit=30 0 0?devpts /dev/pts devpts rw,relatime,mode=600,ptmxmode=000 0 0?shm /dev/shm tmpfs rw,relatime 0 0?
    root      1559  0.0  0.5   2220  1408 ?        R    22:43   0:00 ps axuw
    
    AmazonAlexa
    
    Nodes:

    MY IP: 10.10.0.112

    Other nodes found: 10.10.0.127 / PULSE 2 Flex - Stua queue length: 0

    Version: BluOS: 3.8.8 PIC32: P100-20191115 Songs in index:

     

    0
  • Erik Haga

    Found some more info in the log of the different APs : 

    UniFi AP-AC-Pro

    Finding the MAC address of Flex 2i I find completely normal connections between the AP and the Flex. 

    Thu May 14 00:35:23 2020 daemon.info hostapd: ath1: STA fe:ec:da:11:bd:2a DRIVER: Sead AUTH addr=ac:5d:5c:8b:8b:4b status_code=0

    Thu May 14 00:35:25 2020 daemon.info hostapd: ath1: STA fe:ec:da:11:bd:2a DRIVER: Sead AUTH addr=ac:5d:5c:8b:8b:4b status_code=0

    Thu May 14 00:35:25 2020 kern.info kernel: [202179.179918] ieee80211_sta_leave: ac:5d:5c:8b:8b:4b

    Thu May 14 00:35:25 2020 user.info : wevent[18472]: wevent.ubnt_custom_event(): EVENT_STA_JOIN ath1: ac:5d:5c:8b:8b:4b / 3

    Thu May 14 00:35:25 2020 daemon.info hostapd: ath1: STA ac:5d:5c:8b:8b:4b IEEE 802.11: associated

    Thu May 14 00:35:25 2020 daemon.info hostapd: ath1: STA ac:5d:5c:8b:8b:4b WPA: pairwise key handshake completed (RSN)

    Thu May 14 00:35:25 2020 user.info : wevent[18472]: wevent.ubnt_custom_event(): EVENT_STA_IP ath1: ac:5d:5c:8b:8b:4b / 10.10.0.112

    Thu May 14 00:35:33 2020 user.info : stahtd[18473]: [STA-TRACKER].stahtd_dump_event(): {"ip_assign_type":"dhcp","event_id":"1","message_type":"STA_ASSOC_TRACKER","mac":"ac:5d:5c:8b

    :8b:4b","vap":"ath1","event_type":"success","auth_delta":"0","assoc_status":"0","ip_delta":"250000","wpa_auth_delta":"140000","assoc_delta":"30000","dns_resp_seen":"yes","auth_ts":

    "202177.727376","arp_reply_gw_seen":"yes"}

    Thu May 14 00:35:35 2020 daemon.info hostapd: ath1: STA ac:5d:5c:8b:8b:4b RADIUS: starting accounting session 003BD3B8D78134E9

    Thu May 14 00:38:52 2020 user.info : stahtd[18473]: [STA-TRACKER].stahtd_dump_event(): {"message_type":"STA_ASSOC_TRACKER","mac":"ac:5d:5c:8b:8b:4b","event_id":"1","vap":"ath1","ev

    ent_type":"fixup","assoc_status":"0","dns_resp_seen":"yes","auth_ts":"0.0","arp_reply_gw_seen":"yes"}

    Thu May 14 00:43:46 2020 user.info : stahtd[18473]: [STA-TRACKER].stahtd_dump_event(): {"message_type":"STA_ASSOC_TRACKER","mac":"ac:5d:5c:8b:8b:4b","event_id":"1","vap":"ath1","ev

    ent_type":"fixup","assoc_status":"0","dns_resp_seen":"yes","auth_ts":"0.0","arp_reply_gw_seen":"yes"}

    Thu May 14 00:51:55 2020 user.info : stahtd[18473]: [STA-TRACKER].stahtd_dump_event(): {"message_type":"STA_ASSOC_TRACKER","mac":"ac:5d:5c:8b:8b:4b","event_id":"1","vap":"ath1","ev

    ent_type":"fixup","assoc_status":"0","dns_resp_seen":"yes","auth_ts":"0.0","arp_reply_gw_seen":"yes"}

    Thu May 14 00:55:59 2020 daemon.info hostapd: ath1: STA ac:5d:5c:8b:8b:4b IEEE 802.11: sta_stats

    Thu May 14 00:55:59 2020 daemon.info hostapd: ath1: STA ac:5d:5c:8b:8b:4b IEEE 802.11: disassociated

    Thu May 14 00:55:59 2020 user.info : wevent[18472]: wevent.ubnt_custom_event(): EVENT_STA_LEAVE ath1: ac:5d:5c:8b:8b:4b / 3

    Thu May 14 00:55:59 2020 kern.info kernel: [203413.250726] ieee80211_sta_leave: ac:5d:5c:8b:8b:4b

    Thu May 14 00:55:59 2020 user.info : stahtd[18473]: [STA-TRACKER].stahtd_dump_event(): {"event_id":"1","mac":"ac:5d:5c:8b:8b:4b","message_type":"STA_ASSOC_TRACKER","vap":"ath1","ev

    ent_type":"sta_leave","assoc_status":"0"}

    Thu May 14 00:56:26 2020 daemon.info hostapd: ath1: STA fe:ec:da:11:bd:2a DRIVER: Sead AUTH addr=ac:5d:5c:8b:8b:4b status_code=0

    Thu May 14 00:56:26 2020 user.info : wevent[18472]: wevent.ubnt_custom_event(): EVENT_STA_JOIN ath1: ac:5d:5c:8b:8b:4b / 3

    Thu May 14 00:56:27 2020 daemon.info hostapd: ath1: STA ac:5d:5c:8b:8b:4b IEEE 802.11: associated

    Thu May 14 00:56:27 2020 daemon.info hostapd: ath1: STA ac:5d:5c:8b:8b:4b WPA: pairwise key handshake completed (RSN)

    Thu May 14 00:56:27 2020 user.info : wevent[18472]: wevent.ubnt_custom_event(): EVENT_STA_IP ath1: ac:5d:5c:8b:8b:4b / 10.10.0.112

    Thu May 14 00:56:37 2020 user.info : stahtd[18473]: [STA-TRACKER].stahtd_dump_event(): {"ip_assign_type":"dhcp","event_id":"2","message_type":"STA_ASSOC_TRACKER","mac":"ac:5d:5c:8b

    :8b:4b","vap":"ath1","event_type":"success","auth_delta":"0","assoc_status":"0","ip_delta":"490000","wpa_auth_delta":"320000","assoc_delta":"50000","dns_resp_seen":"yes","auth_ts":

    "203441.46112","arp_reply_gw_seen":"yes"}

    Thu May 14 00:56:37 2020 daemon.info hostapd: ath1: STA ac:5d:5c:8b:8b:4b RADIUS: starting accounting session C52BA9D08A593DEB

    Thu May 14 00:59:52 2020 user.info : stahtd[18473]: [STA-TRACKER].stahtd_dump_event(): {"message_type":"STA_ASSOC_TRACKER","mac":"ac:5d:5c:8b:8b:4b","event_id":"2","vap":"ath1","ev

    ent_type":"fixup","assoc_status":"0","dns_resp_seen":"yes","auth_ts":"0.0","arp_reply_gw_seen":"yes"}

    Thu May 14 01:11:34 2020 user.info : mcad[18468]: wireless_agg_stats.log_sta_anomalies(): bssid=fe:ec:da:11:bd:2a radio=wifi0 vap=ath1 sta=ac:5d:5c:8b:8b:4b satisfaction_now=79 ano

    malies=wifi_retries

    Thu May 14 01:11:36 2020 user.info : mcad[18468]: wireless_agg_stats.log_sta_anomalies(): bssid=fe:ec:da:11:bd:2a radio=wifi0 vap=ath1 sta=ac:5d:5c:8b:8b:4b satisfaction_now=79 ano

    malies=wifi_retries

    Thu May 14 01:12:15 2020 user.info : mcad[18468]: wireless_agg_stats.log_sta_anomalies(): bssid=fe:ec:da:11:bd:2a radio=wifi0 vap=ath1 sta=ac:5d:5c:8b:8b:4b satisfaction_now=79 ano

    malies=wifi_retries

    Thu May 14 01:14:34 2020 user.info : mcad[18468]: wireless_agg_stats.log_sta_anomalies(): bssid=fe:ec:da:11:bd:2a radio=wifi0 vap=ath1 sta=ac:5d:5c:8b:8b:4b satisfaction_now=77 ano

    malies=wifi_retries

    Thu May 14 01:14:50 2020 user.info : mcad[18468]: wireless_agg_stats.log_sta_anomalies(): bssid=fe:ec:da:11:bd:2a radio=wifi0 vap=ath1 sta=ac:5d:5c:8b:8b:4b satisfaction_now=77 ano

    malies=wifi_retries

    But, in the UAP-HD-IW I can't find any info regarding this MAC : 

    Kontor2etg-BZ.v4.3.13# grep "ac:5d:5c:8b:8b:4b" /var/log/messages

    Kontor2etg-BZ.v4.3.13#

     

    While other clients are showing up like it should : 

    Kontor2etg-BZ.v4.3.13# grep "78:4f:43:77:63:8c" /var/log/messages

    Thu May 14 00:35:16 2020 daemon.info hostapd: rai1: STA 78:4f:43:77:63:8c IEEE 802.11: associated

    Thu May 14 00:35:16 2020 user.info : wevent[24552]: wevent.ubnt_custom_event(): EVENT_STA_JOIN rai1: 78:4f:43:77:63:8c / 3

    Thu May 14 00:35:16 2020 daemon.info hostapd: rai1: STA 78:4f:43:77:63:8c WPA: pairwise key handshake completed (RSN)

    Thu May 14 00:35:16 2020 user.info : wevent[24552]: wevent.ubnt_custom_event(): EVENT_STA_IP rai1: 78:4f:43:77:63:8c / 10.10.0.6

    Thu May 14 00:35:17 2020 kern.warn kernel: [PMF]PMF_MlmeSAQueryReq - Send SA Query Request to STA(78:4f:43:77:63:8c)

    Thu May 14 00:35:17 2020 kern.warn kernel: [PMF]PMF_PeerSAQueryRspAction - Compare TransactionID correctly, STA(78:4f:43:77:63:8c)

    Thu May 14 00:35:17 2020 user.info : stahtd[24553]: [STA-TRACKER].stahtd_dump_event(): {"vap":"rai1","message_type":"STA_ASSOC_TRACKER","assoc_status":"0","mac":"78:4f:43:77:63:8c","event_type":"sta_leave","event_id":"0"}

    Thu May 14 00:35:25 2020 kern.warn kernel: [PMF]PMF_MlmeSAQueryReq - Send SA Query Request to STA(78:4f:43:77:63:8c)

    Thu May 14 00:35:25 2020 kern.warn kernel: rai1: (CFG80211_ApStaDel) STA_DEL (78:4f:43:77:63:8c)

    Thu May 14 00:35:25 2020 kern.warn kernel: MlmeDeAuthAction: Send DEAUTH frame with ReasonCode(2) to 78:4f:43:77:63:8c

    Thu May 14 00:35:25 2020 daemon.info hostapd: rai1: STA 78:4f:43:77:63:8c IEEE 802.11: disassociated

    Thu May 14 00:35:25 2020 user.info : wevent[24552]: wevent.ubnt_custom_event(): EVENT_STA_LEAVE rai1: 78:4f:43:77:63:8c / 3

    Thu May 14 00:35:26 2020 user.info : stahtd[24553]: [STA-TRACKER].stahtd_dump_event(): {"vap":"rai1","message_type":"STA_ASSOC_TRACKER","assoc_status":"0","mac":"78:4f:43:77:63:8c","auth_ts":"215933.947230","event_type":"failure","assoc_failures":"1","auth_delta":"0","event_id":"1"}

    Thu May 14 00:35:45 2020 daemon.info hostapd: rai1: STA 78:4f:43:77:63:8c IEEE 802.11: associated

    Thu May 14 00:35:45 2020 user.info : wevent[24552]: wevent.ubnt_custom_event(): EVENT_STA_JOIN rai1: 78:4f:43:77:63:8c / 3

    Thu May 14 00:35:45 2020 daemon.info hostapd: rai1: STA 78:4f:43:77:63:8c WPA: pairwise key handshake completed (RSN)

    Thu May 14 00:35:45 2020 user.info : wevent[24552]: wevent.ubnt_custom_event(): EVENT_STA_IP rai1: 78:4f:43:77:63:8c / 10.10.0.6

    Thu May 14 00:35:55 2020 daemon.info hostapd: rai1: STA 78:4f:43:77:63:8c RADIUS: starting accounting session DB444CFDB9C4B52C

    Thu May 14 00:35:55 2020 user.info : stahtd[24553]: [STA-TRACKER].stahtd_dump_event(): {"vap":"rai1","message_type":"STA_ASSOC_TRACKER","assoc_status":"0","mac":"78:4f:43:77:63:8c","dns_resp_seen":"yes","arp_reply_gw_seen":"yes","auth_ts":"215962.603097","assoc_delta":"0","event_type":"success","ip_delta":"3670000","ip_assign_type":"dhcp","wpa_auth_delta":"10000","auth_delta":"0","event_id":"2"}

    Thu May 14 00:46:03 2020 user.info : stahtd[24553]: [STA-TRACKER].stahtd_dump_event(): {"vap":"rai1","message_type":"STA_ASSOC_TRACKER","assoc_status":"0","mac":"78:4f:43:77:63:8c","dns_resp_seen":"yes","event_type":"fixup","auth_ts":"0.0","arp_reply_gw_seen":"yes","event_id":"2"}

    Thu May 14 01:06:10 2020 user.info : stahtd[24553]: [STA-TRACKER].stahtd_dump_event(): {"vap":"rai1","message_type":"STA_ASSOC_TRACKER","assoc_status":"0","mac":"78:4f:43:77:63:8c","dns_resp_seen":"yes","event_type":"fixup","auth_ts":"0.0","arp_reply_gw_seen":"yes","event_id":"2"}

    Thu May 14 01:08:10 2020 daemon.info hostapd: rai1: STA 78:4f:43:77:63:8c WPA: group key handshake completed (RSN)

    Thu May 14 01:10:35 2020 user.info : stahtd[24553]: [STA-TRACKER].stahtd_dump_event(): {"vap":"rai1","message_type":"STA_ASSOC_TRACKER","assoc_status":"0","mac":"78:4f:43:77:63:8c","dns_resp_seen":"yes","event_type":"fixup","auth_ts":"0.0","arp_reply_gw_seen":"yes","event_id":"2"}

    Thu May 14 01:12:45 2020 user.info : stahtd[24553]: [STA-TRACKER].stahtd_dump_event(): {"vap":"rai1","message_type":"STA_ASSOC_TRACKER","assoc_status":"0","mac":"78:4f:43:77:63:8c","dns_resp_seen":"yes","event_type":"fixup","auth_ts":"0.0","arp_reply_gw_seen":"yes","event_id":"2"}

     

    0
  • Ed Saipetch

    I think I'm having similar issues. I recently upgraded 1 AP to the Unifi AP 6 LR and the bedroom power node gets disconnected after awhile and won't reconnect. A power node in the living room connected to a normal Unifi AC AP Pro doesn't have any connectivity issues. Was there any resolution to this?

    0
  • Erik Haga

    Hi, 

    I found some settings in the UniFi Controller that really helps on how the Bluesounds handle the Wifi connections. 

    1. Under Wifi Setting, find the PMF. This setting can't be "Required". BlueSound does not support PMF and therefor you have to set it to either "Disable" or "Optional".

    2. You have to settings in the UniFi Controller that controlls traffic between two Wifi connected devices. "Point to Point" and "P2P Cross Connect". This have to be checked. But, the strange thing is that you won't find these in the "new GUI". You have to run the old GUI (Select System Setting -> and uncheck "New Settings"). 

    After this changes my BlueSound speakers are working as they should. 

    Regards

       - Erik - 

    0

Please sign in to leave a comment.