celzero / firestack

Userspace wireguard and network monitor
https://rethinkdns.com/app
Mozilla Public License 2.0
96 stars 16 forks source link

desync: no network connectivity #81

Open hussainmohd-a opened 2 months ago

hussainmohd-a commented 2 months ago
logs logs: ```jsx I bootstrap.go:140: I dns: default: DNS reinit rethink w/ 8.8.4.4,2001:4860:4860::8844 I bootstrap.go:68: I dns: default: DNS new 8.8.4.4,2001:4860:4860::8844 rethink I natpt.go:51: I natpt: new; mode(&{{{} 1} {{} 0} {{} 2}}) I proxies.go:157: I proxy: adding reserved proxy: Exit I proxies.go:157: I proxy: adding reserved proxy: Base I proxies.go:157: I proxy: adding reserved proxy: Block I proxies.go:148: I proxy: new I transport.go:910: I dnsx: bootstrap! rethink:53 -> [8.8.4.4 2001:4860:4860::8844] I ipmap.go:238: D ipmap: renew: rethink:53 / seed: [8.8.4.4 2001:4860:4860::8844] / typ: Protected I ipmap.go:264: D ipmap: makeIPSet: rethink, seed: [8.8.4.4 2001:4860:4860::8844], typ: Protected I upstream.go:101: I dns53: (Bootstrap) pre-resolved rethink:53 to 8.8.4.4,2001:4860:4860::8844; ok? true I upstream.go:111: I dns53: (Bootstrap) setup: rethink:53; pre-ips? true; relay? false I bootstrap.go:186: I dns: default: start; DNS with rethink[d.rethink.rethink:53]; ok? true I alg.go:141: I alg: setup done I cacher.go:124: I cache: (CacheDefault) setup: cached.d.rethink.rethink:53; opts: ttl=10m0s;bumps=10;size=256 I transport.go:183: I dns: new! gw? true; default? d.rethink.rethink:53 I goos.go:63: I dns53: goosr: setup done I cacher.go:124: I cache: (CacheGoos) setup: cached.localhost:53; opts: ttl=10m0s;bumps=10;size=256 I transport.go:257: I dns: add transport Goos@localhost:53; cache? true I grounded.go:35: I grounded(BlockAll) setup: 127.0.0.3:53 I cacher.go:124: I cache: (CacheBlockAll) setup: cached.127.0.0.3:53; opts: ttl=10m0s;bumps=10;size=256 I transport.go:257: I dns: add transport BlockAll@127.0.0.3:53; cache? true I servers.go:160: D dnscrypt: refreshing certificates I cacher.go:124: I cache: (CacheDcProxy) setup: cached.; opts: ttl=10m0s;bumps=10;size=256 I transport.go:257: I dns: add transport DcProxy@; cache? true I mdns.go:67: I mdns: setup: 46 I cacher.go:124: I cache: (Cachemdns) setup: cached.224.0.0.251:5353; opts: ttl=10m0s;bumps=10;size=256 I transport.go:257: I dns: add transport mdns@224.0.0.251:5353; cache? true I ips.go:122: I dialers: ips: mapper ok? true I multiserver.go:459: I dnscrypt: refreshRoutes: remove all relays I ipmap.go:135: I ipmap: new resolver; ok? true I servers.go:106: V dnscrypt: getAll: servers [0/0] I multiserver.go:471: I dnscrypt: refreshRoutes: 0/0 for 0 servers I dns64.go:280: I dns64: ipv4only.arpa w underlying network resolver I dns64.go:313: D dns64: id(__local464); add? nat64 ip(64:ff9b:1:fffe::c000:aa / 16) E dns64.go:83: W dns64: err reg underlay(address ipv4only.arpa.: no suitable address found) / local() I dns64.go:382: I dns64: add ipnet [64:ff9b:1:fffe::/96] for server(__local464) I ips.go:168: I dialers: ips: protos set to 46; diff? true I tcp.go:108: I tcp: new handler created I udp.go:123: I udp: new handler created I icmp.go:67: I icmp: new handler created I netstack.go:76: I netstack: pcap stdout(false): done?(false) I netstack.go:246: I netstack: new stack4 and stack6 E fdbased.go:281: W ns: tun(-1 => 99): Swap: no dispatcher? true for new fd; err I fdbased.go:266: D ns: swapping tun... fd: -1 => 99, mtu: 1500 I forwarders.go:228: D ns: tun(99): forwarder: starting 6 procs 1330029298 I dispatchers.go:174: I ns: dispatch: newReadVDispatcher: tun(99) I netstack.go:42: I netstack: new endpoint(fd:99 / mtu:1500); err? I netstack.go:218: I netstack: route(ask:46; set: 46); done I netstack.go:100: I netstack: new nic? true; remove nic? err(unknown nic id) I netstack.go:143: D netstack: transport instances: icmp4/6? true/true, tcp/udp true/true I fdbased.go:295: D ns: attaching nic... true I fdbased.go:320: I ns: tun(99): attach: attach new dispatcher I fdbased.go:490: I ns: tun(99): dispatchLoop: start I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(76 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 1, 34525 | :36608 => :24914 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(48 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 34525 | :34048 => :64934 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(76 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 1, 34525 | :36608 => :24914 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(96 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 34525 | :36608 => :24375 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I netstack.go:169: I netstack: up(1)! new? true; routes? [0.0.0.0/0 nic 1 ::/0 nic 1] I tunnel.go:302: I tun: new netstack up; fd(100), mtu(1500) I tunnel.go:147: I tun: <<< new >>>; ok I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I tun2socks.go:82: I tun: new lvl: 0, clvl: 0 I netstack.go:218: I netstack: route(ask:46; set: 46); done I tunnel.go:371: I tun: new route; (no-op) got 46 but set 46 I netstack.go:76: I netstack: pcap stdout(false): done?(false) I netstack.go:86: I netstack: pcap sink?(false); done?(false) I tunnel.go:322: I netstack: pcap closed (ignored-err? ) I dispatchers.go:283: VV ns: tun(99): dispatch: got(96 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 34525 | :36608 => :24375 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I dispatchers.go:283: VV ns: tun(99): dispatch: got(62 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :37179 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I alg.go:800: D alg: dns64: algip(10.111.222.3) -> realips([]) -> unnated([]) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=10.111.222.1:37179 dst=10.111.222.3:53 I transport.go:420: V dns: fwd: query mtalk.google.com [prefs:&{Base CachePreferred false}; chosen:[]]; id? CachePreferred, sid? , pid? Base, ips? [] I transport.go:453: V dns: fwd: query NOT blocked mtalk.google.com; why? block free transport I cacher.go:414: D cache: miss(k: mtalk.google.com:28): cached? false, hangover? false, stale? true I bootstrap.go:202: V dns: default: udp:Base query? true I upstream.go:137: V dns53: pxdial: (Bootstrap) using udp relay/proxy Base at 127.8.4.5:3690 I rdial.go:210: D rdial: commondial: dialing (host:port) rethink:53 I ipmap.go:195: D ipmap: Get: rethink => [8.8.4.4 2001:4860:4860::8844] I rdial.go:266: D rdial: commondial: trying all ips 2 [2001:4860:4860::8844 8.8.4.4] for rethink:53, failingopen? false I wall.go:114: V wall: no local blockerQ; letting through mtalk.google.com. I protect.go:67: D control: netbinder: Base: udp6([2001:4860:4860::8844]:53); err? I rdial.go:276: V rdial: commondial: dialing ip 2001:4860:4860::8844 for rethink:53 I rdial.go:278: I rdial: commondial: ip 2001:4860:4860::8844 works for rethink:53 I rdial.go:234: D rdial: duration: 1.198438ms; failed rethink:53; confirmed? invalid IP, sz: 2 I base.go:64: I proxy: base: dial(udp) to rethink:53; err? I upstream.go:195: V dns53: send: (udp / Bootstrap) to rethink:53 for mtalk.google.com. using udp? true / px? true / relay? false; err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(82 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 34525 | :39360 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I natpt.go:116: D natpt: no matching prefix64 for ip(2001:4860:4860::8844) in id(__local464/1) I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(2001:4860:4860::8844); ip not ok I alg.go:800: D alg: dns64: algip(2001:4860:4860::8844) -> realips([]) -> unnated([]) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:39360 dst=[2001:4860:4860::8844]:53 I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] I rdial.go:210: D rdial: commondial: dialing (host:port) [2001:4860:4860::8844]:53 E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: [2001:4860:4860::8844]:53 I ipmap.go:195: D ipmap: Get: 2001:4860:4860::8844 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2001:4860:4860::8844 for [2001:4860:4860::8844]:53 I protect.go:67: D control: netbinder: Exit: udp6([2001:4860:4860::8844]:53); err? I rdial.go:245: D rdial: commondial: confirmed ip 2001:4860:4860::8844 for [2001:4860:4860::8844]:53 failed with err dial udp [2001:4860:4860::8844]:53: connect: network is unreachable I rdial.go:234: D rdial: duration: 2.368854ms; failed [2001:4860:4860::8844]:53; confirmed? 2001:4860:4860::8844, sz: 1 I exit.go:53: I proxy: exit: dial(udp) to [2001:4860:4860::8844]:53; err? dial udp [2001:4860:4860::8844]:53: connect: network is unreachable E udp.go:360: W udp: connect: #0: 5f458dcc5698c565 failed; addr([2001:4860:4860::8844]:53); for uid rethink (2s) w err(dial udp [2001:4860:4860::8844]:53: connect: network is unreachable) E udp.go:256: W udp: proxy: unexpected [fd66:f83a:c650::1]:39360 -> [2001:4860:4860::8844]:53; err: dial udp [2001:4860:4860::8844]:53: connect: network is unreachable I common.go:88: VV intra: queueSummary: over 40067fe2a0 4006cf7c20 5f458dcc5698c565 I common.go:135: VV intra: end? sendNotif: socket-summary: id=5f458dcc5698c565 pid=Exit uid=rethink down=0 up=0 dur=0 synack=0 msg=dial udp [2001:4860:4860::8844]:53: connect: network is unreachable I dispatchers.go:283: VV ns: tun(99): dispatch: got(96 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 34525 | :36608 => :24375 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I dispatchers.go:283: VV ns: tun(99): dispatch: got(68 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 4, 2048 | :16191 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I alg.go:800: D alg: dns64: algip(10.111.222.3) -> realips([]) -> unnated([]) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=10.111.222.1:16191 dst=10.111.222.3:53 I transport.go:420: V dns: fwd: query mqtt-mini.facebook.com [prefs:&{Base CachePreferred false}; chosen:[]]; id? CachePreferred, sid? , pid? Base, ips? [] I transport.go:453: V dns: fwd: query NOT blocked mqtt-mini.facebook.com; why? block free transport I cacher.go:414: D cache: miss(k: mqtt-mini.facebook.com:28): cached? false, hangover? false, stale? true I bootstrap.go:202: V dns: default: udp:Base query? true I upstream.go:137: V dns53: pxdial: (Bootstrap) using udp relay/proxy Base at 127.8.4.5:3690 I rdial.go:210: D rdial: commondial: dialing (host:port) rethink:53 I ipmap.go:195: D ipmap: Get: rethink => [8.8.4.4 2001:4860:4860::8844] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2001:4860:4860::8844 for rethink:53 I wall.go:114: V wall: no local blockerQ; letting through mqtt-mini.facebook.com. I protect.go:67: D control: netbinder: Base: udp6([2001:4860:4860::8844]:53); err? I rdial.go:240: V rdial: commondial: ip 2001:4860:4860::8844 works for rethink:53 I rdial.go:234: D rdial: duration: 752.031µs; failed rethink:53; confirmed? 2001:4860:4860::8844, sz: 2 I base.go:64: I proxy: base: dial(udp) to rethink:53; err? I upstream.go:195: V dns53: send: (udp / Bootstrap) to rethink:53 for mqtt-mini.facebook.com. using udp? true / px? true / relay? false; err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(88 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 34525 | :54668 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I natpt.go:116: D natpt: no matching prefix64 for ip(2001:4860:4860::8844) in id(__local464/1) I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(2001:4860:4860::8844); ip not ok I alg.go:800: D alg: dns64: algip(2001:4860:4860::8844) -> realips([]) -> unnated([]) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:54668 dst=[2001:4860:4860::8844]:53 E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: [2001:4860:4860::8844]:53 I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] I rdial.go:210: D rdial: commondial: dialing (host:port) [2001:4860:4860::8844]:53 I ipmap.go:195: D ipmap: Get: 2001:4860:4860::8844 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2001:4860:4860::8844 for [2001:4860:4860::8844]:53 I protect.go:67: D control: netbinder: Exit: udp6([2001:4860:4860::8844]:53); err? I rdial.go:245: D rdial: commondial: confirmed ip 2001:4860:4860::8844 for [2001:4860:4860::8844]:53 failed with err dial udp [2001:4860:4860::8844]:53: connect: network is unreachable I rdial.go:234: D rdial: duration: 1.770885ms; failed [2001:4860:4860::8844]:53; confirmed? 2001:4860:4860::8844, sz: 1 I exit.go:53: I proxy: exit: dial(udp) to [2001:4860:4860::8844]:53; err? dial udp [2001:4860:4860::8844]:53: connect: network is unreachable E udp.go:360: W udp: connect: #0: 82724e0d94633936 failed; addr([2001:4860:4860::8844]:53); for uid rethink (2s) w err(dial udp [2001:4860:4860::8844]:53: connect: network is unreachable) E udp.go:256: W udp: proxy: unexpected [fd66:f83a:c650::1]:54668 -> [2001:4860:4860::8844]:53; err: dial udp [2001:4860:4860::8844]:53: connect: network is unreachable I common.go:88: VV intra: queueSummary: over 40067fe2a0 4006cf7c20 82724e0d94633936 I common.go:135: VV intra: end? sendNotif: socket-summary: id=82724e0d94633936 pid=Exit uid=rethink down=0 up=0 dur=0 synack=0 msg=dial udp [2001:4860:4860::8844]:53: connect: network is unreachable I read from /data/data/com.celzero.bravedns/files/local_blocklist/1717883772940/td.txt len 95842408 I read from /data/data/com.celzero.bravedns/files/local_blocklist/1717883772940/rd.txt len 6738920 I dispatchers.go:283: VV ns: tun(99): dispatch: got(88 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 4, 2048 | :37128 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I alg.go:800: D alg: dns64: algip(10.111.222.3) -> realips([]) -> unnated([]) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=10.111.222.1:37128 dst=10.111.222.3:53 I transport.go:420: V dns: fwd: query epdg.epc.mnc094.mcc404.pub.3gppnetwork.org [prefs:&{Base CachePreferred false}; chosen:[]]; id? CachePreferred, sid? , pid? Base, ips? [] I transport.go:453: V dns: fwd: query NOT blocked epdg.epc.mnc094.mcc404.pub.3gppnetwork.org; why? block free transport I cacher.go:414: D cache: miss(k: epdg.epc.mnc094.mcc404.pub.3gppnetwork.org:28): cached? false, hangover? false, stale? true I bootstrap.go:202: V dns: default: udp:Base query? true I upstream.go:137: V dns53: pxdial: (Bootstrap) using udp relay/proxy Base at 127.8.4.5:3690 I rdial.go:210: D rdial: commondial: dialing (host:port) rethink:53 I ipmap.go:195: D ipmap: Get: rethink => [8.8.4.4 2001:4860:4860::8844] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2001:4860:4860::8844 for rethink:53 I protect.go:67: D control: netbinder: Base: udp6([2001:4860:4860::8844]:53); err? I wall.go:114: V wall: no local blockerQ; letting through epdg.epc.mnc094.mcc404.pub.3gppnetwork.org. I rdial.go:240: V rdial: commondial: ip 2001:4860:4860::8844 works for rethink:53 I rdial.go:234: D rdial: duration: 473.282µs; failed rethink:53; confirmed? 2001:4860:4860::8844, sz: 2 I base.go:64: I proxy: base: dial(udp) to rethink:53; err? I upstream.go:195: V dns53: send: (udp / Bootstrap) to rethink:53 for epdg.epc.mnc094.mcc404.pub.3gppnetwork.org. using udp? true / px? true / relay? false; err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(108 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 4, 34525 | :36380 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I natpt.go:116: D natpt: no matching prefix64 for ip(2001:4860:4860::8844) in id(__local464/1) I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(2001:4860:4860::8844); ip not ok I alg.go:800: D alg: dns64: algip(2001:4860:4860::8844) -> realips([]) -> unnated([]) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:36380 dst=[2001:4860:4860::8844]:53 I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] I rdial.go:210: D rdial: commondial: dialing (host:port) [2001:4860:4860::8844]:53 I ipmap.go:195: D ipmap: Get: 2001:4860:4860::8844 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2001:4860:4860::8844 for [2001:4860:4860::8844]:53 I protect.go:67: D control: netbinder: Exit: udp6([2001:4860:4860::8844]:53); err? E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: [2001:4860:4860::8844]:53 I rdial.go:245: D rdial: commondial: confirmed ip 2001:4860:4860::8844 for [2001:4860:4860::8844]:53 failed with err dial udp [2001:4860:4860::8844]:53: connect: network is unreachable I rdial.go:234: D rdial: duration: 1.729531ms; failed [2001:4860:4860::8844]:53; confirmed? 2001:4860:4860::8844, sz: 1 I exit.go:53: I proxy: exit: dial(udp) to [2001:4860:4860::8844]:53; err? dial udp [2001:4860:4860::8844]:53: connect: network is unreachable I common.go:88: VV intra: queueSummary: over 40067fe2a0 4006cf7c20 4b373044ee534067 I common.go:135: VV intra: end? sendNotif: socket-summary: id=4b373044ee534067 pid=Exit uid=rethink down=0 up=0 dur=0 synack=0 msg=dial udp [2001:4860:4860::8844]:53: connect: network is unreachable E udp.go:360: W udp: connect: #0: 4b373044ee534067 failed; addr([2001:4860:4860::8844]:53); for uid rethink (2s) w err(dial udp [2001:4860:4860::8844]:53: connect: network is unreachable) E udp.go:256: W udp: proxy: unexpected [fd66:f83a:c650::1]:36380 -> [2001:4860:4860::8844]:53; err: dial udp [2001:4860:4860::8844]:53: connect: network is unreachable I dispatchers.go:283: VV ns: tun(99): dispatch: got(61 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 1, 2048 | :32987 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I alg.go:800: D alg: dns64: algip(10.111.222.3) -> realips([]) -> unnated([]) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=10.111.222.1:32987 dst=10.111.222.3:53 I transport.go:420: V dns: fwd: query mail.google.com [prefs:&{Base CachePreferred false}; chosen:[]]; id? CachePreferred, sid? , pid? Base, ips? [] I transport.go:453: V dns: fwd: query NOT blocked mail.google.com; why? block free transport I cacher.go:414: D cache: miss(k: mail.google.com:28): cached? false, hangover? false, stale? true I bootstrap.go:202: V dns: default: udp:Base query? true I wall.go:114: V wall: no local blockerQ; letting through mail.google.com. I upstream.go:137: V dns53: pxdial: (Bootstrap) using udp relay/proxy Base at 127.8.4.5:3690 I rdial.go:210: D rdial: commondial: dialing (host:port) rethink:53 I ipmap.go:195: D ipmap: Get: rethink => [8.8.4.4 2001:4860:4860::8844] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2001:4860:4860::8844 for rethink:53 I protect.go:67: D control: netbinder: Base: udp6([2001:4860:4860::8844]:53); err? I rdial.go:240: V rdial: commondial: ip 2001:4860:4860::8844 works for rethink:53 I rdial.go:234: D rdial: duration: 840.104µs; failed rethink:53; confirmed? 2001:4860:4860::8844, sz: 2 I base.go:64: I proxy: base: dial(udp) to rethink:53; err? I upstream.go:195: V dns53: send: (udp / Bootstrap) to rethink:53 for mail.google.com. using udp? true / px? true / relay? false; err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(81 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 4, 34525 | :39198 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I natpt.go:116: D natpt: no matching prefix64 for ip(2001:4860:4860::8844) in id(__local464/1) I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(2001:4860:4860::8844); ip not ok I alg.go:800: D alg: dns64: algip(2001:4860:4860::8844) -> realips([]) -> unnated([]) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:39198 dst=[2001:4860:4860::8844]:53 I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: [2001:4860:4860::8844]:53 I rdial.go:210: D rdial: commondial: dialing (host:port) [2001:4860:4860::8844]:53 I ipmap.go:195: D ipmap: Get: 2001:4860:4860::8844 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2001:4860:4860::8844 for [2001:4860:4860::8844]:53 I protect.go:67: D control: netbinder: Exit: udp6([2001:4860:4860::8844]:53); err? I rdial.go:245: D rdial: commondial: confirmed ip 2001:4860:4860::8844 for [2001:4860:4860::8844]:53 failed with err dial udp [2001:4860:4860::8844]:53: connect: network is unreachable I rdial.go:234: D rdial: duration: 1.960052ms; failed [2001:4860:4860::8844]:53; confirmed? 2001:4860:4860::8844, sz: 1 E udp.go:360: W udp: connect: #0: 17e54c3d6f8f5e57 failed; addr([2001:4860:4860::8844]:53); for uid rethink (2s) w err(dial udp [2001:4860:4860::8844]:53: connect: network is unreachable) I exit.go:53: I proxy: exit: dial(udp) to [2001:4860:4860::8844]:53; err? dial udp [2001:4860:4860::8844]:53: connect: network is unreachable I common.go:88: VV intra: queueSummary: over 40067fe2a0 4006cf7c20 17e54c3d6f8f5e57 E udp.go:256: W udp: proxy: unexpected [fd66:f83a:c650::1]:39198 -> [2001:4860:4860::8844]:53; err: dial udp [2001:4860:4860::8844]:53: connect: network is unreachable I common.go:135: VV intra: end? sendNotif: socket-summary: id=17e54c3d6f8f5e57 pid=Exit uid=rethink down=0 up=0 dur=0 synack=0 msg=dial udp [2001:4860:4860::8844]:53: connect: network is unreachable I ipmap.go:238: D ipmap: renew: cloudflare-dns.com / seed: [1.1.1.1 104.16.249.249 2606:4700:4700::1111 2606:4700::6810:f8f9] / typ: Auto I ipmap.go:264: D ipmap: makeIPSet: cloudflare-dns.com, seed: [1.1.1.1 104.16.249.249 2606:4700:4700::1111 2606:4700::6810:f8f9], typ: Regular I doh.go:235: I doh: new transport(DNS-over-HTTPS): https://cloudflare-dns.com/dns-query; relay? false; addrs? [1.1.1.1 104.16.249.249 2606:4700:4700::1111 2606:4700::6810:f8f9]; resolved? true I cacher.go:124: I cache: (CachePreferred) setup: cached.cloudflare-dns.com; opts: ttl=10m0s;bumps=10;size=256 I transport.go:257: I dns: add transport Preferred@cloudflare-dns.com; cache? true I alg.go:147: I alg: translate? prev(false) > now(false) I tun2socks.go:100: I tun: loopback? true / ok? false I dispatchers.go:237: I ns: tun(99): wrapup: immediate (loopback) I fds.go:95: I ns: dispatch: fds: stop: eve(115) tun(99); err? I dispatchers.go:198: I ns: dispatch: swap: tun(99 => -1); err E fdbased.go:494: W ns: tun(99): dispatchLoop: exit; err no such file I dispatchers.go:283: VV ns: tun(99): dispatch: got(-1 bytes), err(errno 0) I fdbased.go:266: D ns: swapping tun... fd: -1 => 99, mtu: 1500 I dispatchers.go:198: I ns: dispatch: swap: tun(-1 => 99); err I tunnel.go:362: I tun: new link; fd(99), mtu(1500); err? I fdbased.go:490: I ns: tun(99): dispatchLoop: start I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(76 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 4, 34525 | :36608 => :48464 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(48 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 2, 34525 | :34048 => :21975 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(96 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 34525 | :36608 => :47925 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I ips.go:168: I dialers: ips: protos set to 46; diff? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I tun2socks.go:100: I tun: loopback? true / ok? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(59 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 4, 2048 | :17173 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(60 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :38120 => :80 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I dispatchers.go:283: VV ns: tun(99): dispatch: got(57 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 2048 | :31968 => :53 I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I alg.go:800: D alg: dns64: algip(10.111.222.3) -> realips([]) -> unnated([]) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=10.111.222.1:17173 dst=10.111.222.3:53 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I alg.go:800: D alg: dns64: algip(10.111.222.3) -> realips([]) -> unnated([]) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=10.111.222.1:31968 dst=10.111.222.3:53 I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :38120 => :80 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I tcp.go:134: D ns: tcp: forwarder: complete src(10.111.222.1:38120) => dst(34.107.221.82:80); rst? false I tcp.go:126: VV ns: tcp: forwarder: proxy src(10.111.222.1:38120) => dst(34.107.221.82:80); fin? false I alg.go:800: D alg: dns64: algip(34.107.221.82) -> realips([]) -> unnated([]) I tcp.go:124: D onFlow: no realips() or domains( + ), for src=10.111.222.1:38120 dst=34.107.221.82:80 I transport.go:420: V dns: fwd: query ipv4only.arpa [prefs:&{Base CachePreferred false}; chosen:[]]; id? CachePreferred, sid? , pid? Base, ips? [] I wall.go:121: D wall: skip local for ipv4only.arpa. blockQ for with err no blocklist applies I transport.go:453: V dns: fwd: query NOT blocked ipv4only.arpa; why? no blocklist applies I cacher.go:414: D cache: miss(k: ipv4only.arpa:28): cached? false, hangover? false, stale? true I doh.go:484: V doh: sending query to: cloudflare-dns.com I doh.go:415: VV doh: using proxy Base:127.8.4.5:3690 I wall.go:121: D wall: skip local for ipv4only.arpa. blockQ for with err no blocklist applies I rdial.go:210: D rdial: commondial: dialing (host:port) cloudflare-dns.com:443 I ipmap.go:195: D ipmap: Get: cloudflare-dns.com => [1.1.1.1 104.16.249.249 2606:4700:4700::1111 2606:4700::6810:f8f9] I rdial.go:266: D rdial: commondial: trying all ips 4 [2606:4700::6810:f8f9 2606:4700:4700::1111 104.16.249.249 1.1.1.1] for cloudflare-dns.com:443, failingopen? false I protect.go:67: D control: netbinder: Base: tcp6([2606:4700::6810:f8f9]:443); err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(80 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 34525 | :39142 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I dispatchers.go:283: VV ns: tun(99): dispatch: got(72 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 34525 | :39142 => :443 I rdial.go:276: V rdial: commondial: dialing ip 2606:4700::6810:f8f9 for cloudflare-dns.com:443 I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I rdial.go:278: I rdial: commondial: ip 2606:4700::6810:f8f9 works for cloudflare-dns.com:443 I rdial.go:234: D rdial: duration: 1.623386ms; failed cloudflare-dns.com:443; confirmed? invalid IP, sz: 4 I base.go:64: I proxy: base: dial(tcp) to cloudflare-dns.com:443; err? I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(351 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :38120 => :80 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(350 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 34525 | :39142 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :38120 => :80 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I transport.go:420: V dns: fwd: query example.org [prefs:&{Base CachePreferred false}; chosen:[]]; id? CachePreferred, sid? , pid? Base, ips? [] I wall.go:121: D wall: skip local for example.org. blockQ for with err no blocklist applies I transport.go:453: V dns: fwd: query NOT blocked example.org; why? no blocklist applies I cacher.go:414: D cache: miss(k: example.org:28): cached? false, hangover? false, stale? true I wall.go:121: D wall: skip local for example.org. blockQ for with err no blocklist applies I doh.go:484: V doh: sending query to: cloudflare-dns.com I doh.go:415: VV doh: using proxy Base:127.8.4.5:3690 I rdial.go:210: D rdial: commondial: dialing (host:port) cloudflare-dns.com:443 I ipmap.go:195: D ipmap: Get: cloudflare-dns.com => [1.1.1.1 104.16.249.249 2606:4700:4700::1111 2606:4700::6810:f8f9] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2606:4700::6810:f8f9 for cloudflare-dns.com:443 E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: 34.107.221.82:80 I protect.go:67: D control: netbinder: Base: tcp6([2606:4700::6810:f8f9]:443); err? I tcp.go:119: VV ns: tcp: forwarder: connect src(10.111.222.1:38120) => dst(34.107.221.82:80); fin? false I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] I rdial.go:210: D rdial: commondial: dialing (host:port) 34.107.221.82:80 I ipmap.go:264: D ipmap: makeIPSet: 34.107.221.82, seed: [], typ: ipaddr I ipmap.go:274: D ipmap: makeIPSet: 34.107.221.82 for ipaddr, confirmed addr 34.107.221.82 I ipmap.go:195: D ipmap: Get: 34.107.221.82 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 34.107.221.82 for 34.107.221.82:80 I dispatchers.go:283: VV ns: tun(99): dispatch: got(80 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 2, 34525 | :39144 => :443 I protect.go:67: D control: netbinder: Exit: udp4(:0); err? I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(72 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 2, 34525 | :39144 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I rdial.go:240: V rdial: commondial: ip 2606:4700::6810:f8f9 works for cloudflare-dns.com:443 I rdial.go:234: D rdial: duration: 1.364114ms; failed cloudflare-dns.com:443; confirmed? 2606:4700::6810:f8f9, sz: 4 I base.go:64: I proxy: base: dial(tcp) to cloudflare-dns.com:443; err? I tcp.go:134: D ns: tcp: forwarder: complete src([fd66:f83a:c650::1]:39144) => dst([2606:4700::6810:f8f9]:443); rst? false I tcp.go:126: VV ns: tcp: forwarder: proxy src([fd66:f83a:c650::1]:39144) => dst([2606:4700::6810:f8f9]:443); fin? false I natpt.go:116: D natpt: no matching prefix64 for ip(2606:4700::6810:f8f9) in id(__local464/1) I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(2606:4700::6810:f8f9); ip not ok I alg.go:800: D alg: dns64: algip(2606:4700::6810:f8f9) -> realips([]) -> unnated([]) I tcp.go:124: D onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:39144 dst=[2606:4700::6810:f8f9]:443 I dispatchers.go:283: VV ns: tun(99): dispatch: got(350 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 2, 34525 | :39144 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I split_and_desync.go:184: D split-desync: dialUDP 34.107.221.82:80 137: err? I protect.go:67: D control: netbinder: Exit: tcp4(34.107.221.82:80); err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(57 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 2, 2048 | :34631 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(70 bytes), err(errno 0) I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 4, 2048 | :45736 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I alg.go:800: D alg: dns64: algip(10.111.222.3) -> realips([]) -> unnated([]) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=10.111.222.1:34631 dst=10.111.222.3:53 I tcp.go:134: D ns: tcp: forwarder: complete src([fd66:f83a:c650::1]:39142) => dst([2606:4700::6810:f8f9]:443); rst? false I tcp.go:126: VV ns: tcp: forwarder: proxy src([fd66:f83a:c650::1]:39142) => dst([2606:4700::6810:f8f9]:443); fin? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I natpt.go:116: D natpt: no matching prefix64 for ip(2606:4700::6810:f8f9) in id(__local464/1) I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(2606:4700::6810:f8f9); ip not ok I alg.go:800: D alg: dns64: algip(2606:4700::6810:f8f9) -> realips([]) -> unnated([]) I tcp.go:124: D onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:39142 dst=[2606:4700::6810:f8f9]:443 I alg.go:800: D alg: dns64: algip(10.111.222.3) -> realips([]) -> unnated([]) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=10.111.222.1:45736 dst=10.111.222.3:53 E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: [2606:4700::6810:f8f9]:443 I tcp.go:119: VV ns: tcp: forwarder: connect src([fd66:f83a:c650::1]:39144) => dst([2606:4700::6810:f8f9]:443); fin? false I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] I rdial.go:210: D rdial: commondial: dialing (host:port) [2606:4700::6810:f8f9]:443 I ipmap.go:264: D ipmap: makeIPSet: 2606:4700::6810:f8f9, seed: [], typ: ipaddr I ipmap.go:274: D ipmap: makeIPSet: 2606:4700::6810:f8f9 for ipaddr, confirmed addr 2606:4700::6810:f8f9 I ipmap.go:195: D ipmap: Get: 2606:4700::6810:f8f9 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2606:4700::6810:f8f9 for [2606:4700::6810:f8f9]:443 I protect.go:67: D control: netbinder: Exit: udp6(:0); err? I protect.go:67: D control: netbinder: Exit: tcp6([2606:4700::6810:f8f9]:443); err? E split_and_desync.go:184: E split-desync: dialUDP [2606:4700::6810:f8f9]:443 134: err? write udp6 [::]:47013->[2606:4700::6810:f8f9]:19046: sendto: network is unreachable I tcp.go:119: VV ns: tcp: forwarder: connect src([fd66:f83a:c650::1]:39142) => dst([2606:4700::6810:f8f9]:443); fin? false I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: [2606:4700::6810:f8f9]:443 I rdial.go:210: D rdial: commondial: dialing (host:port) [2606:4700::6810:f8f9]:443 I ipmap.go:195: D ipmap: Get: 2606:4700::6810:f8f9 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2606:4700::6810:f8f9 for [2606:4700::6810:f8f9]:443 I protect.go:67: D control: netbinder: Exit: udp6(:0); err? E split_and_desync.go:184: E split-desync: dialUDP [2606:4700::6810:f8f9]:443 141: err? write udp6 [::]:47690->[2606:4700::6810:f8f9]:47420: sendto: network is unreachable E split_and_desync.go:199: E split-desync: dialTCP [2606:4700::6810:f8f9]:443 err: dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E tcp.go:304: W tcp: err dialing 40d3dce0d97191b2 proxy(Exit) to dst([2606:4700::6810:f8f9]:443) for rethink: dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E tcp.go:262: W tcp: dial: #0: 40d3dce0d97191b2 failed; addr([2606:4700::6810:f8f9]:443); for uid rethink (1); w err(dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable) I protect.go:67: D control: netbinder: Exit: tcp6([2606:4700::6810:f8f9]:443); err? I rdial.go:245: D rdial: commondial: confirmed ip 2606:4700::6810:f8f9 for [2606:4700::6810:f8f9]:443 failed with err dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I rdial.go:234: D rdial: duration: 1.711771ms; failed [2606:4700::6810:f8f9]:443; confirmed? 2606:4700::6810:f8f9, sz: 1 I exit.go:53: I proxy: exit: dial(tcp) to [2606:4700::6810:f8f9]:443; err? dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I common.go:88: VV intra: queueSummary: over 40067fe240 4006cf7b00 40d3dce0d97191b2 I transport.go:420: V dns: fwd: query detectportal.firefox.com [prefs:&{Base CachePreferred false}; chosen:[]]; id? CachePreferred, sid? , pid? Base, ips? [] I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:135: VV intra: end? sendNotif: socket-summary: id=40d3dce0d97191b2 pid=Exit uid=rethink down=0 up=0 dur=0 synack=0 msg=dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E doh.go:387: W doh: fetch #0 (eof? false) for https://cloudflare-dns.com/dns-query, err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39142->[2606:4700::6810:f8f9]:443: read: connection reset by peer E doh.go:451: E doh: query failed: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39142->[2606:4700::6810:f8f9]:443: read: connection reset by peer I wall.go:121: D wall: skip local for detectportal.firefox.com. blockQ for with err no blocklist applies I transport.go:453: V dns: fwd: query NOT blocked detectportal.firefox.com; why? no blocklist applies I doh.go:618: V doh: (p/px udp:Base/Base); len(res): 0, data: --, via: , err? Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39142->[2606:4700::6810:f8f9]:443: read: connection reset by peer I cacher.go:414: D cache: miss(k: detectportal.firefox.com:28): cached? false, hangover? false, stale? true I alg.go:1058: V alg: Req: ipv4only.arpa. servfail; rcode 2 I cacher.go:348: D cache: barrier: empty(k: ipv4only.arpa:28); barrier: v: bumps=0; expiry=0001-01-01 00:00:00 +0000 UTC; s=type: DNS-over-HTTPS, id: CachePreferred, latency: 0.027708, qname: , rdata: --, rcode: 2, rttl: 0, server: cloudflare-dns.com, relay: , status: 2, blocklists: , msg: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39142->[2606:4700::6810:f8f9]:443: read: connection reset by peer, loc: // n: 0; exp: 2024-08-12 03:16:52.583098259 +0000 UTC m=+104.228527930 // err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39142->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: ipv4only.arpa. servfail; rcode 2 I doh.go:484: V doh: sending query to: cloudflare-dns.com I doh.go:415: VV doh: using proxy Base:127.8.4.5:3690 I transport.go:661: VV dns: udp: done; tot: 1, t: 29ms, err: EOF I wall.go:121: D wall: skip local for detectportal.firefox.com. blockQ for with err no blocklist applies I rdial.go:210: D rdial: commondial: dialing (host:port) cloudflare-dns.com:443 I ipmap.go:195: D ipmap: Get: cloudflare-dns.com => [1.1.1.1 104.16.249.249 2606:4700:4700::1111 2606:4700::6810:f8f9] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2606:4700::6810:f8f9 for cloudflare-dns.com:443 I protect.go:67: D control: netbinder: Base: tcp6([2606:4700::6810:f8f9]:443); err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(80 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 34525 | :39148 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false E split_and_desync.go:199: E split-desync: dialTCP [2606:4700::6810:f8f9]:443 err: dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E tcp.go:304: W tcp: err dialing 49e4fc5fc756632b proxy(Exit) to dst([2606:4700::6810:f8f9]:443) for rethink: dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I rdial.go:245: D rdial: commondial: confirmed ip 2606:4700::6810:f8f9 for [2606:4700::6810:f8f9]:443 failed with err dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E tcp.go:262: W tcp: dial: #0: 49e4fc5fc756632b failed; addr([2606:4700::6810:f8f9]:443); for uid rethink (6); w err(dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable) I rdial.go:234: D rdial: duration: 6.488542ms; failed [2606:4700::6810:f8f9]:443; confirmed? 2606:4700::6810:f8f9, sz: 1 I exit.go:53: I proxy: exit: dial(tcp) to [2606:4700::6810:f8f9]:443; err? dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I common.go:88: VV intra: queueSummary: over 40067fe240 4006cf7b00 49e4fc5fc756632b I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:135: VV intra: end? sendNotif: socket-summary: id=49e4fc5fc756632b pid=Exit uid=rethink down=0 up=0 dur=0 synack=0 msg=dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E doh.go:387: W doh: fetch #0 (eof? false) for https://cloudflare-dns.com/dns-query, err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39144->[2606:4700::6810:f8f9]:443: read: connection reset by peer E doh.go:451: E doh: query failed: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39144->[2606:4700::6810:f8f9]:443: read: connection reset by peer I doh.go:618: V doh: (p/px udp:Base/Base); len(res): 0, data: --, via: , err? Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39144->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: example.org. servfail; rcode 2 I cacher.go:348: D cache: barrier: empty(k: example.org:28); barrier: v: bumps=0; expiry=0001-01-01 00:00:00 +0000 UTC; s=type: DNS-over-HTTPS, id: CachePreferred, latency: 0.022995, qname: , rdata: --, rcode: 2, rttl: 0, server: cloudflare-dns.com, relay: , status: 2, blocklists: , msg: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39144->[2606:4700::6810:f8f9]:443: read: connection reset by peer, loc: // n: 0; exp: 2024-08-12 03:16:52.589421592 +0000 UTC m=+104.234851107 // err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39144->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: example.org. servfail; rcode 2 I transport.go:661: VV dns: udp: done; tot: 1, t: 24ms, err: EOF I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(72 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 34525 | :39148 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I rdial.go:240: V rdial: commondial: ip 2606:4700::6810:f8f9 works for cloudflare-dns.com:443 I rdial.go:234: D rdial: duration: 2.545365ms; failed cloudflare-dns.com:443; confirmed? 2606:4700::6810:f8f9, sz: 4 I base.go:64: I proxy: base: dial(tcp) to cloudflare-dns.com:443; err? I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I dispatchers.go:283: VV ns: tun(99): dispatch: got(350 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 34525 | :39148 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false ```
hussainmohd-a commented 2 months ago
more logs logs2: ```jsx I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I tcp.go:134: D ns: tcp: forwarder: complete src([fd66:f83a:c650::1]:39148) => dst([2606:4700::6810:f8f9]:443); rst? false I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I tcp.go:126: VV ns: tcp: forwarder: proxy src([fd66:f83a:c650::1]:39148) => dst([2606:4700::6810:f8f9]:443); fin? false I natpt.go:116: D natpt: no matching prefix64 for ip(2606:4700::6810:f8f9) in id(__local464/1) I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(2606:4700::6810:f8f9); ip not ok I alg.go:800: D alg: dns64: algip(2606:4700::6810:f8f9) -> realips([]) -> unnated([]) I tcp.go:124: D onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:39148 dst=[2606:4700::6810:f8f9]:443 I transport.go:420: V dns: fwd: query example.org [prefs:&{Base CachePreferred false}; chosen:[]]; id? CachePreferred, sid? , pid? Base, ips? [] I wall.go:121: D wall: skip local for example.org. blockQ for with err no blocklist applies I transport.go:453: V dns: fwd: query NOT blocked example.org; why? no blocklist applies I cacher.go:414: D cache: miss(k: example.org:1): cached? false, hangover? false, stale? true I doh.go:484: V doh: sending query to: cloudflare-dns.com I doh.go:415: VV doh: using proxy Base:127.8.4.5:3690 I rdial.go:210: D rdial: commondial: dialing (host:port) cloudflare-dns.com:443 I ipmap.go:195: D ipmap: Get: cloudflare-dns.com => [1.1.1.1 104.16.249.249 2606:4700:4700::1111 2606:4700::6810:f8f9] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2606:4700::6810:f8f9 for cloudflare-dns.com:443 I protect.go:67: D control: netbinder: Base: tcp6([2606:4700::6810:f8f9]:443); err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(80 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 34525 | :39150 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I wall.go:121: D wall: skip local for example.org. blockQ for with err no blocklist applies I dispatchers.go:283: VV ns: tun(99): dispatch: got(72 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 34525 | :39150 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I rdial.go:240: V rdial: commondial: ip 2606:4700::6810:f8f9 works for cloudflare-dns.com:443 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I rdial.go:234: D rdial: duration: 1.672864ms; failed cloudflare-dns.com:443; confirmed? 2606:4700::6810:f8f9, sz: 4 I tcp.go:134: D ns: tcp: forwarder: complete src([fd66:f83a:c650::1]:39150) => dst([2606:4700::6810:f8f9]:443); rst? false I tcp.go:126: VV ns: tcp: forwarder: proxy src([fd66:f83a:c650::1]:39150) => dst([2606:4700::6810:f8f9]:443); fin? false I base.go:64: I proxy: base: dial(tcp) to cloudflare-dns.com:443; err? I natpt.go:116: D natpt: no matching prefix64 for ip(2606:4700::6810:f8f9) in id(__local464/1) I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(2606:4700::6810:f8f9); ip not ok I alg.go:800: D alg: dns64: algip(2606:4700::6810:f8f9) -> realips([]) -> unnated([]) I tcp.go:124: D onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:39150 dst=[2606:4700::6810:f8f9]:443 I dispatchers.go:283: VV ns: tun(99): dispatch: got(350 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 34525 | :39150 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I split_and_desync.go:262: D split-desync: done: 34.107.221.82:80, ok? true, ttl: 8 I rdial.go:240: V rdial: commondial: ip 34.107.221.82 works for 34.107.221.82:80 I rdial.go:234: D rdial: duration: 30.991198ms; failed 34.107.221.82:80; confirmed? 34.107.221.82, sz: 1 I exit.go:53: I proxy: exit: dial(tcp) to 34.107.221.82:80; err? I connmap.go:83: D connmap: track: 2 conns for 65e98d37014b9c6d I tcp.go:314: I tcp: new conn 65e98d37014b9c6d via proxy(Exit); src(10.111.222.1:38120) -> dst(34.107.221.82:80) for 10407 I split_and_desync.go:431: D split-desync: write: n1: 100, n2: 199, err: I retrier.go:422: D rdial: copyOnce: rw [192.168.0.140:36892->34.107.221.82:80] 299/299; err I split_and_desync.go:439: D split-desync: readfrom: copyOnce; sz: 299; err: I common.go:40: D intra: 65e98d37014b9c6d upload(299) done() b/w a(10.111.222.1:38120->34.107.221.82:80) => b(192.168.0.140:36892<-34.107.221.82:80) I tcp.go:119: VV ns: tcp: forwarder: connect src([fd66:f83a:c650::1]:39148) => dst([2606:4700::6810:f8f9]:443); fin? false I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: [2606:4700::6810:f8f9]:443 I rdial.go:210: D rdial: commondial: dialing (host:port) [2606:4700::6810:f8f9]:443 I ipmap.go:195: D ipmap: Get: 2606:4700::6810:f8f9 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2606:4700::6810:f8f9 for [2606:4700::6810:f8f9]:443 I protect.go:67: D control: netbinder: Exit: udp6(:0); err? I tcp.go:119: VV ns: tcp: forwarder: connect src([fd66:f83a:c650::1]:39150) => dst([2606:4700::6810:f8f9]:443); fin? false I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: [2606:4700::6810:f8f9]:443 I rdial.go:210: D rdial: commondial: dialing (host:port) [2606:4700::6810:f8f9]:443 I ipmap.go:195: D ipmap: Get: 2606:4700::6810:f8f9 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2606:4700::6810:f8f9 for [2606:4700::6810:f8f9]:443 I protect.go:67: D control: netbinder: Exit: udp6(:0); err? E split_and_desync.go:184: E split-desync: dialUDP [2606:4700::6810:f8f9]:443 132: err? write udp6 [::]:39235->[2606:4700::6810:f8f9]:39043: sendto: network is unreachable I protect.go:67: D control: netbinder: Exit: tcp6([2606:4700::6810:f8f9]:443); err? E split_and_desync.go:184: E split-desync: dialUDP [2606:4700::6810:f8f9]:443 137: err? write udp6 [::]:60780->[2606:4700::6810:f8f9]:46160: sendto: network is unreachable I protect.go:67: D control: netbinder: Exit: tcp6([2606:4700::6810:f8f9]:443); err? E split_and_desync.go:199: E split-desync: dialTCP [2606:4700::6810:f8f9]:443 err: dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I rdial.go:245: D rdial: commondial: confirmed ip 2606:4700::6810:f8f9 for [2606:4700::6810:f8f9]:443 failed with err dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E tcp.go:304: W tcp: err dialing 8cdf41d3b0ed826f proxy(Exit) to dst([2606:4700::6810:f8f9]:443) for rethink: dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E tcp.go:262: W tcp: dial: #0: 8cdf41d3b0ed826f failed; addr([2606:4700::6810:f8f9]:443); for uid rethink (4); w err(dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable) I rdial.go:234: D rdial: duration: 4.227136ms; failed [2606:4700::6810:f8f9]:443; confirmed? 2606:4700::6810:f8f9, sz: 1 I exit.go:53: I proxy: exit: dial(tcp) to [2606:4700::6810:f8f9]:443; err? dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I common.go:88: VV intra: queueSummary: over 40067fe240 4006cf7b00 8cdf41d3b0ed826f I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:135: VV intra: end? sendNotif: socket-summary: id=8cdf41d3b0ed826f pid=Exit uid=rethink down=0 up=0 dur=0 synack=0 msg=dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E doh.go:387: W doh: fetch #0 (eof? false) for https://cloudflare-dns.com/dns-query, err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39148->[2606:4700::6810:f8f9]:443: read: connection reset by peer E doh.go:451: E doh: query failed: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39148->[2606:4700::6810:f8f9]:443: read: connection reset by peer I doh.go:618: V doh: (p/px udp:Base/Base); len(res): 0, data: --, via: , err? Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39148->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: detectportal.firefox.com. servfail; rcode 2 I cacher.go:348: D cache: barrier: empty(k: detectportal.firefox.com:28); barrier: v: bumps=0; expiry=0001-01-01 00:00:00 +0000 UTC; s=type: DNS-over-HTTPS, id: CachePreferred, latency: 0.017133, qname: , rdata: --, rcode: 2, rttl: 0, server: cloudflare-dns.com, relay: , status: 2, blocklists: , msg: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39148->[2606:4700::6810:f8f9]:443: read: connection reset by peer, loc: // n: 0; exp: 2024-08-12 03:16:52.610914977 +0000 UTC m=+104.256344545 // err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39148->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: detectportal.firefox.com. servfail; rcode 2 I transport.go:661: VV dns: udp: done; tot: 1, t: 18ms, err: EOF I rdial.go:245: D rdial: commondial: confirmed ip 2606:4700::6810:f8f9 for [2606:4700::6810:f8f9]:443 failed with err dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I rdial.go:234: D rdial: duration: 6.273281ms; failed [2606:4700::6810:f8f9]:443; confirmed? 2606:4700::6810:f8f9, sz: 1 I exit.go:53: I proxy: exit: dial(tcp) to [2606:4700::6810:f8f9]:443; err? dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I common.go:88: VV intra: queueSummary: over 40067fe240 4006cf7b00 439f63236659db41 E split_and_desync.go:199: E split-desync: dialTCP [2606:4700::6810:f8f9]:443 err: dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E tcp.go:304: W tcp: err dialing 439f63236659db41 proxy(Exit) to dst([2606:4700::6810:f8f9]:443) for rethink: dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E tcp.go:262: W tcp: dial: #0: 439f63236659db41 failed; addr([2606:4700::6810:f8f9]:443); for uid rethink (6); w err(dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable) E doh.go:387: W doh: fetch #0 (eof? false) for https://cloudflare-dns.com/dns-query, err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39150->[2606:4700::6810:f8f9]:443: read: connection reset by peer E doh.go:451: E doh: query failed: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39150->[2606:4700::6810:f8f9]:443: read: connection reset by peer I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:135: VV intra: end? sendNotif: socket-summary: id=439f63236659db41 pid=Exit uid=rethink down=0 up=0 dur=0 synack=0 msg=dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I doh.go:618: V doh: (p/px udp:Base/Base); len(res): 0, data: --, via: , err? Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39150->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: example.org. servfail; rcode 2 I cacher.go:348: D cache: barrier: empty(k: example.org:1); barrier: v: bumps=0; expiry=0001-01-01 00:00:00 +0000 UTC; s=type: DNS-over-HTTPS, id: CachePreferred, latency: 0.014163, qname: , rdata: --, rcode: 2, rttl: 0, server: cloudflare-dns.com, relay: , status: 2, blocklists: , msg: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39150->[2606:4700::6810:f8f9]:443: read: connection reset by peer, loc: // n: 0; exp: 2024-08-12 03:16:52.61579404 +0000 UTC m=+104.261223607 // err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39150->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: example.org. servfail; rcode 2 I transport.go:661: VV dns: udp: done; tot: 1, t: 15ms, err: EOF I dispatchers.go:283: VV ns: tun(99): dispatch: got(70 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :9732 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I alg.go:800: D alg: dns64: algip(10.111.222.3) -> realips([]) -> unnated([]) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=10.111.222.1:9732 dst=10.111.222.3:53 I transport.go:420: V dns: fwd: query detectportal.firefox.com [prefs:&{Base CachePreferred false}; chosen:[]]; id? CachePreferred, sid? , pid? Base, ips? [] I wall.go:121: D wall: skip local for detectportal.firefox.com. blockQ for with err no blocklist applies I transport.go:453: V dns: fwd: query NOT blocked detectportal.firefox.com; why? no blocklist applies I cacher.go:414: D cache: miss(k: detectportal.firefox.com:1): cached? false, hangover? false, stale? true I doh.go:484: V doh: sending query to: cloudflare-dns.com I doh.go:415: VV doh: using proxy Base:127.8.4.5:3690 I wall.go:121: D wall: skip local for detectportal.firefox.com. blockQ for with err no blocklist applies I rdial.go:210: D rdial: commondial: dialing (host:port) cloudflare-dns.com:443 I ipmap.go:195: D ipmap: Get: cloudflare-dns.com => [1.1.1.1 104.16.249.249 2606:4700:4700::1111 2606:4700::6810:f8f9] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2606:4700::6810:f8f9 for cloudflare-dns.com:443 I protect.go:67: D control: netbinder: Base: tcp6([2606:4700::6810:f8f9]:443); err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(80 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 34525 | :39152 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I dispatchers.go:283: VV ns: tun(99): dispatch: got(72 bytes), err(errno 0) I rdial.go:240: V rdial: commondial: ip 2606:4700::6810:f8f9 works for cloudflare-dns.com:443 I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 34525 | :39152 => :443 I rdial.go:234: D rdial: duration: 1.291719ms; failed cloudflare-dns.com:443; confirmed? 2606:4700::6810:f8f9, sz: 4 I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I base.go:64: I proxy: base: dial(tcp) to cloudflare-dns.com:443; err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(350 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 34525 | :39152 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I tcp.go:134: D ns: tcp: forwarder: complete src([fd66:f83a:c650::1]:39152) => dst([2606:4700::6810:f8f9]:443); rst? false I tcp.go:126: VV ns: tcp: forwarder: proxy src([fd66:f83a:c650::1]:39152) => dst([2606:4700::6810:f8f9]:443); fin? false I natpt.go:116: D natpt: no matching prefix64 for ip(2606:4700::6810:f8f9) in id(__local464/1) I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(2606:4700::6810:f8f9); ip not ok I alg.go:800: D alg: dns64: algip(2606:4700::6810:f8f9) -> realips([]) -> unnated([]) I tcp.go:124: D onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:39152 dst=[2606:4700::6810:f8f9]:443 I common.go:51: D intra: 65e98d37014b9c6d download(0) done() b/w a(10.111.222.1:38120->34.107.221.82:80) => b(192.168.0.140:36892<-34.107.221.82:80) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :38120 => :80 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:88: VV intra: queueSummary: over 40067fe240 4006cf7b00 65e98d37014b9c6d I common.go:135: VV intra: end? sendNotif: socket-summary: id=65e98d37014b9c6d pid=Exit uid=10407 down=0 up=299 dur=0 synack=31 msg=no error I connmap.go:143: D connmap: untrack: 2 conns for 65e98d37014b9c6d I tcp.go:119: VV ns: tcp: forwarder: connect src([fd66:f83a:c650::1]:39152) => dst([2606:4700::6810:f8f9]:443); fin? false I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: [2606:4700::6810:f8f9]:443 I rdial.go:210: D rdial: commondial: dialing (host:port) [2606:4700::6810:f8f9]:443 I ipmap.go:195: D ipmap: Get: 2606:4700::6810:f8f9 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2606:4700::6810:f8f9 for [2606:4700::6810:f8f9]:443 I protect.go:67: D control: netbinder: Exit: udp6(:0); err? E split_and_desync.go:184: E split-desync: dialUDP [2606:4700::6810:f8f9]:443 127: err? write udp6 [::]:40060->[2606:4700::6810:f8f9]:30472: sendto: network is unreachable I protect.go:67: D control: netbinder: Exit: tcp6([2606:4700::6810:f8f9]:443); err? E split_and_desync.go:199: E split-desync: dialTCP [2606:4700::6810:f8f9]:443 err: dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I rdial.go:245: D rdial: commondial: confirmed ip 2606:4700::6810:f8f9 for [2606:4700::6810:f8f9]:443 failed with err dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I rdial.go:234: D rdial: duration: 2.555625ms; failed [2606:4700::6810:f8f9]:443; confirmed? 2606:4700::6810:f8f9, sz: 1 E tcp.go:304: W tcp: err dialing d01e3b01893496e6 proxy(Exit) to dst([2606:4700::6810:f8f9]:443) for rethink: dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E tcp.go:262: W tcp: dial: #0: d01e3b01893496e6 failed; addr([2606:4700::6810:f8f9]:443); for uid rethink (2); w err(dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable) I exit.go:53: I proxy: exit: dial(tcp) to [2606:4700::6810:f8f9]:443; err? dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I common.go:88: VV intra: queueSummary: over 40067fe240 4006cf7b00 d01e3b01893496e6 I common.go:135: VV intra: end? sendNotif: socket-summary: id=d01e3b01893496e6 pid=Exit uid=rethink down=0 up=0 dur=0 synack=0 msg=dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E doh.go:387: W doh: fetch #0 (eof? false) for https://cloudflare-dns.com/dns-query, err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39152->[2606:4700::6810:f8f9]:443: read: connection reset by peer I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) E doh.go:451: E doh: query failed: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39152->[2606:4700::6810:f8f9]:443: read: connection reset by peer I doh.go:618: V doh: (p/px udp:Base/Base); len(res): 0, data: --, via: , err? Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39152->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: detectportal.firefox.com. servfail; rcode 2 I cacher.go:348: D cache: barrier: empty(k: detectportal.firefox.com:1); barrier: v: bumps=0; expiry=0001-01-01 00:00:00 +0000 UTC; s=type: DNS-over-HTTPS, id: CachePreferred, latency: 0.013162, qname: , rdata: --, rcode: 2, rttl: 0, server: cloudflare-dns.com, relay: , status: 2, blocklists: , msg: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39152->[2606:4700::6810:f8f9]:443: read: connection reset by peer, loc: // n: 0; exp: 2024-08-12 03:16:52.64938279 +0000 UTC m=+104.294812357 // err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39152->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: detectportal.firefox.com. servfail; rcode 2 I transport.go:661: VV dns: udp: done; tot: 1, t: 14ms, err: EOF I dispatchers.go:283: VV ns: tun(99): dispatch: got(96 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 34525 | :36608 => :47925 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I dispatchers.go:283: VV ns: tun(99): dispatch: got(96 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 34525 | :36608 => :47925 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I dispatchers.go:283: VV ns: tun(99): dispatch: got(60 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :38278 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :38278 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I tcp.go:134: D ns: tcp: forwarder: complete src(10.111.222.1:38278) => dst(142.250.182.42:443); rst? false I tcp.go:126: VV ns: tcp: forwarder: proxy src(10.111.222.1:38278) => dst(142.250.182.42:443); fin? false I alg.go:800: D alg: dns64: algip(142.250.182.42) -> realips([]) -> unnated([]) I tcp.go:124: D onFlow: no realips() or domains( + ), for src=10.111.222.1:38278 dst=142.250.182.42:443 I tcp.go:119: VV ns: tcp: forwarder: connect src(10.111.222.1:38278) => dst(142.250.182.42:443); fin? false E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: 142.250.182.42:443 I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] I rdial.go:210: D rdial: commondial: dialing (host:port) 142.250.182.42:443 I ipmap.go:264: D ipmap: makeIPSet: 142.250.182.42, seed: [], typ: ipaddr I ipmap.go:274: D ipmap: makeIPSet: 142.250.182.42 for ipaddr, confirmed addr 142.250.182.42 I ipmap.go:195: D ipmap: Get: 142.250.182.42 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 142.250.182.42 for 142.250.182.42:443 I protect.go:67: D control: netbinder: Exit: udp4(:0); err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(60 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :38936 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :38936 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I tcp.go:134: D ns: tcp: forwarder: complete src(10.111.222.1:38936) => dst(192.168.0.145:8009); rst? false I tcp.go:126: VV ns: tcp: forwarder: proxy src(10.111.222.1:38936) => dst(192.168.0.145:8009); fin? false I alg.go:800: D alg: dns64: algip(192.168.0.145) -> realips([]) -> unnated([]) I tcp.go:124: D onFlow: no realips() or domains( + ), for src=10.111.222.1:38936 dst=192.168.0.145:8009 I split_and_desync.go:184: D split-desync: dialUDP 142.250.182.42:443 127: err? I protect.go:67: D control: netbinder: Exit: tcp4(142.250.182.42:443); err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(190 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :38936 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: 192.168.0.145:8009 I tcp.go:119: VV ns: tcp: forwarder: connect src(10.111.222.1:38936) => dst(192.168.0.145:8009); fin? false I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] I dispatchers.go:283: VV ns: tun(99): dispatch: got(60 bytes), err(errno 0) I rdial.go:210: D rdial: commondial: dialing (host:port) 192.168.0.145:8009 I ipmap.go:195: D ipmap: Get: 192.168.0.145 => [] I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 2048 | :34742 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I rdial.go:238: V rdial: commondial: dialing confirmed ip 192.168.0.145 for 192.168.0.145:8009 I protect.go:67: D control: netbinder: Exit: tcp4(192.168.0.145:8009); err? I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 2048 | :34742 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I tcp.go:134: D ns: tcp: forwarder: complete src(10.111.222.1:34742) => dst(192.168.0.222:8009); rst? false I tcp.go:126: VV ns: tcp: forwarder: proxy src(10.111.222.1:34742) => dst(192.168.0.222:8009); fin? false I alg.go:800: D alg: dns64: algip(192.168.0.222) -> realips([]) -> unnated([]) I tcp.go:124: D onFlow: no realips() or domains( + ), for src=10.111.222.1:34742 dst=192.168.0.222:8009 I dispatchers.go:283: VV ns: tun(99): dispatch: got(190 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 2048 | :34742 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I tcp.go:119: VV ns: tcp: forwarder: connect src(10.111.222.1:34742) => dst(192.168.0.222:8009); fin? false I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] I rdial.go:210: D rdial: commondial: dialing (host:port) 192.168.0.222:8009 E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: 192.168.0.222:8009 I ipmap.go:195: D ipmap: Get: 192.168.0.222 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 192.168.0.222 for 192.168.0.222:8009 I protect.go:67: D control: netbinder: Exit: tcp4(192.168.0.222:8009); err? I split_and_desync.go:231: V split-desync: recvmsg 142.250.182.42:443 failed: resource temporarily unavailable I split_and_desync.go:262: D split-desync: done: 142.250.182.42:443, ok? true, ttl: 5 I rdial.go:240: V rdial: commondial: ip 142.250.182.42 works for 142.250.182.42:443 I rdial.go:234: D rdial: duration: 23.389635ms; failed 142.250.182.42:443; confirmed? 142.250.182.42, sz: 1 I exit.go:53: I proxy: exit: dial(tcp) to 142.250.182.42:443; err? I connmap.go:83: D connmap: track: 2 conns for ad791d65d611ebe7 I tcp.go:314: I tcp: new conn ad791d65d611ebe7 via proxy(Exit); src(10.111.222.1:38278) -> dst(142.250.182.42:443) for 10088 I rdial.go:240: V rdial: commondial: ip 192.168.0.222 works for 192.168.0.222:8009 I rdial.go:234: D rdial: duration: 7.946198ms; failed 192.168.0.222:8009; confirmed? 192.168.0.222, sz: 1 I exit.go:53: I proxy: exit: dial(tcp) to 192.168.0.222:8009; err? I connmap.go:83: D connmap: track: 2 conns for 7ec0021fddd51098 I tcp.go:314: I tcp: new conn 7ec0021fddd51098 via proxy(Exit); src(10.111.222.1:34742) -> dst(192.168.0.222:8009) for 10088 I dispatchers.go:283: VV ns: tun(99): dispatch: got(77 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 34525 | :65180 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(79 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 34525 | :34337 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I natpt.go:116: D natpt: no matching prefix64 for ip(fd66:f83a:c650::3) in id(__local464/1) I natpt.go:116: D natpt: no matching prefix64 for ip(fd66:f83a:c650::3) in id(__local464/1) I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(fd66:f83a:c650::3); ip not ok I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(fd66:f83a:c650::3); ip not ok I alg.go:800: D alg: dns64: algip(fd66:f83a:c650::3) -> realips([]) -> unnated([]) I alg.go:800: D alg: dns64: algip(fd66:f83a:c650::3) -> realips([]) -> unnated([]) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:65180 dst=[fd66:f83a:c650::3]:53 I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:34337 dst=[fd66:f83a:c650::3]:53 I transport.go:420: V dns: fwd: query example.org [prefs:&{Base CachePreferred false}; chosen:[]]; id? CachePreferred, sid? , pid? Base, ips? [] I wall.go:121: D wall: skip local for example.org. blockQ for with err no blocklist applies I transport.go:453: V dns: fwd: query NOT blocked example.org; why? no blocklist applies I transport.go:420: V dns: fwd: query ipv4only.arpa [prefs:&{Base CachePreferred false}; chosen:[]]; id? CachePreferred, sid? , pid? Base, ips? [] I wall.go:121: D wall: skip local for ipv4only.arpa. blockQ for with err no blocklist applies I wall.go:121: D wall: skip local for example.org. blockQ for with err no blocklist applies I transport.go:453: V dns: fwd: query NOT blocked ipv4only.arpa; why? no blocklist applies I cacher.go:414: D cache: miss(k: ipv4only.arpa:28): cached? false, hangover? false, stale? true I cacher.go:414: D cache: miss(k: example.org:28): cached? false, hangover? false, stale? true I doh.go:484: V doh: sending query to: cloudflare-dns.com I doh.go:415: VV doh: using proxy Base:127.8.4.5:3690 I wall.go:121: D wall: skip local for ipv4only.arpa. blockQ for with err no blocklist applies I cacher.go:348: D cache: barrier: empty(k: example.org:28); barrier: v: bumps=0; expiry=0001-01-01 00:00:00 +0000 UTC; s=type: DNS-over-HTTPS, id: CachePreferred, latency: 0.022995, qname: , rdata: --, rcode: 2, rttl: 0, server: cloudflare-dns.com, relay: , status: 2, blocklists: , msg: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39144->[2606:4700::6810:f8f9]:443: read: connection reset by peer, loc: // n: 1; exp: 2024-08-12 03:16:52.589421592 +0000 UTC m=+104.234851107 // err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39144->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: example.org. servfail; rcode 2 I rdial.go:210: D rdial: commondial: dialing (host:port) cloudflare-dns.com:443 I transport.go:661: VV dns: udp: done; tot: 1, t: 1ms, err: EOF I ipmap.go:195: D ipmap: Get: cloudflare-dns.com => [1.1.1.1 104.16.249.249 2606:4700:4700::1111 2606:4700::6810:f8f9] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2606:4700::6810:f8f9 for cloudflare-dns.com:443 I protect.go:67: D control: netbinder: Base: tcp6([2606:4700::6810:f8f9]:443); err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(80 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 2, 34525 | :39166 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I rdial.go:240: V rdial: commondial: ip 2606:4700::6810:f8f9 works for cloudflare-dns.com:443 I rdial.go:234: D rdial: duration: 1.177031ms; failed cloudflare-dns.com:443; confirmed? 2606:4700::6810:f8f9, sz: 4 I dispatchers.go:283: VV ns: tun(99): dispatch: got(72 bytes), err(errno 0) I base.go:64: I proxy: base: dial(tcp) to cloudflare-dns.com:443; err? I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 2, 34525 | :39166 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I tcp.go:134: D ns: tcp: forwarder: complete src([fd66:f83a:c650::1]:39166) => dst([2606:4700::6810:f8f9]:443); rst? false I tcp.go:126: VV ns: tcp: forwarder: proxy src([fd66:f83a:c650::1]:39166) => dst([2606:4700::6810:f8f9]:443); fin? false I natpt.go:116: D natpt: no matching prefix64 for ip(2606:4700::6810:f8f9) in id(__local464/1) I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(2606:4700::6810:f8f9); ip not ok I alg.go:800: D alg: dns64: algip(2606:4700::6810:f8f9) -> realips([]) -> unnated([]) I tcp.go:124: D onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:39166 dst=[2606:4700::6810:f8f9]:443 I dispatchers.go:283: VV ns: tun(99): dispatch: got(350 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 2, 34525 | :39166 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: [2606:4700::6810:f8f9]:443 I tcp.go:119: VV ns: tcp: forwarder: connect src([fd66:f83a:c650::1]:39166) => dst([2606:4700::6810:f8f9]:443); fin? false I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] I rdial.go:210: D rdial: commondial: dialing (host:port) [2606:4700::6810:f8f9]:443 I ipmap.go:195: D ipmap: Get: 2606:4700::6810:f8f9 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2606:4700::6810:f8f9 for [2606:4700::6810:f8f9]:443 I protect.go:67: D control: netbinder: Exit: udp6(:0); err? E split_and_desync.go:184: E split-desync: dialUDP [2606:4700::6810:f8f9]:443 139: err? write udp6 [::]:35775->[2606:4700::6810:f8f9]:55302: sendto: network is unreachable I protect.go:67: D control: netbinder: Exit: tcp6([2606:4700::6810:f8f9]:443); err? E split_and_desync.go:199: E split-desync: dialTCP [2606:4700::6810:f8f9]:443 err: dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E tcp.go:304: W tcp: err dialing 22c884a0fa652ed2 proxy(Exit) to dst([2606:4700::6810:f8f9]:443) for rethink: dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E tcp.go:262: W tcp: dial: #0: 22c884a0fa652ed2 failed; addr([2606:4700::6810:f8f9]:443); for uid rethink (2); w err(dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable) I rdial.go:245: D rdial: commondial: confirmed ip 2606:4700::6810:f8f9 for [2606:4700::6810:f8f9]:443 failed with err dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I rdial.go:234: D rdial: duration: 2.484948ms; failed [2606:4700::6810:f8f9]:443; confirmed? 2606:4700::6810:f8f9, sz: 1 I exit.go:53: I proxy: exit: dial(tcp) to [2606:4700::6810:f8f9]:443; err? dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I common.go:88: VV intra: queueSummary: over 40067fe240 4006cf7b00 22c884a0fa652ed2 I common.go:135: VV intra: end? sendNotif: socket-summary: id=22c884a0fa652ed2 pid=Exit uid=rethink down=0 up=0 dur=0 synack=0 msg=dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I doh.go:618: V doh: (p/px udp:Base/Base); len(res): 0, data: --, via: , err? Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39166->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: ipv4only.arpa. servfail; rcode 2 I cacher.go:348: D cache: barrier: empty(k: ipv4only.arpa:28); barrier: v: bumps=0; expiry=0001-01-01 00:00:00 +0000 UTC; s=type: DNS-over-HTTPS, id: CachePreferred, latency: 0.009824, qname: , rdata: --, rcode: 2, rttl: 0, server: cloudflare-dns.com, relay: , status: 2, blocklists: , msg: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39166->[2606:4700::6810:f8f9]:443: read: connection reset by peer, loc: // n: 0; exp: 2024-08-12 03:16:54.585508831 +0000 UTC m=+106.230938398 // err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39166->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: ipv4only.arpa. servfail; rcode 2 E doh.go:387: W doh: fetch #0 (eof? false) for https://cloudflare-dns.com/dns-query, err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39166->[2606:4700::6810:f8f9]:443: read: connection reset by peer E doh.go:451: E doh: query failed: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39166->[2606:4700::6810:f8f9]:443: read: connection reset by peer I transport.go:661: VV dns: udp: done; tot: 1, t: 11ms, err: EOF I dispatchers.go:283: VV ns: tun(99): dispatch: got(90 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 2, 34525 | :65386 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(77 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 34525 | :60040 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I natpt.go:116: D natpt: no matching prefix64 for ip(fd66:f83a:c650::3) in id(__local464/1) I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(fd66:f83a:c650::3); ip not ok I alg.go:800: D alg: dns64: algip(fd66:f83a:c650::3) -> realips([]) -> unnated([]) I natpt.go:116: D natpt: no matching prefix64 for ip(fd66:f83a:c650::3) in id(__local464/1) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:65386 dst=[fd66:f83a:c650::3]:53 I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(fd66:f83a:c650::3); ip not ok I alg.go:800: D alg: dns64: algip(fd66:f83a:c650::3) -> realips([]) -> unnated([]) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:60040 dst=[fd66:f83a:c650::3]:53 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 2048 | :34742 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I transport.go:420: V dns: fwd: query detectportal.firefox.com [prefs:&{Base CachePreferred false}; chosen:[]]; id? CachePreferred, sid? , pid? Base, ips? [] I wall.go:121: D wall: skip local for detectportal.firefox.com. blockQ for with err no blocklist applies I transport.go:453: V dns: fwd: query NOT blocked detectportal.firefox.com; why? no blocklist applies I cacher.go:414: D cache: miss(k: detectportal.firefox.com:28): cached? false, hangover? false, stale? true I doh.go:484: V doh: sending query to: cloudflare-dns.com I doh.go:415: VV doh: using proxy Base:127.8.4.5:3690 I rdial.go:210: D rdial: commondial: dialing (host:port) cloudflare-dns.com:443 I ipmap.go:195: D ipmap: Get: cloudflare-dns.com => [1.1.1.1 104.16.249.249 2606:4700:4700::1111 2606:4700::6810:f8f9] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2606:4700::6810:f8f9 for cloudflare-dns.com:443 I wall.go:121: D wall: skip local for detectportal.firefox.com. blockQ for with err no blocklist applies I dispatchers.go:283: VV ns: tun(99): dispatch: got(145 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 2048 | :34742 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I protect.go:67: D control: netbinder: Base: tcp6([2606:4700::6810:f8f9]:443); err? I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(80 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 34525 | :39168 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I transport.go:420: V dns: fwd: query example.org [prefs:&{Base CachePreferred false}; chosen:[]]; id? CachePreferred, sid? , pid? Base, ips? [] I wall.go:121: D wall: skip local for example.org. blockQ for with err no blocklist applies I transport.go:453: V dns: fwd: query NOT blocked example.org; why? no blocklist applies I cacher.go:414: D cache: miss(k: example.org:1): cached? false, hangover? false, stale? true I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I rdial.go:240: V rdial: commondial: ip 2606:4700::6810:f8f9 works for cloudflare-dns.com:443 I rdial.go:234: D rdial: duration: 2.134427ms; failed cloudflare-dns.com:443; confirmed? 2606:4700::6810:f8f9, sz: 4 I cacher.go:348: D cache: barrier: empty(k: example.org:1); barrier: v: bumps=0; expiry=0001-01-01 00:00:00 +0000 UTC; s=type: DNS-over-HTTPS, id: CachePreferred, latency: 0.014163, qname: , rdata: --, rcode: 2, rttl: 0, server: cloudflare-dns.com, relay: , status: 2, blocklists: , msg: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39150->[2606:4700::6810:f8f9]:443: read: connection reset by peer, loc: // n: 1; exp: 2024-08-12 03:16:52.61579404 +0000 UTC m=+104.261223607 // err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39150->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: example.org. servfail; rcode 2 I wall.go:121: D wall: skip local for example.org. blockQ for with err no blocklist applies I transport.go:661: VV dns: udp: done; tot: 1, t: 3ms, err: EOF I base.go:64: I proxy: base: dial(tcp) to cloudflare-dns.com:443; err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(72 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 34525 | :39168 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I tcp.go:134: D ns: tcp: forwarder: complete src([fd66:f83a:c650::1]:39168) => dst([2606:4700::6810:f8f9]:443); rst? false I tcp.go:126: VV ns: tcp: forwarder: proxy src([fd66:f83a:c650::1]:39168) => dst([2606:4700::6810:f8f9]:443); fin? false I natpt.go:116: D natpt: no matching prefix64 for ip(2606:4700::6810:f8f9) in id(__local464/1) I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(2606:4700::6810:f8f9); ip not ok I alg.go:800: D alg: dns64: algip(2606:4700::6810:f8f9) -> realips([]) -> unnated([]) I tcp.go:124: D onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:39168 dst=[2606:4700::6810:f8f9]:443 I dispatchers.go:283: VV ns: tun(99): dispatch: got(350 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 34525 | :39168 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I rdial.go:240: V rdial: commondial: ip 192.168.0.145 works for 192.168.0.145:8009 I rdial.go:234: D rdial: duration: 65.00474ms; failed 192.168.0.145:8009; confirmed? 192.168.0.145, sz: 1 I exit.go:53: I proxy: exit: dial(tcp) to 192.168.0.145:8009; err? I connmap.go:83: D connmap: track: 2 conns for fba0f32a79df50af I tcp.go:314: I tcp: new conn fba0f32a79df50af via proxy(Exit); src(10.111.222.1:38936) -> dst(192.168.0.145:8009) for 10088 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I tcp.go:119: VV ns: tcp: forwarder: connect src([fd66:f83a:c650::1]:39168) => dst([2606:4700::6810:f8f9]:443); fin? false I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: [2606:4700::6810:f8f9]:443 I rdial.go:210: D rdial: commondial: dialing (host:port) [2606:4700::6810:f8f9]:443 I ipmap.go:195: D ipmap: Get: 2606:4700::6810:f8f9 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2606:4700::6810:f8f9 for [2606:4700::6810:f8f9]:443 I protect.go:67: D control: netbinder: Exit: udp6(:0); err? E split_and_desync.go:184: E split-desync: dialUDP [2606:4700::6810:f8f9]:443 137: err? write udp6 [::]:32925->[2606:4700::6810:f8f9]:59022: sendto: network is unreachable I protect.go:67: D control: netbinder: Exit: tcp6([2606:4700::6810:f8f9]:443); err? E split_and_desync.go:199: E split-desync: dialTCP [2606:4700::6810:f8f9]:443 err: dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I rdial.go:245: D rdial: commondial: confirmed ip 2606:4700::6810:f8f9 for [2606:4700::6810:f8f9]:443 failed with err dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I rdial.go:234: D rdial: duration: 2.926771ms; failed [2606:4700::6810:f8f9]:443; confirmed? 2606:4700::6810:f8f9, sz: 1 I exit.go:53: I proxy: exit: dial(tcp) to [2606:4700::6810:f8f9]:443; err? dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I common.go:88: VV intra: queueSummary: over 40067fe240 4006cf7b00 fe46f8b5a23063a0 E tcp.go:304: W tcp: err dialing fe46f8b5a23063a0 proxy(Exit) to dst([2606:4700::6810:f8f9]:443) for rethink: dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E tcp.go:262: W tcp: dial: #0: fe46f8b5a23063a0 failed; addr([2606:4700::6810:f8f9]:443); for uid rethink (3); w err(dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:135: VV intra: end? sendNotif: socket-summary: id=fe46f8b5a23063a0 pid=Exit uid=rethink down=0 up=0 dur=0 synack=0 msg=dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E doh.go:387: W doh: fetch #0 (eof? false) for https://cloudflare-dns.com/dns-query, err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39168->[2606:4700::6810:f8f9]:443: read: connection reset by peer E doh.go:451: E doh: query failed: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39168->[2606:4700::6810:f8f9]:443: read: connection reset by peer I doh.go:618: V doh: (p/px udp:Base/Base); len(res): 0, data: --, via: , err? Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39168->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: detectportal.firefox.com. servfail; rcode 2 I cacher.go:348: D cache: barrier: empty(k: detectportal.firefox.com:28); barrier: v: bumps=0; expiry=0001-01-01 00:00:00 +0000 UTC; s=type: DNS-over-HTTPS, id: CachePreferred, latency: 0.012393, qname: , rdata: --, rcode: 2, rttl: 0, server: cloudflare-dns.com, relay: , status: 2, blocklists: , msg: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39168->[2606:4700::6810:f8f9]:443: read: connection reset by peer, loc: // n: 0; exp: 2024-08-12 03:16:54.61230081 +0000 UTC m=+106.257730325 // err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39168->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: detectportal.firefox.com. servfail; rcode 2 I transport.go:661: VV dns: udp: done; tot: 1, t: 13ms, err: EOF I dispatchers.go:283: VV ns: tun(99): dispatch: got(60 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :34752 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :34752 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I tcp.go:134: D ns: tcp: forwarder: complete src(10.111.222.1:34752) => dst(192.168.0.222:8009); rst? false I tcp.go:126: VV ns: tcp: forwarder: proxy src(10.111.222.1:34752) => dst(192.168.0.222:8009); fin? false I alg.go:800: D alg: dns64: algip(192.168.0.222) -> realips([]) -> unnated([]) I tcp.go:124: D onFlow: no realips() or domains( + ), for src=10.111.222.1:34752 dst=192.168.0.222:8009 I dispatchers.go:283: VV ns: tun(99): dispatch: got(190 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :34752 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(694 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 2048 | :34742 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I tcp.go:119: VV ns: tcp: forwarder: connect src(10.111.222.1:34752) => dst(192.168.0.222:8009); fin? false I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: 192.168.0.222:8009 I rdial.go:210: D rdial: commondial: dialing (host:port) 192.168.0.222:8009 I ipmap.go:195: D ipmap: Get: 192.168.0.222 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 192.168.0.222 for 192.168.0.222:8009 I protect.go:67: D control: netbinder: Exit: tcp4(192.168.0.222:8009); err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(569 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :38278 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I rdial.go:240: V rdial: commondial: ip 192.168.0.222 works for 192.168.0.222:8009 I rdial.go:234: D rdial: duration: 6.620521ms; failed 192.168.0.222:8009; confirmed? 192.168.0.222, sz: 1 I exit.go:53: I proxy: exit: dial(tcp) to 192.168.0.222:8009; err? I connmap.go:83: D connmap: track: 2 conns for da1afa95e759d556 I tcp.go:314: I tcp: new conn da1afa95e759d556 via proxy(Exit); src(10.111.222.1:34752) -> dst(192.168.0.222:8009) for 10088 I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I split_and_desync.go:431: D split-desync: write: n1: 100, n2: 417, err: I retrier.go:422: D rdial: copyOnce: rw [192.168.0.140:45680->142.250.182.42:443] 517/517; err I split_and_desync.go:439: D split-desync: readfrom: copyOnce; sz: 517; err: I dispatchers.go:283: VV ns: tun(99): dispatch: got(90 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 34525 | :27284 => :53 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I natpt.go:116: D natpt: no matching prefix64 for ip(fd66:f83a:c650::3) in id(__local464/1) I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(fd66:f83a:c650::3); ip not ok I alg.go:800: D alg: dns64: algip(fd66:f83a:c650::3) -> realips([]) -> unnated([]) I udp.go:145: VV udp: onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:27284 dst=[fd66:f83a:c650::3]:53 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :38936 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I transport.go:420: V dns: fwd: query detectportal.firefox.com [prefs:&{Base CachePreferred false}; chosen:[]]; id? CachePreferred, sid? , pid? Base, ips? [] I wall.go:121: D wall: skip local for detectportal.firefox.com. blockQ for with err no blocklist applies I transport.go:453: V dns: fwd: query NOT blocked detectportal.firefox.com; why? no blocklist applies I cacher.go:414: D cache: miss(k: detectportal.firefox.com:1): cached? false, hangover? false, stale? true I wall.go:121: D wall: skip local for detectportal.firefox.com. blockQ for with err no blocklist applies I doh.go:484: V doh: sending query to: cloudflare-dns.com I doh.go:415: VV doh: using proxy Base:127.8.4.5:3690 I rdial.go:210: D rdial: commondial: dialing (host:port) cloudflare-dns.com:443 I ipmap.go:195: D ipmap: Get: cloudflare-dns.com => [1.1.1.1 104.16.249.249 2606:4700:4700::1111 2606:4700::6810:f8f9] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2606:4700::6810:f8f9 for cloudflare-dns.com:443 I protect.go:67: D control: netbinder: Base: tcp6([2606:4700::6810:f8f9]:443); err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(80 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 4, 34525 | :39174 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(145 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :38936 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(72 bytes), err(errno 0) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 4, 34525 | :39174 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I rdial.go:240: V rdial: commondial: ip 2606:4700::6810:f8f9 works for cloudflare-dns.com:443 I rdial.go:234: D rdial: duration: 1.432656ms; failed cloudflare-dns.com:443; confirmed? 2606:4700::6810:f8f9, sz: 4 I base.go:64: I proxy: base: dial(tcp) to cloudflare-dns.com:443; err? I tcp.go:134: D ns: tcp: forwarder: complete src([fd66:f83a:c650::1]:39174) => dst([2606:4700::6810:f8f9]:443); rst? false I tcp.go:126: VV ns: tcp: forwarder: proxy src([fd66:f83a:c650::1]:39174) => dst([2606:4700::6810:f8f9]:443); fin? false I natpt.go:116: D natpt: no matching prefix64 for ip(2606:4700::6810:f8f9) in id(__local464/1) I alg.go:819: V alg: dns64: maybeUndoNat64: No local nat64 to ip4(invalid IP) for ip6(2606:4700::6810:f8f9); ip not ok I alg.go:800: D alg: dns64: algip(2606:4700::6810:f8f9) -> realips([]) -> unnated([]) I tcp.go:124: D onFlow: no realips() or domains( + ), for src=[fd66:f83a:c650::1]:39174 dst=[2606:4700::6810:f8f9]:443 I dispatchers.go:283: VV ns: tun(99): dispatch: got(350 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 4, 34525 | :39174 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 34525 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I tcp.go:119: VV ns: tcp: forwarder: connect src([fd66:f83a:c650::1]:39174) => dst([2606:4700::6810:f8f9]:443); fin? false I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] I rdial.go:210: D rdial: commondial: dialing (host:port) [2606:4700::6810:f8f9]:443 I ipmap.go:195: D ipmap: Get: 2606:4700::6810:f8f9 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 2606:4700::6810:f8f9 for [2606:4700::6810:f8f9]:443 E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: [2606:4700::6810:f8f9]:443 I protect.go:67: D control: netbinder: Exit: udp6(:0); err? I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I protect.go:67: D control: netbinder: Exit: tcp6([2606:4700::6810:f8f9]:443); err? E split_and_desync.go:184: E split-desync: dialUDP [2606:4700::6810:f8f9]:443 139: err? write udp6 [::]:47421->[2606:4700::6810:f8f9]:32453: sendto: network is unreachable E split_and_desync.go:199: E split-desync: dialTCP [2606:4700::6810:f8f9]:443 err: dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I rdial.go:245: D rdial: commondial: confirmed ip 2606:4700::6810:f8f9 for [2606:4700::6810:f8f9]:443 failed with err dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I rdial.go:234: D rdial: duration: 2.562761ms; failed [2606:4700::6810:f8f9]:443; confirmed? 2606:4700::6810:f8f9, sz: 1 I exit.go:53: I proxy: exit: dial(tcp) to [2606:4700::6810:f8f9]:443; err? dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I common.go:88: VV intra: queueSummary: over 40067fe240 4006cf7b00 65e441811c1d251b E tcp.go:304: W tcp: err dialing 65e441811c1d251b proxy(Exit) to dst([2606:4700::6810:f8f9]:443) for rethink: dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable E tcp.go:262: W tcp: dial: #0: 65e441811c1d251b failed; addr([2606:4700::6810:f8f9]:443); for uid rethink (2); w err(dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable) I common.go:135: VV intra: end? sendNotif: socket-summary: id=65e441811c1d251b pid=Exit uid=rethink down=0 up=0 dur=0 synack=0 msg=dial tcp6 [2606:4700::6810:f8f9]:443: connect: network is unreachable I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I doh.go:618: V doh: (p/px udp:Base/Base); len(res): 0, data: --, via: , err? Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39174->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: detectportal.firefox.com. servfail; rcode 2 I cacher.go:348: D cache: barrier: empty(k: detectportal.firefox.com:1); barrier: v: bumps=0; expiry=0001-01-01 00:00:00 +0000 UTC; s=type: DNS-over-HTTPS, id: CachePreferred, latency: 0.009777, qname: , rdata: --, rcode: 2, rttl: 0, server: cloudflare-dns.com, relay: , status: 2, blocklists: , msg: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39174->[2606:4700::6810:f8f9]:443: read: connection reset by peer, loc: // n: 0; exp: 2024-08-12 03:16:54.656149508 +0000 UTC m=+106.301579075 // err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39174->[2606:4700::6810:f8f9]:443: read: connection reset by peer I alg.go:1058: V alg: Req: detectportal.firefox.com. servfail; rcode 2 I transport.go:661: VV dns: udp: done; tot: 1, t: 11ms, err: EOF E doh.go:387: W doh: fetch #0 (eof? false) for https://cloudflare-dns.com/dns-query, err: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39174->[2606:4700::6810:f8f9]:443: read: connection reset by peer E doh.go:451: E doh: query failed: Post "https://cloudflare-dns.com/dns-query": read tcp [fd66:f83a:c650::1]:39174->[2606:4700::6810:f8f9]:443: read: connection reset by peer I dispatchers.go:283: VV ns: tun(99): dispatch: got(694 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :38936 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:51: D intra: ad791d65d611ebe7 download(0) done() b/w a(10.111.222.1:38278->142.250.182.42:443) => b(192.168.0.140:45680<-142.250.182.42:443) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(209 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :38936 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :38278 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :38936 => :8009 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :34752 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :38278 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:40: D intra: ad791d65d611ebe7 upload(517) done() b/w a(10.111.222.1:38278->142.250.182.42:443) => b(192.168.0.140:45680<-142.250.182.42:443) I common.go:88: VV intra: queueSummary: over 40067fe240 4006cf7b00 ad791d65d611ebe7 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:135: VV intra: end? sendNotif: socket-summary: id=ad791d65d611ebe7 pid=Exit uid=10088 down=0 up=517 dur=0 synack=23 msg=no error I connmap.go:143: D connmap: untrack: 2 conns for ad791d65d611ebe7 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 2048 | :34742 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I dispatchers.go:283: VV ns: tun(99): dispatch: got(145 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :34752 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(60 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 2048 | :46992 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 2048 | :46992 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I tcp.go:134: D ns: tcp: forwarder: complete src(10.111.222.1:46992) => dst(142.250.182.74:443); rst? false I tcp.go:126: VV ns: tcp: forwarder: proxy src(10.111.222.1:46992) => dst(142.250.182.74:443); fin? false I alg.go:800: D alg: dns64: algip(142.250.182.74) -> realips([]) -> unnated([]) I tcp.go:124: D onFlow: no realips() or domains( + ), for src=10.111.222.1:46992 dst=142.250.182.74:443 I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :38936 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:40: D intra: fba0f32a79df50af upload(1030) done() b/w a(10.111.222.1:38936->192.168.0.145:8009) => b(192.168.0.140:41082<-192.168.0.145:8009) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(569 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 2048 | :46992 => :443 I common.go:51: D intra: fba0f32a79df50af download(2822) done() b/w a(10.111.222.1:38936->192.168.0.145:8009) => b(192.168.0.140:41082<-192.168.0.145:8009) I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(40 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :38936 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(40 bytes), err(errno 0) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 0, 2048 | :38936 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I common.go:88: VV intra: queueSummary: over 40067fe240 4006cf7b00 fba0f32a79df50af I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I connmap.go:143: D connmap: untrack: 2 conns for fba0f32a79df50af I common.go:135: VV intra: end? sendNotif: socket-summary: id=fba0f32a79df50af pid=Exit uid=10088 down=2822 up=1030 dur=0 synack=65 msg=no error I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I tcp.go:119: VV ns: tcp: forwarder: connect src(10.111.222.1:46992) => dst(142.250.182.74:443); fin? false E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: 142.250.182.74:443 I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] I rdial.go:210: D rdial: commondial: dialing (host:port) 142.250.182.74:443 I ipmap.go:264: D ipmap: makeIPSet: 142.250.182.74, seed: [], typ: ipaddr I ipmap.go:274: D ipmap: makeIPSet: 142.250.182.74 for ipaddr, confirmed addr 142.250.182.74 I ipmap.go:195: D ipmap: Get: 142.250.182.74 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 142.250.182.74 for 142.250.182.74:443 I protect.go:67: D control: netbinder: Exit: udp4(:0); err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(209 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 2048 | :34742 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I split_and_desync.go:184: D split-desync: dialUDP 142.250.182.74:443 132: err? I protect.go:67: D control: netbinder: Exit: tcp4(142.250.182.74:443); err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(194 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :34752 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 2048 | :34742 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:40: D intra: 7ec0021fddd51098 upload(1030) done() b/w a(10.111.222.1:34742->192.168.0.222:8009) => b(192.168.0.140:48946<-192.168.0.222:8009) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:51: D intra: 7ec0021fddd51098 download(3261) done() b/w a(10.111.222.1:34742->192.168.0.222:8009) => b(192.168.0.140:48946<-192.168.0.222:8009) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:88: VV intra: queueSummary: over 40067fe240 4006cf7b00 7ec0021fddd51098 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:135: VV intra: end? sendNotif: socket-summary: id=7ec0021fddd51098 pid=Exit uid=10088 down=3261 up=1030 dur=0 synack=8 msg=no error I connmap.go:143: D connmap: untrack: 2 conns for 7ec0021fddd51098 I dispatchers.go:283: VV ns: tun(99): dispatch: got(40 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 2048 | :34742 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I dispatchers.go:283: VV ns: tun(99): dispatch: got(40 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 2048 | :34742 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I split_and_desync.go:231: V split-desync: recvmsg 142.250.182.74:443 failed: resource temporarily unavailable I split_and_desync.go:262: D split-desync: done: 142.250.182.74:443, ok? true, ttl: 6 I rdial.go:240: V rdial: commondial: ip 142.250.182.74 works for 142.250.182.74:443 I rdial.go:234: D rdial: duration: 21.996979ms; failed 142.250.182.74:443; confirmed? 142.250.182.74, sz: 1 I exit.go:53: I proxy: exit: dial(tcp) to 142.250.182.74:443; err? I connmap.go:83: D connmap: track: 2 conns for a4f9657ff0aa0081 I tcp.go:314: I tcp: new conn a4f9657ff0aa0081 via proxy(Exit); src(10.111.222.1:46992) -> dst(142.250.182.74:443) for 10088 I split_and_desync.go:431: D split-desync: write: n1: 100, n2: 417, err: I retrier.go:422: D rdial: copyOnce: rw [192.168.0.140:37564->142.250.182.74:443] 517/517; err I split_and_desync.go:439: D split-desync: readfrom: copyOnce; sz: 517; err: I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :34752 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :34752 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I common.go:51: D intra: a4f9657ff0aa0081 download(0) done() b/w a(10.111.222.1:46992->142.250.182.74:443) => b(192.168.0.140:37564<-142.250.182.74:443) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 5, 2048 | :46992 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:40: D intra: a4f9657ff0aa0081 upload(517) done() b/w a(10.111.222.1:46992->142.250.182.74:443) => b(192.168.0.140:37564<-142.250.182.74:443) I common.go:88: VV intra: queueSummary: over 40067fe240 4006cf7b00 a4f9657ff0aa0081 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:135: VV intra: end? sendNotif: socket-summary: id=a4f9657ff0aa0081 pid=Exit uid=10088 down=0 up=517 dur=0 synack=22 msg=no error I connmap.go:143: D connmap: untrack: 2 conns for a4f9657ff0aa0081 I dispatchers.go:283: VV ns: tun(99): dispatch: got(688 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :34752 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(60 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 2, 2048 | :51940 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 2, 2048 | :51940 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I tcp.go:134: D ns: tcp: forwarder: complete src(10.111.222.1:51940) => dst(142.250.182.106:443); rst? false I tcp.go:126: VV ns: tcp: forwarder: proxy src(10.111.222.1:51940) => dst(142.250.182.106:443); fin? false I alg.go:800: D alg: dns64: algip(142.250.182.106) -> realips([]) -> unnated([]) I tcp.go:124: D onFlow: no realips() or domains( + ), for src=10.111.222.1:51940 dst=142.250.182.106:443 I dispatchers.go:283: VV ns: tun(99): dispatch: got(569 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 2, 2048 | :51940 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I tcp.go:119: VV ns: tcp: forwarder: connect src(10.111.222.1:51940) => dst(142.250.182.106:443); fin? false I common.go:205: VV intra: makeIPPorts(v4? true, v6? true); tot: 1; in: [], out: [] E common.go:214: W intra: makeIPPorts(v4? true, v6? true): all: 1; out: 142.250.182.106:443 I rdial.go:210: D rdial: commondial: dialing (host:port) 142.250.182.106:443 I ipmap.go:264: D ipmap: makeIPSet: 142.250.182.106, seed: [], typ: ipaddr I ipmap.go:274: D ipmap: makeIPSet: 142.250.182.106 for ipaddr, confirmed addr 142.250.182.106 I ipmap.go:195: D ipmap: Get: 142.250.182.106 => [] I rdial.go:238: V rdial: commondial: dialing confirmed ip 142.250.182.106 for 142.250.182.106:443 I protect.go:67: D control: netbinder: Exit: udp4(:0); err? I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :34752 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I split_and_desync.go:184: D split-desync: dialUDP 142.250.182.106:443 132: err? I protect.go:67: D control: netbinder: Exit: tcp4(142.250.182.106:443); err? I dispatchers.go:283: VV ns: tun(99): dispatch: got(203 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :34752 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I split_and_desync.go:231: V split-desync: recvmsg 142.250.182.106:443 failed: resource temporarily unavailable I split_and_desync.go:262: D split-desync: done: 142.250.182.106:443, ok? true, ttl: 6 I rdial.go:240: V rdial: commondial: ip 142.250.182.106 works for 142.250.182.106:443 I rdial.go:234: D rdial: duration: 21.698854ms; failed 142.250.182.106:443; confirmed? 142.250.182.106, sz: 1 I exit.go:53: I proxy: exit: dial(tcp) to 142.250.182.106:443; err? I connmap.go:83: D connmap: track: 2 conns for 9be09246119ca634 I tcp.go:314: I tcp: new conn 9be09246119ca634 via proxy(Exit); src(10.111.222.1:51940) -> dst(142.250.182.106:443) for 10088 I split_and_desync.go:431: D split-desync: write: n1: 100, n2: 417, err: I retrier.go:422: D rdial: copyOnce: rw [192.168.0.140:44162->142.250.182.106:443] 517/517; err I split_and_desync.go:439: D split-desync: readfrom: copyOnce; sz: 517; err: I dispatchers.go:283: VV ns: tun(99): dispatch: got(638 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :34752 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:51: D intra: 9be09246119ca634 download(0) done() b/w a(10.111.222.1:51940->142.250.182.106:443) => b(192.168.0.140:44162<-142.250.182.106:443) I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 2, 2048 | :51940 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I common.go:40: D intra: 9be09246119ca634 upload(517) done() b/w a(10.111.222.1:51940->142.250.182.106:443) => b(192.168.0.140:44162<-142.250.182.106:443) I common.go:88: VV intra: queueSummary: over 40067fe240 4006cf7b00 9be09246119ca634 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I connmap.go:143: D connmap: untrack: 2 conns for 9be09246119ca634 I common.go:135: VV intra: end? sendNotif: socket-summary: id=9be09246119ca634 pid=Exit uid=10088 down=0 up=517 dur=0 synack=21 msg=no error I dispatchers.go:283: VV ns: tun(99): dispatch: got(60 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :39066 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :39066 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I tcp.go:134: D ns: tcp: forwarder: complete src(10.111.222.1:39066) => dst(142.250.182.138:443); rst? false I tcp.go:126: VV ns: tcp: forwarder: proxy src(10.111.222.1:39066) => dst(142.250.182.138:443); fin? false I alg.go:800: D alg: dns64: algip(142.250.182.138) -> realips([]) -> unnated([]) I tcp.go:124: D onFlow: no realips() or domains( + ), for src=10.111.222.1:39066 dst=142.250.182.138:443 I dispatchers.go:283: VV ns: tun(99): dispatch: got(569 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :39066 => :443 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 I fdbased.go:464: VV ns: tun(99): WritePackets (to tun): written(1)/total(1) I dispatchers.go:283: VV ns: tun(99): dispatch: got(52 bytes), err(errno 0) I forwarders.go:297: VV ns: tun(99): forwarder: q on proc 3, 2048 | :34752 => :8009 I dispatchers.go:270: VV ns: tun(99): dispatch: done? false I fdbased.go:529: VV ns: tun(99): inject-inbound (from tun) 2048 ```
ignoramous commented 2 months ago
# recvmsg always fails, TTL set to default 3
08-12 18:17:43.022 18271 13867 I GoLog   : split_and_desync.go:231: V split-desync: recvmsg 142.250.195.74:443 failed: resource temporarily unavailable
08-12 18:17:43.056 18271 13865 I GoLog   : split_and_desync.go:262: D split-desync: done: 142.250.195.106:443, ok? true, ttl: 3

...

# desync write with default TTL 3 is ok
08-12 18:17:43.057 18271 14135 I GoLog   : split_and_desync.go:431: D split-desync: write: n1: 100, n2: 487, err: <nil>

...

# read / write cycle is ok
08-12 18:17:43.057 18271 14135 I GoLog   : retrier.go:422: D rdial: copyOnce: rw [10.1.204.164:57700->142.250.195.106:443] 587/587; err <nil>
08-12 18:17:43.057 18271 14135 I GoLog   : split_and_desync.go:439: D split-desync: readfrom: copyOnce; sz: 587; err: <nil>

# download (remote -> local) always fails
08-12 18:17:43.060 18271 14134 I GoLog   : common.go:51: D intra: d4a825573852a95f download(0) done(read tcp4 10.1.204.164:57700->142.250.195.106:443: read: connection reset by peer) b/w a(10.111.222.1:44394->142.250.195.42:443) => b(10.1.204.164:57700<-142.250.195.106:443)

Happens on Android 10 (v: Linux version 4.9.186-22990479 (dpi@21HHAG04) (gcc version 4.9.x 20150123 (prerelease) (GCC) ) #1 SMP PREEMPT Thu Mar 31 15:41:33 KST 2022)

https://github.com/celzero/firestack/blob/1a403a45d8c1a5b164f7a832c2751015efba2709/intra/dialers/split_and_desync.go#L445

cc: @Lanius-collaris any clue?

Lanius-collaris commented 2 months ago

@ignoramous Looks like not same as hussainmohd-a's logs (sendto unreachable) . Is the device a hardware device? The emulator of Android Studio doesn't support UDP traceroute, and it is not possible to launch TCB Desynchronization in it (Not all packets will be forwarded and its networking tool always sends packets with default TTL) . If the device is not virtual, maybe the sorcery doesn't work on your device (I found that OPPO A57 doesn't support), but traceroute should work. I haven't tested 1a403a4, so my theory isn't based on 1a403a4

Lanius-collaris commented 2 months ago

Two ways to check if the sorcery works

  1. Run tcpdump on Android, if you see TCP stack sends Http1_1String two times, the sorcery doesn't work.
  2. Send a request to a server controlled by you, if the server receives Http1_1String, the sorcery doesn't work.
ignoramous commented 2 months ago

Thanks.

If the device is not virtual, maybe the sorcery doesn't work on your device (I found that OPPO A57 doesn't support)

If the client doesn't support, then the error must be on the client (and not a RST from the server like it happens in this case)? In the current scenario, desync fails only on this one Samsung S8 device running Android 10, but works on other test devices we have (all running Android 13 or 14). Perplexing, esp in absence of any client-side errors.

Two ways to check if the sorcery works ... Run tcpdump on Android

Unrooted... will see if pcap shows anything.

if the server receives Http1_1String, the sorcery doesn't work

I doubt if the server receives the fake payload. In this case, recvmsg fails with the TTL set to default (3). Unless server is within 3 hops of the client (unlikely for ALL egress), I wonder if it sees it.


unrelated; why copy the first split of the payload on to firstSegment if it is never sent (not with unix.Sendfile nor with conn.Write)? I see byedpi does the same.

https://github.com/celzero/firestack/blob/9a33c57dbb228ad85e7a9c02b13e56f73a7cbf67/intra/dialers/split_and_desync.go#L421

Lanius-collaris commented 2 months ago

unrelated; why copy the first split of the payload on to firstSegment if it is never sent (not with sendfile nor with conn.Write)?

https://github.com/celzero/firestack/blob/9a33c57dbb228ad85e7a9c02b13e56f73a7cbf67/intra/dialers/split_and_desync.go#L421

Just manipulate the buffer to change the payload for retransmission, because we can't contruct IP packets directly. Calling sendfile or conn.Write can't send a segment with previous SEQ.

If the client doesn't support, then the error must be on the client (and not a RST from the server like it happens in this case)?

RST may be from TUN. Could you capture packets on a router? I guess that firestack failed to overwrite kernel buffer, google's server received the HTTP request sent to port 443, then closed the connection. But traceroute shouldn't fail, does UDP traceroute work on this device? (You can install traceroute in termux).

In this case, recvmsg fails with the TTL set to default (3)

Another possibility is that only ICMP messages from first three hops were received, but it's not likely.

Lanius-collaris commented 2 months ago
- -
emulate sendfile(2) with splice(2) still fail on 4.9.x
MSG_ZEROCOPY flag require newer kernel

😞

Lanius-collaris commented 1 week ago

I'd like rethink-app to tell users this issue when they enable it on kernel older than 4.14. Thanks.

ignoramous commented 1 week ago

I'd like rethink-app to tell users this issue when they enable it on kernel older than 4.14. Thanks.

Yes, we'll do so before releasing v055o (which is still undergoing major changes).

emulate sendfile(2) with splice(2)

We could still PR this, if it lets us support more Kernel versions?

Lanius-collaris commented 5 days ago

emulate sendfile(2) with splice(2)

We could still PR this, if it lets us support more Kernel versions?

There is no LTS Linux kernel between 4.9 and 4.14, did you see android devices using non-LTS kernel?

ignoramous commented 5 days ago

you see android devices using non-LTS kernel?

TIL. https://source.android.com/docs/core/architecture/kernel/linux-stable-merges

Lanius-collaris commented 4 days ago

overwriteSplitter.Write() sends original data twice rather than "payload then original data" on some Linux distribution (although I can't reproduce on android), I suggest inserting time.Sleep(time.Microsecond) in https://github.com/celzero/firestack/blob/4f43cdb3ba7165798261aa680b97e5368ae05068/intra/dialers/split_and_desync.go#L454 byedpi's wait_send() is a bit complex, I ignored it before… Sorry.

ignoramous commented 4 days ago

I suggest inserting time.Sleep(time.Microsecond)

byedpi's default delay is 3ms (sec?) (ref). That works?

overwriteSplitter.Write() sends original data twice rather than "payload then original data" on some Linux distribution

How does the delay result in the original payload being memcp/cp'd (as we'd expect it to), though?

ignoramous commented 3 days ago

I suggest inserting time.Sleep(time.Microsecond) in

https://github.com/celzero/firestack/commit/a7d3fc2657680b6e784e37e54b9243d77025f314 (a delay of 3ms)

Lanius-collaris commented 3 days ago

payload: "haha", 1st segment: "1234", 2nd segment: "56\r\n" I have seen three kinds of behavior. 3pcap.zip

Linux localhost 6.6.28-0-virt #1-Alpine SMP PREEMPT_DYNAMIC Wed, 17 Apr 2024 19:38:57 +0000 x86_64 Linux

In this environment, 1 microsecond is enough, it's confusing.