Thread Border Router image for nRF5 SDK for Thread

[Brian (Geek)]

Hello, 

We have a product developed using nRF5 SDK for Thread v4.1 based on mqtt-sn sleepy publisher example. 

Thread border router image supplied by Nordic also have mqtt-sn gateway setup for supporting it's example. I have read threads in dev zone suggesting that the mqtt-sn gateway setup done in border router image has end device connection limit and there are some other issues also associated with that mqtt-sn gateway. 

So I would like to know what's the latest border router image available and supporting nRF5 SDK mqtt-sn thread example. I have read there is docker image also available supporting mqtt-sn publisher subscriber example of nRF5 SDK for Thread. But I am not getting it's link and don't know how to use it. So can anyone help. 

Because currently my product development is finished with mqtt-sn example and I am having issues with border router and mqtt-sn gateway. 

Looking for quick response. 

[Sigurd Hellesvik]

Hi,

I have read there is docker image also available supporting mqtt-sn publisher subscriber example of nRF5 SDK for Thread

Do you have a link to this reference?

Regards,
Sigurd Hellesvik

[Brian (Geek)]

Reason why I want to test this OTBR docker image is because the fact that some of my devices gets detached from thread network when I start publishing at faster rate i.e 10 sample per second using mqtt-sn sleepy publisher example. 

My devices are continuously getting disconnected with in few minutes interval even though all nodes and gateway is in line of sight and few meter away from each other. 

Currently my end device child timeout period is set to 40 sec and parent poll period kept to 50-80 msec during the publish rate of 10 samples per second. 

I have attached the nRF5 sniffer capture below. In the capture at the end you can see my device gets detached from thread network and is trying to connect with thread devices but without any success. 

Sniffer Capture:

EndDevice_Capture.pcapng

Can anyone please help me with this :(

[Sigurd Hellesvik]

Hi,

Brian (Geek) said:

is docker image it fails to start otbr-web. 

Try to run the image on its git Tag v1.2.

Does it still fail then?

Regards,
Sigurd Hellesvik

[Brian (Geek)]

Yeah I ran the master branch which last update is same git Tag v1.2 . I have connected my RPi to internet via WiFi and not LAN. Still got the same error with otbr-web.

I would like to know few things before going forward to again try out that docker image because currently my OTBR image provided by Nordic with different MQTT-SN Gateway (same used in Docker image) is working fine. 

Can I perform following things in docker image 

1. Change network parameters of current thread network ?

2. Perform external thread commissioning using thread app ?

3. Will the webpage for configuring the Thread Network open up on local ip (RPI IP) ? 

4. Also the docker image use RCP, so from where I should build RCP firmware for nRF52840 SOC instead of dongle ?

[Sigurd Hellesvik]

Hi

Brian (Geek) said:

1. Change network parameters of current thread network ?

This should work

Brian (Geek) said:

2. Perform external thread commissioning using thread app ?

This should work

Brian (Geek) said:

3. Will the webpage for configuring the Thread Network open up on local ip (RPI IP) ? 

This should work

Brian (Geek) said:

4. Also the docker image use RCP, so from where I should build RCP firmware for nRF52840 SOC instead of dongle ?

You can get pre-build docker image and hex file for nRF2840Dongle from https://github.com/osaether/otbr-mqtt-sn/releases/tag/v1.2.

If you want to build the RCP manually, you can follow the instructions for Configuring a radio co-processor.

Regards,
Sigurd Hellesvik

[Brian (Geek)]

Okay thanks for information.

Can you please help me with docker image. It's still gives me the same issue of otbr-web . Due to some reason it fails to start otbr-web. 

[Sigurd Hellesvik]

Hi,

Which hardware do you run the docker image on?

Can you share the command you use to run the docker image?

Can you share the build log from the docker image?
PS: Please to use Insert->Code to post the log. This will keep the case tidy.

Regards,
Sigurd Hellesvik

[Brian (Geek)]

My whole setup is currently not working. 

I have used fresh SDK and fresh OTBR image running on RPi 3B+ from Nordic. Currently running MQttsn publisher example for test. 

Nrf52840 dk board get connected to thread network but when I search for gateway by pressing button 1 the gateway is not found. This happens with fresh SDK, fresh OTBR image from Nordic. 

On OTBR side, when I see log I can see the GWSearch packet and gateway search pack being sent to client. But my node is unable to connect to gateway. 

Every system service is running and also tried restarting the mqtt-sn gateway. Still not able to connect to gateway. 

Can you please tell what to do ?

[Sigurd Hellesvik]

Hi,

Can you share the command you use to run the docker image?

Can you share the build log from the docker image?
PS: Please to use Insert->Code to post the log. This will keep the case tidy.

Regards,
Sigurd Hellesvik

[Brian (Geek)]

Hello,

Currently i am trying to get my OTBR image provided by Nrdic get going. I am currently facing issues with mqtssn gateway discovery. My end devices are unable to scan the gateway. It always give error code  3. 

Here are the logs from the Open Thread Border Router on Reboot:

pi@OTBR:~$ sudo reboot
[  OK  ] Started Show Plymouth Reboot Screen.
[  OK  ] Deactivated swap /var/swap.
[  OK  ] Stopped LSB: Autogenerate and use a swap file.
         Stopping LSB: thread border agent...
[  OK  ] Stopped LSB: thread border agent.
         Stopping LSB: tunnel device for ncp...
[  OK  ] Stopped LSB: userspace NAT64.
[  OK  ] Stopped target Network is Online.
[  OK  ] Stopped target Network.
         Stopping Raise network interfaces...
         Stopping WPA supplicant...
[  OK  ] Stopped Network Manager Wait Online.
         Stopping Network Manager...
[  OK  ] Stopped target Remote File Systems.
[  OK  ] Stopped target Remote File Systems (Pre).
[  OK  ] Stopped target NFS client services.
[  OK  ] Stopped LSB: tunnel device for ncp.
[  OK  ] Stopped jlink_config.
[  OK  ] Stopped Raise network interfaces.
[  OK  ] Stopped WPA supplicant.
[  OK  ] Stopped Network Manager.
         Stopping D-Bus System Message Bus...
[  OK  ] Stopped D-Bus System Message Bus.
[  OK  ] Stopped target Basic System.
[  OK  ] Stopped target Sockets.
[  OK  ] Closed Syslog Socket.
[  OK  ] Closed CUPS Scheduler.
[  OK  ] Closed triggerhappy.socket.
[  OK  ] Closed Avahi mDNS/DNS-SD Stack Activation Socket.
[  OK  ] Stopped target Slices.
[  OK  ] Removed slice User and Session Slice.
[  OK  ] Stopped Forward Password Requests to Plymouth Directory Watch.
[  OK  ] Stopped target Paths.
[  OK  ] Stopped CUPS Scheduler.
[  OK  ] Closed D-Bus System Message Bus Socket.
[  OK  ] Stopped target System Initialization.
         Stopping Restore / save the current clock...
         Stopping Update UTMP about System Boot/Shutdown...
[  OK  ] Stopped Apply Kernel Variables.
[  OK  ] Stopped Load Kernel Modules.
[  OK  ] Stopped target Swap.
[  OK  ] Stopped target Encrypted Volumes.
[  OK  ] Stopped Forward Password Requests to Wall Directory Watch.
         Stopping Network Time Synchronization...
         Stopping Load/Save Random Seed...
[  OK  ] Stopped Restore / save the current clock.
[  OK  ] Stopped Load/Save Random Seed.
[  OK  ] Stopped Update UTMP about System Boot/Shutdown.
[  OK  ] Stopped Network Time Synchronization.
[  OK  ] Stopped Create Volatile Files and Directories.
[  OK  ] Stopped target Local File Systems.
         Unmounting /run/user/1000...
         Unmounting /boot...
[  OK  ] Unmounted /run/user/1000.
[  OK  ] Unmounted /boot.
[  OK  ] Reached target Unmount All Filesystems.
[  OK  ] Stopped File System Check on /dev/disk/by-partuuid/e2f29acb-01.
[  OK  ] Removed slice system-systemd\x2dfsck.slice.
[  OK  ] Stopped target Local File Systems (Pre).
[  OK  ] Stopped Create Static Device Nodes in /dev.
[  OK  ] Stopped Remount Root and Kernel File Systems.
[  OK  ] Reached target Shutdown.
[  468.231329] reboot: Restarting system
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.19.66-v7+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-
g8460611)) #1253 SMP Thu Aug 15 11:49:46 BST 2019
[    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] cma: Reserved 8 MiB at 0x3ac00000
[    0.000000] random: get_random_bytes called from start_kernel+0xac/0x4b4 with crng_init=0
[    0.000000] percpu: Embedded 17 pages/cpu s39488 r8192 d21952 u69632
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 240555
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=1 bcm2708_fb.fbwidth=1024 bcm2708_fb.fbheight
=768 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  dwc_otg.lpm_enable=0 console=tty
S0,115200 console=tty1 root=PARTUUID=e2f29acb-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Memory: 939088K/970752K available (8192K kernel code, 629K rwdata, 2176K rodata, 1024K init, 821K
 bss, 23472K reserved, 8192K cma-reserved)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xbb800000 - 0xff800000   (1088 MB)
[    0.000000]     lowmem  : 0x80000000 - 0xbb400000   ( 948 MB)
[    0.000000]     modules : 0x7f000000 - 0x80000000   (  16 MB)
[    0.000000]       .text : 0x(ptrval) - 0x(ptrval)   (9184 kB)
[    0.000000]       .init : 0x(ptrval) - 0x(ptrval)   (1024 kB)
[    0.000000]       .data : 0x(ptrval) - 0x(ptrval)   ( 630 kB)
[    0.000000]        .bss : 0x(ptrval) - 0x(ptrval)   ( 822 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 26394 entries in 78 pages
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 44079
5202767 ns
[    0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000023] Switching to timer-based delay loop, resolution 52ns
[    0.000291] Console: colour dummy device 80x30
[    0.000915] console [tty1] enabled
[    0.000978] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=19
2000)
[    0.001023] pid_max: default: 32768 minimum: 301
[    0.001377] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001414] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.002346] CPU: Testing write buffer coherency: ok
[    0.002832] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.003500] Setting up static identity map for 0x100000 - 0x10003c
[    0.003668] rcu: Hierarchical SRCU implementation.
[    0.004482] smp: Bringing up secondary CPUs ...
[    0.005341] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.006269] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.007133] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.007253] smp: Brought up 1 node, 4 CPUs
[    0.007335] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[    0.007359] CPU: All CPU(s) started in HYP mode.
[    0.007380] CPU: Virtualization extensions available.
[    0.008366] devtmpfs: initialized
[    0.021390] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[    0.021663] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.021712] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    0.022321] pinctrl core: initialized pinctrl subsystem
[    0.023195] NET: Registered protocol family 16
[    0.026148] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.031849] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.031886] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.032108] Serial: AMBA PL011 UART driver
[    0.034425] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.069813] bcm2835-dma 3f007000.dma: DMA legacy API manager at (ptrval), dmachans=0x1
[    0.071743] SCSI subsystem initialized
[    0.071987] usbcore: registered new interface driver usbfs
[    0.072063] usbcore: registered new interface driver hub
[    0.072175] usbcore: registered new device driver usb
[    0.090302] raspberrypi-firmware soc:firmware: Attached to firmware from 2019-08-15 12:06, variant start
[    0.100128] raspberrypi-firmware soc:firmware: Firmware hash is 0e6daa5106dd4164474616408e0dc24f997ffcf3
[    0.111806] clocksource: Switched to clocksource arch_sys_counter
[    0.200966] VFS: Disk quotas dquot_6.6.0
[    0.201076] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.201285] FS-Cache: Loaded
[    0.201520] CacheFiles: Loaded
[    0.211502] NET: Registered protocol family 2
[    0.212322] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes)
[    0.212387] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.212518] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.212723] TCP: Hash tables configured (established 8192 bind 8192)
[    0.212877] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.212943] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.213273] NET: Registered protocol family 1
[    0.213868] RPC: Registered named UNIX socket transport module.
[    0.213896] RPC: Registered udp transport module.
[    0.213918] RPC: Registered tcp transport module.
[    0.213940] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.215575] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    0.218618] Initialise system trusted keyrings
[    0.218824] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    0.228818] FS-Cache: Netfs 'nfs' registered for caching
[    0.229413] NFS: Registering the id_resolver key type
[    0.229470] Key type id_resolver registered
[    0.229492] Key type id_legacy registered
[    0.229524] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.231847] Key type asymmetric registered
[    0.231899] Asymmetric key parser 'x509' registered
[    0.231970] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[    0.232154] io scheduler noop registered
[    0.232178] io scheduler deadline registered (default)
[    0.232386] io scheduler cfq registered
[    0.232410] io scheduler mq-deadline registered (default)
[    0.232434] io scheduler kyber registered
[    0.234655] bcm2708_fb soc:fb: FB found 1 display(s)
[    0.257914] Console: switching to colour frame buffer device 128x48
[    0.270390] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 1024x768
[    0.271685] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    0.273492] bcm2835-rng 3f104000.rng: hwrng registered
[    0.273964] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    0.274695] vc-sm: Videocore shared memory driver
[    0.275161] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[    0.286199] brd: module loaded
[    0.296500] loop: module loaded
[    0.297232] Loading iSCSI transport class v2.0-870.
[    0.298070] libphy: Fixed MDIO Bus: probed
[    0.298290] usbcore: registered new interface driver lan78xx
[    0.298505] usbcore: registered new interface driver smsc95xx
[    0.298680] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    0.331629] dwc_otg 3f980000.usb: base=(ptrval)
[    0.536840] Core Release: 2.80a
[    0.541732] Setting default values for core params
[    0.546669] Finished setting default values for core params
[    0.751887] Using Buffer DMA mode
[    0.756715] Periodic Transfer Interrupt Enhancement - disabled
[    0.761626] Multiprocessor Interrupt Enhancement - disabled
[    0.766587] OTG VER PARAM: 0, OTG VER FLAG: 0
[    0.771602] Dedicated Tx FIFOs mode
[    0.777078] WARN::dwc_otg_hcd_init:1045: FIQ DMA bounce buffers: virt = bad14000 dma = 0xfad14000 len=9024
[    0.782319] FIQ FSM acceleration enabled for :
[    0.782319] Non-periodic Split Transactions
[    0.782319] Periodic Split Transactions
[    0.782319] High-Speed Isochronous Endpoints
[    0.782319] Interrupt/Control Split Transaction hack enabled
[    0.807400] WARN::hcd_init_fiq:457: FIQ on core 1
[    0.812397] WARN::hcd_init_fiq:458: FIQ ASM at 80654f4c length 36
[    0.817438] WARN::hcd_init_fiq:497: MPHI regs_base at bb810000
[    0.822426] dwc_otg 3f980000.usb: DWC OTG Controller
[    0.827395] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    0.832455] dwc_otg 3f980000.usb: irq 56, io mem 0x00000000
[    0.837427] Init: Port Power? op_state=1
[    0.842279] Init: Power Port (0)
[    0.847322] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[    0.852370] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.857420] usb usb1: Product: DWC OTG Controller
[    0.862469] usb usb1: Manufacturer: Linux 4.19.66-v7+ dwc_otg_hcd
[    0.867562] usb usb1: SerialNumber: 3f980000.usb
[    0.873259] hub 1-0:1.0: USB hub found
[    0.878304] hub 1-0:1.0: 1 port detected
[    0.884077] usbcore: registered new interface driver usb-storage
[    0.889181] mousedev: PS/2 mouse device common for all mice
[    0.894985] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    0.900097] bcm2835-cpufreq: min=600000 max=1400000
[    0.905383] sdhci: Secure Digital Host Controller Interface driver
[    0.910178] sdhci: Copyright(c) Pierre Ossman
[    0.915284] mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe
[    0.920515] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
[    0.925577] sdhci-pltfm: SDHCI platform and OF driver helper
[    0.932028] ledtrig-cpu: registered to indicate activity on CPUs
[    0.937114] hidraw: raw HID events driver (C) Jiri Kosina
[    0.942351] usbcore: registered new interface driver usbhid
[    0.947367] usbhid: USB HID core driver
[    0.953158] vchiq: vchiq_init_state: slot_zero = (ptrval), is_master = 0
[    0.959805] [vc_sm_connected_init]: start
[    0.969026] [vc_sm_connected_init]: end - returning 0
[    0.975213] Initializing XFRM netlink socket
[    0.980236] NET: Registered protocol family 17
[    0.985351] Key type dns_resolver registered
[    0.990736] Registering SWP/SWPB emulation handler
[    0.996483] registered taskstats version 1
[    1.001405] Loading compiled-in X.509 certificates
[    1.013468] uart-pl011 3f201000.serial: cts_event_workaround enabled
[    1.018380] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2
[    1.024969] console [ttyS0] disabled
[    1.029949] 3f215040.serial: ttyS0 at MMIO 0x0 (irq = 53, base_baud = 31250000) is a 16550
[    2.059731] console [ttyS0] enabled
[    2.068655] Indeed it is in host mode hprt0 = 00021501
[    2.139979] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    2.151309] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
[    2.167190] random: fast init done
[    2.188714] sdhost: log_buf @ (ptrval) (fad13000)
[    2.236027] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    2.246921] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    2.259011] of_cfs_init
[    2.266796] of_cfs_init: OK
[    2.275399] Waiting for root device PARTUUID=e2f29acb-02...
[    2.278853] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    2.298387] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    2.311736] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    2.322287] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    2.334123] Indeed it is in host mode hprt0 = 00001101
[    2.459406] mmc0: host does not support reading read-only switch, assuming write-enable
[    2.476772] mmc0: new high speed SDHC card at address aaaa
[    2.491020] mmcblk0: mmc0:aaaa SD32G 29.7 GiB
[    2.501176] mmc1: new high speed SDIO card at address 0001
[    2.514166]  mmcblk0: p1 p2
[    2.564396] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    2.577931] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    2.582163] usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
[    2.597872] devtmpfs: mounted
[    2.603575] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    2.625181] hub 1-1:1.0: USB hub found
[    2.626082] Freeing unused kernel memory: 1024K
[    2.634328] hub 1-1:1.0: 4 ports detected
[    2.653633] Run /sbin/init as init process
[    2.971879] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    3.105806] systemd[1]: System time before build time, advancing clock.
[    3.112151] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
[    3.131776] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.132464] hub 1-1.1:1.0: USB hub found
[    3.154128] hub 1-1.1:1.0: 3 ports detected
[    3.276703] NET: Registered protocol family 10
[    3.287904] Segment Routing with IPv6
[    3.329627] random: systemd: uninitialized urandom read (16 bytes read)
[    3.344326] random: systemd: uninitialized urandom read (16 bytes read)
[    3.362637] systemd[1]: systemd 232 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSV
INIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[    3.392518] systemd[1]: Detected architecture arm.
[    3.428839] systemd[1]: Set hostname to <OTBR>.
[    3.470943] usb 1-1.3: new full-speed USB device number 4 using dwc_otg
[    3.508338] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read)
[    3.626473] usb 1-1.3: config 1 has an invalid interface number: 2 but max is 1
[    3.639505] usb 1-1.3: config 1 has no interface number 0
[    3.654590] usb 1-1.3: New USB device found, idVendor=1915, idProduct=cafe, bcdDevice= 1.00
[    3.668770] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    3.681820] usb 1-1.3: Product: nRF52 USB Product
[    3.692167] usb 1-1.3: Manufacturer: Nordic Semiconductor
[    3.703345] usb 1-1.3: SerialNumber: E9DBC5B26CCF
[    3.971879] usb 1-1.1.1: new high-speed USB device number 5 using dwc_otg
[    4.068908] systemd[1]: Listening on udev Kernel Socket.
[    4.087427] systemd[1]: Reached target Swap.
[    4.104136] systemd[1]: Listening on udev Control Socket.
[    4.112288] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800, bcdDevice= 3.00
[    4.129699] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    4.150657] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    4.173913] systemd[1]: Created slice User and Session Slice.
[    4.192603] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[    4.212730] systemd[1]: Listening on Syslog Socket.
[    4.395372] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed
[    4.396489] libphy: lan78xx-mdiobus: probed
[    4.408075] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64
[  OK  ] Started Show Plymouth Boot Screen.
[  OK  ] Reached target Encrypted Volumes.
[  OK  ] Started Forward Password Requests to Plymouth Directory Watch.
[  OK  ] Found device /dev/ttyS0.
[  OK  ] Found device /dev/serial1.
[  OK  ] Found device /dev/disk/by-partuuid/e2f29acb-01.
         Starting File System Check on /dev/disk/by-partuuid/e2f29acb-01...
[  OK  ] Reached target Sound Card.
[  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
[  OK  ] Found device nRF52_USB_Product.
[  OK  ] Started File System Check on /dev/disk/by-partuuid/e2f29acb-01.
         Mounting /boot...
[  OK  ] Mounted /boot.
[  OK  ] Reached target Local File Systems.
         Starting Tell Plymouth To Write Out Runtime Data...
         Starting Preprocess NFS configuration...
         Starting Raise network interfaces...
         Starting Create Volatile Files and Directories...
         Starting Set console font and keymap...
[  OK  ] Started Tell Plymouth To Write Out Runtime Data.
[  OK  ] Started Preprocess NFS configuration.
[  OK  ] Started Set console font and keymap.
         Starting Load/Save RF Kill Switch Status...
[  OK  ] Reached target NFS client services.
[  OK  ] Reached target Remote File Systems (Pre).
[  OK  ] Reached target Remote File Systems.
[  OK  ] Started Create Volatile Files and Directories.
[  OK  ] Started Load/Save RF Kill Switch Status.
         Starting Network Time Synchronization...
         Starting Update UTMP about System Boot/Shutdown...
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Started Network Time Synchronization.
[  OK  ] Reached target System Initialization.
[  OK  ] Listening on D-Bus System Message Bus Socket.
[  OK  ] Listening on triggerhappy.socket.
[  OK  ] Listening on CUPS Scheduler.
[  OK  ] Started Daily Cleanup of Temporary Directories.
[  OK  ] Started CUPS Scheduler.
[  OK  ] Reached target Paths.
[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
[  OK  ] Reached target Sockets.
[  OK  ] Reached target Basic System.
         Starting System Logging Service...
         Starting LSB: mosquitto MQTT v3.1 message broker...
[  OK  ] Started D-Bus System Message Bus.
         Starting Network Manager...
         Starting Save/Restore Sound Card State...
         Starting Restore /etc/resolv.conf if the system crashed before the ppp link was shut down...
         Starting LSB: Switch to ondemand cpu governor (unless shift key is pressed)...
         Starting LSB: Autogenerate and use a swap file...
         Starting Configure Bluetooth Modems connected by UART...
         Starting jlink_config...
[  OK  ] Started Regular background program processing daemon.
[  OK  ] Started CUPS Scheduler.
         Starting Login Service...
         Starting triggerhappy global hotkey daemon...
         Starting Avahi mDNS/DNS-SD Stack...
[  OK  ] Reached target System Time Synchronized.
[  OK  ] Started Daily apt download activities.
[  OK  ] Started Daily apt upgrade and clean activities.
[  OK  ] Reached target Timers.
[  OK  ] Started System Logging Service.
[  OK  ] Started triggerhappy global hotkey daemon.
[  OK  ] Started Raise network interfaces.
[  OK  ] Started Save/Restore Sound Card State.
[  OK  ] Started Restore /etc/resolv.conf if the system crashed before the ppp link was shut down.
[  OK  ] Started jlink_config.
[  OK  ] Started Avahi mDNS/DNS-SD Stack.
[  OK  ] Started Login Service.
[  OK  ] Started Make remote CUPS printers available locally.
         Starting LSB: tunnel device for ncp...
[  OK  ] Started LSB: Switch to ondemand cpu governor (unless shift key is pressed).
[  OK  ] Started LSB: mosquitto MQTT v3.1 message broker.
[    7.831969] Under-voltage detected! (0x00050005)
[  OK  ] Started LSB: Autogenerate and use a swap file.
[  OK  ] Started Network Manager.
[  OK  ] Reached target Network.
[  OK  ] Started BIND Domain Name Server.
[  OK  ] Reached target Host and Network Name Lookups.
         Starting Permit User Sessions...
[  OK  ] Started VNC Server in Service Mode daemon.
         Starting OpenBSD Secure Shell server...
         Starting Ethernet configurator...
         Starting Network Manager Wait Online...
[  OK  ] Started Permit User Sessions.
         Starting Network Manager Script Dispatcher Service...
[  OK  ] Started Network Manager Script Dispatcher Service.
[  OK  ] Started OpenBSD Secure Shell server.
         Starting Hostname Service...
[  OK  ] Started Hostname Service.
         Starting Authorization Manager...
         Starting WPA supplicant...
[  OK  ] Started Authorization Manager.
[  OK  ] Started WPA supplicant.
[  OK  ] Started Ethernet configurator.
[  OK  ] Started LSB: tunnel device for ncp.
         Starting Thread border router autostart service...
[  OK  ] Started Daemon call scripts on every NCP state change.
[  OK  ] Started Paho MQTTSN Gateway.
         Starting LSB: thread border agent...
[  OK  ] Started LSB: thread border agent.
         Starting LSB: web server for boreder router...
[  OK  ] Started LSB: web server for boreder router.
         Starting Load/Save RF Kill Switch Status...
[  OK  ] Started Configure Bluetooth Modems connected by UART.
[  OK  ] Started Load/Save RF Kill Switch Status.
         Starting Bluetooth service...
[  OK  ] Created slice system-bthelper.slice.
[  OK  ] Started Bluetooth service.
[  OK  ] Started Raspberry Pi bluetooth helper.
[  OK  ] Reached target Bluetooth.
[  OK  ] Started Network Manager Wait Online.
[  OK  ] Reached target Network is Online.
         Starting /etc/rc.local Compatibility...
         Starting LSB: userspace NAT64...
My IP address is 192.168.0.3 fd88:f3df:b78f:478b:4b5c:caac:de38:da73 
[  OK  ] Started /etc/rc.local Compatibility.
         Starting Terminate Plymouth Boot Screen...
         Starting Hold until boot process finishes up...

Raspbian GNU/Linux 9 OTBR ttyS0
OTBR login: pi

When i check wpanctl status its hows associated with thread role as Leader.

pi@OTBR:~$ sudo wpanctl status
wpan0 => [
        "NCP:State" => "associated"
        "Daemon:Enabled" => true
        "NCP:Version" => "OPENTHREAD/20191113-00534-gc6a258e3; NRF52840; Apr  5 2020 21:51:18"
        "Daemon:Version" => "0.08.00d (; Apr 21 2020 19:11:43)"
        "Config:NCP:DriverName" => "spinel"
        "NCP:HardwareAddress" => [F4CE362BB5F79CBD]
        "NCP:Channel" => 11
        "Network:NodeType" => "leader"
        "Network:Name" => "OTBR-MQTT-SN"
        "Network:XPANID" => 0xDEAD00BEEF00CAFE
        "Network:PANID" => 0xABCD
        "IPv6:LinkLocalAddress" => "fe80::6c5a:c446:138c:1b91"
        "IPv6:MeshLocalAddress" => "fd88:f3df:b78f:478b:4b5c:caac:de38:da73"
        "IPv6:MeshLocalPrefix" => "fd88:f3df:b78f:478b::/64"
        "com.nestlabs.internal:Network:AllowingJoin" => false
]

Also there is no failed system services:

pi@OTBR:~$ systemctl list-units --state=failed
0 loaded units listed. Pass --all to see loaded but inactive units, too.
To show all installed unit files use 'systemctl list-unit-files'.

 

Here is the log of OTBR by running journalctl -ef:

In the below file you can see client has tried searching for gateway and search gateway info is also sent back to the client but client is unable to receive gateway data,

ep 22 16:28:51 OTBR kernel: IPv6: ADDRCONF(NETDEV_CHANGE): nat64: link becomes ready
Sep 22 16:28:51 OTBR tayga[717]: starting TAYGA 0.9.2
Sep 22 16:28:51 OTBR tayga[717]: Using tun device nat64 with MTU 1500
Sep 22 16:28:51 OTBR tayga[717]: TAYGA's IPv4 address: 192.168.255.1
Sep 22 16:28:51 OTBR tayga[717]: TAYGA's IPv6 address: fdaa:bb:1::1
Sep 22 16:28:51 OTBR tayga[717]: NAT64 prefix: 64:ff9b::/96
Sep 22 16:28:51 OTBR NetworkManager[373]: <info>  [1663844331.3391] device (nat64): state change: ip-config -> ]
Sep 22 16:28:51 OTBR tayga[717]: Note: traffic between IPv6 hosts and private IPv4 addresses (i.e. to/from 64:f.
Sep 22 16:28:51 OTBR tayga[717]: Dynamic pool: 192.168.255.0/24
Sep 22 16:28:51 OTBR NetworkManager[373]: <info>  [1663844331.3587] device (nat64): state change: ip-check -> s]
Sep 22 16:28:51 OTBR named[454]: listening on IPv6 interface wlan0, fe80::ba27:ebff:fe9b:d1f4%3#53
Sep 22 16:28:51 OTBR NetworkManager[373]: <info>  [1663844331.3655] device (nat64): state change: secondaries -]
Sep 22 16:28:51 OTBR named[454]: could not listen on UDP socket: address not available
Sep 22 16:28:51 OTBR named[454]: creating IPv6 interface wlan0 failed; interface ignored
Sep 22 16:28:51 OTBR named[454]: listening on IPv6 interface nat64, fe80::de2f:4687:ea43:c66e%5#53
Sep 22 16:28:51 OTBR named[454]: listening on IPv6 interface wlan0, fe80::ba27:ebff:fe9b:d1f4%3#53
Sep 22 16:28:51 OTBR named[454]: could not listen on UDP socket: address not available
Sep 22 16:28:51 OTBR named[454]: creating IPv6 interface wlan0 failed; interface ignored
Sep 22 16:28:51 OTBR tayga[717]: Loaded 1 dynamic map from /var/spool/tayga/dynamic.map
Sep 22 16:28:51 OTBR named[454]: listening on IPv6 interface wlan0, fe80::ba27:ebff:fe9b:d1f4%3#53
Sep 22 16:28:51 OTBR named[454]: could not listen on UDP socket: address not available
Sep 22 16:28:51 OTBR named[454]: creating IPv6 interface wlan0 failed; interface ignored
Sep 22 16:28:51 OTBR named[454]: received control channel command 'reconfig'
Sep 22 16:28:51 OTBR named[454]: loading configuration from '/etc/bind/named.conf'
Sep 22 16:28:51 OTBR named[454]: reading built-in trusted keys from file '/etc/bind/bind.keys'
Sep 22 16:28:51 OTBR named[454]: initializing GeoIP Country (IPv4) (type 1) DB
Sep 22 16:28:51 OTBR named[454]: GEO-106FREE 20170512 Bu
Sep 22 16:28:51 OTBR named[454]: initializing GeoIP Country (IPv6) (type 12) DB
Sep 22 16:28:51 OTBR named[454]: GEO-106FREE 20170512 Bu
Sep 22 16:28:51 OTBR named[454]: GeoIP City (IPv4) (type 2) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP City (IPv4) (type 6) DB not available
Sep 22 16:28:51 OTBR NetworkManager[373]: <info>  [1663844331.4123] device (nat64): Activation: successful, dev.
Sep 22 16:28:51 OTBR named[454]: GeoIP City (IPv6) (type 30) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP City (IPv6) (type 31) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP Region (type 3) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP Region (type 7) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP ISP (type 4) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP Org (type 5) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP AS (type 9) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP Domain (type 11) DB not available
Sep 22 16:28:51 OTBR systemd[1]: Reloading OpenBSD Secure Shell server.
Sep 22 16:28:51 OTBR named[454]: GeoIP NetSpeed (type 10) DB not available
Sep 22 16:28:51 OTBR named[454]: using default UDP/IPv4 port range: [32768, 60999]
Sep 22 16:28:51 OTBR named[454]: using default UDP/IPv6 port range: [32768, 60999]
Sep 22 16:28:51 OTBR named[454]: listening on IPv6 interface wlan0, fe80::ba27:ebff:fe9b:d1f4%3#53
Sep 22 16:28:51 OTBR named[454]: could not listen on UDP socket: address not available
Sep 22 16:28:51 OTBR named[454]: creating IPv6 interface wlan0 failed; interface ignored
Sep 22 16:28:51 OTBR named[454]: sizing zone task pool based on 5 zones
Sep 22 16:28:51 OTBR named[454]: dns64 reverse zone: 0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.b.9.f.f.4.6.0.0.ip6.arpa.
Sep 22 16:28:51 OTBR named[454]: using built-in root key for view _default
Sep 22 16:28:51 OTBR named[454]: configuring command channel from '/etc/bind/rndc.key'
Sep 22 16:28:51 OTBR named[454]: configuring command channel from '/etc/bind/rndc.key'
Sep 22 16:28:51 OTBR named[454]: zone 0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.b.9.f.f.4.6.0.0.ip6.arpa/IN: (master) remd
Sep 22 16:28:51 OTBR named[454]: additionally listening on IPv6 interface wlan0, fe80::ba27:ebff:fe9b:d1f4%3#53
Sep 22 16:28:51 OTBR named[454]: could not listen on UDP socket: address not available
Sep 22 16:28:51 OTBR named[454]: creating IPv6 interface wlan0 failed; interface ignored
Sep 22 16:28:51 OTBR named[454]: reloading configuration succeeded
Sep 22 16:28:51 OTBR named[454]: any newly configured zones are now loaded
Sep 22 16:28:51 OTBR nm-dispatcher[597]: req:3 'up' [nat64]: new request (3 scripts)
Sep 22 16:28:51 OTBR sshd[474]: Received SIGHUP; restarting.
Sep 22 16:28:51 OTBR systemd[1]: Reloaded OpenBSD Secure Shell server.
Sep 22 16:28:51 OTBR dhcpv6-helper[735]: Enable accepting Router Advertisements on the interface: 'wlan0'
Sep 22 16:28:51 OTBR nm-dispatcher[597]: net.ipv6.conf.wlan0.accept_ra = 2
Sep 22 16:28:51 OTBR dhcpv6-helper[737]: Starting 'dhcpcd' on the interface: 'wlan0'
Sep 22 16:28:51 OTBR sshd[474]: Server listening on 0.0.0.0 port 22.
Sep 22 16:28:51 OTBR sshd[474]: Server listening on :: port 22.
Sep 22 16:28:51 OTBR dhcpcd[738]: forked to background, child pid 739
Sep 22 16:28:51 OTBR nm-dispatcher[597]: sed: can't read /tmp/dhcpcd_interfaces: No such file or directory
Sep 22 16:28:51 OTBR nm-dispatcher[597]: req:3 'up' [nat64]: start running ordered scripts...
Sep 22 16:28:51 OTBR dhcpcd[739]: DUID 00:01:00:01:26:32:09:39:b8:27:eb:b1:ff:fb
Sep 22 16:28:51 OTBR dhcpcd[739]: wlan0: IAID 00:00:00:04
Sep 22 16:28:51 OTBR dhcpcd[739]: wlan0: IAID 00:00:00:05
Sep 22 16:28:51 OTBR dhcpcd[739]: wlan0: IAID 00:00:00:06
Sep 22 16:28:51 OTBR named[454]: received control channel command 'reconfig'
Sep 22 16:28:51 OTBR named[454]: loading configuration from '/etc/bind/named.conf'
Sep 22 16:28:51 OTBR named[454]: reading built-in trusted keys from file '/etc/bind/bind.keys'
Sep 22 16:28:51 OTBR named[454]: initializing GeoIP Country (IPv4) (type 1) DB
Sep 22 16:28:51 OTBR named[454]: GEO-106FREE 20170512 Bu
Sep 22 16:28:51 OTBR named[454]: initializing GeoIP Country (IPv6) (type 12) DB
Sep 22 16:28:51 OTBR named[454]: GEO-106FREE 20170512 Bu
Sep 22 16:28:51 OTBR named[454]: GeoIP City (IPv4) (type 2) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP City (IPv4) (type 6) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP City (IPv6) (type 30) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP City (IPv6) (type 31) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP Region (type 3) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP Region (type 7) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP ISP (type 4) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP Org (type 5) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP AS (type 9) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP Domain (type 11) DB not available
Sep 22 16:28:51 OTBR named[454]: GeoIP NetSpeed (type 10) DB not available
Sep 22 16:28:51 OTBR named[454]: using default UDP/IPv4 port range: [32768, 60999]
Sep 22 16:28:51 OTBR named[454]: using default UDP/IPv6 port range: [32768, 60999]
Sep 22 16:28:51 OTBR named[454]: listening on IPv6 interface wlan0, fe80::ba27:ebff:fe9b:d1f4%3#53
Sep 22 16:28:51 OTBR named[454]: could not listen on UDP socket: address not available
Sep 22 16:28:51 OTBR named[454]: creating IPv6 interface wlan0 failed; interface ignored
Sep 22 16:28:51 OTBR named[454]: sizing zone task pool based on 5 zones
Sep 22 16:28:51 OTBR named[454]: dns64 reverse zone: 0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.b.9.f.f.4.6.0.0.ip6.arpa.
Sep 22 16:28:51 OTBR named[454]: using built-in root key for view _default
Sep 22 16:28:51 OTBR named[454]: configuring command channel from '/etc/bind/rndc.key'
Sep 22 16:28:51 OTBR named[454]: configuring command channel from '/etc/bind/rndc.key'
Sep 22 16:28:51 OTBR named[454]: zone 0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.b.9.f.f.4.6.0.0.ip6.arpa/IN: (master) remd
Sep 22 16:28:51 OTBR named[454]: additionally listening on IPv6 interface wlan0, fe80::ba27:ebff:fe9b:d1f4%3#53
Sep 22 16:28:51 OTBR named[454]: could not listen on UDP socket: address not available
Sep 22 16:28:51 OTBR named[454]: creating IPv6 interface wlan0 failed; interface ignored
Sep 22 16:28:51 OTBR named[454]: reloading configuration succeeded
Sep 22 16:28:51 OTBR named[454]: any newly configured zones are now loaded
Sep 22 16:28:51 OTBR systemd[1]: Reloading OpenBSD Secure Shell server.
Sep 22 16:28:52 OTBR sshd[474]: Received SIGHUP; restarting.
Sep 22 16:28:52 OTBR systemd[1]: Reloaded OpenBSD Secure Shell server.
Sep 22 16:28:52 OTBR sshd[474]: Server listening on 0.0.0.0 port 22.
Sep 22 16:28:52 OTBR sshd[474]: Server listening on :: port 22.
Sep 22 16:28:52 OTBR named[454]: received control channel command 'reconfig'
Sep 22 16:28:52 OTBR named[454]: loading configuration from '/etc/bind/named.conf'
Sep 22 16:28:52 OTBR dhcpcd[739]: wlan0: soliciting an IPv6 router
Sep 22 16:28:52 OTBR named[454]: reading built-in trusted keys from file '/etc/bind/bind.keys'
Sep 22 16:28:52 OTBR named[454]: initializing GeoIP Country (IPv4) (type 1) DB
Sep 22 16:28:52 OTBR named[454]: GEO-106FREE 20170512 Bu
Sep 22 16:28:52 OTBR named[454]: initializing GeoIP Country (IPv6) (type 12) DB
Sep 22 16:28:52 OTBR named[454]: GEO-106FREE 20170512 Bu
Sep 22 16:28:52 OTBR named[454]: GeoIP City (IPv4) (type 2) DB not available
Sep 22 16:28:52 OTBR named[454]: GeoIP City (IPv4) (type 6) DB not available
Sep 22 16:28:52 OTBR named[454]: GeoIP City (IPv6) (type 30) DB not available
Sep 22 16:28:52 OTBR named[454]: GeoIP City (IPv6) (type 31) DB not available
Sep 22 16:28:52 OTBR named[454]: GeoIP Region (type 3) DB not available
Sep 22 16:28:52 OTBR named[454]: GeoIP Region (type 7) DB not available
Sep 22 16:28:52 OTBR named[454]: GeoIP ISP (type 4) DB not available
Sep 22 16:28:52 OTBR named[454]: GeoIP Org (type 5) DB not available
Sep 22 16:28:52 OTBR named[454]: GeoIP AS (type 9) DB not available
Sep 22 16:28:52 OTBR named[454]: GeoIP Domain (type 11) DB not available
Sep 22 16:28:52 OTBR named[454]: GeoIP NetSpeed (type 10) DB not available
Sep 22 16:28:52 OTBR named[454]: using default UDP/IPv4 port range: [32768, 60999]
Sep 22 16:28:52 OTBR named[454]: using default UDP/IPv6 port range: [32768, 60999]
Sep 22 16:28:52 OTBR named[454]: listening on IPv6 interface wlan0, fe80::ba27:ebff:fe9b:d1f4%3#53
Sep 22 16:28:52 OTBR named[454]: could not listen on UDP socket: address not available
Sep 22 16:28:52 OTBR named[454]: creating IPv6 interface wlan0 failed; interface ignored
Sep 22 16:28:52 OTBR named[454]: sizing zone task pool based on 5 zones
Sep 22 16:28:52 OTBR named[454]: dns64 reverse zone: 0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.b.9.f.f.4.6.0.0.ip6.arpa.
Sep 22 16:28:52 OTBR named[454]: using built-in root key for view _default
Sep 22 16:28:52 OTBR named[454]: configuring command channel from '/etc/bind/rndc.key'
Sep 22 16:28:52 OTBR named[454]: configuring command channel from '/etc/bind/rndc.key'
Sep 22 16:28:52 OTBR named[454]: zone 0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.b.9.f.f.4.6.0.0.ip6.arpa/IN: (master) remd
Sep 22 16:28:52 OTBR named[454]: additionally listening on IPv6 interface wlan0, fe80::ba27:ebff:fe9b:d1f4%3#53
Sep 22 16:28:52 OTBR named[454]: could not listen on UDP socket: address not available
Sep 22 16:28:52 OTBR named[454]: creating IPv6 interface wlan0 failed; interface ignored
Sep 22 16:28:52 OTBR named[454]: reloading configuration succeeded
Sep 22 16:28:52 OTBR named[454]: any newly configured zones are now loaded
Sep 22 16:28:52 OTBR named[454]: managed-keys-zone: Unable to fetch DNSKEY set '.': operation canceled
Sep 22 16:28:52 OTBR systemd[1]: Reloading OpenBSD Secure Shell server.
Sep 22 16:28:52 OTBR avahi-daemon[377]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80:.
Sep 22 16:28:52 OTBR avahi-daemon[377]: New relevant interface wlan0.IPv6 for mDNS.
Sep 22 16:28:52 OTBR avahi-daemon[377]: Registering new address record for fe80::ba27:ebff:fe9b:d1f4 on wlan0.*.
Sep 22 16:28:52 OTBR dhcpcd[739]: wlan0: soliciting a DHCPv6 lease
Sep 22 16:28:52 OTBR dhcpcd[739]: wpan0: activating for delegation
Sep 22 16:28:52 OTBR named[454]: listening on IPv6 interface wlan0, fe80::ba27:ebff:fe9b:d1f4%3#53
Sep 22 16:28:52 OTBR dhcpcd[739]: wpan0: carrier acquired
Sep 22 16:28:52 OTBR sshd[474]: Received SIGHUP; restarting.
Sep 22 16:28:52 OTBR systemd[1]: Reloaded OpenBSD Secure Shell server.
Sep 22 16:28:52 OTBR dhcpcd[739]: wpan0: IAID 00:00:00:04
Sep 22 16:28:52 OTBR dhcpcd[739]: wpan0: IAID conflicts with one assigned to wlan0
Sep 22 16:28:52 OTBR sshd[474]: Server listening on 0.0.0.0 port 22.
Sep 22 16:28:52 OTBR sshd[474]: Server listening on :: port 22.
Sep 22 16:28:52 OTBR dhcpcd[739]: wlan0: Router Advertisement from fe80::928d:78ff:fe76:2700
Sep 22 16:28:52 OTBR NetworkManager[373]: <info>  [1663844332.6970] policy: set 'NetweeN' (wlan0) as default foS
Sep 22 16:28:52 OTBR systemd[1]: Started Thread border router autostart service.
Sep 22 16:28:53 OTBR tayga[659]: Starting userspace NAT64: tayga.
Sep 22 16:28:53 OTBR systemd[1]: Started LSB: userspace NAT64.
Sep 22 16:28:53 OTBR systemd[1]: Starting LSB: iptables NAT44...
Sep 22 16:28:53 OTBR systemd[1]: Started LSB: iptables NAT44.
Sep 22 16:28:53 OTBR systemd[1]: Reached target Multi-User System.
Sep 22 16:28:53 OTBR systemd[1]: Starting Update UTMP about System Runlevel Changes...
Sep 22 16:28:53 OTBR systemd[1]: Started Raspberry Pi Wifi Setup.
Sep 22 16:28:53 OTBR systemd[1]: Started Update UTMP about System Runlevel Changes.
Sep 22 16:28:53 OTBR systemd[1]: Startup finished in 3.042s (kernel) + 20.825s (userspace) = 23.867s.
Sep 22 16:28:53 OTBR sh[839]: Trying to Connect to WiFi...
Sep 22 16:28:53 OTBR sudo[844]:       pi : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/nmcli c show --ace
Sep 22 16:28:53 OTBR sudo[844]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 22 16:28:53 OTBR login[677]: pam_unix(login:session): session opened for user pi by LOGIN(uid=0)
Sep 22 16:28:53 OTBR systemd[1]: Created slice User Slice of pi.
Sep 22 16:28:53 OTBR systemd[1]: Starting User Manager for UID 1000...
Sep 22 16:28:53 OTBR systemd-logind[331]: New session c1 of user pi.
Sep 22 16:28:53 OTBR systemd[1]: Started Session c1 of user pi.
Sep 22 16:28:53 OTBR systemd[859]: pam_unix(systemd-user:session): session opened for user pi by (uid=0)
Sep 22 16:28:53 OTBR sudo[844]: pam_unix(sudo:session): session closed for user root
Sep 22 16:28:53 OTBR systemd[859]: Reached target Timers.
Sep 22 16:28:53 OTBR systemd[859]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Sep 22 16:28:53 OTBR systemd[859]: Reached target Paths.
Sep 22 16:28:53 OTBR systemd[859]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Sep 22 16:28:53 OTBR systemd[859]: Listening on GnuPG cryptographic agent (access for web browsers).
Sep 22 16:28:53 OTBR systemd[859]: Listening on GnuPG cryptographic agent and passphrase cache.
Sep 22 16:28:53 OTBR systemd[859]: Reached target Sockets.
Sep 22 16:28:53 OTBR systemd[859]: Reached target Basic System.
Sep 22 16:28:53 OTBR systemd[859]: Reached target Default.
Sep 22 16:28:53 OTBR systemd[859]: Startup finished in 115ms.
Sep 22 16:28:53 OTBR systemd[1]: Started User Manager for UID 1000.
Sep 22 16:28:54 OTBR sudo[868]:       pi : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/bash
Sep 22 16:28:54 OTBR sudo[868]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 22 16:28:54 OTBR NetworkManager[373]: <info>  [1663844334.3248] device (wlan0): state change: activated -> ]
Sep 22 16:28:54 OTBR NetworkManager[373]: <info>  [1663844334.3257] manager: NetworkManager state is now CONNECL
Sep 22 16:28:54 OTBR NetworkManager[373]: <error> [1663844334.3647] audit: failed to open auditd socket: Protocd
Sep 22 16:28:54 OTBR NetworkManager[373]: <info>  [1663844334.3651] audit: op="connection-deactivate" uuid="fb5"
Sep 22 16:28:54 OTBR NetworkManager[373]: <info>  [1663844334.3725] device (wlan0): state change: deactivating ]
Sep 22 16:28:54 OTBR dhcpcd[739]: wlan0: fe80::928d:78ff:fe76:2700 is unreachable, expiring it
Sep 22 16:28:54 OTBR avahi-daemon[377]: Withdrawing address record for fe80::ba27:ebff:fe9b:d1f4 on wlan0.
Sep 22 16:28:54 OTBR avahi-daemon[377]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80:.
Sep 22 16:28:54 OTBR avahi-daemon[377]: Interface wlan0.IPv6 no longer relevant for mDNS.
Sep 22 16:28:54 OTBR named[454]: no longer listening on fe80::ba27:ebff:fe9b:d1f4%3#53
Sep 22 16:28:54 OTBR NetworkManager[373]: <info>  [1663844334.4501] dhcp4 (wlan0): canceled DHCP transaction, D8
Sep 22 16:28:54 OTBR NetworkManager[373]: <info>  [1663844334.4503] dhcp4 (wlan0): state changed bound -> done
Sep 22 16:28:54 OTBR wpa_supplicant[495]: wlan0: CTRL-EVENT-DISCONNECTED bssid=90:8d:78:76:27:00 reason=3 local1
Sep 22 16:28:54 OTBR wpa_supplicant[495]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Sep 22 16:28:54 OTBR avahi-daemon[377]: Withdrawing address record for 192.168.0.3 on wlan0.
Sep 22 16:28:54 OTBR avahi-daemon[377]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.1.
Sep 22 16:28:54 OTBR avahi-daemon[377]: Interface wlan0.IPv4 no longer relevant for mDNS.
Sep 22 16:28:54 OTBR named[454]: no longer listening on 192.168.0.3#53
Sep 22 16:28:55 OTBR dhcpcd[739]: wlan0: new hardware address: 9e:e4:31:ed:38:7b
Sep 22 16:28:55 OTBR NetworkManager[373]: <info>  [1663844335.0422] device (wlan0): set-hw-addr: set MAC addres)
Sep 22 16:28:55 OTBR NetworkManager[373]: <info>  [1663844335.0499] dns-mgr: Removing DNS information from /sbif
Sep 22 16:28:55 OTBR kernel: IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Sep 22 16:28:55 OTBR kernel: brcmfmac: power management disabled
Sep 22 16:28:55 OTBR sudo[911]:       pi : TTY=tty1 ; PWD=/etc/mosquitto/conf.d ; USER=root ; COMMAND=/bin/kill0
Sep 22 16:28:55 OTBR sudo[911]: pam_unix(sudo:session): session opened for user root by pi(uid=0)
Sep 22 16:28:55 OTBR sudo[911]: pam_unix(sudo:session): session closed for user root
Sep 22 16:28:55 OTBR dhcpcd[739]: wlan0: dhcp6_sendmessage: sendmsg: Network is unreachable
Sep 22 16:28:55 OTBR NetworkManager[373]: Too few arguments.
Sep 22 16:28:55 OTBR NetworkManager[373]: Too few arguments.
Sep 22 16:28:55 OTBR NetworkManager[373]: <warn>  [1663844335.2692] sup-iface[0x1191198,wlan0]: connection disc)
Sep 22 16:28:55 OTBR NetworkManager[373]: <info>  [1663844335.2698] device (wlan0): supplicant interface state:d
Sep 22 16:28:55 OTBR nm-dispatcher[597]: req:4 'down' [wlan0]: new request (3 scripts)
Sep 22 16:28:55 OTBR nm-dispatcher[597]: req:4 'down' [wlan0]: start running ordered scripts...
Sep 22 16:28:55 OTBR dhcpv6-helper[987]: Releasing 'dhcpcd' on the interface: 'wlan0'
Sep 22 16:28:55 OTBR dhcpcd[988]: sending signal ARLM to pid 739
Sep 22 16:28:55 OTBR dhcpcd[739]: received SIGALRM, releasing
Sep 22 16:28:55 OTBR dhcpcd[739]: wpan0: removing interface
Sep 22 16:28:55 OTBR dhcpcd[988]: waiting for pid 739 to exit
Sep 22 16:28:55 OTBR dhcpcd[739]: wlan0: removing interface
Sep 22 16:28:55 OTBR NetworkManager[373]: <info>  [1663844335.6349] keyfile: add connection in-memory (9185434e)
Sep 22 16:28:55 OTBR NetworkManager[373]: <info>  [1663844335.6674] audit: op="connection-add" uuid="9185434e-5"
Sep 22 16:28:55 OTBR dhcpcd[739]: dhcpcd exited
Sep 22 16:28:55 OTBR nm-dispatcher[597]: sending signal ARLM to pid 739
Sep 22 16:28:55 OTBR nm-dispatcher[597]: waiting for pid 739 to exit
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.1210] policy: auto-activating connection 'NetweeN'
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.1290] device (wlan0): Activation: starting connec)
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.1306] device (wlan0): state change: disconnected ]
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.1315] manager: NetworkManager state is now CONNECG
Sep 22 16:28:58 OTBR nm-dispatcher[597]: req:5 'connectivity-change': new request (3 scripts)
Sep 22 16:28:58 OTBR nm-dispatcher[597]: req:5 'connectivity-change': start running ordered scripts...
Sep 22 16:28:58 OTBR ap-helper[1013]: Unsupported action: 'connectivity-change'
Sep 22 16:28:58 OTBR dhcpv6-helper[1015]: Unsupported action: 'connectivity-change'
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.6676] device (wlan0): set-hw-addr: reset MAC addr)
Sep 22 16:28:58 OTBR kernel: IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Sep 22 16:28:58 OTBR kernel: brcmfmac: power management disabled
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.7483] device (wlan0): supplicant interface state:d
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.7514] device (wlan0): state change: prepare -> co]
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.7529] device (wlan0): Activation: (wifi) access p.
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.7531] device (wlan0): state change: config -> nee]
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.7857] device (wlan0): state change: need-auth -> ]
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.7887] device (wlan0): state change: prepare -> co]
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.7904] device (wlan0): Activation: (wifi) connecti.
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.7907] Config: added 'ssid' value 'NetweeN'
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.7908] Config: added 'scan_ssid' value '1'
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.7910] Config: added 'key_mgmt' value 'WPA-PSK'
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.7911] Config: added 'psk' value '<hidden>'
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.7957] device (wlan0): supplicant interface state:d
Sep 22 16:28:58 OTBR NetworkManager[373]: <info>  [1663844338.9159] device (wlan0): supplicant interface state:g
Sep 22 16:29:01 OTBR wpa_supplicant[495]: wlan0: Trying to associate with SSID 'NetweeN'
Sep 22 16:29:01 OTBR NetworkManager[373]: <info>  [1663844341.5944] device (wlan0): supplicant interface state:g
Sep 22 16:29:01 OTBR CRON[1018]: pam_unix(cron:session): session opened for user pi by (uid=0)
Sep 22 16:29:01 OTBR CRON[1017]: pam_unix(cron:session): session opened for user root by (uid=0)
Sep 22 16:29:01 OTBR CRON[1025]: (pi) CMD (/home/pi/Autorun/reboot_wpan0.sh )
Sep 22 16:29:01 OTBR CRON[1027]: (root) CMD (/home/pi/Autorun/reboot_wpan0sh >> /home/pi/rtxt.txt)
Sep 22 16:29:01 OTBR CRON[1017]: (CRON) info (No MTA installed, discarding output)
Sep 22 16:29:01 OTBR CRON[1018]: (CRON) info (No MTA installed, discarding output)
Sep 22 16:29:01 OTBR CRON[1017]: pam_unix(cron:session): session closed for user root
Sep 22 16:29:01 OTBR CRON[1018]: pam_unix(cron:session): session closed for user pi
Sep 22 16:29:04 OTBR wpa_supplicant[495]: wlan0: Associated with 90:8d:78:76:27:00
Sep 22 16:29:04 OTBR wpa_supplicant[495]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.2772] device (wlan0): supplicant interface state:e
Sep 22 16:29:04 OTBR wpa_supplicant[495]: wlan0: WPA: Key negotiation completed with 90:8d:78:76:27:00 [PTK=CCM]
Sep 22 16:29:04 OTBR wpa_supplicant[495]: wlan0: CTRL-EVENT-CONNECTED - Connection to 90:8d:78:76:27:00 complet]
Sep 22 16:29:04 OTBR kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.3531] device (wlan0): supplicant interface state:d
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.3537] device (wlan0): Activation: (wifi) Stage 2 .
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.3544] device (wlan0): state change: config -> ip-]
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.3574] dhcp4 (wlan0): activation: beginning transa)
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.3685] dhcp4 (wlan0): dhclient started with pid 106
Sep 22 16:29:04 OTBR dhclient[1036]: DHCPREQUEST of 192.168.0.3 on wlan0 to 255.255.255.255 port 67
Sep 22 16:29:04 OTBR dhclient[1036]: DHCPACK of 192.168.0.3 from 192.168.0.1
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.5138] dhcp4 (wlan0):   address 192.168.0.3
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.5139] dhcp4 (wlan0):   plen 24 (255.255.255.0)
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.5140] dhcp4 (wlan0):   gateway 192.168.0.1
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.5141] dhcp4 (wlan0):   server identifier 192.168.1
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.5142] dhcp4 (wlan0):   lease time 7200
Sep 22 16:29:04 OTBR avahi-daemon[377]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.1.
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.5143] dhcp4 (wlan0):   nameserver '8.8.8.8'
Sep 22 16:29:04 OTBR avahi-daemon[377]: New relevant interface wlan0.IPv4 for mDNS.
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.5144] dhcp4 (wlan0):   nameserver '4.2.2.2'
Sep 22 16:29:04 OTBR avahi-daemon[377]: Registering new address record for 192.168.0.3 on wlan0.IPv4.
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.5145] dhcp4 (wlan0):   domain name 'domain.name'
Sep 22 16:29:04 OTBR named[454]: listening on IPv4 interface wlan0, 192.168.0.3#53
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.5147] dhcp4 (wlan0): state changed unknown -> boud
Sep 22 16:29:04 OTBR named[454]: listening on IPv6 interface wlan0, fe80::ba27:ebff:fe9b:d1f4%3#53
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.5321] device (wlan0): state change: ip-config -> ]
Sep 22 16:29:04 OTBR named[454]: could not listen on UDP socket: address not available
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.5380] device (wlan0): state change: ip-check -> s]
Sep 22 16:29:04 OTBR named[454]: creating IPv6 interface wlan0 failed; interface ignored
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.5416] device (wlan0): state change: secondaries -]
Sep 22 16:29:04 OTBR dhclient[1036]: bound to 192.168.0.3 -- renewal in 3132 seconds.
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.5427] manager: NetworkManager state is now CONNECL
Sep 22 16:29:04 OTBR nm-dispatcher[597]: req:6 'connectivity-change': new request (3 scripts)
Sep 22 16:29:04 OTBR nm-dispatcher[597]: req:6 'connectivity-change': start running ordered scripts...
Sep 22 16:29:04 OTBR ap-helper[1047]: Unsupported action: 'connectivity-change'
Sep 22 16:29:04 OTBR dhcpv6-helper[1049]: Unsupported action: 'connectivity-change'
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.5990] manager: NetworkManager state is now CONNECL
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.6006] policy: set 'NetweeN' (wlan0) as default foS
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.6012] dns-mgr: Writing DNS information to /sbin/rf
Sep 22 16:29:04 OTBR NetworkManager[373]: Too few arguments.
Sep 22 16:29:04 OTBR NetworkManager[373]: Too few arguments.
Sep 22 16:29:04 OTBR NetworkManager[373]: <info>  [1663844344.7919] device (wlan0): Activation: successful, dev.
Sep 22 16:29:04 OTBR nm-dispatcher[597]: req:7 'up' [wlan0]: new request (3 scripts)
Sep 22 16:29:04 OTBR nm-dispatcher[597]: req:7 'up' [wlan0]: start running ordered scripts...
Sep 22 16:29:04 OTBR named[454]: received control channel command 'reconfig'
Sep 22 16:29:04 OTBR named[454]: loading configuration from '/etc/bind/named.conf'
Sep 22 16:29:05 OTBR named[454]: reading built-in trusted keys from file '/etc/bind/bind.keys'
Sep 22 16:29:05 OTBR named[454]: initializing GeoIP Country (IPv4) (type 1) DB
Sep 22 16:29:05 OTBR named[454]: GEO-106FREE 20170512 Bu
Sep 22 16:29:05 OTBR named[454]: initializing GeoIP Country (IPv6) (type 12) DB
Sep 22 16:29:05 OTBR named[454]: GEO-106FREE 20170512 Bu
Sep 22 16:29:05 OTBR named[454]: GeoIP City (IPv4) (type 2) DB not available
Sep 22 16:29:05 OTBR named[454]: GeoIP City (IPv4) (type 6) DB not available
Sep 22 16:29:05 OTBR named[454]: GeoIP City (IPv6) (type 30) DB not available
Sep 22 16:29:05 OTBR named[454]: GeoIP City (IPv6) (type 31) DB not available
Sep 22 16:29:05 OTBR named[454]: GeoIP Region (type 3) DB not available
Sep 22 16:29:05 OTBR named[454]: GeoIP Region (type 7) DB not available
Sep 22 16:29:05 OTBR named[454]: GeoIP ISP (type 4) DB not available
Sep 22 16:29:05 OTBR named[454]: GeoIP Org (type 5) DB not available
Sep 22 16:29:05 OTBR named[454]: GeoIP AS (type 9) DB not available
Sep 22 16:29:05 OTBR named[454]: GeoIP Domain (type 11) DB not available
Sep 22 16:29:05 OTBR named[454]: GeoIP NetSpeed (type 10) DB not available
Sep 22 16:29:05 OTBR named[454]: using default UDP/IPv4 port range: [32768, 60999]
Sep 22 16:29:05 OTBR named[454]: using default UDP/IPv6 port range: [32768, 60999]
Sep 22 16:29:05 OTBR named[454]: listening on IPv6 interface wlan0, fe80::ba27:ebff:fe9b:d1f4%3#53
Sep 22 16:29:05 OTBR named[454]: could not listen on UDP socket: address not available
Sep 22 16:29:05 OTBR named[454]: creating IPv6 interface wlan0 failed; interface ignored
Sep 22 16:29:05 OTBR named[454]: sizing zone task pool based on 5 zones
Sep 22 16:29:05 OTBR named[454]: dns64 reverse zone: 0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.b.9.f.f.4.6.0.0.ip6.arpa.
Sep 22 16:29:05 OTBR named[454]: using built-in root key for view _default
Sep 22 16:29:05 OTBR named[454]: configuring command channel from '/etc/bind/rndc.key'
Sep 22 16:29:05 OTBR named[454]: configuring command channel from '/etc/bind/rndc.key'
Sep 22 16:29:05 OTBR named[454]: zone 0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.b.9.f.f.4.6.0.0.ip6.arpa/IN: (master) remd
Sep 22 16:29:05 OTBR named[454]: additionally listening on IPv6 interface wlan0, fe80::ba27:ebff:fe9b:d1f4%3#53
Sep 22 16:29:05 OTBR named[454]: could not listen on UDP socket: address not available
Sep 22 16:29:05 OTBR named[454]: creating IPv6 interface wlan0 failed; interface ignored
Sep 22 16:29:05 OTBR named[454]: reloading configuration succeeded
Sep 22 16:29:05 OTBR named[454]: any newly configured zones are now loaded
Sep 22 16:29:05 OTBR systemd[1]: Reloading OpenBSD Secure Shell server.
Sep 22 16:29:05 OTBR sshd[474]: Received SIGHUP; restarting.
Sep 22 16:29:05 OTBR systemd[1]: Reloaded OpenBSD Secure Shell server.
Sep 22 16:29:05 OTBR dhcpv6-helper[1133]: Enable accepting Router Advertisements on the interface: 'wlan0'
Sep 22 16:29:05 OTBR nm-dispatcher[597]: net.ipv6.conf.wlan0.accept_ra = 2
Sep 22 16:29:05 OTBR dhcpv6-helper[1135]: Starting 'dhcpcd' on the interface: 'wlan0'
Sep 22 16:29:05 OTBR dhcpcd[1136]: forked to background, child pid 1137
Sep 22 16:29:05 OTBR sshd[474]: Server listening on 0.0.0.0 port 22.
Sep 22 16:29:05 OTBR sshd[474]: Server listening on :: port 22.
Sep 22 16:29:05 OTBR dhcpcd[1137]: DUID 00:01:00:01:26:32:09:39:b8:27:eb:b1:ff:fb
Sep 22 16:29:05 OTBR dhcpcd[1137]: wlan0: IAID 00:00:00:04
Sep 22 16:29:05 OTBR dhcpcd[1137]: wlan0: IAID 00:00:00:05
Sep 22 16:29:05 OTBR dhcpcd[1137]: wlan0: IAID 00:00:00:06
Sep 22 16:29:05 OTBR dhcpcd[1137]: wlan0: soliciting an IPv6 router
Sep 22 16:29:06 OTBR avahi-daemon[377]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80:.
Sep 22 16:29:06 OTBR dhcpcd[1137]: wlan0: soliciting a DHCPv6 lease
Sep 22 16:29:06 OTBR avahi-daemon[377]: New relevant interface wlan0.IPv6 for mDNS.
Sep 22 16:29:06 OTBR avahi-daemon[377]: Registering new address record for fe80::ba27:ebff:fe9b:d1f4 on wlan0.*.
Sep 22 16:29:06 OTBR dhcpcd[1137]: wpan0: activating for delegation
Sep 22 16:29:06 OTBR named[454]: listening on IPv6 interface wlan0, fe80::ba27:ebff:fe9b:d1f4%3#53
Sep 22 16:29:06 OTBR dhcpcd[1137]: wpan0: carrier acquired
Sep 22 16:29:06 OTBR dhcpcd[1137]: wpan0: IAID 00:00:00:04
Sep 22 16:29:06 OTBR dhcpcd[1137]: wpan0: IAID conflicts with one assigned to wlan0
Sep 22 16:29:06 OTBR sudo[868]: pam_unix(sudo:session): session closed for user root
Sep 22 16:29:06 OTBR dhcpcd[1137]: wlan0: Router Advertisement from fe80::928d:78ff:fe76:2700
Sep 22 16:29:06 OTBR NetworkManager[373]: <info>  [1663844346.5833] policy: set 'NetweeN' (wlan0) as default foS
Sep 22 16:29:17 OTBR systemd[859]: Time has been changed
Sep 22 16:29:17 OTBR systemd[1]: Time has been changed
Sep 22 16:29:17 OTBR systemd-timesyncd[280]: Synchronized to time server 192.46.211.253:123 (2.debian.pool.ntp..
Sep 22 16:29:17 OTBR systemd[1]: apt-daily-upgrade.timer: Adding 8min 32.320501s random time.
Sep 22 16:29:17 OTBR systemd[1]: apt-daily.timer: Adding 53min 24.085739s random time.
Sep 22 16:29:47 OTBR login[676]: pam_unix(login:session): session opened for user pi by LOGIN(uid=0)
Sep 22 16:29:47 OTBR systemd-logind[331]: New session c2 of user pi.
Sep 22 16:29:47 OTBR systemd[1]: Started Session c2 of user pi.
Sep 22 16:29:48 OTBR sudo[1204]:       pi : TTY=serial0 ; PWD=/etc/mosquitto/conf.d ; USER=root ; COMMAND=/bin/3
Sep 22 16:29:48 OTBR sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 22 16:29:48 OTBR sudo[1204]: pam_unix(sudo:session): session closed for user root
Sep 22 16:30:11 OTBR CRON[1214]: pam_unix(cron:session): session opened for user root by (uid=0)
Sep 22 16:30:11 OTBR CRON[1215]: pam_unix(cron:session): session opened for user pi by (uid=0)
Sep 22 16:30:11 OTBR CRON[1222]: (pi) CMD (/home/pi/Autorun/reboot_wpan0.sh )
Sep 22 16:30:11 OTBR CRON[1223]: (root) CMD (/home/pi/Autorun/reboot_wpan0sh >> /home/pi/rtxt.txt)
Sep 22 16:30:11 OTBR CRON[1214]: (CRON) info (No MTA installed, discarding output)
Sep 22 16:30:11 OTBR CRON[1215]: (CRON) info (No MTA installed, discarding output)
Sep 22 16:30:11 OTBR CRON[1214]: pam_unix(cron:session): session closed for user root
Sep 22 16:30:11 OTBR CRON[1215]: pam_unix(cron:session): session closed for user pi
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]:  *********************************************************************
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]:  * MQTT-SN Gateway
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]:  * Part of Project Paho in Eclipse
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]:  * (http://git.eclipse.org/c/paho/org.eclipse.paho.mqtt-sn.embedded-c)
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]:  *
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]:  * Author : Tomoaki YAMAGUCHI
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]:  * Version: 1.5.1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]:  *********************************************************************
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162842.850 PahoGateway-01 has been started.
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]:  ConfigFile: /etc/paho-mqtt-sn-gateway.conf
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]:  SensorN/W:   Gateway Port: 47193 Broadcast Address: ff33:40:fdde:ad05
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]:  Broker:     103.238.13.202 : 1883, 8883
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]:  Max number of Clients: 10000
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]:  RootCApath: (null)
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]:  RootCAfile: (null)
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]:  CertKey:    (null)
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]:  PrivateKey: (null)
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162853.465   SEARCHGW          <---  Client                 1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162853.465   GWINFO            --->  Clients                1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162859.740   SEARCHGW          <---  Client                 1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162859.740   GWINFO            --->  Clients                1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162904.353   SEARCHGW          <---  Client                 1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162904.353   GWINFO            --->  Clients                1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162918.578   SEARCHGW          <---  Client                 1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162918.578   GWINFO            --->  Clients                1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162923.441   SEARCHGW          <---  Client                 1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162923.441   GWINFO            --->  Clients                1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162934.377   SEARCHGW          <---  Client                 1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162934.377   GWINFO            --->  Clients                1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162939.078   SEARCHGW          <---  Client                 1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162939.079   GWINFO            --->  Clients                1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162949.779   SEARCHGW          <---  Client                 1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162949.779   GWINFO            --->  Clients                1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162953.269   SEARCHGW          <---  Client                 1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162953.269   GWINFO            --->  Clients                1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162958.769   SEARCHGW          <---  Client                 1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 162958.769   GWINFO            --->  Clients                1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 163003.880   SEARCHGW          <---  Client                 1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 163003.880   GWINFO            --->  Clients                1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 163008.468   SEARCHGW          <---  Client                 1
Sep 22 16:30:19 OTBR MQTT-SNGateway[503]: 20220922 163008.468   GWINFO            --->  Clients                1
^[[1;2ASep 22 16:30:39 OTBR kernel: Voltage normalised (0x00000000)
[  121.987047] Under-voltage detected! (0x00050005)
Sep 22 16:30:41 OTBR kernel: Under-voltage detected! (0x00050005)
Sep 22 16:30:49 OTBR kernel: Voltage normalised (0x00000000)
[  132.386925] Under-voltage detected! (0x00050005)
Sep 22 16:30:52 OTBR kernel: Under-voltage detected! (0x00050005)
Sep 22 16:31:01 OTBR CRON[1235]: pam_unix(cron:session): session opened for user pi by (uid=0)
Sep 22 16:31:01 OTBR CRON[1234]: pam_unix(cron:session): session opened for user root by (uid=0)
Sep 22 16:31:01 OTBR CRON[1243]: (pi) CMD (/home/pi/Autorun/reboot_wpan0.sh )
Sep 22 16:31:01 OTBR CRON[1242]: (root) CMD (/home/pi/Autorun/reboot_wpan0sh >> /home/pi/rtxt.txt)
Sep 22 16:31:01 OTBR CRON[1234]: (CRON) info (No MTA installed, discarding output)
Sep 22 16:31:01 OTBR CRON[1234]: pam_unix(cron:session): session closed for user root
Sep 22 16:31:01 OTBR CRON[1235]: (CRON) info (No MTA installed, discarding output)
Sep 22 16:31:01 OTBR CRON[1235]: pam_unix(cron:session): session closed for user pi

I have tried both with fresh Nordic OTBR image and mqttsn publisher example still get the same issue.

Please help .