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

embassy-net-nrf91 panics during tcp retransmission #3604

Open
mdrssv opened this issue Dec 3, 2024 · 4 comments
Open

embassy-net-nrf91 panics during tcp retransmission #3604

mdrssv opened this issue Dec 3, 2024 · 4 comments

Comments

@mdrssv
Copy link

mdrssv commented Dec 3, 2024 •
edited
Loading

Hi,

I've been toying around with the nrf9160 example using a Thingy91.
However when running the program for some period of time I almost always run into an panic due to NoFreeBufs. My guess would be, that the retransmission generates to many packets(one packet = one TX_BUF) which cannot be written out fast enough, thus exhausting the 4 defined buffers.

Modified main, otherwise everthing is pretty much like in the original example

#[embassy_executor::main]
async fn main(spawner: Spawner) {
    let p = embassy_nrf::init(Default::default());

    info!("Hello World!");

    let ipc_mem = unsafe {
        let ipc_start = &__start_ipc as *const u8 as *mut MaybeUninit<u8>;
        let ipc_end = &__end_ipc as *const u8 as *mut MaybeUninit<u8>;
        let ipc_len = ipc_end.offset_from(ipc_start) as usize;
        slice::from_raw_parts_mut(ipc_start, ipc_len)
    };

    static mut TRACE_BUF: [u8; 4096] = [0u8; 4096];
    let mut config = uarte::Config::default();
    config.baudrate = Baudrate::BAUD1M;
    let uart = BufferedUarteTx::new(
        //let trace_uart = BufferedUarteTx::new(
        unsafe { peripherals::SERIAL0::steal() },
        Irqs,
        unsafe { peripherals::P0_01::steal() },
        //unsafe { peripherals::P0_14::steal() },
        config,
        unsafe { &mut *addr_of_mut!(TRACE_BUF) },
    );

    static STATE: StaticCell<State> = StaticCell::new();
    static TRACE: StaticCell<TraceBuffer> = StaticCell::new();
    let (device, control, runner, tracer) = embassy_net_nrf91::new_with_trace(
        STATE.init(State::new()),
        ipc_mem,
        TRACE.init(TraceBuffer::new()),
    )
    .await;
    unwrap!(spawner.spawn(modem_task(runner)));
    unwrap!(spawner.spawn(trace_task(uart, tracer)));

    let led = |_, _, _| {};
    #[cfg(feature = "led")]
    let mut led = {
        let mut led_pwm = SimplePwm::new_3ch(p.PWM0, p.P0_29, p.P0_30, p.P0_31);
        led_pwm.set_period(16_000_000);
        led_pwm.set_max_duty(255);
        for ch in 0..3 {
            led_pwm.set_duty(ch, 255);
        }
        led_pwm.enable();
        let mut led = move |r: u8, g: u8, b: u8| {
            let max_duty = led_pwm.max_duty() as u32;
            let duty = |val: u8| (val as u32 * max_duty / 255u32) as u16;
            for (ch, dc) in [r, g, b].into_iter().enumerate() {
                led_pwm.set_duty(ch, duty(dc));
            }
        };
        led(10, 10, 10);
        led
    };

    let config = embassy_net::Config::default();

    // Generate "random" seed. nRF91 has no RNG, TODO figure out something...
    let seed = 1;

    // Init network stack
    static RESOURCES: StaticCell<StackResources<2>> = StaticCell::new();
    let (stack, runner) = embassy_net::new(
        device,
        config,
        RESOURCES.init(StackResources::<2>::new()),
        seed,
    );

    unwrap!(spawner.spawn(net_task(runner)));

    static CONTROL: StaticCell<context::Control<'static>> = StaticCell::new();
    let control = CONTROL.init(context::Control::new(control, 0).await);

    unwrap!(spawner.spawn(control_task(
        control,
        context::Config {
            apn: b"iot.1nce.net ",
            auth_prot: context::AuthProt::None,
            auth: None,
            pin: None,
        },
        stack
    )));

    stack.wait_config_up().await;
    let ip = stack.config_v4().unwrap().address.address();
    info!("local ip: {}", ip);

    let mut rx_buffer = [0; 4096];
    let mut tx_buffer = [0; 4096];
    let interval = Duration::from_secs(10);
    #[cfg(feature = "infrequent")]
    let interval = Duration::from_secs(60);
    let mut connection_count = 0;
    loop {
        connection_count += 1u32;
        led(200, 255, 0);
        let mut socket = embassy_net::tcp::TcpSocket::new(stack, &mut rx_buffer, &mut tx_buffer);
        socket.set_timeout(Some(Duration::from_secs(10) + interval));
        let port = 4242;
        if cfg!(feature = "server") {
            info!("listening on: {}:{}", ip, port);
            if let Err(e) = socket.accept(SocketAddrV4::new(ip, port)).await {
                error!("accept error: {}", e);
                Timer::after_secs(1).await;
                continue;
            }
            info!("client connected: {}", socket.remote_endpoint());
        } else {
            let hostname = option_env!("ECHO_HOSTNAME").unwrap_or("tcpbin.com");
            let resp = stack
                .dns_query(hostname, smoltcp::wire::DnsQueryType::A)
                .await;
            let resp = match resp {
                Err(e) => {
                    error!("failed to resolve hostname {}", e);
                    Timer::after_secs(30).await;
                    continue;
                }
                Ok(a) => a,
            };
            if let Some(embassy_net::IpAddress::Ipv4(v4)) = resp.get(0) {
                let sa = SocketAddrV4::new(*v4, port);
                if let Err(e) = socket.connect(sa).await {
                    error!("failed to connect to {}: {}", sa, e);
                    Timer::after_secs(30).await;
                    continue;
                }
                info!(
                    "connected to: {}/{}, send capacity: {}, recv capacity: {}",
                    hostname,
                    sa,
                    socket.send_capacity(),
                    socket.recv_capacity()
                );
                if let Some(auth) = option_env!("ECHO_AUTH").map(|a| a.as_bytes()) {
                    let mut auth_msg = [0u8; 33];
                    auth_msg[..auth.len()].copy_from_slice(auth);
                    auth_msg[auth.len()] = b'\n';
                    if let Err(e) = socket.write_all(&auth_msg[..]).await {
                        warn!("write error: {:?}", e);
                        break;
                    }
                }
            } else {
                error!("hostname resolved to nothing");
                Timer::after_secs(30).await;
                continue;
            }
        }
        Timer::after_millis(50).await;
        let mut roundtrip_time = None;
        let mut iteration = 0;
        loop {
            iteration += 1u32;
            let next = Instant::now() + interval;
            let res: Result<_, embassy_net::tcp::Error> = try {
                    let mut buf = [0u8; 300];
                    for _ in 0..3 {
                        socket.write_all(b"ping\n").await?;
                        socket.read(&mut buf[..5]).await?;
                    }
                Timer::at(next).await;
            };
            if let Err(e) = res {
                trace!("about to print an error");
                error!("{:?}", e);
                led(255, 0, 0);
                Timer::after_secs(10).await;
                break;
            }
        }
    }
}
 INFO  Hello World!
└─ embassy_nrf9160_examples::____embassy_main_task::{async_fn#0} @ src/main.rs:233
DEBUG Setting IPC RAM as nonsecure...
└─ embassy_net_nrf91::new_internal::{async_fn#0} @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:137
TRACE [0]: adding
└─ smoltcp::iface::socket_set::{impl#2}::add::put @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
<snip>

TRACE stop delayed ack timer
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
INFO  using tx buf idx 1
└─ embassy_net_nrf91::{impl#3}::find_free_tx_buf @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:499
INFO  tx buf 1
└─ embassy_net_nrf91::{impl#3}::send_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:515
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 196 msg: Message { id: 1879441412, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x20000f44, data_len: 40, param_len: 12, param: [0, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
DEBUG rx seq 292 msg: Message { id: 2147876868, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x0, data_len: 0, param_len: 16, param: [0, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 40, 0, 0, 0, 221, 66, 0, 0, 112, 105, 110, 103, 10, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
DEBUG rx seq 293 msg: Message { id: 2415984644, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x20002288, data_len: 45, param_len: 24, param: [128, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 197 msg: Message { id: 131073, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x20002288, data_len: 45, param_len: 0, param: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
TRACE rx buffer: receiving 5 octets at offset 0
└─ smoltcp::socket::tcp::{impl#9}::process @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE rx buffer: enqueueing 5 octets (now 35)
└─ smoltcp::socket::tcp::{impl#9}::process @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE starting delayed ack timer
└─ smoltcp::socket::tcp::{impl#9}::process @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE outgoing segment will acknowledge
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE sending ACK
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE stop delayed ack timer
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
INFO  using tx buf idx 2
└─ embassy_net_nrf91::{impl#3}::find_free_tx_buf @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:499
INFO  tx buf 2
└─ embassy_net_nrf91::{impl#3}::send_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:515
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 198 msg: Message { id: 1879441412, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x20001520, data_len: 40, param_len: 12, param: [0, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
DEBUG rx seq 294 msg: Message { id: 2147876868, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x0, data_len: 0, param_len: 16, param: [0, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 40, 0, 0, 0, 221, 61, 0, 0, 112, 105, 110, 103, 10, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 2
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:331
INFO  using tx buf idx 3
└─ embassy_net_nrf91::{impl#3}::find_free_tx_buf @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:499
INFO  tx buf 3
└─ embassy_net_nrf91::{impl#3}::send_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:515
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 199 msg: Message { id: 65539, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x20001afc, data_len: 11, param_len: 4, param: [179, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 2
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:331
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
DEBUG rx seq 295 msg: Message { id: 131073, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x20000968, data_len: 4, param_len: 0, param: [1, 0, 2, 0, 1, 0, 0, 0, 68, 15, 0, 32, 4, 0, 0, 0, 0, 0, 0, 0, 1, 0, 2, 0, 1, 0, 0, 0, 32, 21, 0, 32, 4, 0, 0, 0, 0, 0, 0, 0, 1, 0, 2, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE control free pointer 20000968 idx 0
└─ embassy_net_nrf91::{impl#3}::handle_control_free @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:578
DEBUG rx seq 296 msg: Message { id: 131073, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x20000f44, data_len: 4, param_len: 0, param: [1, 0, 2, 0, 1, 0, 0, 0, 32, 21, 0, 32, 4, 0, 0, 0, 0, 0, 0, 0, 1, 0, 2, 0, 1, 0, 0, 0, 252, 26, 0, 32, 4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE control free pointer 20000f44 idx 1
└─ embassy_net_nrf91::{impl#3}::handle_control_free @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:578
DEBUG rx seq 297 msg: Message { id: 131073, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x20001520, data_len: 4, param_len: 0, param: [1, 0, 2, 0, 1, 0, 0, 0, 252, 26, 0, 32, 4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 0, 0, 104, 9, 0, 32, 4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE control free pointer 20001520 idx 2
└─ embassy_net_nrf91::{impl#3}::handle_control_free @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:578
DEBUG rx seq 298 msg: Message { id: 131073, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x20001afc, data_len: 4, param_len: 0, param: [0, 0, 0, 0, 1, 0, 0, 0, 104, 9, 0, 32, 4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE control free pointer 20001afc idx 3
└─ embassy_net_nrf91::{impl#3}::handle_control_free @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:578
DEBUG rx seq 299 msg: Message { id: 131075, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x0, data_len: 0, param_len: 4, param: [179, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 3, 0, 3, 0, 2, 0, 0, 0, 200, 34, 0, 32, 16, 0, 0, 0, 4, 0, 0, 0, 179, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
DEBUG rx seq 300 msg: Message { id: 196611, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x200022c8, data_len: 16, param_len: 4, param: [179, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0, 43, 67, 71, 65, 84, 84, 58, 32, 49, 13, 10, 79, 75, 13, 10, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 200 msg: Message { id: 131073, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x200022c8, data_len: 16, param_len: 0, param: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
INFO  using tx buf idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_tx_buf @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:499
INFO  tx buf 0
└─ embassy_net_nrf91::{impl#3}::send_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:515
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 201 msg: Message { id: 65539, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x20000968, data_len: 11, param_len: 4, param: [180, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 2
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:331
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
DEBUG rx seq 301 msg: Message { id: 131073, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x20000968, data_len: 4, param_len: 0, param: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE control free pointer 20000968 idx 0
└─ embassy_net_nrf91::{impl#3}::handle_control_free @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:578
DEBUG rx seq 302 msg: Message { id: 131075, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x0, data_len: 0, param_len: 4, param: [180, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 3, 0, 3, 0, 2, 0, 0, 0, 200, 34, 0, 32, 16, 0, 0, 0, 4, 0, 0, 0, 180, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
DEBUG rx seq 303 msg: Message { id: 196611, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x200022c8, data_len: 16, param_len: 4, param: [180, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0, 43, 67, 71, 65, 84, 84, 58, 32, 49, 13, 10, 79, 75, 13, 10, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 202 msg: Message { id: 131073, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x200022c8, data_len: 16, param_len: 0, param: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
INFO  using tx buf idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_tx_buf @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:499
INFO  tx buf 0
└─ embassy_net_nrf91::{impl#3}::send_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:515
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 203 msg: Message { id: 65539, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x20000968, data_len: 11, param_len: 4, param: [181, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 2
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:331
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
DEBUG rx seq 304 msg: Message { id: 131073, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x20000968, data_len: 4, param_len: 0, param: [0, 0, 0, 0, 1, 0, 0, 0, 68, 15, 0, 32, 4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 0, 0, 32, 21, 0, 32, 4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE control free pointer 20000968 idx 0
└─ embassy_net_nrf91::{impl#3}::handle_control_free @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:578
DEBUG rx seq 305 msg: Message { id: 131075, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x0, data_len: 0, param_len: 4, param: [181, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 3, 0, 3, 0, 2, 0, 0, 0, 200, 34, 0, 32, 16, 0, 0, 0, 4, 0, 0, 0, 181, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
DEBUG rx seq 306 msg: Message { id: 196611, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x200022c8, data_len: 16, param_len: 4, param: [181, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0, 43, 67, 71, 65, 84, 84, 58, 32, 49, 13, 10, 79, 75, 13, 10, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 204 msg: Message { id: 131073, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x200022c8, data_len: 16, param_len: 0, param: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
INFO  using tx buf idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_tx_buf @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:499
INFO  tx buf 0
└─ embassy_net_nrf91::{impl#3}::send_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:515
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 205 msg: Message { id: 65539, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x20000968, data_len: 11, param_len: 4, param: [182, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 2
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:331
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
DEBUG rx seq 307 msg: Message { id: 131073, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x20000968, data_len: 4, param_len: 0, param: [0, 0, 0, 0, 1, 0, 0, 0, 32, 21, 0, 32, 4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 0, 0, 252, 26, 0, 32, 4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE control free pointer 20000968 idx 0
└─ embassy_net_nrf91::{impl#3}::handle_control_free @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:578
DEBUG rx seq 308 msg: Message { id: 131075, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x0, data_len: 0, param_len: 4, param: [182, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 3, 0, 3, 0, 2, 0, 0, 0, 200, 34, 0, 32, 16, 0, 0, 0, 4, 0, 0, 0, 182, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
DEBUG rx seq 309 msg: Message { id: 196611, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x200022c8, data_len: 16, param_len: 4, param: [182, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0, 43, 67, 71, 65, 84, 84, 58, 32, 49, 13, 10, 79, 75, 13, 10, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 206 msg: Message { id: 131073, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x200022c8, data_len: 16, param_len: 0, param: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
INFO  using tx buf idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_tx_buf @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:499
INFO  tx buf 0
└─ embassy_net_nrf91::{impl#3}::send_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:515
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 207 msg: Message { id: 65539, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x20000968, data_len: 11, param_len: 4, param: [183, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 2
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:331
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
DEBUG rx seq 310 msg: Message { id: 131073, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x20000968, data_len: 4, param_len: 0, param: [0, 0, 0, 0, 1, 0, 0, 0, 104, 9, 0, 32, 4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 0, 0, 32, 21, 0, 32, 4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE control free pointer 20000968 idx 0
└─ embassy_net_nrf91::{impl#3}::handle_control_free @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:578
DEBUG rx seq 311 msg: Message { id: 131075, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x0, data_len: 0, param_len: 4, param: [183, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 3, 0, 3, 0, 2, 0, 0, 0, 200, 34, 0, 32, 16, 0, 0, 0, 4, 0, 0, 0, 183, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
DEBUG rx seq 312 msg: Message { id: 196611, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x200022c8, data_len: 16, param_len: 4, param: [183, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0, 43, 67, 71, 65, 84, 84, 58, 32, 49, 13, 10, 79, 75, 13, 10, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 208 msg: Message { id: 131073, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x200022c8, data_len: 16, param_len: 0, param: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
INFO  using tx buf idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_tx_buf @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:499
INFO  tx buf 0
└─ embassy_net_nrf91::{impl#3}::send_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:515
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 209 msg: Message { id: 65539, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x20000968, data_len: 11, param_len: 4, param: [184, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 2
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:331
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
DEBUG rx seq 313 msg: Message { id: 131073, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x20000968, data_len: 4, param_len: 0, param: [0, 0, 0, 0, 1, 0, 0, 0, 32, 21, 0, 32, 4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 0, 0, 252, 26, 0, 32, 4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE control free pointer 20000968 idx 0
└─ embassy_net_nrf91::{impl#3}::handle_control_free @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:578
DEBUG rx seq 314 msg: Message { id: 131075, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x0, data_len: 0, param_len: 4, param: [184, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 3, 0, 3, 0, 2, 0, 0, 0, 200, 34, 0, 32, 16, 0, 0, 0, 4, 0, 0, 0, 184, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
DEBUG rx seq 315 msg: Message { id: 196611, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x200022c8, data_len: 16, param_len: 4, param: [184, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0, 43, 67, 71, 65, 84, 84, 58, 32, 49, 13, 10, 79, 75, 13, 10, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 210 msg: Message { id: 131073, channel: 1, unk1: 0, unk2: 0, unk3: 0, data: 0x200022c8, data_len: 16, param_len: 0, param: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
TRACE outgoing segment will send data or flags
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE tx buffer: sending 15 octets at offset 0
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE sending PSH|ACK
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE rtte: sampling at seq=1368494406
└─ smoltcp::socket::tcp::{impl#6}::on_send @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
INFO  using tx buf idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_tx_buf @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:499
INFO  tx buf 0
└─ embassy_net_nrf91::{impl#3}::send_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:515
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 211 msg: Message { id: 1879441412, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x20000968, data_len: 55, param_len: 12, param: [0, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
DEBUG rx seq 316 msg: Message { id: 2147876868, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x0, data_len: 0, param_len: 16, param: [0, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 55, 0, 0, 0, 2, 0, 0, 0, 200, 34, 0, 32, 16, 0, 0, 0, 4, 0, 0, 0, 184, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
DEBUG retransmitting at t+0.518s
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:18
TRACE rtte: abort sampling due to retransmit
└─ smoltcp::socket::tcp::{impl#6}::on_retransmit @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE outgoing segment will send data or flags
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE tx buffer: sending 15 octets at offset 0
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE sending PSH|ACK
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
INFO  using tx buf idx 1
└─ embassy_net_nrf91::{impl#3}::find_free_tx_buf @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:499
INFO  tx buf 1
└─ embassy_net_nrf91::{impl#3}::send_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:515
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 212 msg: Message { id: 1879441412, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x20000f44, data_len: 55, param_len: 12, param: [0, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
DEBUG rx seq 317 msg: Message { id: 2147876868, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x0, data_len: 0, param_len: 16, param: [0, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 55, 0, 0, 0, 2, 0, 0, 0, 200, 34, 0, 32, 16, 0, 0, 0, 4, 0, 0, 0, 184, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
DEBUG retransmitting at t+0.518s
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:18
TRACE outgoing segment will send data or flags
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE tx buffer: sending 15 octets at offset 0
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE sending PSH|ACK
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
INFO  using tx buf idx 2
└─ embassy_net_nrf91::{impl#3}::find_free_tx_buf @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:499
INFO  tx buf 2
└─ embassy_net_nrf91::{impl#3}::send_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:515
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 213 msg: Message { id: 1879441412, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x20001520, data_len: 55, param_len: 12, param: [0, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
DEBUG rx seq 318 msg: Message { id: 2147876868, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x0, data_len: 0, param_len: 16, param: [0, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 55, 0, 0, 0, 2, 0, 0, 0, 200, 34, 0, 32, 16, 0, 0, 0, 4, 0, 0, 0, 184, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
DEBUG retransmitting at t+0.518s
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:18
TRACE rtte: too many retransmissions, increasing: rtt=612 dev=53 rto=824
└─ smoltcp::socket::tcp::{impl#6}::on_retransmit @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE outgoing segment will send data or flags
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE tx buffer: sending 15 octets at offset 0
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE sending PSH|ACK
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
INFO  using tx buf idx 3
└─ embassy_net_nrf91::{impl#3}::find_free_tx_buf @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:499
INFO  tx buf 3
└─ embassy_net_nrf91::{impl#3}::send_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:515
TRACE using tx msg idx 0
└─ embassy_net_nrf91::{impl#3}::find_free_message @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:489
DEBUG tx seq 214 msg: Message { id: 1879441412, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x20001afc, data_len: 55, param_len: 12, param: [0, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0,
0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::send_message_raw @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:539
TRACE irq
└─ embassy_net_nrf91::on_ipc_irq @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:41
TRACE poll!
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:321
TRACE ipc 4
└─ embassy_net_nrf91::{impl#3}::poll @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:355
DEBUG rx seq 319 msg: Message { id: 2147876868, channel: 2, unk1: 0, unk2: 0, unk3: 0, data: 0x0, data_len: 0, param_len: 16, param: [0, 0, 0, 0, 128, 0, 0, 0, 0, 0, 0, 0, 55, 0, 0, 0, 2, 0, 0, 0, 200, 34, 0, 32, 16, 0, 0, 0, 4, 0, 0, 0, 184, 0, 0, 0, 4, 0, 1, 144, 2, 0, 0, 0] }
└─ embassy_net_nrf91::{impl#3}::process @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:468
WARN  no free tx buf
└─ embassy_net_nrf91::{impl#3}::find_free_tx_buf @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:503
ERROR panicked at 'unwrap failed: state.send_message(msg, req_data)'
error: `NoFreeBufs`
└─ embassy_net_nrf91::{impl#5}::request::{async_fn#0} @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/04c1d69/embassy-net-nrf91/src/lib.rs:762
ERROR panicked at /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/defmt-0.3.10/src/lib.rs:380:5:
explicit panic
└─ panic_probe::print_defmt::print @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/panic-probe-0.3.2/src/lib.rs:104

Full log
@lulf
Copy link
Member

lulf commented Dec 3, 2024

Hi, could you try the fixes in this PR? #3603

@mdrssv
Copy link
Author

mdrssv commented Dec 3, 2024

Hi, could you try the fixes in this PR? #3603

Still happening

DEBUG retransmitting at t+0.796s
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:18
TRACE rtte: abort sampling due to retransmit
└─ smoltcp::socket::tcp::{impl#6}::on_retransmit @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE outgoing segment will send data or flags
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE tx buffer: sending 5 octets at offset 0
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE sending PSH|ACK
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE tx buffer: dequeueing 5 octets (now 0)
└─ smoltcp::socket::tcp::{impl#9}::process @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE rx buffer: receiving 4 octets at offset 0
└─ smoltcp::socket::tcp::{impl#9}::process @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE rx buffer: enqueueing 4 octets (now 24)
└─ smoltcp::socket::tcp::{impl#9}::process @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE starting delayed ack timer
└─ smoltcp::socket::tcp::{impl#9}::process @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE outgoing segment will send data or flags
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE tx buffer: sending 5 octets at offset 0
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE sending PSH|ACK
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE stop delayed ack timer
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE rtte: sampling at seq=22509129
└─ smoltcp::socket::tcp::{impl#6}::on_send @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE rtte: sample=49 rtt=224 dev=147 rto=812
└─ smoltcp::socket::tcp::{impl#6}::sample @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE tx buffer: dequeueing 5 octets (now 0)
└─ smoltcp::socket::tcp::{impl#9}::process @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE rx buffer: receiving 1 octets at offset 0
└─ smoltcp::socket::tcp::{impl#9}::process @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE rx buffer: enqueueing 1 octets (now 20)
└─ smoltcp::socket::tcp::{impl#9}::process @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE starting delayed ack timer
└─ smoltcp::socket::tcp::{impl#9}::process @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE outgoing segment will acknowledge
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE sending ACK
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE stop delayed ack timer
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE rx buffer: receiving 5 octets at offset 0
└─ smoltcp::socket::tcp::{impl#9}::process @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE rx buffer: enqueueing 5 octets (now 20)
└─ smoltcp::socket::tcp::{impl#9}::process @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE starting delayed ack timer
└─ smoltcp::socket::tcp::{impl#9}::process @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE outgoing segment will acknowledge
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE sending ACK
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
TRACE stop delayed ack timer
└─ smoltcp::socket::tcp::{impl#9}::dispatch @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/smoltcp-0.12.0/src/macros.rs:17
ERROR panicked at 'unwrap failed: state.send_message(msg, req_data)'
error: `NoFreeBufs`
└─ embassy_net_nrf91::{impl#5}::request::{async_fn#0} @ /home/marvin/.cargo/git/checkouts/embassy-9312dcb0ed774b29/6065bc6/embassy-net-nrf91/src/lib.rs:765
ERROR panicked at /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/defmt-0.3.10/src/lib.rs:380:5:
explicit panic
└─ panic_probe::print_defmt::print @ /home/marvin/.cargo/registry/src/index.crates.io-6f17d22bba15001f/panic-probe-0.3.2/src/lib.rs:104

@lulf
Copy link
Member

lulf commented Dec 3, 2024

Ok, i will try your reproducer tomorrow, thanks for testing .

@lulf
Copy link
Member

lulf commented Dec 4, 2024

@mdrssv I've not gotten a public tcp server setup yet to reproduce the retransmits, and the reproducer have been running for a while. However, I've got another patch here #3609 - It should make it wait for free bufs instead of failing.

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
2 participants
@lulf @mdrssv