search

n0-computer / iroh

A toolkit for building distributed applications
https://iroh.computer
Apache License 2.0
2.39k stars 155 forks source link

iroh-net: Unable to connect due to handshake issue: cryptographic handshake failed: #1651

Open rklaehn opened 11 months ago

rklaehn commented 11 months ago

Unable to connect due to handshake issue:

❯ cargo run get --ticket eaa54kekl2oa7yd5ro3u65kynwolq3h2msu65c3jfj44ja23fstmcaibamambkcwddcfoafnhdo5d5ucamak2og52hcfoaktirii7wbi74vv3q62zpgrxop72kgu4caunmhu4a3xhh6xpviqyqaa > /dev/null Finished dev [unoptimized + debuginfo] target(s) in 0.22s Running target/debug/iroh get --ticket eaa54kekl2oa7yd5ro3u65kynwolq3h2msu65c3jfj44ja23fstmcaibamambkcwddcfoafnhdo5d5ucamak2og52hcfoaktirii7wbi74vv3q62zpgrxop72kgu4caunmhu4a3xhh6xpviqyqaa Fetching: bafkr4ictirii7wbi74vv3q62zpgrxop72kgu4caunmhu4a3xhh6xpviqyq Error: failed to connect to provider

Caused by: 0: failed connecting to provider 1: aborted by peer: the cryptographic handshake failed: error 120: peer doesn't support any known protocol

rklaehn commented 11 months ago

❯ RUST_LOG=trace cargo run get --ticket eaa54kekl2oa7yd5ro3u65kynwolq3h2msu65c3jfj44ja23fstmcaibamambkcwddcfoafnhdo5d5ucamak2og52hcfoaktirii7wbi74vv3q62zpgrxop72kgu4caunmhu4a3xhh6xpviqyqaa > /dev/null Finished dev [unoptimized + debuginfo] target(s) in 0.22s Running target/debug/iroh get --ticket eaa54kekl2oa7yd5ro3u65kynwolq3h2msu65c3jfj44ja23fstmcaibamambkcwddcfoafnhdo5d5ucamak2og52hcfoaktirii7wbi74vv3q62zpgrxop72kgu4caunmhu4a3xhh6xpviqyqaa 2023-10-17T13:43:20.532540Z DEBUG iroh::config: make_config: Config { defaults: {}, overrides: {}, sources: [], cache: Value { origin: None, kind: Table( {}, ), }, }

2023-10-17T13:43:20.533472Z INFO iroh::commands: Metrics server not started, no address provided Fetching: bafkr4ictirii7wbi74vv3q62zpgrxop72kgu4caunmhu4a3xhh6xpviqyq [1/3] Connecting ... ⠁ [00:00:00] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:20.540752Z DEBUG magicsock{me=mefmz7clbwdlzey2}:portmapper.service: iroh_net::portmapper: portmap starting 2023-10-17T13:43:20.540752Z DEBUG magicsock{me=mefmz7clbwdlzey2}: iroh_net::magicsock::rebinding_conn: bind_socket: network=Ipv6 cur_port_fate=Keep 2023-10-17T13:43:20.541139Z DEBUG magicsock{me=mefmz7clbwdlzey2}: iroh_net::magicsock::rebinding_conn: bind_socket: candidate ports: [0] 2023-10-17T13:43:20.541430Z DEBUG magicsock{me=mefmz7clbwdlzey2}: iroh_net::magicsock::rebinding_conn: bind_socket: successfully bound Ipv6 [::]:61474 2023-10-17T13:43:20.541463Z TRACE magicsock{me=mefmz7clbwdlzey2}: mio::poll: registering event source with poller: token=Token(5443189632), interests=READABLE | WRITABLE
2023-10-17T13:43:20.541522Z DEBUG magicsock{me=mefmz7clbwdlzey2}: iroh_net::magicsock::rebinding_conn: bind_socket: network=Ipv4 cur_port_fate=Keep 2023-10-17T13:43:20.541534Z DEBUG magicsock{me=mefmz7clbwdlzey2}: iroh_net::magicsock::rebinding_conn: bind_socket: candidate ports: [0] 2023-10-17T13:43:20.541571Z DEBUG magicsock{me=mefmz7clbwdlzey2}: iroh_net::magicsock::rebinding_conn: bind_socket: successfully bound Ipv4 0.0.0.0:62394 2023-10-17T13:43:20.541587Z TRACE magicsock{me=mefmz7clbwdlzey2}: mio::poll: registering event source with poller: token=Token(5443213184), interests=READABLE | WRITABLE
2023-10-17T13:43:20.541746Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service: iroh_net::portmapper: tick: msg Some(UpdateLocalPort { local_port: Some(62394) }) 2023-10-17T13:43:20.542455Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::netcheck: netcheck actor starting 2023-10-17T13:43:20.542847Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: tick 2023-10-17T13:43:20.542995Z TRACE iroh_net::magic_endpoint: created magicsock 2023-10-17T13:43:20.543403Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv6: poll_recv 2023-10-17T13:43:20.543427Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv4: poll_recv 2023-10-17T13:43:20.543766Z DEBUG magicsock{me=mefmz7clbwdlzey2}:portmapper.service: iroh_net::portmapper: getting a port mapping for 192.168.1.141:62394 -> None 2023-10-17T13:43:20.544148Z TRACE iroh_net::magic_endpoint: created quinn endpoint 2023-10-17T13:43:20.544560Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: scheduling periodic_stun to run in 25s 2023-10-17T13:43:20.544940Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service:upnp: mio::poll: registering event source with poller: token=Token(5472536704), interests=READABLE | WRITABLE
2023-10-17T13:43:20.544997Z DEBUG magicsock{me=mefmz7clbwdlzey2}:portmapper.service:upnp: igd::aio::search: sending broadcast request to: 239.255.255.250:1900 on interface: Ok(0.0.0.0:50396)
2023-10-17T13:43:20.559595Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: mio::poll: registering event source with poller: token=Token(5472536960), interests=READABLE | WRITABLE
2023-10-17T13:43:20.559963Z TRACE iroh_net::net::netmon::bsd: AF_ROUTE monitor started 2023-10-17T13:43:20.560251Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: tick: msg msg=AddKnownAddr(PeerAddr { peer_id: PublicKey(ahpcrcs6tqh6a7ml), info: AddrInfo { derp_region: Some(1), direct_addresses: {192.168.86.24:11204, 173.56.221.209:49526, 173.56.221.209:11204} } }, Sender { inner: Some(Inner { state: State { is_complete: false, is_closed: false, is_rx_task_set: true, is_tx_task_set: false } }) }) 2023-10-17T13:43:20.560400Z INFO magicsock{me=mefmz7clbwdlzey2}:actor:add_known_addr: iroh_net::magicsock::endpoint: inserting peer's endpoint in PeerMap peer_id=ahpcrcs6tqh6a7mlw5hxkwdnts4gz6tevhxiw2jkphcigwzmu3aq 2023-10-17T13:43:20.560570Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor:add_known_addr: iroh_net::magicsock::endpoint: insert ip -> id: IpPort { ip: 192.168.86.24, port: 11204 } -> 0 2023-10-17T13:43:20.560601Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor:add_known_addr: iroh_net::magicsock::endpoint: insert ip -> id: IpPort { ip: 173.56.221.209, port: 49526 } -> 0 2023-10-17T13:43:20.560614Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor:add_known_addr: iroh_net::magicsock::endpoint: insert ip -> id: IpPort { ip: 173.56.221.209, port: 11204 } -> 0 2023-10-17T13:43:20.560704Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: tick: re_stun Instant { tv_sec: 68959, tv_nsec: 300960375 } 2023-10-17T13:43:20.560842Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:re_stun{reason="periodic"}: iroh_net::magicsock: started 2023-10-17T13:43:20.560896Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: tick: endpoints update receiver Some("periodic") 2023-10-17T13:43:20.560942Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:update_endpoints: iroh_net::magicsock: starting endpoint update (periodic) 2023-10-17T13:43:20.561011Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:update_endpoints:determine_endpoints:update_net_info: iroh_net::magicsock: requesting netcheck report 2023-10-17T13:43:20.561105Z TRACE iroh_net::net::netmon::actor: tick: wall_time_interval 2023-10-17T13:43:20.561149Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service: iroh_net::portmapper: tick: msg Some(ProcureMapping) 2023-10-17T13:43:20.561108Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::netcheck: handling message msg=RunCheck { derp_map: DerpMap { regions: {1: DerpRegion { region_id: 1, nodes: [DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns }], avoid: false, region_code: "default-1" }, 2: DerpRegion { region_id: 2, nodes: [DerpNode { name: "eu-default-1", region_id: 2, url: https://euw1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.253.75.5), ipv6: TryDns }], avoid: false, region_code: "default-2" }} }, stun_sock_v4: Some(PollEvented { io: Some(UdpSocket { addr: 0.0.0.0:62394, fd: 50 }) }), stun_sock_v6: Some(PollEvented { io: Some(UdpSocket { addr: [::]:61474, fd: 49 }) }), response_tx: Sender { inner: Some(Inner { state: State { is_complete: false, is_closed: false, is_rx_task_set: true, is_tx_task_set: false } }) } } 2023-10-17T13:43:20.561628Z DEBUG magicsock{me=mefmz7clbwdlzey2}:portmapper.service: iroh_net::portmapper: getting a port mapping for 192.168.1.141:62394 -> None 2023-10-17T13:43:20.561635Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:hairpin.actor: mio::poll: registering event source with poller: token=Token(5473585536), interests=READABLE | WRITABLE
2023-10-17T13:43:20.561811Z TRACE mio::poll: deregistering event source from poller
2023-10-17T13:43:20.561773Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: reportstate actor starting port_mapper=true skip_external_network=false 2023-10-17T13:43:20.561946Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service:upnp: mio::poll: registering event source with poller: token=Token(5472537216), interests=READABLE | WRITABLE
2023-10-17T13:43:20.561952Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: mio::poll: registering event source with poller: token=Token(5471502976), interests=READABLE | WRITABLE
2023-10-17T13:43:20.561970Z DEBUG magicsock{me=mefmz7clbwdlzey2}:portmapper.service:upnp: igd::aio::search: sending broadcast request to: 239.255.255.250:1900 on interface: Ok(0.0.0.0:58657)
2023-10-17T13:43:20.561992Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: mio::poll: deregistering event source from poller
2023-10-17T13:43:20.570654Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: probe plan plan=ProbePlan { ProbeSet("region-1-https") { Https after 300ms to na-default-1, Https after 400ms to na-default-1, Https after 500ms to na-default-1, } ProbeSet("region-1-icmp") { Icmp after 300ms to na-default-1, Icmp after 400ms to na-default-1, Icmp after 500ms to na-default-1, } ProbeSet("region-1-stunipv4") { Ipv4 after 0ns to na-default-1, Ipv4 after 100ms to na-default-1, Ipv4 after 200ms to na-default-1, } ProbeSet("region-2-https") { Https after 600ms to eu-default-1, Https after 700ms to eu-default-1, Https after 800ms to eu-default-1, } ProbeSet("region-2-icmp") { Icmp after 600ms to eu-default-1, Icmp after 700ms to eu-default-1, Icmp after 800ms to eu-default-1, } ProbeSet("region-2-stunipv4") { Ipv4 after 0ns to eu-default-1, Ipv4 after 100ms to eu-default-1, Ipv4 after 200ms to eu-default-1, } }

2023-10-17T13:43:20.570813Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: mio::poll: registering event source with poller: token=Token(5474634496), interests=READABLE | WRITABLE
2023-10-17T13:43:20.570857Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: mio::poll: registering event source with poller: token=Token(5474634880), interests=READABLE | WRITABLE
2023-10-17T13:43:20.570940Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: tick; awaiting tasks awaiting=OutstandingTasks { probes: true, port_mapper: true, captive_task: true, hairpin: false } 2023-10-17T13:43:20.571074Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Https after 300ms to na-default-1}: iroh_net::netcheck::reportgen: delaying probe 2023-10-17T13:43:20.571120Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Https after 400ms to na-default-1}: iroh_net::netcheck::reportgen: delaying probe 2023-10-17T13:43:20.571144Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Https after 500ms to na-default-1}: iroh_net::netcheck::reportgen: delaying probe 2023-10-17T13:43:20.571172Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Icmp after 300ms to na-default-1}: iroh_net::netcheck::reportgen: delaying probe 2023-10-17T13:43:20.571196Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Icmp after 400ms to na-default-1}: iroh_net::netcheck::reportgen: delaying probe 2023-10-17T13:43:20.571220Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Icmp after 500ms to na-default-1}: iroh_net::netcheck::reportgen: delaying probe 2023-10-17T13:43:20.571269Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service: iroh_net::portmapper: tick: msg Some(Probe { resulttx: }) 2023-10-17T13:43:20.571345Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Ipv4 after 0ns to na-default-1}: iroh_net::netcheck::reportgen: starting probe 2023-10-17T13:43:20.571401Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Ipv4 after 100ms to na-default-1}: iroh_net::netcheck::reportgen: delaying probe 2023-10-17T13:43:20.571434Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Ipv4 after 200ms to na-default-1}: iroh_net::netcheck::reportgen: delaying probe 2023-10-17T13:43:20.571461Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Https after 600ms to eu-default-1}: iroh_net::netcheck::reportgen: delaying probe 2023-10-17T13:43:20.571486Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Https after 700ms to eu-default-1}: iroh_net::netcheck::reportgen: delaying probe 2023-10-17T13:43:20.571508Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Https after 800ms to eu-default-1}: iroh_net::netcheck::reportgen: delaying probe 2023-10-17T13:43:20.571531Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: handling message msg=ProbeWouldHelp(StunIpv4 { delay: 0ns, node: DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns } }, DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns }, Sender { inner: Some(Inner { state: State { is_complete: false, is_closed: false, is_rx_task_set: true, is_tx_task_set: false } }) }) 2023-10-17T13:43:20.571584Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: tick; awaiting tasks awaiting=OutstandingTasks { probes: true, port_mapper: true, captive_task: true, hairpin: false } 2023-10-17T13:43:20.571609Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Icmp after 600ms to eu-default-1}: iroh_net::netcheck::reportgen: delaying probe 2023-10-17T13:43:20.571633Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Icmp after 700ms to eu-default-1}: iroh_net::netcheck::reportgen: delaying probe 2023-10-17T13:43:20.571657Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Icmp after 800ms to eu-default-1}: iroh_net::netcheck::reportgen: delaying probe 2023-10-17T13:43:20.571681Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Ipv4 after 0ns to eu-default-1}: iroh_net::netcheck::reportgen: starting probe 2023-10-17T13:43:20.571705Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Ipv4 after 100ms to eu-default-1}: iroh_net::netcheck::reportgen: delaying probe 2023-10-17T13:43:20.571725Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service:portmapper.probe: mio::poll: registering event source with poller: token=Token(5474636800), interests=READABLE | WRITABLE
2023-10-17T13:43:20.571728Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Ipv4 after 200ms to eu-default-1}: iroh_net::netcheck::reportgen: delaying probe 2023-10-17T13:43:20.571760Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: handling message msg=ProbeWouldHelp(StunIpv4 { delay: 0ns, node: DerpNode { name: "eu-default-1", region_id: 2, url: https://euw1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.253.75.5), ipv6: TryDns } }, DerpNode { name: "eu-default-1", region_id: 2, url: https://euw1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.253.75.5), ipv6: TryDns }, Sender { inner: Some(Inner { state: State { is_complete: false, is_closed: false, is_rx_task_set: true, is_tx_task_set: false } }) }) 2023-10-17T13:43:20.571779Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: tick; awaiting tasks awaiting=OutstandingTasks { probes: true, port_mapper: true, captive_task: true, hairpin: false } 2023-10-17T13:43:20.571783Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service:portmapper.probe: mio::poll: registering event source with poller: token=Token(5474637056), interests=READABLE | WRITABLE
2023-10-17T13:43:20.571822Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service:portmapper.probe: mio::poll: registering event source with poller: token=Token(5474637312), interests=READABLE | WRITABLE
2023-10-17T13:43:20.571839Z DEBUG magicsock{me=mefmz7clbwdlzey2}:portmapper.service:portmapper.probe: igd::aio::search: sending broadcast request to: 239.255.255.250:1900 on interface: Ok(0.0.0.0:60937)
2023-10-17T13:43:20.571979Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::netcheck: handling message msg=InFlightStun(Inflight { txn: TransactionId(0xFAA07B0D50F8C6004DBDF82A), start: Instant { tv_sec: 68959, tv_nsec: 328258750 }, s: Sender { inner: Some(Inner { state: State { is_complete: false, is_closed: false, is_rx_task_set: false, is_tx_task_set: false } }) } }, Sender { inner: Some(Inner { state: State { is_complete: false, is_closed: false, is_rx_task_set: true, is_tx_task_set: false } }) }) 2023-10-17T13:43:20.572046Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::netcheck: handling message msg=InFlightStun(Inflight { txn: TransactionId(0xA3CD316948E5FD9E4B145E90), start: Instant { tv_sec: 68959, tv_nsec: 328279541 }, s: Sender { inner: Some(Inner { state: State { is_complete: false, is_closed: false, is_rx_task_set: false, is_tx_task_set: false } }) } }, Sender { inner: Some(Inner { state: State { is_complete: false, is_closed: false, is_rx_task_set: true, is_tx_task_set: false } }) }) 2023-10-17T13:43:20.572121Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Ipv4 after 0ns to na-default-1}: iroh_net::netcheck::reportgen: sending probe StunIpv4 derp_addr=34.207.161.128:3478 send_res=Ok(28) txid=transaction id (0xFAA07B0D50F8C6004DBDF82A) 2023-10-17T13:43:20.572173Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Ipv4 after 0ns to eu-default-1}: iroh_net::netcheck::reportgen: sending probe StunIpv4 derp_addr=34.253.75.5:3478 send_res=Ok(28) txid=transaction id (0xA3CD316948E5FD9E4B145E90) 2023-10-17T13:43:20.572539Z DEBUG surge_ping::client: no one is waiting for ICMP packet (V4(Icmpv4Packet { source: 192.168.1.1, destination: 192.168.1.141, ttl: Some(64), icmp_type: IcmpType(3), icmp_code: IcmpCode(3), size: 4, real_dest: 192.168.1.1, identifier: PingIdentifier(32), sequence: PingSequence(57173) })) 2023-10-17T13:43:20.572601Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service:portmapper.probe: mio::poll: deregistering event source from poller
2023-10-17T13:43:20.572627Z DEBUG magicsock{me=mefmz7clbwdlzey2}:portmapper.service:portmapper.probe: iroh_net::portmapper::pcp: probe failed: Connection refused (os error 61) 2023-10-17T13:43:20.572723Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service:portmapper.probe: iroh_net::portmapper: tick: pcp probe ready 2023-10-17T13:43:20.573035Z DEBUG surge_ping::client: no one is waiting for ICMP packet (V4(Icmpv4Packet { source: 192.168.1.1, destination: 192.168.1.141, ttl: Some(64), icmp_type: IcmpType(3), icmp_code: IcmpCode(3), size: 4, real_dest: 192.168.1.1, identifier: PingIdentifier(10), sequence: PingSequence(35793) })) 2023-10-17T13:43:20.573049Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service:portmapper.probe: mio::poll: deregistering event source from poller
2023-10-17T13:43:20.573071Z DEBUG magicsock{me=mefmz7clbwdlzey2}:portmapper.service:portmapper.probe: iroh_net::portmapper::nat_pmp: probe failed: Connection refused (os error 61) [1/3] Connecting ... ⠉ [00:00:00] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:20.631397Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv6: poll_recv 2023-10-17T13:43:20.631437Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv4: poll_recv 2023-10-17T13:43:20.631509Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::rebinding_conn: [UDP] <- 34.253.75.5:3478 dest: Some(192.168.1.141) (44b) 2023-10-17T13:43:20.631547Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv4: recv 1 msgs 2023-10-17T13:43:20.631604Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: tick: ip_msgs 2023-10-17T13:43:20.631629Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: tick: stun packet 2023-10-17T13:43:20.631649Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: tick 2023-10-17T13:43:20.631669Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv6: poll_recv 2023-10-17T13:43:20.631717Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv4: poll_recv 2023-10-17T13:43:20.631795Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::netcheck: handling message msg=StunPacket { payload: b"\x01\x01\0\x18!\x12\xa4B\xa3\xcd1iH\xe5\xfd\x9eK\x14^\x90\0 \0\x14\0\x02\xd2\xa8!\x12\xa4B\xa3\xcd1iH\xe5\x02a\x1do\xbbU", from_addr: 34.253.75.5:3478 } 2023-10-17T13:43:20.631827Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::netcheck: received STUN packet src=34.253.75.5:3478 2023-10-17T13:43:20.631918Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::netcheck: received known STUN packet src=34.253.75.5:3478 txn=transaction id (0xA3CD316948E5FD9E4B145E90) 2023-10-17T13:43:20.631994Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Ipv4 after 0ns to eu-default-1}: iroh_net::netcheck::reportgen: probe successfull probe=StunIpv4 { delay: 0ns, node: DerpNode { name: "eu-default-1", region_id: 2, url: https://euw1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.253.75.5), ipv6: TryDns } } 2023-10-17T13:43:20.632065Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: finished probe: ProbeReport { ipv4_can_send: true, ipv6_can_send: false, icmpv4: false, delay: Some(59.982334ms), probe: StunIpv4 { delay: 0ns, node: DerpNode { name: "eu-default-1", region_id: 2, url: https://euw1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.253.75.5), ipv6: TryDns } }, addr: Some(86.123.229.197:62394) } 2023-10-17T13:43:20.632112Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: add udp node latency derp_node=eu-default-1 latency=59.982334ms 2023-10-17T13:43:20.632141Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: tick; awaiting tasks awaiting=OutstandingTasks { probes: true, port_mapper: true, captive_task: true, hairpin: true } 2023-10-17T13:43:20.632181Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:hairpin.actor: iroh_net::netcheck::reportgen::hairpin: Sending hairpin with transaction ID txn=transaction id (0x9B7395BD44D19304FFC66951) 2023-10-17T13:43:20.632230Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::netcheck: handling message msg=InFlightStun(Inflight { txn: TransactionId(0x9B7395BD44D19304FFC66951), start: Instant { tv_sec: 68959, tv_nsec: 388520625 }, s: Sender { inner: Some(Inner { state: State { is_complete: false, is_closed: false, is_rx_task_set: false, is_tx_task_set: false } }) } }, Sender { inner: Some(Inner { state: State { is_complete: false, is_closed: false, is_rx_task_set: tr[1/3] Connecting ... ⠙ [00:00:00] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:20.673079Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Ipv4 after 100ms to na-default-1}: iroh_net::netcheck::reportgen: starting probe 2023-10-17T13:43:20.673168Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: handling message msg=ProbeWouldHelp(StunIpv4 { delay: 100ms, node: DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns } }, DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns }, Sender { inner: Some(Inner { state: State { is_complete: false, is_closed: false, is_rx_task_set: true, is_tx_task_set: false } }) }) 2023-10-17T13:43:20.673205Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: tick; awaiting tasks awaiting=OutstandingTasks { probes: true, port_mapper: true, captive_task: true, hairpin: true } 2023-10-17T13:43:20.673287Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::netcheck: handling message msg=InFlightStun(Inflight { txn: TransactionId(0x15F2C3AE5226C9179D8609C1), start: Instant { tv_sec: 68959, tv_nsec: 429578083 }, s: Sender { inner: Some(Inner { state: State { is_complete: false, is_closed: false, is_rx_task_set: false, is_tx_task_set: false } }) } }, Sender { inner: Some(Inner { state: State { is_complete: false, is_closed: false, is_rx_task_set: true, is_tx_task_set: false } }) }) 2023-10-17T13:43:20.673373Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Ipv4 after 100ms to na-default-1}: iroh_net::netcheck::reportgen: sending probe StunIpv4 derp_addr=34.207.161.128:3478 send_res=Ok(2[1/3] Connecting ... ⠚ [00:00:00] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:20.703440Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv6: poll_recv 2023-10-17T13:43:20.703484Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv4: poll_recv 2023-10-17T13:43:20.703509Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::rebinding_conn: [UDP] <- 34.207.161.128:3478 dest: Some(192.168.1.141) (44b) 2023-10-17T13:43:20.703524Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv4: recv 1 msgs 2023-10-17T13:43:20.703546Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: tick: ip_msgs 2023-10-17T13:43:20.703558Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: tick: stun packet 2023-10-17T13:43:20.703573Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: tick 2023-10-17T13:43:20.703585Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv6: poll_recv 2023-10-17T13:43:20.703596Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv4: poll_recv 2023-10-17T13:43:20.703772Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::netcheck: handling message msg=StunPacket { payload: b"\x01\x01\0\x18!\x12\xa4B\xfa\xa0{\rP\xf8\xc6\0M\xbd\xf8*\0 \0\x14\0\x02\xd2\xa8!\x12\xa4B\xfa\xa0{\rP\xf89\xff\x1b\xc6\x1d\xef", from_addr: 34.207.161.128:3478 } 2023-10-17T13:43:20.703796Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::netcheck: received STUN packet src=34.207.161.128:3478 2023-10-17T13:43:20.703839Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::netcheck: received known STUN packet src=34.207.161.128:3478 txn=transaction id (0xFAA07B0D50F8C6004DBDF82A) 2023-10-17T13:43:20.703902Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Ipv4 after 0ns to na-default-1}: iroh_net::netcheck::reportgen: probe successfull probe=StunIpv4 { delay: 0ns, node: DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns } } 2023-10-17T13:43:20.703971Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: finished probe: ProbeReport { ipv4_can_send: true, ipv6_can_send: false, icmpv4: false, delay: Some(131.919166ms), probe: StunIpv4 { delay: 0ns, node: DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns } }, addr: Some(86.123.229.197:62394) } 2023-10-17T13:43:20.704007Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: add udp node latency derp_node=na-default-1 latency=131.919166ms 2023-10-17T13:43:20.704034Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: Have enough probe reports, aborting further probes soon reports=2 delay=263.838332ms 2023-10-17T13:43:20.704063Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: tick; awaiting tasks awaiting=OutstandingTasks { probes: true, port_mapper: true, captive_task: true, hairpin: true } 2023-10-17T13:43:20.733826Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:hairpin.actor: mio::poll: deregistering event source from poller
2023-10-17T13:43:20.733897Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:hairpin.actor: iroh_net::netcheck::reportgen::hairpin: hairpin actor finished successfully 2023-10-17T13:43:20.733980Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: handling message msg=HairpinResult(false) 2023-10-17T13:43:20.733997Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: tick; awaiting tasks awaiting=OutstandingTasks { probes: true, port_mapper: true, captive_task: true, hairpin: false[1/3] Connecting ... ⠒ [00:00:00] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:20.774022Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:captive-portal: hyper::client::pool: checkout waiting for idle connection: ("http", euw1-1.derp.iroh.network.) 2023-10-17T13:43:20.774161Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:captive-portal: reqwest::connect: starting new connection: http://euw1-1.derp.iroh.network./
2023-10-17T13:43:20.774228Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:captive-portal: hyper::client::connect::http: Http::connect; scheme=Some("http"), host=Some("euw1-1.derp.iroh.network."), port=None 2023-10-17T13:43:20.774422Z DEBUG hyper::client::connect::dns: resolving host="euw1-1.derp.iroh.network." 2023-10-17T13:43:20.800722Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:captive-portal: hyper::client::connect::http: connecting to 34.253.75.5:80 2023-10-17T13:43:20.800890Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:captive-portal: mio::poll: registering event source with poller: token=Token(5470457600), interests=READABLE | WRITABLE
2023-10-17T13:43:20.804000Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv6: poll_recv 2023-10-17T13:43:20.804039Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv4: poll_recv 2023-10-17T13:43:20.804063Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::rebinding_conn: [UDP] <- 34.207.161.128:3478 dest: Some(192.168.1.141) (44b) 2023-10-17T13:43:20.804075Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv4: recv 1 msgs 2023-10-17T13:43:20.804096Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: tick: ip_msgs 2023-10-17T13:43:20.804107Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: tick: stun packet 2023-10-17T13:43:20.804122Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: tick 2023-10-17T13:43:20.804133Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv6: poll_recv 2023-10-17T13:43:20.804142Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv4: poll_recv 2023-10-17T13:43:20.804172Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::netcheck: handling message msg=StunPacket { payload: b"\x01\x01\0\x18!\x12\xa4B\x15\xf2\xc3\xaeR&\xc9\x17\x9d\x86\t\xc1\0 \0\x14\0\x02\xd2\xa8!\x12\xa4B\x15\xf2\xc3\xaeR&6\xe8\xcb\xfd\xec\x04", from_addr: 34.207.161.128:3478 } 2023-10-17T13:43:20.804266Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::netcheck: received STUN packet src=34.207.161.128:3478 [1/3] Connecting ... ⠂ [00:00:00] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:20.859081Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:captive-portal: hyper::client::connect::http: connected to 34.253.75.5:80 2023-10-17T13:43:20.859187Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:captive-portal: hyper::client::conn: client handshake Http1 2023-10-17T13:43:20.859299Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:captive-portal: hyper::client::client: handshake complete, spawning background dispatcher task 2023-10-17T13:43:20.859564Z TRACE hyper::proto::h1::conn: flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Busy } 2023-10-17T13:43:20.859648Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:captive-portal: hyper::client::pool: checkout dropped for ("http", euw1-1.derp.iroh.network.) 2023-10-17T13:43:20.859790Z TRACE encode_headers: hyper::proto::h1::role: Client::encode method=GET, body=None 2023-10-17T13:43:20.859854Z DEBUG hyper::proto::h1::io: flushed 129 bytes [1/3] Connecting ... ⠂ [00:00:00] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:20.872971Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Https after 300ms to na-default-1}: iroh_net::netcheck::reportgen: starting probe 2023-10-17T13:43:20.873037Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:run_probe{probe=Icmp after 300ms to na-default-1}: iroh_net::netcheck::reportgen: starting probe 2023-10-17T13:43:20.873073Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: handling message msg=ProbeWouldHelp(Https { delay: 300ms, node: DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns }, region: DerpRegion { region_id: 1, nodes: [DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns }], avoid: false, region_code: "default-1" } }, DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns }, Sender { inner: Some(Inner { state: State { is_complete: false, is_closed: false, is_rx_task_set: true, is_tx_task_set: false } }) }) 2023-10-17T13:43:20.873279Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: tick; awaiting tasks awaiting=OutstandingTasks { probes: true, port_mapper: true, captive_task: true, hairpin: false } 2023-10-17T13:43:20.873337Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: probe set aborted: ReportCheck says probe set no longer useful probe=Https { delay: 300ms, node: DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns }, region: DerpRegion { region_id: 1, nodes: [DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns }], avoid: false, region_code: "default-1" } } 2023-10-17T13:43:20.873383Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: tick; awaiting tasks awaiting=OutstandingTasks { probes: true, port_mapper: true, captive_task: true, hairpin: false } 2023-10-17T13:43:20.873407Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: handling message msg=ProbeWouldHelp(Icmp { delay: 300ms, node: DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns } }, DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns }, Sender { inner: Some(Inner { state: State { is_complete: false, is_closed: false, is_rx_task_set: true, is_tx_task_set: false } }) }) 2023-10-17T13:43:20.873426Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: tick; awaiting tasks awaiting=OutstandingTasks { probes: true, port_mapper: true, captive_task: true, hairpin: false } 2023-10-17T13:43:20.873556Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: probe set aborted: ReportCheck says probe set no longer useful probe=Icmp { delay: 300ms, node: DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns } } 2023-10-17T13:43:20.873584Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: tick; awaiting tasks awaiting=OutstandingTasks { probes: true, port_mapper: true, captive_task: true, hairpin: false } 2023-10-17T13:43:20.910154Z DEBUG surge_ping::client: no one is waiting for ICMP packet (V4(Icmpv4Packet { source: 52.30.229.248, destination: 192.168.1.141, ttl: Some(52), icmp_type: IcmpType(0), icmp_code: IcmpCode(0), size: 20, real_dest: 52.30.229.248, identifier: PingIdentifier(42136), sequence: PingSequence(0) })) 2023-10-17T13:43:20.917944Z TRACE hyper::proto::h1::conn: Conn::read_head 2023-10-17T13:43:20.917982Z TRACE hyper::proto::h1::io: received 125 bytes 2023-10-17T13:43:20.918031Z TRACE parse_headers: hyper::proto::h1::role: Response.parse bytes=125 2023-10-17T13:43:20.918066Z TRACE parse_headers: hyper::proto::h1::role: Response.parse Complete(125) 2023-10-17T13:43:20.918142Z DEBUG hyper::proto::h1::io: parsed 2 headers 2023-10-17T13:43:20.918148Z DEBUG hyper::proto::h1::conn: incoming body is empty 2023-10-17T13:43:20.918157Z TRACE hyper::proto::h1::conn: maybe_notify; read_from_io blocked 2023-10-17T13:43:20.918184Z TRACE hyper::proto::h1::conn: flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 2023-10-17T13:43:20.918192Z TRACE hyper::proto::h1::conn: flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 2023-10-17T13:43:20.918254Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:captive-portal: hyper::client::pool: put; add idle connection for ("http", euw1-1.derp.iroh.network.) 2023-10-17T13:43:20.918284Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:captive-portal: hyper::client::pool: pooling idle connection for ("http", euw1-1.derp.iroh.network.) 2023-10-17T13:43:20.918341Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor:captive-portal: iroh_net::netcheck::reportgen: check_captive_portal url=http://euw1-1.derp.iroh.network./generate_204 status_code=204 No Content valid_response=true 2023-10-17T13:43:20.918453Z TRACE hyper::client::pool: pool closed, canceling idle interval 2023-10-17T13:43:20.918450Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: captive portal task future done 2023-10-17T13:43:20.918476Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: tick; awaiting tasks awaiting=OutstandingTasks { probes: true, port_mapper: true, captive_task: false, hairpin: false } 2023-10-17T13:43:20.918513Z TRACE hyper::proto::h1::dispatch: client tx closed 2023-10-17T13:43:20.918520Z TRACE hyper::proto::h1::conn: State::close_read() 2023-10-17T13:43:20.918525Z TRACE hyper::proto::h1::conn: State::close_write() 2023-10-17T13:43:20.918529Z TRACE hyper::proto::h1::conn: flushed({role=client}): State { reading: Closed, writing: Closed, keep_alive: Disabled } 2023-10-17T13:43:20.918562Z TRACE hyper::proto::h1::conn: shut down IO complete [1/3] Connecting ... ⠒ [00:00:00] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:20.969167Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: handling message msg=AbortProbes 2023-10-17T13:43:20.969206Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: tick; awaiting tasks awaiting=OutstandingTasks { probes: false, port_mapper: true, captive_task: false, hairpin: fal[1/3] Connecting ... ⠠ [00:00:00] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:21.388431Z DEBUG surge_ping::client: no one is waiting for ICMP packet (V4(Icmpv4Packet { source: 35.175.99.113, destination: 192.168.1.141, ttl: S[1/3] Connecting ... ⠒ [00:00:01] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:21.564004Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service:upnp: mio::poll: deregistering event source from poller
2023-10-17T13:43:21.564376Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service: iroh_net::portmapper: tick: mapping ready 2023-10-17T13:43:21.564469Z DEBUG magicsock{me=mefmz7clbwdlzey2}:portmapper.service: iroh_net::portmapper: failed to get a port mapping IO error: search timed out 2023-10-17T13:43:21.573496Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service:portmapper.probe: mio::poll: deregistering event source from poller
2023-10-17T13:43:21.573679Z DEBUG magicsock{me=mefmz7clbwdlzey2}:portmapper.service:portmapper.probe: iroh_net::portmapper::upnp: upnp probe failed: IO error: search timed out 2023-10-17T13:43:21.574105Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service:portmapper.probe: iroh_net::portmapper: tick: upnp probe ready 2023-10-17T13:43:21.574311Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service: iroh_net::portmapper: tick: probe ready 2023-10-17T13:43:21.574422Z TRACE magicsock{me=mefmz7clbwdlzey2}:portmapper.service: iroh_net::portmapper: probe output output=ProbeOutput { upnp: false, pcp: false, nat_pmp: false } 2023-10-17T13:43:21.574586Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: Portmapper probe report report=Some(ProbeOutput { upnp: false, pcp: false, nat_pmp: false }) 2023-10-17T13:43:21.574647Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: portmapper future done 2023-10-17T13:43:21.574693Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: tick; awaiting tasks awaiting=OutstandingTasks { probes: false, port_mapper: false, captive_task: false, hairpin: false } 2023-10-17T13:43:21.574749Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: all tasks done 2023-10-17T13:43:21.574798Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: aborting 2 probe sets, already have enough reports 2023-10-17T13:43:21.574980Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: Sending report to netcheck actor 2023-10-17T13:43:21.575136Z TRACE mio::poll: deregistering event source from poller
2023-10-17T13:43:21.575197Z TRACE mio::poll: deregistering event source from poller
2023-10-17T13:43:21.575255Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::netcheck::reportgen: reportgen actor finished 2023-10-17T13:43:21.575471Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor:reportgen.actor: iroh_net::util: hairpin actor completed 2023-10-17T13:43:21.575547Z TRACE magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::netcheck: handling message msg=ReportReady { report: Report { udp: true, ipv6: false, ipv4: true, ipv6_can_send: false, ipv4_can_send: true, os_has_ipv6: true, icmpv4: false, mapping_varies_by_dest_ip: Some(false), hair_pinning: Some(false), portmap_probe: Some(ProbeOutput { upnp: false, pcp: false, nat_pmp: false }), preferred_derp: 0, region_latency: RegionLatencies({1: 131.919166ms, 2: 59.982334ms}), region_v4_latency: RegionLatencies({2: 59.982334ms, 1: 131.919166ms}), region_v6_latency: RegionLatencies({}), global_v4: Some(86.123.229.197:62394), global_v6: None, captive_portal: Some(false) }, derp_map: DerpMap { regions: {1: DerpRegion { region_id: 1, nodes: [DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns }], avoid: false, region_code: "default-1" }, 2: DerpRegion { region_id: 2, nodes: [DerpNode { name: "eu-default-1", region_id: 2, url: https://euw1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.253.75.5), ipv6: TryDns }], avoid: false, region_code: "default-2" }} } } 2023-10-17T13:43:21.575847Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::netcheck: report: udp=true v6=false v6os=true mapvarydest=Some(false) hair=Some(false) portmap={ UPnP: false, PMP: false, PCP: false } v4a=86.123.229.197:62394 captiveportal=false derp=2 derpdist=1v4:131,2v4:59 2023-10-17T13:43:21.575907Z DEBUG magicsock{me=mefmz7clbwdlzey2}:netcheck.actor: iroh_net::util: reportgen actor completed 2023-10-17T13:43:21.576005Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:update_endpoints:determine_endpoints:update_net_info: iroh_net::magicsock: setting no_v4_send false -> false 2023-10-17T13:43:21.576114Z INFO magicsock{me=mefmz7clbwdlzey2}:actor:update_endpoints:determine_endpoints:update_net_info: iroh_net::magicsock: home is now derp-2 (default-2) 2023-10-17T13:43:21.581076Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:update_endpoints: iroh_net::magicsock: endpoints changed: 86.123.229.197:62394 (stun), 192.168.1.132:62394 (local), 192.168.1.141:62394 (local) 2023-10-17T13:43:21.581119Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:update_endpoints: iroh_net::magicsock: scheduling periodic_stun to run in 25s 2023-10-17T13:43:21.581157Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:update_endpoints: iroh_net::magicsock: endpoint update done (periodic) 2023-10-17T13:43:21.581190Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: tick: msg msg=GetMappingAddr(PublicKey(ahpcrcs6tqh6a7ml), Sender { inner: Some(Inner { state: State { is_complete: false, is_closed: false, is_rx_task_set: true, is_tx_task_set: false } }) }) 2023-10-17T13:43:21.581325Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: tick: endpoints update receiver None 2023-10-17T13:43:21.581556Z INFO magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::magicsock::derp_actor: adding connection to derp-2 for home-keep-alive 2023-10-17T13:43:21.581783Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::magicsock::derp_actor: 1 active derp conns: derp-2=cr0,wr0 2023-10-17T13:43:21.581879Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: iroh_net::derp::http::client: recv_detail tick 2023-10-17T13:43:21.581953Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}: iroh_net::derp::http::client: no connection, trying to connect 2023-10-17T13:43:21.581999Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: url: None, is_test_url: false 2023-10-17T13:43:21.582048Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: region: DerpRegion { region_id: 2, nodes: [DerpNode { name: "eu-default-1", region_id: 2, url: https://euw1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.253.75.5), ipv6: TryDns }], avoid: false, region_code: "default-2" } 2023-10-17T13:43:21.582082Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: dial region: DerpRegion { region_id: 2, nodes: [DerpNode { name: "eu-default-1", region_id: 2, url: https://euw1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.253.75.5), ipv6: TryDns }], avoid: false, region_code: "default-2" } 2023-10-17T13:43:21.582100Z DEBUG iroh_net::magic_endpoint: connecting to ahpcrcs6tqh6a7mlw5hxkwdnts4gz6tevhxiw2jkphcigwzmu3aq: (via [fd15:70a:510b::]:12345 - {192.168.86.24:11204, 173.56.221.209:49526, 173.56.221.209:11204}) 2023-10-17T13:43:21.582136Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: dial node: DerpNode { name: "eu-default-1", region_id: 2, url: https://euw1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.253.75.5), ipv6: TryDns } 2023-10-17T13:43:21.582211Z TRACE quinn_proto::endpoint: initial_dcid=1ca132c2932ddf9fead8a8131dcd59c06c18b2b3 2023-10-17T13:43:21.582229Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv4"}: iroh_net::derp::http::client: dial start: Ipv4(Some(34.253.75.5)) 2023-10-17T13:43:21.582274Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv4"}: iroh_net::derp::http::client: dialing 34.253.75.5:443 2023-10-17T13:43:21.582347Z DEBUG rustls::client::hs: No cached session for DnsName("localhost")
2023-10-17T13:43:21.582416Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv4"}: mio::poll: registering event source with poller: token=Token(5472535296), interests=READABLE | WRITABLE
2023-10-17T13:43:21.582461Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: iroh_net::derp::http::client: dial start: Ipv6(TryDns) 2023-10-17T13:43:21.582559Z DEBUG rustls::client::hs: Not resuming any session
2023-10-17T13:43:21.582834Z TRACE rustls::client::hs: Sending ClientHello Message { version: TLSv1_0, payload: Handshake { parsed: HandshakeMessagePayload { typ: ClientHello, payload: ClientHello( ClientHelloPayload { client_version: TLSv1_2, random: 5c75ba242ce4861db9466e1340f864e48868a4451b9bc3553bc977a90b0e3d5c, session_id: , cipher_suites: [ TLS13_CHACHA20_POLY1305_SHA256, TLS13_AES_256_GCM_SHA384, TLS13_AES_128_GCM_SHA256, TLS_EMPTY_RENEGOTIATION_INFO_SCSV, ], compression_methods: [ Null, ], extensions: [ SupportedVersions( [ TLSv1_3, ], ), ECPointFormats( [ Uncompressed, ], ), NamedGroups( [ X25519, secp256r1, secp384r1, ], ), SignatureAlgorithms( [ ECDSA_NISTP384_SHA384, ECDSA_NISTP256_SHA256, ED25519, ], ), ExtendedMasterSecretRequest, CertificateStatusRequest( OCSP( OCSPCertificateStatusRequest { responder_ids: [], extensions: , }, ), ), ServerName( [ ServerName { typ: HostName, payload: HostName( DnsName( "localhost", ), ), }, ], ), SignedCertificateTimestampRequest, KeyShare( [ KeyShareEntry { group: X25519, payload: 3f1a23dcccb676bd4d3a1f6937b9040f4a415b9473265a5d17191199220c0513, }, ], ), PresharedKeyModes( [ PSK_DHE_KE, ], ), Protocols( [ ProtocolName( 2f69726f682d62797465732f32, ), ], ), TransportParameters( [ 1, 2, 103, 16, 3, 2, 69, 192, 4, 8, 255, 255, 255, 255, 255, 255, 255, 255, 5, 4, 128, 19, 18, 208, 6, 4, 128, 19, 18, 208, 7, 4, 128, 19, 18, 208, 8, 2, 64, 100, 9, 2, 64, 100, 14, 1, 5, 64, 182, 0, 32, 4, 128, 0, 255, 255, 15, 8, 113, 228, 158, 5, 247, 154, 104, 3, 106, 178, 0, ], ), SessionTicket( Request, ), ], }, ), }, encoded: 0100010a03035c75ba242ce4861db9466e1340f864e48868a4451b9bc3553bc977a90b0e3d5c00000813031302130100ff010000d9002b0003020304000b00020100000a00080006001d00170018000d00080006050304030807001700000005000501000000000000000e000c0000096c6f63616c686f737400120000003300260024001d00203f1a23dcccb676bd4d3a1f6937b9040f4a415b9473265a5d17191199220c0513002d0002010100100010000e0d2f69726f682d62797465732f320039004501026710030245c00408ffffffffffffffff0504801312d00604801312d00704801312d008024064090240640e010540b60020048000ffff0f0871e49e05f79a68036ab20000230000, }, }
2023-10-17T13:43:21.583398Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_resolver::async_resolver: handle passed back 2023-10-17T13:43:21.583768Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::xfer::dns_handle: querying: euw1-1.derp.iroh.network. A 2023-10-17T13:43:21.583896Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_resolver::name_server::name_server_pool: sending request: [Query { name: Name("euw1-1.derp.iroh.network."), query_type: A, query_class: IN }] 2023-10-17T13:43:21.583943Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_resolver::name_server::name_server: reconnecting: NameServerConfig { socket_addr: 193.231.252.1:53, protocol: Udp, tls_dns_name: None, trust_nx_responses: false, bind_addr: None } 2023-10-17T13:43:21.584005Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::xfer: enqueueing message:QUERY:[Query { name: Name("euw1-1.derp.iroh.network."), query_type: A, query_class: IN }] 2023-10-17T13:43:21.584032Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_resolver::name_server::name_server: reconnecting: NameServerConfig { socket_addr: 213.154.124.1:53, protocol: Udp, tls_dns_name: None, trust_nx_responses: false, bind_addr: None } 2023-10-17T13:43:21.584064Z TRACE quinn_proto::connection: wrote 270 Initial CRYPTO bytes 2023-10-17T13:43:21.584076Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::xfer: enqueueing message:QUERY:[Query { name: Name("euw1-1.derp.iroh.network."), query_type: A, query_class: IN }] 2023-10-17T13:43:21.584208Z DEBUG trust_dns_proto::udp::udp_client_stream: final message: ; header 65176:QUERY:RD:NoError:QUERY:0/0/0 ; query ;; euw1-1.derp.iroh.network. IN A

2023-10-17T13:43:21.584240Z DEBUG trust_dns_proto::udp::udp_client_stream: final message: ; header 24458:QUERY:RD:NoError:QUERY:0/0/0 ; query ;; euw1-1.derp.iroh.network. IN A

2023-10-17T13:43:21.584260Z TRACE drive{id=0}:send{space=Initial pn=0}: quinn_proto::connection: CRYPTO: off 0 len 270 2023-10-17T13:43:21.584289Z TRACE drive{id=0}:send{space=Initial pn=0}: quinn_proto::connection::packet_builder: PADDING * 871 2023-10-17T13:43:21.584336Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: mio::poll: registering event source with poller: token=Token(5472543232), interests=READABLE | WRITABLE
2023-10-17T13:43:21.584353Z TRACE drive{id=0}: quinn_proto::connection: sending 1200 bytes in 1 datagrams 2023-10-17T13:43:21.584376Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::udp::udp_stream: created socket successfully 2023-10-17T13:43:21.584414Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: mio::poll: registering event source with poller: token=Token(5472543488), interests=READABLE | WRITABLE
2023-10-17T13:43:21.584435Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::udp::udp_stream: created socket successfully 2023-10-17T13:43:21.584454Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: tick: network send 2023-10-17T13:43:21.584548Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: sending: dest: QuicMappedAddr([fd15:70a:510b::]:12345), src: None, content_len: 1200

2023-10-17T13:43:21.584575Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: Sending to endpoint for QuicMappedAddr([fd15:70a:510b::]:12345) (PublicKey(ahpcrcs6tqh6a7ml)) 2023-10-17T13:43:21.584612Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock::endpoint: using candidate addr Some(173.56.221.209:11204), derp addr: Some(1) 2023-10-17T13:43:21.584625Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock::endpoint: want full ping? Instant { tv_sec: 68960, tv_nsec: 340913375 } 2023-10-17T13:43:21.584705Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock::endpoint: full ping: no full ping done 2023-10-17T13:43:21.584733Z INFO magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock::endpoint: start ping to Derp(1): Discovery 2023-10-17T13:43:21.584753Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock::endpoint: sending pings to [IpPort { ip: 173.56.221.209, port: 49526 }, IpPort { ip: 173.56.221.209, port: 11204 }, IpPort { ip: 192.168.86.24, port: 11204 }] 2023-10-17T13:43:21.584767Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock::endpoint: disco: send, starting discovery for PublicKey(ahpcrcs6tqh6a7ml) 2023-10-17T13:43:21.584780Z INFO magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock::endpoint: start ping to UDP(173.56.221.209:49526): Discovery 2023-10-17T13:43:21.584834Z INFO magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock::endpoint: start ping to UDP(173.56.221.209:11204): Discovery 2023-10-17T13:43:21.584849Z INFO magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock::endpoint: start ping to UDP(192.168.86.24:11204): Discovery 2023-10-17T13:43:21.584863Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock::endpoint: sending UDP: Some(173.56.221.209:11204), DERP: Some(1), #pings: 5 2023-10-17T13:43:21.584877Z INFO magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: handle_ping_actions (5): [SendPing { id: 0, dst: Derp(1), dst_key: PublicKey(ahpcrcs6tqh6a7ml), tx_id: TransactionId(0xE3E8BB2FCCA957D61ADC4B6E), purpose: Discovery }, SendPing { id: 0, dst: Udp(173.56.221.209:49526), dst_key: PublicKey(ahpcrcs6tqh6a7ml), tx_id: TransactionId(0xAF753DE871329979B514F8F1), purpose: Discovery }, SendPing { id: 0, dst: Udp(173.56.221.209:11204), dst_key: PublicKey(ahpcrcs6tqh6a7ml), tx_id: TransactionId(0xBF5C043D980C81A587A81C8E), purpose: Discovery }, SendPing { id: 0, dst: Udp(192.168.86.24:11204), dst_key: PublicKey(ahpcrcs6tqh6a7ml), tx_id: TransactionId(0xAFE6B3C1A346EE0E397D9829), purpose: Discovery }, EnqueueCallMeMaybe { derp_region: 1, endpoint_id: 0 }] 2023-10-17T13:43:21.585007Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message: iroh_net::magicsock: sending disco message to Derp(1): Ping(Ping { tx_id: TransactionId(0xE3E8BB2FCCA957D61ADC4B6E), node_key: PublicKey(mefmz7clbwdlzey2) }) 2023-10-17T13:43:21.585564Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message: iroh_net::magicsock: disco: sent message to Derp(1) 2023-10-17T13:43:21.585589Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock::endpoint: disco: sent ping [transaction id (0xE3E8BB2FCCA957D61ADC4B6E)] 2023-10-17T13:43:21.585649Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message: iroh_net::magicsock: sending disco message to UDP(173.56.221.209:49526): Ping(Ping { tx_id: TransactionId(0xAF753DE871329979B514F8F1), node_key: PublicKey(mefmz7clbwdlzey2) }) 2023-10-17T13:43:21.585674Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor: mio::poll: deregistering event source from poller
2023-10-17T13:43:21.585717Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message:send_addr:send_raw: iroh_net::magicsock: send_raw: 1 packets 2023-10-17T13:43:21.585728Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor: mio::poll: deregistering event source from poller
2023-10-17T13:43:21.585752Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor: mio::poll: deregistering event source from poller
2023-10-17T13:43:21.585756Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message:send_addr:send_raw: iroh_net::magicsock::rebinding_conn: [UDP] -> 173.56.221.209:49526 src: None (124b) 2023-10-17T13:43:21.585778Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message:send_addr:send_raw: iroh_net::magicsock: sent 1 packets to 173.56.221.209:49526 2023-10-17T13:43:21.585800Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message: iroh_net::magicsock: disco: sent message to UDP(173.56.221.209:49526) 2023-10-17T13:43:21.585815Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock::endpoint: disco: sent ping [transaction id (0xAF753DE871329979B514F8F1)] 2023-10-17T13:43:21.585853Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message: iroh_net::magicsock: sending disco message to UDP(173.56.221.209:11204): Ping(Ping { tx_id: TransactionId(0xBF5C043D980C81A587A81C8E), node_key: PublicKey(mefmz7clbwdlzey2) }) 2023-10-17T13:43:21.585842Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::magicsock::derp_actor: sending derp region_id=1 peer=PublicKey(ahpcrcs6tqh6a7ml) 2023-10-17T13:43:21.585883Z INFO magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::magicsock::derp_actor: adding connection to derp-1 for PublicKey(ahpcrcs6tqh6a7ml) 2023-10-17T13:43:21.585916Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message:send_addr:send_raw: iroh_net::magicsock: send_raw: 1 packets 2023-10-17T13:43:21.585942Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message:send_addr:send_raw: iroh_net::magicsock::rebinding_conn: [UDP] -> 173.56.221.209:11204 src: None (124b) 2023-10-17T13:43:21.585957Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message:send_addr:send_raw: iroh_net::magicsock: sent 1 packets to 173.56.221.209:11204 2023-10-17T13:43:21.585977Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message: iroh_net::magicsock: disco: sent message to UDP(173.56.221.209:11204) 2023-10-17T13:43:21.585995Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::magicsock::derp_actor: 2 active derp conns: derp-1=cr0,wr0 derp-2=cr0,wr0 2023-10-17T13:43:21.586046Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::magicsock::derp_actor: [DERP] -> 1 (124b) PublicKey(ahpcrcs6tqh6a7ml) 2023-10-17T13:43:21.586049Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock::endpoint: disco: sent ping [transaction id (0xBF5C043D980C81A587A81C8E)] 2023-10-17T13:43:21.586091Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message: iroh_net::magicsock: sending disco message to UDP(192.168.86.24:11204): Ping(Ping { tx_id: TransactionId(0xAFE6B3C1A346EE0E397D9829), node_key: PublicKey(mefmz7clbwdlzey2) }) 2023-10-17T13:43:21.586101Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::derp::http::client: send 2023-10-17T13:43:21.586208Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}: iroh_net::derp::http::client: no connection, trying to connect 2023-10-17T13:43:21.586218Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message:send_addr:send_raw: iroh_net::magicsock: send_raw: 1 packets 2023-10-17T13:43:21.586231Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: url: None, is_test_url: false 2023-10-17T13:43:21.586256Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message:send_addr:send_raw: iroh_net::magicsock::rebinding_conn: [UDP] -> 192.168.86.24:11204 src: None (124b) 2023-10-17T13:43:21.586303Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: region: DerpRegion { region_id: 1, nodes: [DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns }], avoid: false, region_code: "default-1" } 2023-10-17T13:43:21.586321Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message:send_addr:send_raw: iroh_net::magicsock: sent 1 packets to 192.168.86.24:11204 2023-10-17T13:43:21.586326Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: dial region: DerpRegion { region_id: 1, nodes: [DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns }], avoid: false, region_code: "default-1" } 2023-10-17T13:43:21.586434Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message: iroh_net::magicsock: disco: sent message to UDP(192.168.86.24:11204) 2023-10-17T13:43:21.586442Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: dial node: DerpNode { name: "na-default-1", region_id: 1, url: https://use1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.207.161.128), ipv6: TryDns } 2023-10-17T13:43:21.586459Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock::endpoint: disco: sent ping [transaction id (0xAFE6B3C1A346EE0E397D9829)] 2023-10-17T13:43:21.586478Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv4"}: iroh_net::derp::http::client: dial start: Ipv4(Some(34.207.161.128)) 2023-10-17T13:43:21.586603Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv4"}: iroh_net::derp::http::client: dialing 34.207.161.128:443 2023-10-17T13:43:21.586638Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_raw: iroh_net::magicsock: send_raw: 1 packets 2023-10-17T13:43:21.586671Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor:send_raw: iroh_net::magicsock::rebinding_conn: [UDP] -> 173.56.221.209:11204 src: None (1200b) 2023-10-17T13:43:21.586685Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_raw: iroh_net::magicsock: sent 1 packets to 173.56.221.209:11204 2023-10-17T13:43:21.586726Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv4"}: mio::poll: registering event source with poller: token=Token(5469402752), interests=READABLE | WRITABLE
2023-10-17T13:43:21.586760Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: iroh_net::derp::http::client: dial start: Ipv6(TryDns) 2023-10-17T13:43:21.586776Z WARN iroh_net::magicsock: sending call me maybe to PublicKey(ahpcrcs6tqh6a7ml) 2023-10-17T13:43:21.586837Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::xfer::dns_handle: querying: use1-1.derp.iroh.network. A 2023-10-17T13:43:21.586866Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_resolver::name_server::name_server_pool: sending request: [Query { name: Name("use1-1.derp.iroh.network."), query_type: A, query_class: IN }] 2023-10-17T13:43:21.586904Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_resolver::name_server::name_server: existing connection: NameServerConfig { socket_addr: 193.231.252.1:53, protocol: Udp, tls_dns_name: None, trust_nx_responses: false, bind_addr: None } 2023-10-17T13:43:21.586961Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: tick: msg msg=SendCallMeMaybe { dst: Derp(1), dst_key: PublicKey(ahpcrcs6tqh6a7ml), msg: CallMeMaybe { my_number: [86.123.229.197:62394, 192.168.1.132:62394, 192.168.1.141:62394] } } 2023-10-17T13:43:21.586965Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::xfer: enqueueing message:QUERY:[Query { name: Name("use1-1.derp.iroh.network."), query_type: A, query_class: IN }] 2023-10-17T13:43:21.586986Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message: iroh_net::magicsock: sending disco message to Derp(1): CallMeMaybe(CallMeMaybe { my_number: [86.123.229.197:62394, 192.168.1.132:62394, 192.168.1.141:62394] }) 2023-10-17T13:43:21.586994Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_resolver::name_server::name_server: existing connection: NameServerConfig { socket_addr: 213.154.124.1:53, protocol: Udp, tls_dns_name: None, trust_nx_responses: false, bind_addr: None } 2023-10-17T13:43:21.587103Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::xfer: enqueueing message:QUERY:[Query { name: Name("use1-1.derp.iroh.network."), query_type: A, query_class: IN }] 2023-10-17T13:43:21.587187Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_disco_message: iroh_net::magicsock: disco: sent message to Derp(1) 2023-10-17T13:43:21.587244Z DEBUG trust_dns_proto::udp::udp_client_stream: final message: ; header 57660:QUERY:RD:NoError:QUERY:0/0/0 ; query ;; use1-1.derp.iroh.network. IN A

2023-10-17T13:43:21.587269Z DEBUG trust_dns_proto::udp::udp_client_stream: final message: ; header 43744:QUERY:RD:NoError:QUERY:0/0/0 ; query ;; use1-1.derp.iroh.network. IN A

2023-10-17T13:43:21.587287Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}: iroh_net::derp::http::client: no connection, trying to connect 2023-10-17T13:43:21.587309Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: url: None, is_test_url: false 2023-10-17T13:43:21.587325Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: region: DerpRegion { region_id: 2, nodes: [DerpNode { name: "eu-default-1", region_id: 2, url: https://euw1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.253.75.5), ipv6: TryDns }], avoid: false, region_code: "default-2" } 2023-10-17T13:43:21.587322Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: mio::poll: registering event source with poller: token=Token(5472544896), interests=READABLE | WRITABLE
2023-10-17T13:43:21.587427Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::udp::udp_stream: created socket successfully 2023-10-17T13:43:21.587404Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: dial region: DerpRegion { region_id: 2, nodes: [DerpNode { name: "eu-default-1", region_id: 2, url: https://euw1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.253.75.5), ipv6: TryDns }], avoid: false, region_code: "default-2" } 2023-10-17T13:43:21.587481Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: mio::poll: registering event source with poller: token=Token(5469405312), interests=READABLE | WRITABLE
2023-10-17T13:43:21.587498Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: dial node: DerpNode { name: "eu-default-1", region_id: 2, url: https://euw1-1.derp.iroh.network./, stun_only: false, stun_port: 3478, ipv4: Some(34.253.75.5), ipv6: TryDns } 2023-10-17T13:43:21.587502Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::udp::udp_stream: created socket successfully 2023-10-17T13:43:21.587526Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv4"}: iroh_net::derp::http::client: dial start: Ipv4(Some(34.253.75.5)) 2023-10-17T13:43:21.587545Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv4"}: iroh_net::derp::http::client: dialing 34.253.75.5:443 2023-10-17T13:43:21.587651Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv4"}: mio::poll: registering event source with poller: token=Token(5471564800), interests=READABLE | WRITABLE
2023-10-17T13:43:21.587764Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: iroh_net::derp::http::client: dial start: Ipv6(TryDns) 2023-10-17T13:43:21.587840Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::xfer::dns_handle: querying: euw1-1.derp.iroh.network. A 2023-10-17T13:43:21.587865Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_resolver::name_server::name_server_pool: sending request: [Query { name: Name("euw1-1.derp.iroh.network."), query_type: A, query_class: IN }] 2023-10-17T13:43:21.587899Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_resolver::name_server::name_server: existing connection: NameServerConfig { socket_addr: 193.231.252.1:53, protocol: Udp, tls_dns_name: None, trust_nx_responses: false, bind_addr: None } 2023-10-17T13:43:21.587915Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::xfer: enqueueing message:QUERY:[Query { name: Name("euw1-1.derp.iroh.network."), query_type: A, query_class: IN }] 2023-10-17T13:43:21.587934Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_resolver::name_server::name_server: existing connection: NameServerConfig { socket_addr: 213.154.124.1:53, protocol: Udp, tls_dns_name: None, trust_nx_responses: false, bind_addr: None } 2023-10-17T13:43:21.587956Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::xfer: enqueueing message:QUERY:[Query { name: Name("euw1-1.derp.iroh.network."), query_type: A, query_class: IN }] 2023-10-17T13:43:21.588009Z DEBUG trust_dns_proto::udp::udp_client_stream: final message: ; header 51327:QUERY:RD:NoError:QUERY:0/0/0 ; query ;; euw1-1.derp.iroh.network. IN A

2023-10-17T13:43:21.588009Z DEBUG trust_dns_proto::udp::udp_client_stream: final message: ; header 13099:QUERY:RD:NoError:QUERY:0/0/0 ; query ;; euw1-1.derp.iroh.network. IN A

2023-10-17T13:43:21.588060Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: mio::poll: registering event source with poller: token=Token(5471565952), interests=READABLE | WRITABLE
2023-10-17T13:43:21.588082Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::udp::udp_stream: created socket successfully 2023-10-17T13:43:21.588201Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: mio::poll: registering event source with poller: token=Token(5471566336), interests=READABLE | WRITABLE
2023-10-17T13:43:21.588222Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::udp::udp_stream: created socket successfully 2023-10-17T13:43:21.594741Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::rr::record_data: reading A 2023-10-17T13:43:21.594797Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::udp::udp_client_stream: received message id: 13099 2023-10-17T13:43:21.594819Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: mio::poll: deregistering event source from poller
2023-10-17T13:43:21.594871Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_resolver::error: Response:; header 13099:RESPONSE:RD,RA:NoError:QUERY:1/0/0 ; query ;; euw1-1.derp.iroh.network. IN A ; answers 1 euw1-1.derp.iroh.network. 17 IN A 34.253.75.5 ; nameservers 0 ; additionals 0

2023-10-17T13:43:21.594914Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: mio::poll: deregistering event source from poller
2023-10-17T13:43:21.594946Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_resolver::error: Response:; header 13099:RESPONSE:RD,RA:NoError:QUERY:1/0/0 ; query ;; euw1-1.derp.iroh.network. IN A ; answers 1 euw1-1.derp.iroh.network. 17 IN A 34.253.75.5 ; nameservers 0 ; additionals 0 [1/3] Connecting ... ⠐ [00:00:01] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:21.613114Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::rr::record_data: reading A 2023-10-17T13:43:21.613162Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_proto::udp::udp_client_stream: received message id: 57660 2023-10-17T13:43:21.613190Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: mio::poll: deregistering event source from poller
2023-10-17T13:43:21.613244Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_resolver::error: Response:; header 57660:RESPONSE:RD,RA:NoError:QUERY:1/0/0 ; query ;; use1-1.derp.iroh.network. IN A ; answers 1 use1-1.derp.iroh.network. 60 IN A 34.207.161.128 ; nameservers 0 ; additionals 0

2023-10-17T13:43:21.613343Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: mio::poll: deregistering event source from poller
2023-10-17T13:43:21.613388Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv6"}: trust_dns_resolver::error: Response:; header 57660:RESPONSE:RD,RA:NoError:QUERY:1/0/0 ; query ;; use1-1.derp.iroh.network. IN A ; answers 1 use1-1.derp.iroh.network. 60 IN A 34.207.161.128 ; nameservers 0 ; additionals 0

2023-10-17T13:43:21.644567Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv4"}: iroh_net::derp::http::client: dial done: Ipv4(Some(34.253.75.5)) 2023-10-17T13:43:21.644627Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: dialed region 2023-10-17T13:43:21.644666Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: Starting TLS handshake 2023-10-17T13:43:21.644694Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::hs: No cached session for DnsName("euw1-1.derp.iroh.network.")
2023-10-17T13:43:21.644816Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::hs: Not resuming any session
2023-10-17T13:43:21.644872Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::hs: Sending ClientHello Message { version: TLSv1_0, payload: Handshake { parsed: HandshakeMessagePayload { typ: ClientHello, payload: ClientHello( ClientHelloPayload { client_version: TLSv1_2, random: 71e14cb132b3f3c6166cba7395c1fe73c795606a3865758ed71576c6e784ab44, session_id: 5ee1f269e312774a0550bce0b11e226ba8c919c9ca1c43ccfaaaa318b1a6b509, cipher_suites: [ TLS13_AES_256_GCM_SHA384, TLS13_AES_128_GCM_SHA256, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_CHACHA20_POLY1305_SHA256, TLS_EMPTY_RENEGOTIATION_INFO_SCSV, ], compression_methods: [ Null, ], extensions: [ SupportedVersions( [ TLSv1_3, TLSv1_2, ], ), ECPointFormats( [ Uncompressed, ], ), NamedGroups( [ X25519, secp256r1, secp384r1, ], ), SignatureAlgorithms( [ ECDSA_NISTP384_SHA384, ECDSA_NISTP256_SHA256, ED25519, RSA_PSS_SHA512, RSA_PSS_SHA384, RSA_PSS_SHA256, RSA_PKCS1_SHA512, RSA_PKCS1_SHA384, RSA_PKCS1_SHA256, ], ), ExtendedMasterSecretRequest, CertificateStatusRequest( OCSP( OCSPCertificateStatusRequest { responder_ids: [], extensions: , }, ), ), ServerName( [ ServerName { typ: HostName, payload: HostName( DnsName( "euw1-1.derp.iroh.network", ), ), }, ], ), SignedCertificateTimestampRequest, KeyShare( [ KeyShareEntry { group: X25519, payload: e846266e75889199ecaca9487be1a123605bf3bc891b8ede29bc77434aa97b3b, }, ], ), PresharedKeyModes( [ PSK_DHE_KE, ], ), SessionTicket( Request, ), ], }, ), }, encoded: 010000f6030371e14cb132b3f3c6166cba7395c1fe73c795606a3865758ed71576c6e784ab44205ee1f269e312774a0550bce0b11e226ba8c919c9ca1c43ccfaaaa318b1a6b5090014130213011303c02cc02bcca9c030c02fcca800ff01000099002b00050403040303000b00020100000a00080006001d00170018000d00140012050304030807080608050804060105010401001700000005000501000000000000001d001b000018657577312d312e646572702e69726f682e6e6574776f726b00120000003300260024001d0020e846266e75889199ecaca9487be1a123605bf3bc891b8ede29bc77434aa97b3b002d0002010100230000, }, [1/3] Connecting ... ⠐ [00:00:01] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:21.702349Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::hs: We got ServerHello ServerHelloPayload { legacy_version: TLSv1_2, random: a3ecdc5edc51404f54078bcd5c6a41e895ee83e523e8d03723edce1ce01f73e1, session_id: 5ee1f269e312774a0550bce0b11e226ba8c919c9ca1c43ccfaaaa318b1a6b509, cipher_suite: TLS13_AES_256_GCM_SHA384, compression_method: Null, extensions: [ KeyShare( KeyShareEntry { group: X25519, payload: 562e69b72e1147eff9146fa43fa7293cfd47a440156f0ac80094cb11b76dde0e, }, ), SupportedVersions( TLSv1_3, ), ], }
2023-10-17T13:43:21.702416Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::hs: Using ciphersuite TLS13_AES_256_GCM_SHA384
2023-10-17T13:43:21.702432Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::tls13: Not resuming
2023-10-17T13:43:21.702442Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::client_conn: EarlyData rejected
2023-10-17T13:43:21.702716Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::conn: Dropping CCS
2023-10-17T13:43:21.702737Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::tls13: TLS1.3 encrypted extensions: [ServerNameAck]
2023-10-17T13:43:21.702750Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::hs: ALPN protocol is None
2023-10-17T13:43:21.702813Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::tls13: Server cert is [Certificate(b"0\x82\x0410\x82\x03\x19\xa0\x03\x02\x01\x02\x02\x12\x03P7]V>h#\xc8\xcb\xd6\xbe(\x8eKUK\xe60\r\x06\t\x86H\x86\xf7\r\x01\x01\x0b\x05\0021\x0b0\t\x06\x03U\x04\x06\x13\x02US1\x160\x14\x06\x03U\x04\n\x13\rLet's Encrypt1\x0b0\t\x06\x03U\x04\x03\x13\x02R30\x1e\x17\r231010081340Z\x17\r240108081339Z0#1!0\x1f\x06\x03U\x04\x03\x13\x18euw1-1.derp.iroh.network0Y0\x13\x06\x07\x86H\xce=\x02\x01\x06\x08\x86H\xce=\x03\x01\x07\x03B\0\x04\xd2\xda\x9d\x88\x9a;\x86'\n\xc5E\xb9\x0c\xbe\xa4\xf8%7\xea\xc7pT\x14\xa54E.\xd8\x1d\x8f\x18\xb7\t}z+?\xf2\xdc\x99\xa4\'\xe2[(o\xf4\xf9\xa6\xe7Y\xef\xc1E\xee\xdcD\xf2\xe6\x99\x0b\xb4\xfc\xa3\x82\x02\x190\x82\x02\x150\x0e\x06\x03U\x1d\x0f\x01\x01\xff\x04\x04\x03\x02\x07\x800\x1d\x06\x03U\x1d%\x04\x160\x14\x06\x08+\x06\x01\x05\x05\x07\x03\x01\x06\x08+\x06\x01\x05\x05\x07\x03\x020\x0c\x06\x03U\x1d\x13\x01\x01\xff\x04\x020\00\x1d\x06\x03U\x1d\x0e\x04\x16\x04\x14d\x96\x05U\x14\xca\xe4[\x0c\n\xa1\xe1(\xf7\x07=\xb3\xe9&\x1b0\x1f\x06\x03U\x1d#\x04\x180\x16\x80\x14\x14.\xb3\x17\xb7XV\xcb\xaeP\t@\xe6\x1f\xaf\x9d\x8b\x14\xc2\xc60U\x06\x08+\x06\x01\x05\x05\x07\x01\x01\x04I0G0!\x06\x08+\x06\x01\x05\x05\x070\x01\x86\x15http://r3.o.lencr.org0\"\x06\x08+\x06\x01\x05\x05\x070\x02\x86\x16http://r3.i.lencr.org/0#\x06\x03U\x1d\x11\x04\x1c0\x1a\x82\x18euw1-1.derp.iroh.network0\x13\x06\x03U\x1d \x04\x0c0\n0\x08\x06\x06g\x81\x0c\x01\x02\x010\x82\x01\x03\x06\n+\x06\x01\x04\x01\xd6y\x02\x04\x02\x04\x81\xf4\x04\x81\xf1\0\xef\0v\0\xda\xb6\xbfk?\xb5\xb6\"\x9f\x9b\xc2\xbb\k\xe8p\x91ql\xbbQ\x84\x854\xbd\xa4=0H\xd7\xfb\xab\0\0\x01\x8b\x18\xdd\x06\xcc\0\0\x04\x03\0G0E\x02 n\xa6\xbd7\xa6\xe0\x10{I\xfe{/\x95\xb0\xc0[Z\xb6\x84\xa6d\x88`@\xb5\xf22{\n\xb5\xad-\x02!\0\xe2R'\xb2\xa2\xc5\xd6\xfd\xe4\x95\xff7\x14\xf4\xe7y\xf6\x19_NJ\x99\xfc\xbb\x96Si\x0c/\xcf\x97\xb1\0u\0;Swu>-\xb9\x80N\x8b0[\x06\xfe@;g\xd8O\xc3\xf4\xc7\xbd\0\r-ro\xe1\xfa\xd4\x17\0\0\x01\x8b\x18\xdd\x06\xcf\0\0\x04\x03\0F0D\x02 %\x1dm\xb4\x04\xb7\x90\xd1\xc2\xc8\xa4\x0c\xe5\xab\xdf\x1fE\xa4\x98\xd3\xa5\xa9P\xc0pZ \xd4\xf6\xb2\xd0\t\x02 7\x8d\xe7\xf6B\xd4$H\xfe7\xa3\x18x\xd3m\xf6Z+\x03\xee\xc8}\x95\x19X\rW\xe4\xd3/@\x130\r\x06\t\x86H\x86\xf7\r\x01\x01\x0b\x05\0\x03\x82\x01\x01\0]\x17\x1d\x06i=0x\xeb7\x90$\xad\xf3POP\xeb\x97\xf5:73\xe4=!:%\xd7\x06\xc3\xbaPM\xa8\xa0\x1e\x1f<\x04\xe7!\xf7=/\nr\xf6+#\x15\xc9\xeat\xe5\x97\xc1\xdes\xbf\xa8\x19T\xbb\x08\t\x8d\x11=\xd7>n\xce|o=\xddU\xf2-\xaf\xf6\x88\xb6\xd69q\xe4~\xf8`\x01c4\x19\xb2\xa8\x11\xca\x06\xdeY\xb1k\x9c,\xf8nf\x87\xa8\\xe6\xb6\xfbHc\x13\xd0\x80\xd0H\x8d\xe8\x91\xa0-!?\xf2)Xn\xea\xae\xbeEd\x16\xb27\x9fd6\xd3Fa\xbeH\xfc\xd7\xcd\xd7dDL\xa9;\xc9\x1a\xd5\xd1T\xac\x88{L\n\xa4\xfd\xdf\x02'\xce\xd6kS\xadK\xec\xeb\xf1%_\xf4\xa3\xa1\x82\x1bS&\x07\xee\xd9\x91\x8b,\x10\xae\xc4\x12)\x92=(\xd2\xb7f\xe9\xefD\x92\x91;\xa2\x1c^c)k\x87\xb4\x03\xfa\xaa\x86\x17\x93.6g\x8b\xe4\xc3\r\xb6\xa0\x90&#,\xf0<\xf3\x9b|\x9b\xb7\xb2Fgu%"), Certificate(b"0\x82\x05\x160\x82\x02\xfe\xa0\x03\x02\x01\x02\x02\x11\0\x91+\x08J\xcf\x0c\x18\xa7S\xf6\xd6.%\xa7_Z0\r\x06\t*\x86H\x86\xf7\r\x01\x01\x0b\x05\00O1\x0b0\t\x06\x03U\x04\x06\x13\x02US1)0'\x06\x03U\x04\n\x13 Internet Security Research Group1\x150\x13\x06\x03U\x04\x03\x13\x0cISRG Root X10\x1e\x17\r200904000000Z\x17\r250915160000Z021\x0b0\t\x06\x03U\x04\x06\x13\x02US1\x160\x14\x06\x03U\x04\n\x13\rLet's Encrypt1\x0b0\t\x06\x03U\x04\x03\x13\x02R30\x82\x01\"0\r\x06\t*\x86H\x86\xf7\r\x01\x01\x01\x05\0\x03\x82\x01\x0f\00\x82\x01\n\x02\x82\x01\x01\0\xbb\x02\x15(\xcc\xf6\xa0\x94\xd3\x0f\x12\xec\x8dU\x92\xc3\xf8\x82\xf1\x99\xa6zB\x88\xa7]&\xaa\xb5+\xb9\xc5L\xb1\xaf\x8ek\xf9u\xc8\xa3\xd7\x0fG\x94\x14U5W\x8c\x9e\xa8\xa29\x19\xf5\x82<B\xa9Nn\xf5;\xc3.\xdb\x8d\xc0\xb0\\\xf3Y8\xe7\xed\xcfi\xf0Z\x0b\x1b\xbe\xc0\x94$%\x87\xfa7q\xb3\x13\xe7\x1c\xac\xe1\x9b\xef\xdb\xe4;ERE\x96\xa9\xc1S\xce4\xc8R\xee\xb5\xae\xed\x8f\xdep\xe2\xa5T\xab\xb6m\x0e\x97\xa5@4k+\xd3\xbcf\xebf4|\xfak\x8b\x8fW)\x99\xf80\x17]\xbaro\xfb\x81\xc5\xad\xd2\x86X=\x17\xc7\xe7\t\xbb\xf1+\xf7\x86\xdc\xc1\xdaq]\xd4F\xe3\xcc\xad%\xc1\x88\xbcguf\xb3\xf1\x18\xf7\xa2\\\xe6S\xff:\x88\xb6G\xa5\xff\x13\x18\xea\x98\tw?\x9dS\xf9\xcf\x01\xe5\xf5\xa6p\x17\x14\xafc\xa4\xff\x99\xb3\x93\x9d\xdcS\xa7\x06\xfeH\x85\x1d\xa1i\xae%u\xbb\x13\xccR\x03\xf5\xedQ\xa1\x8b\xdb\x15\x02\x03\x01\0\x01\xa3\x82\x01\x080\x82\x01\x040\x0e\x06\x03U\x1d\x0f\x01\x01\xff\x04\x04\x03\x02\x01\x860\x1d\x06\x03U\x1d%\x04\x160\x14\x06\x08+\x06\x01\x05\x05\x07\x03\x02\x06\x08+\x06\x01\x05\x05\x07\x03\x010\x12\x06\x03U\x1d\x13\x01\x01\xff\x04\x080\x06\x01\x01\xff\x02\x01\00\x1d\x06\x03U\x1d\x0e\x04\x16\x04\x14\x14.\xb3\x17\xb7XV\xcb\xaeP\t@\xe6\x1f\xaf\x9d\x8b\x14\xc2\xc60\x1f\x06\x03U\x1d#\x04\x180\x16\x80\x14y\xb4Y\xe6{\xb6\xe5\xe4\x01s\x80\x08\x88\xc8\x1aX\xf6\xe9\x9bn02\x06\x08+\x06\x01\x05\x05\x07\x01\x01\x04&0$0\"\x06\x08+\x06\x01\x05\x05\x070\x02\x86\x16http://x1.i.lencr.org/0'\x06\x03U\x1d\x1f\x04 0\x1e0\x1c\xa0\x1a\xa0\x18\x86\x16http://x1.c.lencr.org/0\"\x06\x03U\x1d \x04\x1b0\x190\x08\x06\x06g\x81\x0c\x01\x02\x010\r\x06\x0b+\x06\x01\x04\x01\x82\xdf\x13\x01\x01\x010\r\x06\t*\x86H\x86\xf7\r\x01\x01\x0b\x05\0\x03\x82\x02\x01\0\x85\xcaNG>\xa3\xf7\x85D\x85\xbc\xd5gx\xb2\x98c\xaduM\x1e\x96=3erT-\x81\xa0\xea\xc3\xed\xf8 \xbf_\xcc\xb7p\0\xb7n;\xf6^\x94\xde\xe4 \x9f\xa6\xef\x8b\xb2\x03\xe7\xa2\xb5\x16<\x91\xce\xb4\xed9\x02\xe7|%\x8aG\xe6en?F\xf4\xd9\xf0\xce\x94+\xeeT\xce\x12\xbc\x8c'K\xb8\xc1\x98/\xa2\xaf\xcdq\x91J\x08\xb7\xc8\xb8#{\x04-\x08\xf9\x08W>\x83\xd9\x043\nG!x\t\x82'\xc3*\xc8\x9b\xb9\xce\\\xf2d\xc8\xc0\xbey\xc0O\x8emD\x0c^\x92\xbb.\xf7\x8b\x10\xe1\xe8\x1dD)\xdbY \xedc\xb9!\xf8\x12&\x94\x93W\xa0\x1de\x04\xc1\n\"\xae\x10\rC\x97\xa1\x18\x1f~\xe0\xe0\x867\xb5Z\xb1\xbd0\xbf\x87n+*\xff!N\x1b\x05\xc3\xf5\x18\x97\xf0^\xac\xc3\xa5\xb8j\xf0.\xbc;3\xb9\xeeK\xde\xcc\xfc\xe4\xaf\x84\x0b\x86?\xc0UC6\xf6h\xe16\x17j\x8e\x99\xd1\xff\xa5@\xa74\xb7\xc0\xd0c959un\xf2\xbav\xc8\x93\x02\xe9\xa9Kl\x17\xce\x0c\x02\xd9\xbd\x81\xfb\x9f\xb7h\xd4\x06e\xb3\x82=wS\xf8\x8ey\x03\xad\n1\x07u*C\xd8U\x97r\xc4)\x0e\xf7\xc4]N\xc8\xaeF\x840\xd7\xf2\x85_\x18\xa1y\xbb\xe7^p\x8b\x07\xe1\x86\x93\xc3\xb9\x8f\xdcaq%*\xaf\xdf\xed%PRh\x8b\x92\xdc\xe5\xd6\xb5\xe3\xda}\xd0\x87l\x84!1\xae\x82\xf5\xfb\xb9\xab\xc8\x89\x17=\xe1L\xe58\x0e\xf6\xbd+\xbd\x96\x81\x14\xeb\xd5\xdb= \xa7~Y\xd3\xe2\xf8X\xf9[\xb8H\xcd\xfe\\O\x16)\xfe\x1eU#\xaf\xc8\x11\xb0\x8d\xea|\x93\x90\x17/\xfd\xac\xa2\tGF?\xf0\xe9\xb0\xb7\xff(Mh2\xd6g^\x1ei\xa3\x93\xb8\xf5\x9d\x8b/\x0b\xd2RC\xa6o2WeM2\x81\xdf8S\x85]~]f)\xea\xb8\xdd\xe4\x95\xb5\xcd\xb5V\x12B\xcd\xc4N\xc6%8DPm\xec\xce\0U\x18\xfe\xe9Id\xd4N\xca\x97\x9c\xb4[\xc0s\xa8\xab\xb8G\xc2"), Certificate(b"0\x82\x050\x82\x04H\xa0\x03\x02\x01\x02\x02\x10@\x01w!7\xd4\xe9B\xb8\xeev\xaa<d\n\xb70\r\x06\t\x86H\x86\xf7\r\x01\x01\x0b\x05\00?1$0\"\x06\x03U\x04\n\x13\x1bDigital Signature Trust Co.1\x170\x15\x06\x03U\x04\x03\x13\x0eDST Root CA X30\x1e\x17\r210120191403Z\x17\r240930181403Z0O1\x0b0\t\x06\x03U\x04\x06\x13\x02US1)0'\x06\x03U\x04\n\x13 Internet Security Research Group1\x150\x13\x06\x03U\x04\x03\x13\x0cISRG Root X10\x82\x02\"0\r\x06\t\x86H\x86\xf7\r\x01\x01\x01\x05\0\x03\x82\x02\x0f\00\x82\x02\n\x02\x82\x02\x01\0\xad\xe8$s\xf4\x147\xf3\x9b\x9e+W(\x1c\x87\xbe\xdc\xb7\xdf8\x90\x8cn\xe6W\xa0x\xf7u\xc2\xa2\xfe\xf5jn\xf6\0O(\xdb\xdeh\x86lD\x93\xb6\xb1c\xfd\x14\x12k\xbf\x1f\xd2\xea1\x9b!~\xd13<\xbaH\xf5\xddy\xdf\xb3\xb8\xff\x12\xf1!\x9aK\xc1\x8a\x86qiJffl\x8f~<p\xbf\xad)\"\x06\xf3\xe4\xc0\xe6\x80\xae\xe2K\x8f\xb7\x99~\x94\x03\x9f\xd3G\x97|\x99H#S\xe88\xaeO\no\x83.\xd1IW\x8c\x80t\xb6\xda/\xd08\x8d{\x03p!\x1bu\xf20<\xfa\x8f\xae\xdd\xdac\xab\xeb\x16O\xc2\x8e\x11K~\xcf\x0b\xe8\xff\xb5w.\xf4\xb2{J\xe0L\x12%\x0cp\x8d\x03)\xa0\xe1S$\xec\x13\xd9\xee\x19\xbf\x10\xb3J\x8c?\x89\xa3aQ\xde\xac\x87\x07\x94\xf4cq\xec.\xe2o[\x98\x81\xe1\x89\\4ylv\xef;\x90by\xe6\xdb\xa4\x9a/&\xc5\xd0\x10\xe1\x0e\xde\xd9\x10\x8e\x16\xfb\xb7\xf7\xa8\xf7\xc7\xe5\x02\x07\x98\x8f6\x08\x95\xe7\xe27\x96\r6u\x9e\xfb\x0er\xb1\x1d\x9b\xbc\x03\xf9I\x05\xd8\x81\xdd\x05\xb4*\xd6A\xe9\xac\x01v\x95\n\x0f\xd8\xdf\xd5\xbd\x12\x1f5/(\x17l\xd2\x98\xc1\xa8\tdwnG7\xba\xce\xacY^h\x9d\x7fr\xd6\x89\xc5\x06A)>Y>\xdd&\xf5$\xc9\x11\xa7Z\xa3L@\x1fF\xa1\x99\xb5\xa7:Qn\x86;\x9e}r\xa7\x12\x05xY\xedQx\x15\x0b\x03\x8f\x8d\xd0/\x05\xb2>{J\x1cKs\x05\x12\xfc\xc6\xea\xe0P\x13|C\x93t\xb3\xcat\xe7\x8e\x1f\x01\x08\xd00\xd4[q6\xb4\x07\xba\xc100\H\xb7\x82;\x98\xa6}\x8a\xa2\xa3)\x82\xcc\xba\xbd\x83\x04\x1b\xa2\x83\x03A\xa1\xd6\x05\xf1\x1b\xc2\xb6\xf0\xa8|\x86;F\xa8H*\x88\xdcv\x9av\xbf\x1fj\xa5=\x19\x8f\xeb8\xf3d\xde\xc8+\r\n(\xff\xf7\xdb\xe2\x15B\xd4\"\xd0']\xe1y\xfe\x18\xe7p\x88\xadN\xe6\xd9\x8b:\xc6\xdd'Qn\xff\xbcd\xf53CO\x02\x03\x01\0\x01\xa3\x82\x01F0\x82\x01B0\x0f\x06\x03U\x1d\x13\x01\x01\xff\x04\x050\x03\x01\x01\xff0\x0e\x06\x03U\x1d\x0f\x01\x01\xff\x04\x04\x03\x02\x01\x060K\x06\x08+\x06\x01\x05\x05\x07\x01\x01\x04?0=0;\x06\x08+\x06\x01\x05\x05\x070\x02\x86/http://apps.identrust.com/roots/dstrootcax3.p7c0\x1f\x06\x03U\x1d#\x04\x180\x16\x80\x14\xc4\xa7\xb1\xa4{,q\xfa\xdb\xe1K\x90u\xff\xc4\x15\x85\x89\x100T\x06\x03U\x1d \x04M0K0\x08\x06\x06g\x81\x0c\x01\x02\x010?\x06\x0b+\x06\x01\x04\x01\x82\xdf\x13\x01\x01\x01000.\x06\x08+\x06\x01\x05\x05\x07\x02\x01\x16\"http://cps.root-x1.letsencrypt.org0<\x06\x03U\x1d\x1f\x0450301\xa0/\xa0-\x86+http://crl.identrust.com/DSTROOTCAX3CRL.crl0\x1d\x06\x03U\x1d\x0e\x04\x16\x04\x14y\xb4Y\xe6{\xb6\xe5\xe4\x01s\x80\x08\x88\xc8\x1aX\xf6\xe9\x9bn0\r\x06\t*\x86H\x86\xf7\r\x01\x01\x0b\x05\0\x03\x82\x01\x01\0\ns\0l\x96n\xff\x0eR\xd0\xae\xdd\x8c\xe7Z\x06\xad/\xa8\xe3\x8f\xbf\xc9\n\x03\x15P\xc2\xe5lB\xbbo\x9b\xf4\xb4O\xc2D\x88\x08u\xcc\xeb\x07\x9b\x14bnx\xde\xec'\xba9\\\xf5\xa2\xa1nV\x94p\x10S\xb1\xbb\xe4\xaf\xd0\xa2\xc3+\x01\xd4\x96\xf4\xc5 53\xf9\xd8a6\xe0q\x8d\xb4\xb8\xb5\xaa\x82E\x95\xc0\xf2\xa9#(\xe7\xd6\xa1\xcbg\x08\xda\xa0C,\xaa\x1b\x93\x1f\xc9\xde\xf5\xabi]\x13\xf5[\x86X\"\xcaMU\xe4pgm\xc2W\xc5F9A\xcf\x8aX\x83Xm\x99\xfeW\xe86\x0e\xf0\x0e#\xaa\xfd\x88\x97\xd0\xe3\\x0e\x94I\xb5\xb5\x175\xd2.\xbfN\x85\xef\x18\xe0\x85\x92\xeb\x06;l)#\t\xdcE\x02L\x12\x18;\xe9\xfb\x0e\xde\xdcD\xf8X\x98\xae\xea\xbdEE\xa1\x88]f\xca\xfe\x10\xe9o\x82\xc8\x11B\r\xfb\xe9\xec\xe3\x86\0\xde\x9d\x10\xe38\xfa\xa4}\xb1\xd8\xe8I\x82\x84\x06\x9b+\xe8kO\x01\x0c8w.\xf9\xdd\xe79")] 2023-10-17T13:43:21.704066Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: tls_connector connect success 2023-10-17T13:43:21.704087Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: hyper::client::conn: client handshake Http1 2023-10-17T13:43:21.704201Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: sending upgrade request 2023-10-17T13:43:21.704249Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: iroh_net::derp::http::client: waiting for connection 2023-10-17T13:43:21.704389Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn:encode_headers: hyper::proto::h1::role: Client::encode method=GET, body=None 2023-10-17T13:43:21.704434Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::io: flushed 47 bytes 2023-10-17T13:43:21.704446Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: flushed({role=client}): State { reading: Init, writing: KeepAlive, keep_alive: Busy } 2023-10-17T13:43:21.706897Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:dial{proto="ipv4"}: iroh_net::derp::http::client: dial done: Ipv4(Some(34.207.161.128)) 2023-10-17T13:43:21.706933Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: dialed region 2023-10-17T13:43:21.706967Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: Starting TLS handshake 2023-10-17T13:43:21.706989Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::hs: No cached session for DnsName("use1-1.derp.iroh.network.") 2023-10-17T13:43:21.707097Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::hs: Not resuming any session 2023-10-17T13:43:21.707142Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::hs: Sending ClientHello Message { version: TLSv1_0, payload: Handshake { parsed: HandshakeMessagePayload { typ: ClientHello, payload: ClientHello( ClientHelloPayload { client_version: TLSv1_2, random: 61608f128790bdb4e4312f4d973205fa1dc0762352a6fd5c71c01cd60290eb12, session_id: 8f50e5d375936be94cd0e3cd6e6868bc9e3fac0fb930a1cf6aed4f6e43d77c64, cipher_suites: [ TLS13_AES_256_GCM_SHA384, TLS13_AES_128_GCM_SHA256, TLS13_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_CHACHA20_POLY1305_SHA256, TLS_EMPTY_RENEGOTIATION_INFO_SCSV, ], compression_methods: [ Null, ], extensions: [ SupportedVersions( [ TLSv1_3, TLSv1_2, ], ), ECPointFormats( [ Uncompressed, ], ), NamedGroups( [ X25519, secp256r1, secp384r1, ], ), SignatureAlgorithms( [ ECDSA_NISTP384_SHA384, ECDSA_NISTP256_SHA256, ED25519, RSA_PSS_SHA512, RSA_PSS_SHA384, RSA_PSS_SHA256, RSA_PKCS1_SHA512, RSA_PKCS1_SHA384, RSA_PKCS1_SHA256, ], ), ExtendedMasterSecretRequest, CertificateStatusRequest( OCSP( OCSPCertificateStatusRequest { responder_ids: [], extensions: , }, ), ), ServerName( [ ServerName { typ: HostName, payload: HostName( DnsName( "use1-1.derp.iroh.network", ), ), }, ], ), SignedCertificateTimestampRequest, KeyShare( [ KeyShareEntry { group: X25519, payload: 47c63580a9fee320ab40c551b01f960709dc3d533bd07e76fd1b2471e2c7c95f, }, ], ), PresharedKeyModes( [ PSK_DHE_KE, ], ), SessionTicket( Request, ), ], }, ), }, encoded: 010000f6030361608f128790bdb4e4312f4d973205fa1dc0762352a6fd5c71c01cd60290eb12208f50e5d375936be94cd0e3cd6e6868bc9e3fac0fb930a1cf6aed4f6e43d77c640014130213011303c02cc02bcca9c030c02fcca800ff01000099002b00050403040303000b00020100000a00080006001d00170018000d00140012050304030807080608050804060105010401001700000005000501000000000000001d001b000018757365312d312e646572702e69726f682e6e6574776f726b00120000003300260024001d002047c63580a9fee320ab40c551b01f960709dc3d533bd07e76fd1b2471e2c7c95f002d0002010100230000, }, [1/3] Connecting ... ⠒ [00:00:01] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:21.720965Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv6: poll_recv 2023-10-17T13:43:21.720993Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv4: poll_recv 2023-10-17T13:43:21.721016Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::rebinding_conn: [UDP] <- 173.56.221.209:49526 dest: Some(192.168.1.141) (110b) 2023-10-17T13:43:21.721029Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv4: recv 1 msgs 2023-10-17T13:43:21.721048Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: tick: ip_msgs 2023-10-17T13:43:21.721101Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: tick: disco packet: RecvMeta { addr: 173.56.221.209:49526, len: 110, stride: 110, ecn: None, dst_ip: Some(192.168.1.141) } 2023-10-17T13:43:21.721123Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: tick 2023-10-17T13:43:21.721134Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv6: poll_recv 2023-10-17T13:43:21.721142Z TRACE magicsock{me=mefmz7clbwdlzey2}:udp.actor: iroh_net::magicsock::udp_actor: ipv4: poll_recv 2023-10-17T13:43:21.721186Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: tick: ip_receiver 2023-10-17T13:43:21.721232Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:handle_disco_message: iroh_net::magicsock: handle_disco_message start UDP(173.56.221.209:49526) - None 2023-10-17T13:43:21.721293Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:handle_disco_message: iroh_net::magicsock: disco: disco.parse = Ok(Pong(Pong { tx_id: TransactionId(0xAF753DE871329979B514F8F1), src: 86.123.229.197:62394 })) 2023-10-17T13:43:21.721324Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:handle_disco_message: iroh_net::magicsock: got disco message: Pong(Pong { tx_id: TransactionId(0xAF753DE871329979B514F8F1), src: 86.123.229.197:62394 }) 2023-10-17T13:43:21.721374Z INFO magicsock{me=mefmz7clbwdlzey2}:actor:handle_disco_message: iroh_net::magicsock::endpoint: disco: received pong [transaction id (0xAF753DE871329979B514F8F1)] from UDP(173.56.221.209:49526) (is_derp: false) 86.123.229.197:62394 2023-10-17T13:43:21.721427Z INFO magicsock{me=mefmz7clbwdlzey2}:actor:handle_disco_message: iroh_net::magicsock::endpoint: disco: PublicKey(mefmz7clbwdlzey2)<-PublicKey(ahpcrcs6tqh6a7ml) (Udp(173.56.221.209:49526)) got pong tx=%x latency=TransactionId(0xAF753DE871329979B514F8F1) pong.src=13586.123.229.197:62394 2023-10-17T13:43:21.721469Z INFO magicsock{me=mefmz7clbwdlzey2}:actor:handle_disco_message: iroh_net::magicsock::endpoint: disco: node PublicKey(ahpcrcs6tqh6a7ml) now using Udp(173.56.221.209:49526) 2023-10-17T13:43:21.721483Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor:handle_disco_message: iroh_net::magicsock::endpoint: updating best addr trust 173.56.221.209:49526 [1/3] Connecting ... ⠋ [00:00:01] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:21.828105Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::hs: We got ServerHello ServerHelloPayload { legacy_version: TLSv1_2, random: 06b5bb475c832e7410f2c7281279ee073fa01f08dffd64d0fce9372ca076a10e, session_id: 8f50e5d375936be94cd0e3cd6e6868bc9e3fac0fb930a1cf6aed4f6e43d77c64, cipher_suite: TLS13_AES_256_GCM_SHA384, compression_method: Null, extensions: [ KeyShare( KeyShareEntry { group: X25519, payload: 9b63f1debf467e4f44ad501592357e70c6cd495d55893e2aff4c55cd14068c20, }, ), SupportedVersions( TLSv1_3, ), ], } 2023-10-17T13:43:21.828267Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::hs: Using ciphersuite TLS13_AES_256_GCM_SHA384 2023-10-17T13:43:21.828974Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::tls13: Not resuming 2023-10-17T13:43:21.829025Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::client_conn: EarlyData rejected 2023-10-17T13:43:21.831112Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::conn: Dropping CCS 2023-10-17T13:43:21.831231Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::tls13: TLS1.3 encrypted extensions: [ServerNameAck] 2023-10-17T13:43:21.831295Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::hs: ALPN protocol is None 2023-10-17T13:43:21.831420Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: rustls::client::tls13: Server cert is [Certificate(b"0\x82\x0420\x82\x03\x1a\xa0\x03\x02\x01\x02\x02\x12\x03\x0fHl:\x14\xea\x8c\x16\xea\xc6\x05\xe0\x07\xd3\x1fu\xfa0\r\x06\t*\x86H\x86\xf7\r\x01\x01\x0b\x05\0021\x0b0\t\x06\x03U\x04\x06\x13\x02US1\x160\x14\x06\x03U\x04\n\x13\rLet's Encrypt1\x0b0\t\x06\x03U\x04\x03\x13\x02R30\x1e\x17\r231010080310Z\x17\r240108080309Z0#1!0\x1f\x06\x03U\x04\x03\x13\x18use1-1.derp.iroh.network0Y0\x13\x06\x07*\x86H\xce=\x02\x01\x06\x08*\x86H\xce=\x03\x01\x07\x03B\0\x04\xf0+\x89+:\x1b\xbe:\\J3\xee\xfd\xbbH\x175\xed\xe1g\x1b\xa5\xd1a\tm\x97\xfe\xaf\xc8\xc4\xbc\x14\x04O\xad\x93-^y\x06\xfc$\x0b\xfe\x84GAuD\xca\xc8W\xaa\xfeQ\xaf\xd21\xc4\xff{\xaf\xd4\xa3\x82\x02\x1a0\x82\x02\x160\x0e\x06\x03U\x1d\x0f\x01\x01\xff\x04\x04\x03\x02\x07\x800\x1d\x06\x03U\x1d%\x04\x160\x14\x06\x08+\x06\x01\x05\x05\x07\x03\x01\x06\x08+\x06\x01\x05\x05\x07\x03\x020\x0c\x06\x03U\x1d\x13\x01\x01\xff\x04\x020\00\x1d\x06\x03U\x1d\x0e\x04\x16\x04\x14\xbf\xb5\xef5\x82\xe8\xfa\x0boC\xdf\xc22\xae\x99\x10\xfe\xde\xa7L0\x1f\x06\x03U\x1d#\x04\x180\x16\x80\x14\x14.\xb3\x17\xb7XV\xcb\xaeP\t@\xe6\x1f\xaf\x9d\x8b\x14\xc2\xc60U\x06\x08+\x06\x01\x05\x05\x07\x01\x01\x04I0G0!\x06\x08+\x06\x01\x05\x05\x070\x01\x86\x15http://r3.o.lencr.org0\"\x06\x08+\x06\x01\x05\x05\x070\x02\x86\x16http://r3.i.lencr.org/0#\x06\x03U\x1d\x11\x04\x1c0\x1a\x82\x18use1-1.derp.iroh.network0\x13\x06\x03U\x1d \x04\x0c0\n0\x08\x06\x06g\x81\x0c\x01\x02\x010\x82\x01\x04\x06\n+\x06\x01\x04\x01\xd6y\x02\x04\x02\x04\x81\xf5\x04\x81\xf2\0\xf0\0v\0\xda\xb6\xbfk?\xb5\xb6\"\x9f\x9b\xc2\xbb\\k\xe8p\x91ql\xbbQ\x84\x854\xbd\xa4=0H\xd7\xfb\xab\0\0\x01\x8b\x18\xd3iM\0\0\x04\x03\0G0E\x02!\0\x85\xff{R\xfeb\"\n\xba\xaa\xa6\xf7+t\xfd)\xafp\xfc\x02\xfb\x9d\xb20\xae\xd0\x88\xa5\xe2\"1Y\x02 c\x92\xc1\xac\xcd\x87|aC\xbc\xaa\xf8\xe2\xf5'\xac!R\x01\x1f\xc7)\x17\0\x98:n\xf1\xc5\xf6\xfe\0v\0;Swu>-\xb9\x80N\x8b0[\x06\xfe@;g\xd8O\xc3\xf4\xc7\xbd\0\r-ro\xe1\xfa\xd4\x17\0\0\x01\x8b\x18\xd3i@\0\0\x04\x03\0G0E\x02!\0\xc4\nS\xc1\xa4\x15\x17U4\x14kl\xf0\xf9\x96\x87C\xb9\x06\xa1'\xfe\x99mQ\xb4C_\xfeO\x90\xdd\x02 6\x8b\x81\x89]\xc4\x10(\x8fs;F\xe7\x98\xd8\xf0\x0b\x14'kQa\x16\t\x8efc\xad\xec\xf9L\x9b0\r\x06\t\x86H\x86\xf7\r\x01\x01\x0b\x05\0\x03\x82\x01\x01\0\x9f\x83\xb5\x1e\xbfO\xa4\xf8\xa4R\xdc\xe3\xb4\xbb\x90\t\xfcJ\n\xba\xa9#\xaa\x0e{>\xbbBR\xb9Q\xf6<\x93C\x16\xa6tD\xc8\x191\x97\xa7Q4\xcd\x0b\x92\xa5\n\xc4\xe8\xe6\x02A\xc4+N\xccZ3\xa4S\x87\x17\x95\x17\xfa'V\xa1\0\xabL\x9c\xc8\xdf\xb2\xaa\xb9s\xcdT~Xzw#)sF\x98Z\xf4l\xb9\\xa0\xe5>%A\xa7\xfaV\x84EO\xd4\xbd\r\x8d\xe9S\x8a\xfe\xef\r5x\xf1\xc5t\x18\x061\x1b4M\xf5\xa1\xc0\xe6\xf2Y\xab\xb0U\x9e\xfc\xa4[4w\x12\xfbJ\xcb\x7f\xe4\xf3\xd8e\xe9/\xcb(\xd6\x1d6\x9bN\xa1\x99l\xc1N\xcf\x1f\xa4\x8fu~@j\xf0\x1fj5\x19\x1cA\x17\x8a\xca\x1c%\x95\xb8\xca\xb9p\x8f?\xe0\xcd\x08\xd5\x9d+Z0:\xed7\x81G3\xa7\x8bx\xa4\xa2\xe8\xd6\xc7\x03\xdb\xd99\xe5>\xe2\xc3\xe5\xf3B\xf1V\xd6\"\r\xfc\xbf\xca\xe6\xf2K\x19\xd7\xc7\xec\x8d7\x95\x0cU\xf5\xaa\x9d\xb9\xea\x13\xf6"), Certificate(b"0\x82\x05\x160\x82\x02\xfe\xa0\x03\x02\x01\x02\x02\x11\0\x91+\x08J\xcf\x0c\x18\xa7S\xf6\xd6.%\xa7Z0\r\x06\t\x86H\x86\xf7\r\x01\x01\x0b\x05\00O1\x0b0\t\x06\x03U\x04\x06\x13\x02US1)0'\x06\x03U\x04\n\x13 Internet Security Research Group1\x150\x13\x06\x03U\x04\x03\x13\x0cISRG Root X10\x1e\x17\r200904000000Z\x17\r250915160000Z021\x0b0\t\x06\x03U\x04\x06\x13\x02US1\x160\x14\x06\x03U\x04\n\x13\rLet's Encrypt1\x0b0\t\x06\x03U\x04\x03\x13\x02R30\x82\x01\"0\r\x06\t\x86H\x86\xf7\r\x01\x01\x01\x05\0\x03\x82\x01\x0f\00\x82\x01\n\x02\x82\x01\x01\0\xbb\x02\x15(\xcc\xf6\xa0\x94\xd3\x0f\x12\xec\x8dU\x92\xc3\xf8\x82\xf1\x99\xa6zB\x88\xa7]&\xaa\xb5+\xb9\xc5L\xb1\xaf\x8ek\xf9u\xc8\xa3\xd7\x0fG\x94\x14U5W\x8c\x9e\xa8\xa29\x19\xf5\x82<B\xa9Nn\xf5;\xc3.\xdb\x8d\xc0\xb0\\xf3Y8\xe7\xed\xcfi\xf0Z\x0b\x1b\xbe\xc0\x94$%\x87\xfa7q\xb3\x13\xe7\x1c\xac\xe1\x9b\xef\xdb\xe4;ERE\x96\xa9\xc1S\xce4\xc8R\xee\xb5\xae\xed\x8f\xdep\xe2\xa5T\xab\xb6m\x0e\x97\xa5@4k+\xd3\xbcf\xebf4|\xfak\x8b\x8fW)\x99\xf80\x17]\xbaro\xfb\x81\xc5\xad\xd2\x86X=\x17\xc7\xe7\t\xbb\xf1+\xf7\x86\xdc\xc1\xdaq]\xd4F\xe3\xcc\xad%\xc1\x88\xbcguf\xb3\xf1\x18\xf7\xa2\\xe6S\xff:\x88\xb6G\xa5\xff\x13\x18\xea\x98\tw?\x9dS\xf9\xcf\x01\xe5\xf5\xa6p\x17\x14\xafc\xa4\xff\x99\xb3\x93\x9d\xdcS\xa7\x06\xfeH\x85\x1d\xa1i\xae%u\xbb\x13\xccR\x03\xf5\xedQ\xa1\x8b\xdb\x15\x02\x03\x01\0\x01\xa3\x82\x01\x080\x82\x01\x040\x0e\x06\x03U\x1d\x0f\x01\x01\xff\x04\x04\x03\x02\x01\x860\x1d\x06\x03U\x1d%\x04\x160\x14\x06\x08+\x06\x01\x05\x05\x07\x03\x02\x06\x08+\x06\x01\x05\x05\x07\x03\x010\x12\x06\x03U\x1d\x13\x01\x01\xff\x04\x080\x06\x01\x01\xff\x02\x01\00\x1d\x06\x03U\x1d\x0e\x04\x16\x04\x14\x14.\xb3\x17\xb7XV\xcb\xaeP\t@\xe6\x1f\xaf\x9d\x8b\x14\xc2\xc60\x1f\x06\x03U\x1d#\x04\x180\x16\x80\x14y\xb4Y\xe6{\xb6\xe5\xe4\x01s\x80\x08\x88\xc8\x1aX\xf6\xe9\x9bn02\x06\x08+\x06\x01\x05\x05\x07\x01\x01\x04&0$0\"\x06\x08+\x06\x01\x05\x05\x070\x02\x86\x16http://x1.i.lencr.org/0'\x06\x03U\x1d\x1f\x04 0\x1e0\x1c\xa0\x1a\xa0\x18\x86\x16http://x1.c.lencr.org/0\"\x06\x03U\x1d \x04\x1b0\x190\x08\x06\x06g\x81\x0c\x01\x02\x010\r\x06\x0b+\x06\x01\x04\x01\x82\xdf\x13\x01\x01\x010\r\x06\t*\x86H\x86\xf7\r\x01\x01\x0b\x05\0\x03\x82\x02\x01\0\x85\xcaNG>\xa3\xf7\x85D\x85\xbc\xd5gx\xb2\x98c\xaduM\x1e\x96=3erT-\x81\xa0\xea\xc3\xed\xf8 \xbf\xcc\xb7p\0\xb7n;\xf6^\x94\xde\xe4 \x9f\xa6\xef\x8b\xb2\x03\xe7\xa2\xb5\x16<\x91\xce\xb4\xed9\x02\xe7|%\x8aG\xe6en?F\xf4\xd9\xf0\xce\x94+\xeeT\xce\x12\xbc\x8c'K\xb8\xc1\x98/\xa2\xaf\xcdq\x91J\x08\xb7\xc8\xb8#{\x04-\x08\xf9\x08W>\x83\xd9\x043\nG!x\t\x82'\xc3\xc8\x9b\xb9\xce\\xf2d\xc8\xc0\xbey\xc0O\x8emD\x0c^\x92\xbb.\xf7\x8b\x10\xe1\xe8\x1dD)\xdbY \xedc\xb9!\xf8\x12&\x94\x93W\xa0\x1de\x04\xc1\n\"\xae\x10\rC\x97\xa1\x18\x1f~\xe0\xe0\x867\xb5Z\xb1\xbd0\xbf\x87n+\xff!N\x1b\x05\xc3\xf5\x18\x97\xf0^\xac\xc3\xa5\xb8j\xf0.\xbc;3\xb9\xeeK\xde\xcc\xfc\xe4\xaf\x84\x0b\x86?\xc0UC6\xf6h\xe16\x17j\x8e\x99\xd1\xff\xa5@\xa74\xb7\xc0\xd0c959un\xf2\xbav\xc8\x93\x02\xe9\xa9Kl\x17\xce\x0c\x02\xd9\xbd\x81\xfb\x9f\xb7h\xd4\x06e\xb3\x82=wS\xf8\x8ey\x03\xad\n1\x07uC\xd8U\x97r\xc4)\x0e\xf7\xc4]N\xc8\xaeF\x840\xd7\xf2\x85_\x18\xa1y\xbb\xe7^p\x8b\x07\xe1\x86\x93\xc3\xb9\x8f\xdcaq%\xaf\xdf\xed%PRh\x8b\x92\xdc\xe5\xd6\xb5\xe3\xda}\xd0\x87l\x84!1\xae\x82\xf5\xfb\xb9\xab\xc8\x89\x17=\xe1L\xe58\x0e\xf6\xbd+\xbd\x96\x81\x14\xeb\xd5\xdb= \xa7~Y\xd3\xe2\xf8X\xf9[\xb8H\xcd\xfe\O\x16)\xfe\x1eU#\xaf\xc8\x11\xb0\x8d\xea|\x93\x90\x17/\xfd\xac\xa2\tGF?\xf0\xe9\xb0\xb7\xff(Mh2\xd6g^\x1ei\xa3\x93\xb8\xf5\x9d\x8b/\x0b\xd2RC\xa6o2WeM2\x81\xdf8S\x85]~]f)\xea\xb8\xdd\xe4\x95\xb5\xcd\xb5V\x12B\xcd\xc4N\xc6%8DPm\xec\xce\0U\x18\xfe\xe9Id\xd4N\xca\x97\x9c\xb4[\xc0s\xa8\xab\xb8G\xc2"), Certificate(b"0\x82\x050\x82\x04H\xa0\x03\x02\x01\x02\x02\x10@\x01w!7\xd4\xe9B\xb8\xeev\xaa<d\n\xb70\r\x06\t*\x86H\x86\xf7\r\x01\x01\x0b\x05\00?1$0\"\x06\x03U\x04\n\x13\x1bDigital Signature Trust Co.1\x170\x15\x06\x03U\x04\x03\x13\x0eDST Root CA X30\x1e\x17\r210120191403Z\x17\r240930181403Z0O1\x0b0\t\x06\x03U\x04\x06\x13\x02US1)0'\x06\x03U\x04\n\x13 Internet Security Research Group1\x150\x13\x06\x03U\x04\x03\x13\x0cISRG Root X10\x82\x02\"0\r\x06\t*\x86H\x86\xf7\r\x01\x01\x01\x05\0\x03\x82\x02\x0f\00\x82\x02\n\x02\x82\x02\x01\0\xad\xe8$s\xf4\x147\xf3\x9b\x9e+W(\x1c\x87\xbe\xdc\xb7\xdf8\x90\x8cn<\xe6W\xa0x\xf7u\xc2\xa2\xfe\xf5jn\xf6\0O(\xdb\xdeh\x86lD\x93\xb6\xb1c\xfd\x14\x12k\xbf\x1f\xd2\xea1\x9b!~\xd13<\xbaH\xf5\xddy\xdf\xb3\xb8\xff\x12\xf1!\x9aK\xc1\x8a\x86qiJffl\x8f~<p\xbf\xad)\"\x06\xf3\xe4\xc0\xe6\x80\xae\xe2K\x8f\xb7\x99~\x94\x03\x9f\xd3G\x97|\x99H#S\xe88\xaeO\no\x83.\xd1IW\x8c\x80t\xb6\xda/\xd08\x8d{\x03p!\x1bu\xf20<\xfa\x8f\xae\xdd\xdac\xab\xeb\x16O\xc2\x8e\x11K~\xcf\x0b\xe8\xff\xb5w.\xf4\xb2{J\xe0L\x12%\x0cp\x8d\x03)\xa0\xe1S$\xec\x13\xd9\xee\x19\xbf\x10\xb3J\x8c?\x89\xa3aQ\xde\xac\x87\x07\x94\xf4cq\xec.\xe2o[\x98\x81\xe1\x89\\4ylv\xef;\x90by\xe6\xdb\xa4\x9a/&\xc5\xd0\x10\xe1\x0e\xde\xd9\x10\x8e\x16\xfb\xb7\xf7\xa8\xf7\xc7\xe5\x02\x07\x98\x8f6\x08\x95\xe7\xe27\x96\r6u\x9e\xfb\x0er\xb1\x1d\x9b\xbc\x03\xf9I\x05\xd8\x81\xdd\x05\xb4*\xd6A\xe9\xac\x01v\x95\n\x0f\xd8\xdf\xd5\xbd\x12\x1f5/(\x17l\xd2\x98\xc1\xa8\tdwnG7\xba\xce\xacY^h\x9d\x7fr\xd6\x89\xc5\x06A)>Y>\xdd&\xf5$\xc9\x11\xa7Z\xa3L@\x1fF\xa1\x99\xb5\xa7:Qn\x86;\x9e}r\xa7\x12\x05xY\xed>Qx\x15\x0b\x03\x8f\x8d\xd0/\x05\xb2>{J\x1cKs\x05\x12\xfc\xc6\xea\xe0P\x13|C\x93t\xb3\xcat\xe7\x8e\x1f\x01\x08\xd00\xd4[q6\xb4\x07\xba\xc100\\H\xb7\x82;\x98\xa6}\x8a\xa2\xa3)\x82\xcc\xba\xbd\x83\x04\x1b\xa2\x83\x03A\xa1\xd6\x05\xf1\x1b\xc2\xb6\xf0\xa8|\x86;F\xa8H\x88\xdcv\x9av\xbf\x1fj\xa5=\x19\x8f\xeb8\xf3d\xde\xc8+\r\n(\xff\xf7\xdb\xe2\x15B\xd4\"\xd0']\xe1y\xfe\x18\xe7p\x88\xadN\xe6\xd9\x8b:\xc6\xdd'Qn\xff\xbcd\xf53CO\x02\x03\x01\0\x01\xa3\x82\x01F0\x82\x01B0\x0f\x06\x03U\x1d\x13\x01\x01\xff\x04\x050\x03\x01\x01\xff0\x0e\x06\x03U\x1d\x0f\x01\x01\xff\x04\x04\x03\x02\x01\x060K\x06\x08+\x06\x01\x05\x05\x07\x01\x01\x04?0=0;\x06\x08+\x06\x01\x05\x05\x070\x02\x86/http://apps.identrust.com/roots/dstrootcax3.p7c0\x1f\x06\x03U\x1d#\x04\x180\x16\x80\x14\xc4\xa7\xb1\xa4{,q\xfa\xdb\xe1K\x90u\xff\xc4\x15`\x85\x89\x100T\x06\x03U\x1d \x04M0K0\x08\x06\x06g\x81\x0c\x01\x02\x010?\x06\x0b+\x06\x01\x04\x01\x82\xdf\x13\x01\x01\x01000.\x06\x08+\x06\x01\x05\x05\x07\x02\x01\x16\"http://cps.root-x1.letsencrypt.org0<\x06\x03U\x1d\x1f\x0450301\xa0/\xa0-\x86+http://crl.identrust.com/DSTROOTCAX3CRL.crl0\x1d\x06\x03U\x1d\x0e\x04\x16\x04\x14y\xb4Y\xe6{\xb6\xe5\xe4\x01s\x80\x08\x88\xc8\x1aX\xf6\xe9\x9bn0\r\x06\t\x86H\x86\xf7\r\x01\x01\x0b\x05\0\x03\x82\x01\x01\0\ns\0l\x96n\xff\x0eR\xd0\xae\xdd\x8c\xe7Z\x06\xad/\xa8\xe3\x8f\xbf\xc9\n\x03\x15P\xc2\xe5lB\xbbo\x9b\xf4\xb4O\xc2D\x88\x08u\xcc\xeb\x07\x9b\x14bnx\xde\xec'\xba9\\xf5\xa2\xa1nV\x94p\x10S\xb1\xbb\xe4\xaf\xd0\xa2\xc3+\x01\xd4\x96\xf4\xc5 53\xf9\xd8a6\xe0q\x8d\xb4\xb8\xb5\xaa\x82E\x95\xc0\xf2\xa9#(\xe7\xd6\xa1\xcbg\x08\xda\xa0C,\xaa\x1b\x93\x1f\xc9\xde\xf5\xabi]\x13\xf5[\x86X\"\xcaMU\xe4pgm\xc2W\xc5F9A\xcf\x8aX\x83Xm\x99\xfeW\xe86\x0e\xf0\x0e#\xaa\xfd\x88\x97\xd0\xe3\\x0e\x94I\xb5\xb5\x175\xd2.\xbfN\x85\xef\x18\xe0\x85\x92\xeb\x06;l)#\t`\xdcE\x02L\x12\x18;\xe9\xfb\x0e\xde\xdcD\xf8X\x98\xae\xea\xbdEE\xa1\x88]f\xca\xfe\x10\xe9o\x82\xc8\x11B\r\xfb\xe9\xec\xe3\x86\0\xde\x9d\x10\xe38\xfa\xa4}\xb1\xd8\xe8I\x82\x84\x06\x9b+\xe8kO\x01\x0c8w.\xf9\xdd\xe79")]
2023-10-17T13:43:21.833301Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: tls_connector connect success 2023-10-17T13:43:21.833338Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: hyper::client::conn: client handshake Http1 2023-10-17T13:43:21.833393Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: sending upgrade request 2023-10-17T13:43:21.833444Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: iroh_net::derp::http::client: waiting for connection 2023-10-17T13:43:21.833497Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn:encode_headers: hyper::proto::h1::role: Client::encode method=GET, body=None 2023-10-17T13:43:21.833551Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::io: flushed 47 bytes 2023-10-17T13:43:21.833568Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: flushed({role=client}): State { reading: Init, writing: KeepAlive, keep_alive: Busy } 2023-10-17T13:43:21.861767Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: Conn::read_head [1/3] Connecting ... ⠉ [00:00:01] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:21.917207Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: Conn::read_head 2023-10-17T13:43:21.917303Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::io: received 359 bytes 2023-10-17T13:43:21.917337Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn:parse_headers: hyper::proto::h1::role: Response.parse bytes=359 2023-10-17T13:43:21.917360Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn:parse_headers: hyper::proto::h1::role: Response.parse Complete(314) 2023-10-17T13:43:21.917409Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::io: parsed 4 headers 2023-10-17T13:43:21.917420Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: incoming body is empty 2023-10-17T13:43:21.917482Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: remote disabling keep-alive 2023-10-17T13:43:21.917498Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: try_keep_alive({role=client}): could keep-alive, but status = Disabled 2023-10-17T13:43:21.917510Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: State::close() 2023-10-17T13:43:21.917520Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: {role=client}: prepare possible HTTP upgrade 2023-10-17T13:43:21.917651Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: flushed({role=client}): State { reading: Closed, writing: Closed, keep_alive: Disabled } 2023-10-17T13:43:21.917679Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::upgrade: pending upgrade fulfill 2023-10-17T13:43:21.917691Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: iroh_net::derp::http::client: connection done 2023-10-17T13:43:21.917755Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: starting upgrade 2023-10-17T13:43:21.917833Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: connection upgraded 2023-10-17T13:43:21.917893Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::client: server_handshake: started 2023-10-17T13:43:21.917932Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: attempting to decode a frame 2023-10-17T13:43:21.917997Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: frame decoded from buffer 2023-10-17T13:43:21.918009Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::client: server_handshake: received server_key: PublicKey(lfjwr7decczvfu27) 2023-10-17T13:43:21.918029Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::client: server_handshake: sending client_key: ClientInfo { version: 2, mesh_key: None, can_ack_pings: true, is_prober: false } 2023-10-17T13:43:21.918408Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: flushing framed transport 2023-10-17T13:43:21.918420Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: writing; remaining=81 2023-10-17T13:43:21.918496Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: framed transport flushed 2023-10-17T13:43:21.918508Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: flushing framed transport 2023-10-17T13:43:21.918516Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: framed transport flushed [1/3] Connecting ... ⠈ [00:00:01] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:21.975254Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: attempting to decode a frame 2023-10-17T13:43:21.975295Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: frame decoded from buffer 2023-10-17T13:43:21.975355Z DEBUG client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::client: server_handshake: done 2023-10-17T13:43:21.975424Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: connect_0 done 2023-10-17T13:43:21.975451Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}: iroh_net::derp::http::client: got connection, conn num 1 2023-10-17T13:43:21.975585Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: flushing framed transport 2023-10-17T13:43:21.975598Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: writing; remaining=6 2023-10-17T13:43:21.975667Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: framed transport flushed 2023-10-17T13:43:21.975680Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: flushing framed transport [1/3] Connecting ... ⠁ [00:00:01] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:22.115576Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: Conn::read_head 2023-10-17T13:43:22.116016Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: flushed({role=client}): State { reading: Init, wri[1/3] Connecting ... ⠙ [00:00:01] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:22.235575Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: Conn::read_head 2023-10-17T13:43:22.235789Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::io: received 359 bytes 2023-10-17T13:43:22.236071Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn:parse_headers: hyper::proto::h1::role: Response.parse bytes=359 2023-10-17T13:43:22.236500Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn:parse_headers: hyper::proto::h1::role: Response.parse Complete(314) 2023-10-17T13:43:22.236648Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::io: parsed 4 headers 2023-10-17T13:43:22.236860Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: incoming body is empty 2023-10-17T13:43:22.236939Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: remote disabling keep-alive 2023-10-17T13:43:22.236991Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: try_keep_alive({role=client}): could keep-alive, but status = Disabled 2023-10-17T13:43:22.237039Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: State::close() 2023-10-17T13:43:22.237512Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: {role=client}: prepare possible HTTP upgrade 2023-10-17T13:43:22.237611Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::proto::h1::conn: flushed({role=client}): State { reading: Closed, writing: Closed, keep_alive: Disabled } 2023-10-17T13:43:22.237973Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: hyper::upgrade: pending upgrade fulfill 2023-10-17T13:43:22.238044Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:http.conn: iroh_net::derp::http::client: connection done 2023-10-17T13:43:22.238314Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: starting upgrade 2023-10-17T13:43:22.238490Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: connection upgraded 2023-10-17T13:43:22.238526Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::client: server_handshake: started 2023-10-17T13:43:22.238572Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: attempting to decode a frame 2023-10-17T13:43:22.238646Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: frame decoded from buffer 2023-10-17T13:43:22.238664Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::client: server_handshake: received server_key: PublicKey(wlqhl3k7gj52zprt) 2023-10-17T13:43:22.238690Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::client: server_handshake: sending client_key: ClientInfo { version: 2, mesh_key: None, can_ack_pings: true, is_prober: false } 2023-10-17T13:43:22.239160Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: flushing framed transport 2023-10-17T13:43:22.239180Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: writing; remaining=81 2023-10-17T13:43:22.239258Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: framed transport flushed 2023-10-17T13:43:22.239275Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: flushing framed transport 2023-10-17T13:43:22.239289Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: framed transport flushed [1/3] Connecting ... ⠂ [00:00:01] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:22.359717Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: attempting to decode a frame 2023-10-17T13:43:22.359867Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: tokio_util::codec::framed_impl: frame decoded from buffer 2023-10-17T13:43:22.360170Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::client: server_handshake: done 2023-10-17T13:43:22.360742Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0: iroh_net::derp::http::client: connect_0 done 2023-10-17T13:43:22.360991Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}: iroh_net::derp::http::client: got connection, conn num 1 2023-10-17T13:43:22.361212Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::derp::client: [DERP] send dstkey=ahpcrcs6tqh6a7mlw5hxkwdnts4gz6tevhxiw2jkphcigwzmu3aq len=126 2023-10-17T13:43:22.362660Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: flushing framed transport 2023-10-17T13:43:22.362782Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: writing; remaining=163 2023-10-17T13:43:22.363695Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::magicsock::derp_actor: sending derp region_id=1 peer=PublicKey(ahpcrcs6tqh6a7ml) 2023-10-17T13:43:22.364102Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::magicsock::derp_actor: [DERP] -> 1 (1200b) PublicKey(ahpcrcs6tqh6a7ml) 2023-10-17T13:43:22.364210Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::derp::http::client: send 2023-10-17T13:43:22.363376Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: framed transport flushed 2023-10-17T13:43:22.364361Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: flushing framed transport 2023-10-17T13:43:22.364461Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: framed transport flushed 2023-10-17T13:43:22.364419Z TRACE client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}: iroh_net::derp::http::client: already had connection 2023-10-17T13:43:22.364652Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}: iroh_net::derp::http::client: already had connection 2023-10-17T13:43:22.364820Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::derp::client: [DERP] send dstkey=ahpcrcs6tqh6a7mlw5hxkwdnts4gz6tevhxiw2jkphcigwzmu3aq len=1202 2023-10-17T13:43:22.364918Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::magicsock::derp_actor: sending derp region_id=1 peer=PublicKey(ahpcrcs6tqh6a7ml) 2023-10-17T13:43:22.364976Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::magicsock::derp_actor: [DERP] -> 1 (134b) PublicKey(ahpcrcs6tqh6a7ml) 2023-10-17T13:43:22.365081Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::derp::http::client: send 2023-10-17T13:43:22.366141Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}: iroh_net::derp::http::client: already had connection 2023-10-17T13:43:22.366209Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::derp::client: [DERP] send dstkey=ahpcrcs6tqh6a7mlw5hxkwdnts4gz6tevhxiw2jkphcigwzmu3aq len=136 2023-10-17T13:43:22.366603Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: iroh_net::derp::http::client: recv_detail tick 2023-10-17T13:43:22.366957Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}: iroh_net::derp::http::client: already had connection 2023-10-17T13:43:22.367140Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: tokio_util::codec::framed_impl: attempting to decode a frame 2023-10-17T13:43:22.367257Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: iroh_net::derp::http::client: recv_detail tick 2023-10-17T13:43:22.367513Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}: iroh_net::derp::http::client: already had connection 2023-10-17T13:43:22.367583Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: tokio_util::codec::framed_impl: attempting to decode a frame 2023-10-17T13:43:22.367686Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: flushing framed transport 2023-10-17T13:43:22.367741Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: writing; remaining=1239 2023-10-17T13:43:22.367876Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: framed transport flushed 2023-10-17T13:43:22.367971Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: flushing framed transport 2023-10-17T13:43:22.368001Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: framed transport flushed 2023-10-17T13:43:22.368034Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: flushing framed transport 2023-10-17T13:43:22.368120Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: writing; remaining=173 2023-10-17T13:43:22.368221Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: framed transport flushed 2023-10-17T13:43:22.368238Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}:connect_0:client.writer: tokio_util::codec::framed_impl: flushing framed transport [1/3] Connecting ... ⠒ [00:00:01] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:22.497540Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: tokio_util::codec::framed_impl: attempting to decode a frame 2023-10-17T13:43:22.498121Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: tokio_util::codec::framed_impl: frame decoded from buffer 2023-10-17T13:43:22.498360Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: iroh_net::derp::http::client: [DERP] <- region 1 (default-1) (ReceivedPacket { source: PublicKey(ahpcrcs6tqh6a7ml), .. }) 2023-10-17T13:43:22.498512Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::magicsock::derp_actor: derp.recv received region_id=1 msg=Ok((ReceivedPacket { source: PublicKey(ahpcrcs6tqh6a7ml), .. }, 1)) 2023-10-17T13:43:22.498700Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::magicsock::derp_actor: [DERP] <- 1 (112b) 2023-10-17T13:43:22.498995Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::magicsock::derp_actor: tick: recvs: Yield(DerpReadResult { region_id: 1, src: PublicKey(ahpcrcs6tqh6a7ml), .. }), AddPeerRoutes { peers: [PublicKey(ahpcrcs6tqh6a7ml)], region: 1, derp_client: Client {} } 2023-10-17T13:43:22.500359Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: iroh_net::derp::http::client: recv_detail tick 2023-10-17T13:43:22.500815Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}: iroh_net::derp::http::client: already had connection 2023-10-17T13:43:22.501105Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: iroh_net::derp::http::client: recv_detail tick 2023-10-17T13:43:22.501355Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}: iroh_net::derp::http::client: already had connection 2023-10-17T13:43:22.501462Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: tokio_util::codec::framed_impl: attempting to decode a frame 2023-10-17T13:43:22.501723Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: tick: msg msg=ReceiveDerp(DerpReadResult { region_id: 1, src: PublicKey(ahpcrcs6tqh6a7ml), .. }) 2023-10-17T13:43:22.501790Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: process_derp_read 112 bytes 2023-10-17T13:43:22.502184Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:handle_disco_message: iroh_net::magicsock: handle_disco_message start Derp(1) - Some(PublicKey(ahpcrcs6tqh6a7ml)) 2023-10-17T13:43:22.502349Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:handle_disco_message: iroh_net::magicsock: disco: disco.parse = Ok(Pong(Pong { tx_id: TransactionId(0xE3E8BB2FCCA957D61ADC4B6E), src: 127.3.3.40:1 })) 2023-10-17T13:43:22.502401Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:handle_disco_message: iroh_net::magicsock: got disco message: Pong(Pong { tx_id: TransactionId(0xE3E8BB2FCCA957D61ADC4B6E), src: 127.3.3.40:1 }) 2023-10-17T13:43:22.502791Z INFO magicsock{me=mefmz7clbwdlzey2}:actor:handle_disco_message: iroh_net::magicsock::endpoint: disco: received pong [transaction id (0xE3E8BB2FCCA957D61ADC4B6E)] from Derp(1) (is_derp: true) 127.3.3.40:1 2023-10-17T13:43:22.502927Z INFO magicsock{me=mefmz7clbwdlzey2}:actor:handle_disco_message: iroh_net::magicsock::endpoint: disco: PublicKey(mefmz7clbwdlzey2)<-PublicKey(ahpcrcs6tqh6a7ml) (Derp(1)) got pong tx=%x latency=TransactionId(0xE3E8BB2FCCA957D61ADC4B6E) pong.src=917127.3.3.40:1 [1/3] Connecting ... ⠴ [00:00:02] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:22.584846Z TRACE drive{id=0}: quinn_proto::connection: timeout timer=LossDetection 2023-10-17T13:43:22.584882Z TRACE drive{id=0}: quinn_proto::connection: PTO fired in_flight=1200 count=0 space=Initial 2023-10-17T13:43:22.585004Z TRACE drive{id=0}:send{space=Initial pn=1}: quinn_proto::connection: CRYPTO: off 0 len 270 2023-10-17T13:43:22.585026Z TRACE drive{id=0}:send{space=Initial pn=1}: quinn_proto::connection::packet_builder: PADDING 871 2023-10-17T13:43:22.585066Z TRACE drive{id=0}: quinn_proto::connection: sending 1200 bytes in 1 datagrams 2023-10-17T13:43:22.585095Z TRACE drive{id=0}:send{space=Initial pn=2}: quinn_proto::connection: CRYPTO: off 0 len 270 2023-10-17T13:43:22.585109Z TRACE drive{id=0}:send{space=Initial pn=2}: quinn_proto::connection::packet_builder: PADDING 871 2023-10-17T13:43:22.585131Z TRACE drive{id=0}: quinn_proto::connection: sending 1200 bytes in 1 datagrams 2023-10-17T13:43:22.585227Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: tick: network send 2023-10-17T13:43:22.585249Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: sending: dest: QuicMappedAddr([fd15:70a:510b::]:12345), src: None, content_len: 1200 dest: QuicMappedAddr([fd15:70a:510b::]:12345), src: None, content_len: 1200

2023-10-17T13:43:22.585273Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: Sending to endpoint for QuicMappedAddr([fd15:70a:510b::]:12345) (PublicKey(ahpcrcs6tqh6a7ml)) 2023-10-17T13:43:22.585297Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock::endpoint: is_best_addr_valid: best_addr: Some(AddrLatency { addr: 173.56.221.209:49526, latency: Some(135.596083ms) }), trust_best: Some(Instant { tv_sec: 68966, tv_nsec: 977744458 }) 2023-10-17T13:43:22.585314Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock::endpoint: sending UDP: Some(173.56.221.209:49526), DERP: None, #pings: 0 2023-10-17T13:43:22.585333Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor:send_raw: iroh_net::magicsock: send_raw: 2 packets 2023-10-17T13:43:22.585403Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor:send_raw: iroh_net::magicsock::rebinding_conn: [UDP] -> 173.56.221.209:49526 src: None (1200b) 2023-10-17T13:43:22.585418Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor:send_raw: iroh_net::magicsock::rebinding_conn: [UDP] -> 173.56.221.209:49526 src: None (1200b) [1/3] Connecting ... ⠤ [00:00:02] [--------------------------------------------------] 0 B/0 B (0.0s)2023-10-17T13:43:22.616946Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: tokio_util::codec::framed_impl: attempting to decode a frame 2023-10-17T13:43:22.617055Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: tokio_util::codec::framed_impl: frame decoded from buffer 2023-10-17T13:43:22.617103Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: iroh_net::derp::http::client: [DERP] <- region 1 (default-1) (ReceivedPacket { source: PublicKey(ahpcrcs6tqh6a7ml), .. }) 2023-10-17T13:43:22.617171Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::magicsock::derp_actor: derp.recv received region_id=1 msg=Ok((ReceivedPacket { source: PublicKey(ahpcrcs6tqh6a7ml), .. }, 1)) 2023-10-17T13:43:22.617239Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::magicsock::derp_actor: [DERP] <- 1 (89b) 2023-10-17T13:43:22.617286Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor: iroh_net::magicsock::derp_actor: tick: recvs: Yield(DerpReadResult { region_id: 1, src: PublicKey(ahpcrcs6tqh6a7ml), .. }), None 2023-10-17T13:43:22.617356Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: iroh_net::derp::http::client: recv_detail tick 2023-10-17T13:43:22.617412Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}: iroh_net::derp::http::client: already had connection 2023-10-17T13:43:22.617473Z DEBUG magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: iroh_net::derp::http::client: recv_detail tick 2023-10-17T13:43:22.617514Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail:client-connect{key=mefmz7clbwdlzey2i6euvzt5ymsaaggnypoez5k6etfvnugye42q}: iroh_net::derp::http::client: already had connection 2023-10-17T13:43:22.617536Z TRACE magicsock{me=mefmz7clbwdlzey2}:derp.actor:recv_detail: tokio_util::codec::framed_impl: attempting to decode a frame 2023-10-17T13:43:22.617574Z TRACE magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: tick: msg msg=ReceiveDerp(DerpReadResult { region_id: 1, src: PublicKey(ahpcrcs6tqh6a7ml), .. }) 2023-10-17T13:43:22.617596Z DEBUG magicsock{me=mefmz7clbwdlzey2}:actor: iroh_net::magicsock: process_derp_read 89 bytes 2023-10-17T13:43:22.617733Z TRACE poll_recv{me=mefmz7clbwdlzey2}: iroh_net::magicsock: [QUINN] <- [fd15:70a:510b::]:12345 (87b) (mefmz7clbwdlzey2) (Some(::), Derp) 2023-10-17T13:43:22.617749Z TRACE poll_recv{me=mefmz7clbwdlzey2}: iroh_net::magicsock: received 1 datagrams 2023-10-17T13:43:22.617863Z TRACE drive{id=0}: quinn_proto::connection: got Initial packet (87 bytes) from [fd15:70a:510b::]:12345 using id 71e49e05f79a6803 2023-10-17T13:43:22.617939Z TRACE drive{id=0}:recv{space=Initial pn=0}: quinn_proto::connection: switching remote CID to e1e0bcce7a74aa63 2023-10-17T13:43:22.618690Z TRACE mio::poll: deregistering event source from poller
2023-10-17T13:43:22.618730Z TRACE mio::poll: deregistering event source from poller
2023-10-17T13:43:22.618732Z TRACE mio::poll: deregistering event source from poller
2023-10-17T13:43:22.618756Z DEBUG iroh_net::util: portmap_service completed 2023-10-17T13:43:22.618780Z DEBUG iroh_net::util: netcheck actor completed 2023-10-17T13:43:22.618837Z TRACE mio::poll: deregistering event source from poller
2023-10-17T13:43:22.618893Z TRACE mio::poll: deregistering event source from poller
Error: failed to connect to provider

Caused by: 0: failed connecting to provider 1: aborted by peer: the cryptographic handshake failed: error 120: peer doesn't support any known protocol