Unifi AP-HD-In Wall and BlueSound
Answered
Hi,
Just installed three UniFi AP-HD-In Wall in my house. All in all I have 4 different BlueSounds (one Pluse 2, one Flex and two Flex 2i). None of them are able to connect on Wifi to the AP-HD-In Wall. All the Bluesound speakers connect to a AP-AC-Pro down in the basement even if the nearest AP-HD-In Wall are within one meter from the speakers. All of the AP are controlled by and configured via the same CloudKey.
I have now be reconfigured all Channels that are in use. But, without any effect.
Do you have any known issues with UniFi AP-HD-In Wall ??
Regards
- Erik -
0
-
Official comment
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. -
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 -
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 -
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 -
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.
Comments
5 comments