Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

docker: Couldn't load target OTBR_FORWARD_INGRESS and mDNSPlatformSendUdp error 99. #2189

Open
tim-vk opened this issue Feb 11, 2024 · 0 comments
Open

docker: Couldn't load target OTBR_FORWARD_INGRESS and mDNSPlatformSendUdp error 99. #2189

tim-vk opened this issue Feb 11, 2024 · 0 comments

Comments

@tim-vk
Copy link

tim-vk commented Feb 11, 2024 •
edited

Bug description

I'm not entirely sure if it is a bug but it is an error being continously thrown when starting the docker image I get two errors:

ip6tables v1.6.1: Couldn't load target OTBR_FORWARD_INGRESS':No such file or directory`

Default: mDNSPlatformSendUDP got error 99 (Cannot assign requested address) sending packet to ff02::fb on interface 172.17.0.19/eth0/242

Reproduce:

Follow the documentation on the docker image

  • correct firmware (sonoff-e stick)
  • sudo modprobe ip6table_filter
  • Check location of thread stick
  • install openthread/otbr:latest as per 2024-11-02 with:
    • docker run --sysctl "net.ipv6.conf.all.disable_ipv6=0 net.ipv4.conf.all.forwarding=1 net.ipv6.conf.all.forwarding=1" -p 8080:80 -p 8081:8081 --dns=127.0.0.1 -it --volume /dev/ttyACM0:/dev/ttyACM0 --privileged openthread/otbr --radio-url spinel+hdlc+uart:///dev/ttyACM0?uart-baudrate=460800

Expected behavior:

No errors.

Console log output:

Log file 
WARNING: Localhost DNS setting (--dns=127.0.0.1) may fail in containers.
RADIO_URL: spinel+hdlc+uart:///dev/ttyACM0?uart-baudrate=460800
TREL_URL:
TUN_INTERFACE_NAME: wpan0
BACKBONE_INTERFACE: eth0
NAT64_PREFIX: 64:ff9b::/96
+++ dirname /app/script/server
++ cd /app/script/..
++ [[ ! -n x ]]
++ echo 'Current platform is ubuntu'
Current platform is ubuntu
++ with BORDER_ROUTING
++ local value
+++ printenv BORDER_ROUTING
++ value=1
++ [[ -z 1 ]]
++ [[ 1 == 1 ]]
++ with DHCPV6_PD
++ local value
+++ printenv DHCPV6_PD
++ value=
++ [[ -z '' ]]
++ [[ -f examples/platforms/ubuntu/default ]]
++ [[ '' == 1 ]]
++ with BORDER_ROUTING
++ local value
+++ printenv BORDER_ROUTING
++ value=1
++ [[ -z 1 ]]
++ [[ 1 == 1 ]]
++ with NETWORK_MANAGER
++ local value
+++ printenv NETWORK_MANAGER
++ value=
++ [[ -z '' ]]
++ [[ -f examples/platforms/ubuntu/default ]]
++ [[ '' == 1 ]]
++ STAGE_DIR=/app/stage
++ BUILD_DIR=/app/build
++ [[ -d /app/stage ]]
++ mkdir -v -p /app/stage
mkdir: created directory '/app/stage'
++ [[ -d /app/build ]]
++ mkdir -v -p /app/build
mkdir: created directory '/app/build'
++ export PATH=/app/stage/usr/bin:/app/stage/usr/sbin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
++ PATH=/app/stage/usr/bin:/app/stage/usr/sbin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
+++ basename /app/script/server
++ TASKNAME=server
++ BEFORE_HOOK=examples/platforms/ubuntu/before_server
++ AFTER_HOOK=examples/platforms/ubuntu/after_server
++ [[ ! -f examples/platforms/ubuntu/before_server ]]
++ BEFORE_HOOK=/dev/null
++ [[ ! -f examples/platforms/ubuntu/after_server ]]
++ AFTER_HOOK=/dev/null
+ . script/_nat64
++ NAT64_SERVICE=openthread
++ TAYGA_DEFAULT=/etc/default/tayga
++ TAYGA_CONF=/etc/tayga.conf
++ TAYGA_IPV4_ADDR=192.168.255.1
++ TAYGA_IPV6_ADDR=fdaa:bb:1::1
++ TAYGA_TUN_V6_ADDR=fdaa:bb:1::2
++ NAT64_PREFIX=64:ff9b::/96
++ DYNAMIC_POOL=192.168.255.0/24
++ NAT44_SERVICE=/etc/init.d/otbr-nat44
++ WLAN_IFNAMES=eth0
++ THREAD_IF=wpan0
+ . script/_dns64
++ BIND_CONF_OPTIONS=/etc/bind/named.conf.options
++ NAT64_PREFIX=64:ff9b::/96
++ DNS64_NAMESERVER_ADDR=127.0.0.1
+++ tr '"/"' '"/"'
+++ echo 64:ff9b::/96
++ DNS64_CONF='dns64 64:ff9b::/96 { clients { thread; }; recursive-only yes; };'
++ without NAT64
++ with NAT64
++ local value
+++ printenv NAT64
++ value=1
++ [[ -z 1 ]]
++ [[ 1 == 1 ]]
++ without DNS64
++ with DNS64
++ local value
+++ printenv DNS64
++ value=0
++ [[ -z 0 ]]
++ [[ 0 == 1 ]]
++ '[' ubuntu = raspbian ']'
++ '[' ubuntu = beagleboneblack ']'
++ '[' ubuntu = ubuntu ']'
++ RESOLV_CONF_HEAD=/etc/resolvconf/resolv.conf.d/head
+ . script/_firewall
++ FIREWALL_SERVICE=/etc/init.d/otbr-firewall
++ sudo modprobe ip6table_filter
sudo: modprobe: command not found
++ true
++ FIREWALL=1
+ main
+ [[ '' == \s\h\u\t\d\o\w\n ]]
+ startup
+ . /dev/null
+ sudo sysctl --system
* Applying /etc/sysctl.d/10-console-messages.conf ...
kernel.printk = 4 4 1 7
* Applying /etc/sysctl.d/10-ipv6-privacy.conf ...
net.ipv6.conf.all.use_tempaddr = 2
net.ipv6.conf.default.use_tempaddr = 2
* Applying /etc/sysctl.d/10-kernel-hardening.conf ...
kernel.kptr_restrict = 1
* Applying /etc/sysctl.d/10-link-restrictions.conf ...
fs.protected_hardlinks = 1
fs.protected_symlinks = 1
* Applying /etc/sysctl.d/10-magic-sysrq.conf ...
kernel.sysrq = 176
* Applying /etc/sysctl.d/10-network-security.conf ...
net.ipv4.conf.default.rp_filter = 1
net.ipv4.conf.all.rp_filter = 1
net.ipv4.tcp_syncookies = 1
* Applying /etc/sysctl.d/10-ptrace.conf ...
kernel.yama.ptrace_scope = 1
* Applying /etc/sysctl.d/10-zeropage.conf ...
vm.mmap_min_addr = 65536
* Applying /etc/sysctl.d/60-otbr-accept-ra.conf ...
net.ipv6.conf.eth0.accept_ra = 2
net.ipv6.conf.eth0.accept_ra_rt_info_max_plen = 64
* Applying /etc/sysctl.d/60-otbr-ip-forward.conf ...
net.ipv6.conf.all.forwarding = 1
net.ipv4.ip_forward = 1
* Applying /etc/sysctl.conf ...
+ nat64_start
+ with NAT64
+ local value
++ printenv NAT64
+ value=1
+ [[ -z 1 ]]
+ [[ 1 == 1 ]]
+ '[' openthread = tayga ']'
+ nat44_start
+ with DOCKER
+ local value
++ printenv DOCKER
+ value=1
+ [[ -z 1 ]]
+ [[ 1 == 1 ]]
+ service otbr-nat44 start
+ dns64_start
+ with NAT64
+ local value
++ printenv NAT64
+ value=1
+ [[ -z 1 ]]
+ [[ 1 == 1 ]]
+ with DNS64
+ local value
++ printenv DNS64
+ value=0
+ [[ -z 0 ]]
+ [[ 0 == 1 ]]
+ return 0
+ firewall_start
+ with FIREWALL
+ local value
++ printenv FIREWALL
+ value=1
+ [[ -z 1 ]]
+ [[ 1 == 1 ]]
+ with DOCKER
+ local value
++ printenv DOCKER
+ value=1
+ [[ -z 1 ]]
+ [[ 1 == 1 ]]
+ service otbr-firewall start
+ case "$1" in
+ firewall_start
+ firewall_stop
+ ip6tables -C FORWARD -o wpan0 -j OTBR_FORWARD_INGRESS
ip6tables v1.6.1: Couldn't load target `OTBR_FORWARD_INGRESS':No such file or directory

Try `ip6tables -h' or 'ip6tables --help' for more information.
+ ip6tables -L OTBR_FORWARD_INGRESS
ip6tables: No chain/target/match by that name.
+ ipset_destroy_if_exist otbr-ingress-deny-src
+ ipset list otbr-ingress-deny-src
ipset v6.34: The set with the given name does not exist
+ ipset_destroy_if_exist otbr-ingress-deny-src-swap
+ ipset list otbr-ingress-deny-src-swap
ipset v6.34: The set with the given name does not exist
+ ipset_destroy_if_exist otbr-ingress-allow-dst
+ ipset list otbr-ingress-allow-dst
ipset v6.34: The set with the given name does not exist
+ ipset_destroy_if_exist otbr-ingress-allow-dst-swap
+ ipset list otbr-ingress-allow-dst-swap
ipset v6.34: The set with the given name does not exist
+ ipset create -exist otbr-ingress-deny-src hash:net family inet6
+ ipset create -exist otbr-ingress-deny-src-swap hash:net family inet6
+ ipset create -exist otbr-ingress-allow-dst hash:net family inet6
+ ipset create -exist otbr-ingress-allow-dst-swap hash:net family inet6
+ ip6tables -N OTBR_FORWARD_INGRESS
+ ip6tables -I FORWARD 1 -o wpan0 -j OTBR_FORWARD_INGRESS
+ ip6tables -A OTBR_FORWARD_INGRESS -m pkttype --pkt-type unicast -i wpan0 -j DROP
+ ip6tables -A OTBR_FORWARD_INGRESS -m set --match-set otbr-ingress-deny-src src -j DROP
+ ip6tables -A OTBR_FORWARD_INGRESS -m set --match-set otbr-ingress-allow-dst dst -j ACCEPT
+ ip6tables -A OTBR_FORWARD_INGRESS -m pkttype --pkt-type unicast -j DROP
+ ip6tables -A OTBR_FORWARD_INGRESS -j ACCEPT
+ have systemctl
+ command -v systemctl
+ have service
+ command -v service
+ sudo service rsyslog status
 * rsyslogd is not running
+ sudo service rsyslog start
 * Starting enhanced syslogd rsyslogd                                                                                                                                                                     [ OK ]
+ sudo service dbus status
 * dbus is not running
+ sudo service dbus start
 * Starting system message bus dbus                                                                                                                                                                       [ OK ]
+ sudo service mdns status
Usage: /etc/init.d/mDNS {start|stop|reload|restart}
+ sudo service mdns start
Starting Apple Darwin Multicast DNS / DNS Service Discovery daemon: mdnsd.
+ sudo service avahi-daemon status
Avahi mDNS/DNS-SD Daemon is not running
+ sudo service avahi-daemon start
 * Starting Avahi mDNS/DNS-SD Daemon avahi-daemon                                                                                                                                                         [ OK ]
+ sudo service otbr-agent status
 * otbr-agent is not running
+ sudo service otbr-agent start
 * Starting thread border agent otbr-agent                                                                                                                                                                [ OK ]
+ without WEB_GUI
+ with WEB_GUI
+ local value
++ printenv WEB_GUI
+ value=1
+ [[ -z 1 ]]
+ [[ 1 == 1 ]]
+ sudo service otbr-web status
 * otbr-web is not running
+ sudo service otbr-web start
 * Starting thread web interface otbr-web                                                                                                                                                                 [ OK ]
+ . /dev/null
Feb 11 09:47:22 aa2569fa26f5 avahi-daemon[166]: New relevant interface eth0.IPv4 for mDNS.
Feb 11 09:47:22 aa2569fa26f5 avahi-daemon[166]: Joining mDNS multicast group on interface lo.IPv6 with address ::1.
Feb 11 09:47:22 aa2569fa26f5 avahi-daemon[166]: New relevant interface lo.IPv6 for mDNS.
Feb 11 09:47:22 aa2569fa26f5 avahi-daemon[166]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.
Feb 11 09:47:22 aa2569fa26f5 avahi-daemon[166]: New relevant interface lo.IPv4 for mDNS.
Feb 11 09:47:22 aa2569fa26f5 avahi-daemon[166]: Network interface enumeration completed.
Feb 11 09:47:22 aa2569fa26f5 avahi-daemon[166]: Registering new address record for fe80::42:acff:fe11:13 on eth0.*.
Feb 11 09:47:22 aa2569fa26f5 avahi-daemon[166]: Registering new address record for 172.17.0.19 on eth0.IPv4.
Feb 11 09:47:22 aa2569fa26f5 avahi-daemon[166]: Registering new address record for ::1 on lo.*.
Feb 11 09:47:22 aa2569fa26f5 avahi-daemon[166]: Registering new address record for 127.0.0.1 on lo.IPv4.
Feb 11 09:47:22 aa2569fa26f5 otbr-agent: [NOTE]-AGENT---: Backbone interface: eth0
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: [NOTE]-AGENT---: Running 0.3.0-c5a7a35
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: [NOTE]-AGENT---: Thread version: 1.3.0
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: [NOTE]-AGENT---: Thread interface: wpan0
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: [NOTE]-AGENT---: Radio URL: spinel+hdlc+uart:///dev/ttyACM0?uart-baudrate=460800
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: [NOTE]-ILS-----: Infra link selected: eth0
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: [INFO]-NCP-----: OpenThread log level changed to 5
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.384 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:0, cmd:RESET
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.384 [D] P-RadioSpinel-: Wait response: tid=0 key=0
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.385 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:RESET_POWER_ON
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.385 [I] P-RadioSpinel-: RCP reset: RESET_POWER_ON
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.385 [I] P-RadioSpinel-: Software reset RCP successfully
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.385 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_GET, key:PROTOCOL_VERSION
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.385 [D] P-RadioSpinel-: Wait response: tid=1 key=1
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.386 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:PROTOCOL_VERSION, major:4, minor:3
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.386 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_GET, key:NCP_VERSION
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.386 [D] P-RadioSpinel-: Wait response: tid=2 key=2
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.388 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_IS, key:NCP_VERSION, version:SL-OPENTHREAD/2.3.1.0_GitHub-e6df00dd6; EFR32
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.388 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_GET, key:HWADDR
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.388 [D] P-RadioSpinel-: Wait response: tid=3 key=8
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.389 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_IS, key:HWADDR, eui64:4c5bb3fffe92b869
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.389 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_GET, key:CAPS
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.389 [D] P-RadioSpinel-: Wait response: tid=4 key=5
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.390 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_IS, key:CAPS, caps:COUNTERS UNSOL_UPDATE_FILTER 802_15_4_2450MHZ_OQPSK CONFIG_RADIO MAC_RAW RCP_API_VERSION RCP_MIN_HOST_API_VERSION
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.390 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_GET, key:RCP_API_VERSION
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.390 [D] P-RadioSpinel-: Wait response: tid=5 key=176
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.391 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_IS, key:RCP_API_VERSION, version:9
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.391 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_GET, key:RADIO_CAPS
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.391 [D] P-RadioSpinel-: Wait response: tid=6 key=4619
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.392 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_IS, key:RADIO_CAPS, caps:255
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 50d.11:57:03.392 [I] Platform------: [netif] Sent request#1 to set addr_gen_mode to 1
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.000 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_GET, key:PHY_CHAN_SUPPORTED
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.000 [D] P-RadioSpinel-: Wait response: tid=7 key=34
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.001 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_IS, key:PHY_CHAN_SUPPORTED, channelMask:0x07fff800
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.001 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_SET, key:PHY_ENABLED, enabled:1
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.001 [D] P-RadioSpinel-: Wait response: tid=8 key=32
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.002 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_IS, key:PHY_ENABLED, enabled:1
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.002 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_SET, key:MAC_15_4_PANID, panid:0xffff
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.002 [D] P-RadioSpinel-: Wait response: tid=9 key=54
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.003 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_IS, key:MAC_15_4_PANID, panid:0xffff
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.003 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_SET, key:MAC_15_4_SADDR, saddr:0x0000
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.003 [D] P-RadioSpinel-: Wait response: tid=10 key=53
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.004 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_IS, key:MAC_15_4_SADDR, saddr:0x0000
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.004 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_GET, key:PHY_RX_SENSITIVITY
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.004 [D] P-RadioSpinel-: Wait response: tid=11 key=39
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.005 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_IS, key:PHY_RX_SENSITIVITY, sensitivity:-100
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.005 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_SET, key:RCP_MAC_KEY, keyIdMode:8, keyId:1, prevKey:***, currKey:***, nextKey:***
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.005 [D] P-RadioSpinel-: Wait response: tid=12 key=2048
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.007 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.007 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_SET, key:MAC_15_4_LADDR, laddr:5ed907c5c1828410
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.007 [D] P-RadioSpinel-: Wait response: tid=13 key=52
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.008 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_IS, key:MAC_15_4_LADDR, laddr:5ed907c5c1828410
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.008 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_SET, key:MAC_15_4_SADDR, saddr:0xfffe
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.008 [D] P-RadioSpinel-: Wait response: tid=14 key=53
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.009 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_IS, key:MAC_15_4_SADDR, saddr:0xfffe
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.009 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_SET, key:MAC_SRC_MATCH_SHORT_ADDRESSES, saddr:none
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.009 [D] P-RadioSpinel-: Wait response: tid=15 key=4868
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.010 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.010 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_SET, key:MAC_SRC_MATCH_EXTENDED_ADDRESSES, extaddr:none
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.010 [D] P-RadioSpinel-: Wait response: tid=1 key=4869
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.011 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.011 [I] ChildSupervsn-: Timeout: 0 -> 190
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.011 [I] RoutingManager: Initializing - InfraIfIndex:248
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.011 [I] InfraIf-------: Init infra netif 248
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.011 [N] RoutingManager: No valid /48 BR ULA prefix found in settings, generating new one
Feb 11 09:47:22 aa2569fa26f5 otbr-web[222]: [INFO]-WEB-----: Running 0.3.0-c5a7a35
Feb 11 09:47:22 aa2569fa26f5 otbr-web[222]: [INFO]-WEB-----: Border router web started on wpan0
Feb 11 09:47:22 aa2569fa26f5 otbr-web[222]: [ERR ]-WEB-----: OpenThread daemon is not running.
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.014 [I] Settings------: Saved BrUlaPrefix fdcf:d4c0:bf97::/48
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.014 [N] RoutingManager: BR ULA prefix: fdcf:d4c0:bf97::/48 (generated)
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.014 [I] RoutingManager: Generated local OMR prefix: fdcf:d4c0:bf97:1::/64
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.014 [I] RoutingManager: Generated local NAT64 prefix: fdcf:d4c0:bf97:2:0:0::/96
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.014 [N] RoutingManager: Local on-link prefix: fdde:ad00:beef:cafe::/64
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.014 [I] InfraIf-------: State changed: NOT RUNNING -> RUNNING
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.014 [I] RoutingManager: Enabling
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.014 [I] Nat64---------: IPv4 CIDR for NAT64: 192.168.255.0/24 (actual address pool: 192.168.255.1 - 192.168.255.254, 254 addresses)
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.014 [I] Nat64---------: NAT64 translator is now Disabled
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.014 [I] Platform------: Got nameserver #0: 127.0.0.1
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: [INFO]-UTILS---: Set state callback: OK
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.014 [I] Nat64---------: NAT64 translator is now NotRunning
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: [INFO]-BA------: Publish meshcop service OpenThread BorderRouter #8410._meshcop._udp.local.
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.015 [I] Settings------: Saved BorderAgentId {id:ce1377b42e8b410da42a0ba31a2a81bd}
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: [INFO]-MDNS----: Registering service OpenThread BorderRouter #8410._meshcop._udp
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: [INFO]-ADPROXY-: Publish all hosts and services
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: [INFO]-ADPROXY-: Started
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: [INFO]-DPROXY--: Started
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: [INFO]-BA------: Start Thread Border Agent: OK
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.015 [I] BbrLocal------: Add Domain Prefix: ::/0, NotFound
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.015 [I] BbrLocal------: Add BBR Service: seqno (88), delay (5s), timeout (3600s), InvalidState
Feb 11 09:47:22 aa2569fa26f5 mDNSResponder: Default: mDNSPlatformSendUDP got error 99 (Cannot assign requested address) sending packet to ff02::fb on interface 172.17.0.19/eth0/248
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: [INFO]-APP-----: Thread Border Router started on AIL eth0.
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.016 [I] Notifier------: StateChanged (0x42038210) [MLAddr NetData PanId NetName ExtPanId BbrState Nat64]
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.016 [I] Bbr-----------: Start listening on port 61631
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.016 [I] Bbr-----------: Backbone TMF subscribes ff32:40:fdde:ad00:beef:0:0:3: OK
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.016 [I] BbrManager----: Start Backbone TMF agent: OK
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.017 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.018 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
Feb 11 09:47:22 aa2569fa26f5 otbr-agent[194]: 00:00:00.019 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fdde:ad00:beef:0::/64 -exist` = 0
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: 00:00:00.021 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: 00:00:00.022 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: 00:00:00.022 [I] Platform------: [netif] NAT64 CIDR updated to 192.168.255.0/24.
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: 00:00:00.022 [I] Platform------: [netif] Deleting route for NAT64
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: 00:00:00.022 [I] Platform------: MulticastRoutingManager: Disable: OK
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: [INFO]-BA------: Publish meshcop service OpenThread BorderRouter #8410._meshcop._udp.local.
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: [DEBG]-BBA-----: BackboneAgent: HandleBackboneRouterState: state=1, mBackboneRouterState=0
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: 00:00:00.022 [I] RouterTable---: Route table
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: 00:00:00.022 [D] P-RadioSpinel-: Trying to get RCP time offset
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: 00:00:00.022 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_GET, key:TIMESTAMP
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: 00:00:00.022 [D] P-RadioSpinel-: Wait response: tid=2 key=2050
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: 00:00:00.023 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_IS, key:TIMESTAMP, timestamp:3621062015
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: 00:00:00.023 [I] Platform------: [netif] Host netif is down
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: 00:00:00.023 [I] Platform------: [netif] Succeeded to process request#1
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: 00:00:00.023 [W] Platform------: [netif] Failed to process request#2: No such process
Feb 11 09:47:23 aa2569fa26f5 mDNSResponder: Default: mDNSPlatformSendUDP got error 99 (Cannot assign requested address) sending packet to ff02::fb on interface 172.17.0.19/eth0/248
Feb 11 09:47:23 aa2569fa26f5 mDNSResponder: Default: mDNSPlatformSendUDP got error 99 (Cannot assign requested address) sending packet to ff02::fb on interface 172.17.0.19/eth0/248
Feb 11 09:47:23 aa2569fa26f5 avahi-daemon[166]: Server startup complete. Host name is aa2569fa26f5.local. Local service cookie is 2838291796.
Feb 11 09:47:23 aa2569fa26f5 rsyslogd: rsyslogd's groupid changed to 101
Feb 11 09:47:23 aa2569fa26f5 rsyslogd: rsyslogd's userid changed to 101
Feb 11 09:47:23 aa2569fa26f5 rsyslogd:  [origin software="rsyslogd" swVersion="8.32.0" x-pid="97" x-info="http://www.rsyslog.com"] start
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: [INFO]-MDNS----: Successfully registered service OpenThread BorderRouter #8410._meshcop._udp
Feb 11 09:47:23 aa2569fa26f5 otbr-agent[194]: [INFO]-BA------: Result of publish meshcop service OpenThread BorderRouter #8410._meshcop._udp.local: OK

additional context:

The web ui works & is accessible. however the mDNSPlatformSendUDP error keeps occuring & third party software (home assistant) cannot find the border router after a while.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@tim-vk