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

Flaky test: test_blob_delete_mem #2783

Closed
flub opened this issue Oct 4, 2024 · 2 comments
Closed

Flaky test: test_blob_delete_mem #2783

flub opened this issue Oct 4, 2024 · 2 comments
Labels
_c-iroh-legacy Formerly big iroh node with all protocols flaky-test

Comments

@flub
Copy link
Contributor

flub commented Oct 4, 2024

/~https://github.com/n0-computer/iroh/actions/runs/11166690673/job/31041223663?pr=2782

--- STDOUT:              iroh client::blobs::tests::test_blob_delete_mem ---

running 1 test
2024-10-03T17:39:24.604818Z DEBUG iroh_net::discovery::pkarr: 152: creating pkarr publisher that publishes to https://staging-dns.iroh.link/pkarr
2024-10-03T17:39:24.661372Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock: iroh_net::magicsock::udp_conn: 114: binding addr=0.0.0.0:11204
2024-10-03T17:39:24.661452Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock: iroh_net::magicsock::udp_conn: 129: candidate ports ports=[11204, 0]
2024-10-03T17:39:24.663075Z  WARN ep{me=7uiwjh7f42fdio3e}:magicsock: iroh_net::magicsock::udp_conn: 140: failed to bind: Error {
    context: "binding",
    source: Os {
        code: 10048,
        kind: AddrInUse,
        message: "Only one usage of each socket address (protocol/network address/port) is normally permitted.",
    },
} addr=0.0.0.0:11204
2024-10-03T17:39:24.663432Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock: iroh_net::magicsock::udp_conn: 136: successfully bound addr=0.0.0.0:0 local_addr=0.0.0.0:51754
2024-10-03T17:39:24.663575Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock: iroh_net::magicsock::udp_conn: 114: binding addr=[::]:11205
2024-10-03T17:39:24.663608Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock: iroh_net::magicsock::udp_conn: 129: candidate ports ports=[11205, 0]
2024-10-03T17:39:24.663838Z  WARN ep{me=7uiwjh7f42fdio3e}:magicsock: iroh_net::magicsock::udp_conn: 140: failed to bind: Error {
    context: "binding",
    source: Os {
        code: 10048,
        kind: AddrInUse,
        message: "Only one usage of each socket address (protocol/network address/port) is normally permitted.",
    },
} addr=[::]:11205
2024-10-03T17:39:24.663946Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock: iroh_net::magicsock::udp_conn: 136: successfully bound addr=[::]:0 local_addr=[::]:51757
2024-10-03T17:39:24.688002Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock: wmi::connection: 185: Calling CoCreateInstance for CLSID_WbemLocator    
2024-10-03T17:39:24.689646Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock: wmi::connection: 189: Got locator IWbemLocator(IUnknown(0x203dda314f0))    
2024-10-03T17:39:24.689924Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock: wmi::connection: 195: Calling ConnectServer    
2024-10-03T17:39:24.698041Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock: wmi::connection: 211: Got service IWbemServices(IUnknown(0x203de09b6f0))    
2024-10-03T17:39:24.698103Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock: wmi::connection: 165: Calling CoSetProxyBlanket    
2024-10-03T17:39:24.750327Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:portmapper.service: iroh_net::portmapper: 440: portmap starting
2024-10-03T17:39:24.893295Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:portmapper.service: iroh_net::portmapper: 585: getting a port mapping for 144.76.78.249:51754 -> None
2024-10-03T17:39:24.893450Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor: iroh_net::netcheck: 438: netcheck actor starting
2024-10-03T17:39:24.893780Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:actor: iroh_net::magicsock: 2486: scheduling periodic_stun to run immediately and in 20s
2024-10-03T17:39:24.894282Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:portmapper.service:upnp: igd_next::aio::tokio: 79: sending broadcast request to: 239.255.255.250:1900 on interface: Ok(0.0.0.0:52084)    
2024-10-03T17:39:24.894782Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:actor:re_stun: iroh_net::magicsock: 1258: re_stun: periodic
2024-10-03T17:39:24.894878Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:actor:update_direct_addrs: iroh_net::magicsock: 2061: starting direct addr update (periodic)
2024-10-03T17:39:24.894951Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:actor:update_direct_addrs:update_net_info: iroh_net::magicsock: 2283: requesting netcheck report
2024-10-03T17:39:24.902692Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:portmapper.service: iroh_net::portmapper: 585: getting a port mapping for 144.76.78.249:51754 -> None
2024-10-03T17:39:24.904144Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:portmapper.service:upnp: igd_next::aio::tokio: 79: sending broadcast request to: 239.255.255.250:1900 on interface: Ok(0.0.0.0:52154)    
2024-10-03T17:39:24.905182Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: 215: reportstate actor starting port_mapper=true
2024-10-03T17:39:24.918618Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor: wmi::connection: 185: Calling CoCreateInstance for CLSID_WbemLocator    
2024-10-03T17:39:24.918674Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor: wmi::connection: 189: Got locator IWbemLocator(IUnknown(0x203dda31930))    
2024-10-03T17:39:24.918703Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor: wmi::connection: 195: Calling ConnectServer    
2024-10-03T17:39:24.921842Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor: wmi::connection: 211: Got service IWbemServices(IUnknown(0x203de09b1e0))    
2024-10-03T17:39:24.921902Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor: wmi::connection: 165: Calling CoSetProxyBlanket    
2024-10-03T17:39:24.926810Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: 521: Local interfaces if_state=1. {2147C046-7FA7-11EC-AF65-806E6F6E6963} Loopback ipv4=[127.0.0.1/8] ipv6=[::1/128]; 6. {DCB3D97C-312E-4C6C-B5B8-858648AC3597} Ethernet ipv4=[144.76.78.249/27] ipv6=[fe80::ff24:2d0a:fc0:e15f/64]; 
2024-10-03T17:39:24.928028Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) iroh_net::netcheck::reportgen: 683: starting probe
2024-10-03T17:39:24.928724Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) iroh_net::netcheck::reportgen: 683: starting probe
2024-10-03T17:39:24.953295Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:portmapper.service:portmapper.probe: igd_next::aio::tokio: 79: sending broadcast request to: 239.255.255.250:1900 on interface: Ok(0.0.0.0:52307)    
2024-10-03T17:39:24.953391Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) iroh_net::netcheck::reportgen: 937: Performing DNS A lookup for relay addr proto=StunIpv4 hostname=staging-euw1-1.relay.iroh.network.
2024-10-03T17:39:24.953490Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) iroh_net::netcheck::reportgen: 937: Performing DNS A lookup for relay addr proto=StunIpv4 hostname=staging-use1-1.relay.iroh.network.
2024-10-03T17:39:24.965171Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) hickory_proto::xfer::dns_handle: 64: querying: staging-euw1-1.relay.iroh.network. A
2024-10-03T17:39:24.965269Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) hickory_resolver::name_server::name_server_pool: 211: sending request: [Query { name: Name("staging-euw1-1.relay.iroh.network."), query_type: A, query_class: IN }]
2024-10-03T17:39:24.965360Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) hickory_resolver::name_server::name_server: 103: reconnecting: NameServerConfig { socket_addr: 185.12.64.2:53, protocol: Udp, tls_dns_name: None, trust_negative_responses: false, tls_config: None, bind_addr: None }
2024-10-03T17:39:24.965460Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) hickory_proto::xfer: 170: enqueueing message:QUERY:[Query { name: Name("staging-euw1-1.relay.iroh.network."), query_type: A, query_class: IN }]
2024-10-03T17:39:24.965516Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) hickory_resolver::name_server::name_server: 103: reconnecting: NameServerConfig { socket_addr: 185.12.64.1:53, protocol: Udp, tls_dns_name: None, trust_negative_responses: false, tls_config: None, bind_addr: None }
2024-10-03T17:39:24.965571Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) hickory_proto::xfer: 170: enqueueing message:QUERY:[Query { name: Name("staging-euw1-1.relay.iroh.network."), query_type: A, query_class: IN }]
2024-10-03T17:39:24.965725Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) hickory_proto::xfer::dns_handle: 64: querying: staging-use1-1.relay.iroh.network. A
2024-10-03T17:39:24.965793Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) hickory_resolver::name_server::name_server_pool: 211: sending request: [Query { name: Name("staging-use1-1.relay.iroh.network."), query_type: A, query_class: IN }]
2024-10-03T17:39:24.965863Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) hickory_resolver::name_server::name_server: 117: existing connection: NameServerConfig { socket_addr: 185.12.64.2:53, protocol: Udp, tls_dns_name: None, trust_negative_responses: false, tls_config: None, bind_addr: None }
2024-10-03T17:39:24.965904Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) hickory_proto::xfer: 170: enqueueing message:QUERY:[Query { name: Name("staging-use1-1.relay.iroh.network."), query_type: A, query_class: IN }]
2024-10-03T17:39:24.965951Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) hickory_resolver::name_server::name_server: 117: existing connection: NameServerConfig { socket_addr: 185.12.64.1:53, protocol: Udp, tls_dns_name: None, trust_negative_responses: false, tls_config: None, bind_addr: None }
2024-10-03T17:39:24.966040Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) hickory_proto::xfer: 170: enqueueing message:QUERY:[Query { name: Name("staging-use1-1.relay.iroh.network."), query_type: A, query_class: IN }]
2024-10-03T17:39:24.966183Z DEBUG hickory_proto::udp::udp_client_stream: 228: final message: ; header 22930:QUERY:RD:NoError:QUERY:0/0/0
; query
;; staging-euw1-1.relay.iroh.network. IN A

2024-10-03T17:39:24.966252Z DEBUG hickory_proto::udp::udp_client_stream: 228: final message: ; header 37449:QUERY:RD:NoError:QUERY:0/0/0
; query
;; staging-use1-1.relay.iroh.network. IN A

2024-10-03T17:39:24.966304Z DEBUG hickory_proto::udp::udp_client_stream: 228: final message: ; header 49955:QUERY:RD:NoError:QUERY:0/0/0
; query
;; staging-euw1-1.relay.iroh.network. IN A

2024-10-03T17:39:24.966522Z DEBUG hickory_proto::udp::udp_client_stream: 228: final message: ; header 58678:QUERY:RD:NoError:QUERY:0/0/0
; query
;; staging-use1-1.relay.iroh.network. IN A

2024-10-03T17:39:24.966676Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) hickory_proto::udp::udp_stream: 291: created socket successfully
2024-10-03T17:39:24.966828Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) hickory_proto::udp::udp_stream: 291: created socket successfully
2024-10-03T17:39:24.967021Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) hickory_proto::udp::udp_stream: 291: created socket successfully
2024-10-03T17:39:24.967260Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) hickory_proto::udp::udp_stream: 291: created socket successfully
2024-10-03T17:39:24.967867Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) hickory_proto::udp::udp_client_stream: 395: received message id: 22930
2024-10-03T17:39:24.968000Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) hickory_proto::error: 421: Response:; header 22930:RESPONSE:RD,RA:NoError:QUERY:1/0/0
; query
;; staging-euw1-1.relay.iroh.network. IN A
; answers 1
staging-euw1-1.relay.iroh.network. 300 IN A 116.203.122.110
; nameservers 0
; additionals 0

2024-10-03T17:39:24.968156Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) hickory_proto::error: 421: Response:; header 22930:RESPONSE:RD,RA:NoError:QUERY:1/0/0
; query
;; staging-euw1-1.relay.iroh.network. IN A
; answers 1
staging-euw1-1.relay.iroh.network. 300 IN A 116.203.122.110
; nameservers 0
; additionals 0

2024-10-03T17:39:24.968544Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) iroh_net::netcheck::reportgen: 801: sending StunIpv4 probe relay_addr=116.203.122.110:3478 txid=transaction id (0xF5AE7A82CA55236BAA7F9223)
2024-10-03T17:39:24.968725Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) hickory_proto::udp::udp_client_stream: 395: received message id: 37449
2024-10-03T17:39:24.968827Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) hickory_proto::error: 421: Response:; header 37449:RESPONSE:RD,RA:NoError:QUERY:1/0/0
; query
;; staging-use1-1.relay.iroh.network. IN A
; answers 1
staging-use1-1.relay.iroh.network. 300 IN A 5.161.53.121
; nameservers 0
; additionals 0

2024-10-03T17:39:24.968936Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) hickory_proto::error: 421: Response:; header 37449:RESPONSE:RD,RA:NoError:QUERY:1/0/0
; query
;; staging-use1-1.relay.iroh.network. IN A
; answers 1
staging-use1-1.relay.iroh.network. 300 IN A 5.161.53.121
; nameservers 0
; additionals 0

2024-10-03T17:39:24.969165Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 0ns to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) iroh_net::netcheck::reportgen: 801: sending StunIpv4 probe relay_addr=5.161.53.121:3478 txid=transaction id (0x3B63957C58275B1559B12DC1)
2024-10-03T17:39:24.971525Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor: iroh_net::netcheck: 571: received known STUN packet src=116.203.122.110:3478 txn=transaction id (0xF5AE7A82CA55236BAA7F9223)
2024-10-03T17:39:24.971850Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: 338: finished probe probe_report=ProbeReport { ipv4_can_send: true, ipv6_can_send: false, icmpv4: None, icmpv6: None, latency: Some(3.1327ms), probe: StunIpv4 { delay: 0ns, node: RelayNode { url: RelayUrl("https://staging-euw1-1.relay.iroh.network./"), stun_only: false, stun_port: 3478 } }, addr: Some(144.76.78.249:51754) }
2024-10-03T17:39:24.972266Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor: iroh_net::netcheck: 585: received our hairpin STUN request src=144.76.78.249:52178 txn=transaction id (0x024DB801EA29A2FA7A20F985)
2024-10-03T17:39:24.972413Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:hairpin.actor: iroh_net::netcheck::reportgen::hairpin: 138: hairpinning done in 325.5µs, res: true
2024-10-03T17:39:25.043646Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 100ms to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) iroh_net::netcheck::reportgen: 683: starting probe
2024-10-03T17:39:25.043775Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 100ms to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) iroh_net::netcheck::reportgen: 937: Performing DNS A lookup for relay addr proto=StunIpv4 hostname=staging-use1-1.relay.iroh.network.
2024-10-03T17:39:25.060531Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=STUN Ipv4 after 100ms to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) iroh_net::netcheck::reportgen: 801: sending StunIpv4 probe relay_addr=5.161.53.121:3478 txid=transaction id (0x071C3797F7C95B32FCF9D9D6)
2024-10-03T17:39:25.070723Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor: iroh_net::netcheck: 571: received known STUN packet src=5.161.53.121:3478 txn=transaction id (0x3B63957C58275B1559B12DC1)
2024-10-03T17:39:25.070950Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: 338: finished probe probe_report=ProbeReport { ipv4_can_send: true, ipv6_can_send: false, icmpv4: None, icmpv6: None, latency: Some(101.695ms), probe: StunIpv4 { delay: 0ns, node: RelayNode { url: RelayUrl("https://staging-use1-1.relay.iroh.network./"), stun_only: false, stun_port: 3478 } }, addr: Some(144.76.78.249:51754) }
2024-10-03T17:39:25.071023Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: 361: Have enough probe reports, aborting further probes soon reports=2 delay=203.39ms
2024-10-03T17:39:25.139441Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: 469: Captive portal check started after 200ms
2024-10-03T17:39:25.140247Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:captive-portal: reqwest::connect: 497: starting new connection: http://staging-use1-1.relay.iroh.network./    
2024-10-03T17:39:25.150853Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:captive-portal: hyper_util::client::legacy::connect::http: 643: connecting to 5.161.53.121:80
2024-10-03T17:39:25.162051Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor: iroh_net::netcheck: 571: received known STUN packet src=5.161.53.121:3478 txn=transaction id (0x071C3797F7C95B32FCF9D9D6)
2024-10-03T17:39:25.234796Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=HTTPS after 300ms to [https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) iroh_net::netcheck::reportgen: 683: starting probe
2024-10-03T17:39:25.234881Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=HTTPS after 300ms to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) iroh_net::netcheck::reportgen: 683: starting probe
2024-10-03T17:39:25.234927Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=ICMPv4 after 300ms to [https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) iroh_net::netcheck::reportgen: 683: starting probe
2024-10-03T17:39:25.234971Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:run_probe{probe=ICMPv4 after 300ms to [https://staging-use1-1.relay.iroh.network./}:](https://staging-use1-1.relay.iroh.network./%7D:) iroh_net::netcheck::reportgen: 683: starting probe
2024-10-03T17:39:25.235288Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:probe: iroh_net::netcheck::reportgen: 577: probe set aborted: ReportCheck says probe set no longer useful probe=Https { delay: 300ms, node: RelayNode { url: RelayUrl("https://staging-euw1-1.relay.iroh.network./"), stun_only: false, stun_port: 3478 } }
2024-10-03T17:39:25.235377Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:probe: iroh_net::netcheck::reportgen: 577: probe set aborted: ReportCheck says probe set no longer useful probe=Https { delay: 300ms, node: RelayNode { url: RelayUrl("https://staging-use1-1.relay.iroh.network./"), stun_only: false, stun_port: 3478 } }
2024-10-03T17:39:25.235447Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:probe: iroh_net::netcheck::reportgen: 577: probe set aborted: ReportCheck says probe set no longer useful probe=IcmpV4 { delay: 300ms, node: RelayNode { url: RelayUrl("https://staging-euw1-1.relay.iroh.network./"), stun_only: false, stun_port: 3478 } }
2024-10-03T17:39:25.235515Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor:probe: iroh_net::netcheck::reportgen: 577: probe set aborted: ReportCheck says probe set no longer useful probe=IcmpV4 { delay: 300ms, node: RelayNode { url: RelayUrl("https://staging-use1-1.relay.iroh.network./"), stun_only: false, stun_port: 3478 } }
2024-10-03T17:39:25.235651Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: 234: all tasks done
2024-10-03T17:39:25.235687Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: 305: Sending report to netcheck actor
2024-10-03T17:39:25.235903Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: 192: reportgen actor finished
2024-10-03T17:39:25.236094Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:netcheck.actor: iroh_net::netcheck: 615: Report { udp: true, ipv6: false, ipv4: true, ipv6_can_send: false, ipv4_can_send: true, os_has_ipv6: true, icmpv4: None, icmpv6: None, mapping_varies_by_dest_ip: Some(false), mapping_varies_by_dest_ipv6: None, hair_pinning: Some(true), portmap_probe: None, preferred_relay: Some(RelayUrl("https://staging-euw1-1.relay.iroh.network./")), relay_latency: RelayLatencies({RelayUrl("https://staging-euw1-1.relay.iroh.network./"): 3.1327ms, RelayUrl("https://staging-use1-1.relay.iroh.network./"): 101.695ms}), relay_v4_latency: RelayLatencies({RelayUrl("https://staging-euw1-1.relay.iroh.network./"): 3.1327ms, RelayUrl("https://staging-use1-1.relay.iroh.network./"): 101.695ms}), relay_v6_latency: RelayLatencies({}), global_v4: Some(144.76.78.249:51754), global_v6: None, captive_portal: None }
2024-10-03T17:39:25.236293Z  INFO ep{me=7uiwjh7f42fdio3e}:magicsock:actor: iroh_net::magicsock: 2378: home is now relay https://staging-euw1-1.relay.iroh.network./, was None
2024-10-03T17:39:25.236448Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:actor: iroh_net::magicsock: 2483: scheduling periodic_stun to run in 23s
2024-10-03T17:39:25.236491Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:actor: iroh_net::magicsock: 2236: direct addr update done (periodic)
2024-10-03T17:39:25.236557Z DEBUG pkarr_publish{me=7uiwjh7f42fdio3e}: iroh_net::discovery::pkarr: 265: Publish node info to pkarr (info changed)
2024-10-03T17:39:25.236591Z  INFO pkarr_publish{me=7uiwjh7f42fdio3e}: iroh_net::discovery::pkarr: 274: Publish node info to pkarr relay_url=Some("https://staging-euw1-1.relay.iroh.network./") pkarr_relay=https://staging-dns.iroh.link/pkarr
2024-10-03T17:39:25.237519Z DEBUG pkarr_publish{me=7uiwjh7f42fdio3e}: reqwest::connect: 497: starting new connection: https://staging-dns.iroh.link/    
2024-10-03T17:39:25.237690Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor: iroh_net::magicsock::relay_actor: 423: connect relay url=https://staging-euw1-1.relay.iroh.network./ remote_node=None
2024-10-03T17:39:25.237745Z  INFO ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor: iroh_net::magicsock::relay_actor: 471: adding connection to relay: https://staging-euw1-1.relay.iroh.network./ for home-keep-alive
2024-10-03T17:39:25.237933Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor: iroh_net::magicsock::relay_actor: 662: 1 active relay conns: relay-https://staging-euw1-1.relay.iroh.network./
2024-10-03T17:39:25.238045Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client: iroh_net::relay::client: 558: connect: initial connect, current client false
2024-10-03T17:39:25.238108Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: iroh_net::relay::client: 849: dial url self.url=https://staging-euw1-1.relay.iroh.network./
2024-10-03T17:39:25.238299Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: hickory_proto::xfer::dns_handle: 64: querying: staging-euw1-1.relay.iroh.network. AAAA
2024-10-03T17:39:25.238364Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: hickory_resolver::name_server::name_server_pool: 211: sending request: [Query { name: Name("staging-euw1-1.relay.iroh.network."), query_type: AAAA, query_class: IN }]
2024-10-03T17:39:25.238451Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: hickory_resolver::name_server::name_server: 117: existing connection: NameServerConfig { socket_addr: 185.12.64.1:53, protocol: Udp, tls_dns_name: None, trust_negative_responses: false, tls_config: None, bind_addr: None }
2024-10-03T17:39:25.238493Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: hickory_proto::xfer: 170: enqueueing message:QUERY:[Query { name: Name("staging-euw1-1.relay.iroh.network."), query_type: AAAA, query_class: IN }]
2024-10-03T17:39:25.238540Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: hickory_resolver::name_server::name_server: 117: existing connection: NameServerConfig { socket_addr: 185.12.64.2:53, protocol: Udp, tls_dns_name: None, trust_negative_responses: false, tls_config: None, bind_addr: None }
2024-10-03T17:39:25.238574Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: hickory_proto::xfer: 170: enqueueing message:QUERY:[Query { name: Name("staging-euw1-1.relay.iroh.network."), query_type: AAAA, query_class: IN }]
2024-10-03T17:39:25.238656Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:active-relay{url=[https://staging-euw1-1.relay.iroh.network./}:](https://staging-euw1-1.relay.iroh.network./%7D:) iroh_net::magicsock::relay_actor: 103: initial dial https://staging-euw1-1.relay.iroh.network./
2024-10-03T17:39:25.238741Z DEBUG hickory_proto::udp::udp_client_stream: 228: final message: ; header 9747:QUERY:RD:NoError:QUERY:0/0/0
; query
;; staging-euw1-1.relay.iroh.network. IN AAAA

2024-10-03T17:39:25.238794Z DEBUG hickory_proto::udp::udp_client_stream: 228: final message: ; header 45596:QUERY:RD:NoError:QUERY:0/0/0
; query
;; staging-euw1-1.relay.iroh.network. IN AAAA

2024-10-03T17:39:25.238918Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: hickory_proto::udp::udp_stream: 291: created socket successfully
2024-10-03T17:39:25.239028Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: hickory_proto::udp::udp_stream: 291: created socket successfully
2024-10-03T17:39:25.239130Z DEBUG pkarr_publish{me=7uiwjh7f42fdio3e}: hyper_util::client::legacy::connect::http: 643: connecting to 49.13.237.105:443
2024-10-03T17:39:25.239658Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: hickory_proto::udp::udp_client_stream: 395: received message id: 9747
2024-10-03T17:39:25.239738Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: hickory_proto::error: 421: Response:; header 9747:RESPONSE:RD,RA:NoError:QUERY:1/0/0
; query
;; staging-euw1-1.relay.iroh.network. IN AAAA
; answers 1
staging-euw1-1.relay.iroh.network. 300 IN AAAA 2a01:4f8:c0c:a493::1
; nameservers 0
; additionals 0

2024-10-03T17:39:25.239850Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: hickory_proto::error: 421: Response:; header 9747:RESPONSE:RD,RA:NoError:QUERY:1/0/0
; query
;; staging-euw1-1.relay.iroh.network. IN AAAA
; answers 1
staging-euw1-1.relay.iroh.network. 300 IN AAAA 2a01:4f8:c0c:a493::1
; nameservers 0
; additionals 0

2024-10-03T17:39:25.239956Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: iroh_net::relay::client: 857: connecting to 116.203.122.110:443
2024-10-03T17:39:25.241876Z DEBUG pkarr_publish{me=7uiwjh7f42fdio3e}: hyper_util::client::legacy::connect::http: 646: connected to 49.13.237.105:443
2024-10-03T17:39:25.241952Z DEBUG pkarr_publish{me=7uiwjh7f42fdio3e}: rustls::client::hs: 80: No cached session for DnsName("staging-dns.iroh.link")    
2024-10-03T17:39:25.242600Z DEBUG pkarr_publish{me=7uiwjh7f42fdio3e}: rustls::client::hs: 138: Not resuming any session    
2024-10-03T17:39:25.242990Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: iroh_net::relay::client: 651: TCP stream connected server_addr=Ok(116.203.122.110:443) local_addr=144.76.78.249:50412
2024-10-03T17:39:25.243041Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: iroh_net::relay::client: 654: Starting TLS handshake
2024-10-03T17:39:25.243084Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: rustls::client::hs: 80: No cached session for DnsName("staging-euw1-1.relay.iroh.network.")    
2024-10-03T17:39:25.243708Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: rustls::client::hs: 138: Not resuming any session    
2024-10-03T17:39:25.246218Z DEBUG pkarr_publish{me=7uiwjh7f42fdio3e}: rustls::client::hs: 781: Using ciphersuite TLS13_AES_256_GCM_SHA384    
2024-10-03T17:39:25.246282Z DEBUG pkarr_publish{me=7uiwjh7f42fdio3e}: rustls::client::tls13: 142: Not resuming    
2024-10-03T17:39:25.247640Z DEBUG pkarr_publish{me=7uiwjh7f42fdio3e}: rustls::client::tls13: 419: TLS1.3 encrypted extensions: [ServerNameAck]    
2024-10-03T17:39:25.247687Z DEBUG pkarr_publish{me=7uiwjh7f42fdio3e}: rustls::client::hs: 638: ALPN protocol is None    
2024-10-03T17:39:25.251504Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: rustls::client::hs: 781: Using ciphersuite TLS13_AES_256_GCM_SHA384    
2024-10-03T17:39:25.251556Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: rustls::client::tls13: 142: Not resuming    
2024-10-03T17:39:25.252881Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: rustls::client::tls13: 419: TLS1.3 encrypted extensions: [ServerNameAck]    
2024-10-03T17:39:25.252931Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: rustls::client::hs: 638: ALPN protocol is None    
2024-10-03T17:39:25.256251Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: iroh_net::relay::client: 660: tls_connector connect success
2024-10-03T17:39:25.256352Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect: iroh_net::relay::client: 717: Sending upgrade request
2024-10-03T17:39:25.256438Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:relay-actor:client:connect:http-driver: iroh_net::relay::client: 709: HTTP upgrade driver started
2024-10-03T17:39:25.265590Z DEBUG ep{me=7uiwjh7f42fdio3e}:magicsock:actor: events.net.direct_addrs: 2570: addrs=[DirectAddr { addr: 144.76.78.249:51754, typ: Stun }]
2024-10-03T17:39:25.267437Z DEBUG gossip{me=7uiwjh7f42fdio3e}: iroh_gossip::net: 537: handle in_event event=UpdatePeerData(PeerData(54b))
2024-10-03T17:39:25.267564Z DEBUG gossip{me=7uiwjh7f42fdio3e}: iroh_gossip::net: 537: handle in_event event=UpdatePeerData(PeerData(54b))
2024-10-03T17:39:25.267650Z DEBUG node{me=7uiwjh7f42fdio3e}: iroh::node: 277: listening at: 0.0.0.0:51754 and [::]:51757
2024-10-03T17:39:25.267679Z DEBUG node{me=7uiwjh7f42fdio3e}: iroh::node: 282: rpc listening at: []
2024-10-03T17:39:25.267917Z DEBUG iroh::node::rpc: 467: handling rpc request: Blobs
2024-10-03T17:39:25.267955Z DEBUG iroh::node::rpc: 195: handling blob request: AddStream
2024-10-03T17:39:25.271528Z DEBUG iroh::node::rpc: 467: handling rpc request: Blobs
2024-10-03T17:39:25.271553Z DEBUG iroh::node::rpc: 195: handling blob request: List
2024-10-03T17:39:25.272012Z DEBUG iroh::node::rpc: 467: handling rpc request: Blobs
2024-10-03T17:39:25.272035Z DEBUG iroh::node::rpc: 195: handling blob request: Delete
2024-10-03T17:39:25.272325Z DEBUG iroh::node::rpc: 467: handling rpc request: Blobs
2024-10-03T17:39:25.272347Z DEBUG iroh::node::rpc: 195: handling blob request: List
test client::blobs::tests::test_blob_delete_mem ... FAILED

failures:

failures:
    client::blobs::tests::test_blob_delete_mem

test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 29 filtered out; finished in 1.35s


--- STDERR:              iroh client::blobs::tests::test_blob_delete_mem ---
thread 'client::blobs::tests::test_blob_delete_mem' panicked at iroh\src\client\blobs.rs:1657:9:
assertion failed: hashes.is_empty()
stack backtrace:
   0: std::panicking::begin_panic_handler
             at /rustc/eeb90cda1969383f56a2637cbd3037bdf598841c/library\std\src\panicking.rs:665
   1: core::panicking::panic_fmt
             at /rustc/eeb90cda1969383f56a2637cbd3037bdf598841c/library\core\src\panicking.rs:74
   2: core::panicking::panic
             at /rustc/eeb90cda1969383f56a2637cbd3037bdf598841c/library\core\src\panicking.rs:148
   3: iroh::client::blobs::tests::test_blob_delete_mem::async_block$0
             at .\src\client\blobs.rs:1657
   4: core::future::future::impl$1::poll<ref_mut$<dyn$<core::future::future::Future<assoc$<Output,enum2$<core::result::Result<tuple$<>,anyhow::Error> > > > > > >
             at /rustc/eeb90cda1969383f56a2637cbd3037bdf598841c\library\core\src\future\future.rs:123
   5: core::future::future::impl$1::poll<ref_mut$<core::pin::Pin<ref_mut$<dyn$<core::future::future::Future<assoc$<Output,enum2$<core::result::Result<tuple$<>,anyhow::Error> > > > > > > > >
             at /rustc/eeb90cda1969383f56a2637cbd3037bdf598841c\library\core\src\future\future.rs:123
   6: tokio::runtime::scheduler::current_thread::impl$8::block_on::closure$0::closure$0::closure$0<core::pin::Pin<ref_mut$<core::pin::Pin<ref_mut$<dyn$<core::future::future::Future<assoc$<Output,enum2$<core::result::Result<tuple$<>,anyhow::Error> > > > > > > > >
             at C:\Users\Administrator\.cargo\registry\src\index.crates.io-6f17d22bba15001f\tokio-1.38.1\src\runtime\scheduler\current_thread\mod.rs:659
   7: tokio::runtime::coop::with_budget
             at C:\Users\Administrator\.cargo\registry\src\index.crates.io-6f17d22bba15001f\tokio-1.38.1\src\runtime\coop.rs:107
   8: tokio::runtime::coop::budget
             at C:\Users\Administrator\.cargo\registry\src\index.crates.io-6f17d22bba15001f\tokio-1.38.1\src\runtime\coop.rs:73
   9: tokio::runtime::scheduler::current_thread::impl$8::block_on::closure$0::closure$0<core::pin::Pin<ref_mut$<core::pin::Pin<ref_mut$<dyn$<core::future::future::Future<assoc$<Output,enum2$<core::result::Result<tuple$<>,anyhow::Error> > > > > > > > > >
             at C:\Users\Administrator\.cargo\registry\src\index.crates.io-6f17d22bba15001f\tokio-1.38.1\src\runtime\scheduler\current_thread\mod.rs:659
  10: tokio::runtime::scheduler::current_thread::Context::enter<enum2$<core::task::poll::Poll<enum2$<core::result::Result<tuple$<>,anyhow::Error> > > >,tokio::runtime::scheduler::current_thread::impl$8::block_on::closure$0::closure_env$0<core::pin::Pin<ref_mut$<
             at C:\Users\Administrator\.cargo\registry\src\index.crates.io-6f17d22bba15001f\tokio-1.38.1\src\runtime\scheduler\current_thread\mod.rs:404
  11: tokio::runtime::scheduler::current_thread::impl$8::block_on::closure$0<core::pin::Pin<ref_mut$<core::pin::Pin<ref_mut$<dyn$<core::future::future::Future<assoc$<Output,enum2$<core::result::Result<tuple$<>,anyhow::Error> > > > > > > > > >
             at C:\Users\Administrator\.cargo\registry\src\index.crates.io-6f17d22bba15001f\tokio-1.38.1\src\runtime\scheduler\current_thread\mod.rs:658
  12: tokio::runtime::scheduler::current_thread::impl$8::enter::closure$0<tokio::runtime::scheduler::current_thread::impl$8::block_on::closure_env$0<core::pin::Pin<ref_mut$<core::pin::Pin<ref_mut$<dyn$<core::future::future::Future<assoc$<Output,enum2$<core::resu
             at C:\Users\Administrator\.cargo\registry\src\index.crates.io-6f17d22bba15001f\tokio-1.38.1\src\runtime\scheduler\current_thread\mod.rs:737
  13: tokio::runtime::context::scoped::Scoped<enum2$<tokio::runtime::scheduler::Context> >::set<enum2$<tokio::runtime::scheduler::Context>,tokio::runtime::scheduler::current_thread::impl$8::enter::closure_env$0<tokio::runtime::scheduler::current_thread::impl$8::
             at C:\Users\Administrator\.cargo\registry\src\index.crates.io-6f17d22bba15001f\tokio-1.38.1\src\runtime\context\scoped.rs:40
  14: tokio::runtime::context::set_scheduler::closure$0<tuple$<alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core,alloc::alloc::Global>,enum2$<core::option::Option<enum2$<core::result::Result<tuple$<>,anyhow::Error> > > > >,tokio::runtime::schedul
             at C:\Users\Administrator\.cargo\registry\src\index.crates.io-6f17d22bba15001f\tokio-1.38.1\src\runtime\context.rs:180
  15: std::thread::local::LocalKey<tokio::runtime::context::Context>::try_with<tokio::runtime::context::Context,tokio::runtime::context::set_scheduler::closure_env$0<tuple$<alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core,alloc::alloc::Global>,e
             at /rustc/eeb90cda1969383f56a2637cbd3037bdf598841c\library\std\src\thread\local.rs:283
  16: std::thread::local::LocalKey<tokio::runtime::context::Context>::with<tokio::runtime::context::Context,tokio::runtime::context::set_scheduler::closure_env$0<tuple$<alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core,alloc::alloc::Global>,enum2
             at /rustc/eeb90cda1969383f56a2637cbd3037bdf598841c\library\std\src\thread\local.rs:260
  17: tokio::runtime::context::set_scheduler<tuple$<alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core,alloc::alloc::Global>,enum2$<core::option::Option<enum2$<core::result::Result<tuple$<>,anyhow::Error> > > > >,tokio::runtime::scheduler::current
             at C:\Users\Administrator\.cargo\registry\src\index.crates.io-6f17d22bba15001f\tokio-1.38.1\src\runtime\context.rs:180
  18: tokio::runtime::scheduler::current_thread::CoreGuard::enter<tokio::runtime::scheduler::current_thread::impl$8::block_on::closure_env$0<core::pin::Pin<ref_mut$<core::pin::Pin<ref_mut$<dyn$<core::future::future::Future<assoc$<Output,enum2$<core::result::Resu
             at C:\Users\Administrator\.cargo\registry\src\index.crates.io-6f17d22bba15001f\tokio-1.38.1\src\runtime\scheduler\current_thread\mod.rs:737
  19: tokio::runtime::scheduler::current_thread::CoreGuard::block_on<core::pin::Pin<ref_mut$<core::pin::Pin<ref_mut$<dyn$<core::future::future::Future<assoc$<Output,enum2$<core::result::Result<tuple$<>,anyhow::Error> > > > > > > > > >
             at C:\Users\Administrator\.cargo\registry\src\index.crates.io-6f17d22bba15001f\tokio-1.38.1\src\runtime\scheduler\current_thread\mod.rs:646
  20: tokio::runtime::scheduler::current_thread::impl$0::block_on::closure$0<core::pin::Pin<ref_mut$<dyn$<core::future::future::Future<assoc$<Output,enum2$<core::result::Result<tuple$<>,anyhow::Error> > > > > > > >
             at C:\Users\Administrator\.cargo\registry\src\index.crates.io-6f17d22bba15001f\tokio-1.38.1\src\runtime\scheduler\current_thread\mod.rs:175
  21: tokio::runtime::context::runtime::enter_runtime<tokio::runtime::scheduler::current_thread::impl$0::block_on::closure_env$0<core::pin::Pin<ref_mut$<dyn$<core::future::future::Future<assoc$<Output,enum2$<core::result::Result<tuple$<>,anyhow::Error> > > > > >
             at C:\Users\Administrator\.cargo\registry\src\index.crates.io-6f17d22bba15001f\tokio-1.38.1\src\runtime\context\runtime.rs:65
  22: tokio::runtime::scheduler::current_thread::CurrentThread::block_on<core::pin::Pin<ref_mut$<dyn$<core::future::future::Future<assoc$<Output,enum2$<core::result::Result<tuple$<>,anyhow::Error> > > > > > > >
             at C:\Users\Administrator\.cargo\registry\src\index.crates.io-6f17d22bba15001f\tokio-1.38.1\src\runtime\scheduler\current_thread\mod.rs:167
  23: tokio::runtime::runtime::Runtime::block_on<core::pin::Pin<ref_mut$<dyn$<core::future::future::Future<assoc$<Output,enum2$<core::result::Result<tuple$<>,anyhow::Error> > > > > > > >
             at C:\Users\Administrator\.cargo\registry\src\index.crates.io-6f17d22bba15001f\tokio-1.38.1\src\runtime\runtime.rs:347
  24: iroh::client::blobs::tests::test_blob_delete_mem
             at .\src\client\blobs.rs:1659
  25: iroh::client::blobs::tests::test_blob_delete_mem::closure$0
             at .\src\client\blobs.rs:1642
  26: core::ops::function::FnOnce::call_once<iroh::client::blobs::tests::test_blob_delete_mem::closure_env$0,tuple$<> >
             at /rustc/eeb90cda1969383f56a2637cbd3037bdf598841c\library\core\src\ops\function.rs:250
  27: core::ops::function::FnOnce::call_once
             at /rustc/eeb90cda1969383f56a2637cbd3037bdf598841c/library\core\src\ops\function.rs:250
note: Some details are omitted, run with `RUST_BACKTRACE=full` for a verbose backtrace.
@flub flub added flaky-test _c-iroh-legacy Formerly big iroh node with all protocols labels Oct 4, 2024
flub added a commit that referenced this issue Oct 4, 2024
@flub
tests(iroh): Mark test_blob_delete_mem as flaky
1881c6b 
Only observed on windows, probably because this machine is always
slower and triggers some race condition easier.  We can change this to
be flaky everywhere if we notice it happening on other platforms too.

See #2783.
@flub flub mentioned this issue Oct 4, 2024
Merged
2 tasks
github-merge-queue bot pushed a commit that referenced this issue Oct 4, 2024
@flub @Arqu
tests(iroh): Mark test_blob_delete_mem as flaky (#2784)
730f717 
## Description

Only observed on windows, probably because this machine is always
slower and triggers some race condition easier.  We can change this to
be flaky everywhere if we notice it happening on other platforms too.

See #2783.


## Breaking Changes

<!-- Optional, if there are any breaking changes document them,
including how to migrate older code. -->

## Notes & open questions

<!-- Any notes, remarks or open questions you have to make about the PR.
-->

## Change checklist

- [x] Self-review.
- ~~[ ] Documentation updates following the [style
guide](https://rust-lang.github.io/rfcs/1574-more-api-documentation-conventions.html#appendix-a-full-conventions-text),
if relevant.~~
- [x] Tests if relevant.
- ~~[ ] All breaking changes documented.~~

Co-authored-by: Asmir Avdicevic <asmir.avdicevic64@gmail.com>
@iacore
Copy link
Contributor

iacore commented Oct 6, 2024

Not a duplicate of #2730?

@flub
Copy link
Contributor Author

flub commented Oct 7, 2024

oh, thanks @iacore !

@flub flub closed this as not planned Won't fix, can't repro, duplicate, stale Oct 7, 2024
@github-project-automation github-project-automation bot moved this to ✅ Done in iroh Oct 7, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
_c-iroh-legacy Formerly big iroh node with all protocols flaky-test
Projects
iroh
Archived in project
Milestone
No milestone
Development

No branches or pull requests
2 participants
@flub @iacore