Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

route creation attempt loop [router]: Established transport exist. - transport not in discovery but exists locally #1788

Open
0pcom opened this issue Mar 23, 2024 · 1 comment
Open

route creation attempt loop [router]: Established transport exist. - transport not in discovery but exists locally #1788

0pcom opened this issue Mar 23, 2024 · 1 comment
Labels
bug Something isn't working routing transports

Comments

@0pcom
Copy link
Collaborator

0pcom commented Mar 23, 2024

An attempt was made to start the proxy.

 skywire-cli proxy start -k 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac

In the visor debug logging, I observed a continuous loop of route creation attempts when the 'established transport exists'

I've observed this situation before. If the transport the visor says 'exists' is deleted with skywire-cli visor tp rm then it is immediately re-established and the route creation attempt works.

This may have been due to the fact that the transport existed for the local visor but not for the remote?

this line of logging would seem to indicate that this is the case; when the transport was removed from the visor it was not found in the discovery

[2024-03-23T08:21:26.499044916-05:00] DEBUG [tp:038240]: Error deleting transport error="404 Not Found: {"error":"transport not found"}" tp-id=247984d4-4a1a-0eda-a67c-9e69bf0fb169

Here is the visor debug logging:


[2024-03-22T10:44:50.563276879-05:00] ERROR (STDERR) [proc:skysocks-client:ec838d78042049f2af5d270d10203b91]: Error serving proxy client: accept: accept tcp [::]:1080: use of closed network connection
[2024-03-22T10:44:50.563336722-05:00] INFO [proc:skysocks-client:ec838d78042049f2af5d270d10203b91]: App skysocks-client is Stopped
[2024-03-22T10:44:50.567217546-05:00] INFO [proc:skysocks-client:ec838d78042049f2af5d270d10203b91]: App skysocks-client is Stopped
[2024-03-22T10:44:50.82370147-05:00] DEBUG [RouteGroup rAddr:0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c:49153, lAddr:03d0c4a4df6fd1db6f9329a8bbfeb6879a171d554afe9bfbdcac5cb9c34f62a2d6:3]: Got close packet with code 0
[2024-03-22T10:44:50.823811018-05:00] DEBUG [RouteGroup rAddr:0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c:49153, lAddr:03d0c4a4df6fd1db6f9329a8bbfeb6879a171d554afe9bfbdcac5cb9c34f62a2d6:3]: Handling response close packet with code 0
[2024-03-22T10:44:50.823933137-05:00] DEBUG [RouteGroup rAddr:0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c:49153, lAddr:03d0c4a4df6fd1db6f9329a8bbfeb6879a171d554afe9bfbdcac5cb9c34f62a2d6:3]: Remote got closed, stopping ping loop
[2024-03-22T10:44:50.82397634-05:00] DEBUG [RouteGroup rAddr:0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c:49153, lAddr:03d0c4a4df6fd1db6f9329a8bbfeb6879a171d554afe9bfbdcac5cb9c34f62a2d6:3]: Remote got closed, stopping keep-alive loop
[2024-03-23T08:20:12.684158211-05:00] INFO [visor]: Changing skysocks-client Settings to map["--addr":":1080" "--srv":"0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac" "app":"skysocks-client"]
[2024-03-23T08:20:12.684202651-05:00] INFO [visor:config]: Flushing config to file. config_version="unknown" filepath="/opt/skywire/skywire.json"
[2024-03-23T08:20:12.68444522-05:00] INFO [visor:config]: Flushing config to file. config_version="unknown" filepath="/opt/skywire/skywire.json"
[2024-03-23T08:20:12.691972336-05:00] INFO [visor]: Updated Settings.
[2024-03-23T08:20:12.692186818-05:00] DEBUG [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: No app discovery associated with app. appName="skysocks-client"
[2024-03-23T08:20:12.830628507-05:00] DEBUG [proc_manager]: Accepting proc conn... remote=127.0.0.1:33182
[2024-03-23T08:20:12.83070353-05:00] DEBUG [proc_manager]: Read hello from proc. hello="{"proc_key":"f40bbfff4dd9439b8cc9381ae716296b"}" remote=127.0.0.1:33182
[2024-03-23T08:20:12.830735866-05:00] DEBUG [proc_manager]: Accepted proc conn. hello="{"proc_key":"f40bbfff4dd9439b8cc9381ae716296b"}" remote=127.0.0.1:33182
[2024-03-23T08:20:12.83074885-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: Version "v1.3.20-rc2" built on "2024-03-21T16:17:12Z" against commit "8e782b1305d75f7052215ad7591726fbd970eaa8"
[2024-03-23T08:20:12.830800835-05:00] DEBUG [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: Associated and serving proc conn.
[2024-03-23T08:20:14.347995395-05:00] DEBUG [transport_manager]: Initializing TP with ID 247984d4-4a1a-0eda-a67c-9e69bf0fb169
[2024-03-23T08:20:14.34814619-05:00] DEBUG [transport_manager]: Dialing transport to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac via sudph
[2024-03-23T08:20:15.795245675-05:00] DEBUG [sudph]: Resolved PK 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac to visor data {104.62.49.99:51817 false {51817 [127.0.0.1 192.168.0.8 ::1]}}
[2024-03-23T08:20:15.795322581-05:00] DEBUG [sudph]: Dialing 104.62.49.99:51817
[2024-03-23T08:20:15.795468755-05:00] DEBUG [sudph]: Dialed 104.62.49.99:51817
[2024-03-23T08:20:15.795511104-05:00] DEBUG [sudph]: Performing handshake with 104.62.49.99:51817
[2024-03-23T08:20:15.899100491-05:00] DEBUG [sudph]: Sent handshake to 104.62.49.99:51817, local addr 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c:49156, remote addr 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac:45
[2024-03-23T08:20:16.878270331-05:00] DEBUG [tp:038240]: Sent signal to 'mt.transportCh'.
[2024-03-23T08:20:16.878364777-05:00] DEBUG [transport_manager]: saved transport: remote(0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac) type(sudph) tpID(247984d4-4a1a-0eda-a67c-9e69bf0fb169)
[2024-03-23T08:20:16.878417529-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:16.878446049-05:00] DEBUG [tp:038240]: Serving. remote_pk=0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac tp_id=247984d4-4a1a-0eda-a67c-9e69bf0fb169 tp_index=2
[2024-03-23T08:20:17.429359798-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:17.429450776-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:17.429088014-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:18.429583175-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:18.429673495-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:18.429312624-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:19.429728176-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:19.429824484-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:19.429577677-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:20.430703621-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:20.430797034-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:20.430431887-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:21.431602134-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:21.431688469-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:21.431358101-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:22.432036133-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:22.432122553-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:22.431800752-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:23.432599809-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:23.432694236-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:23.432305691-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:24.433733066-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:24.43382513-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:24.433478429-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:25.434613922-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:25.434662173-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:25.434412082-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:26.435420826-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:26.435510657-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:26.435225641-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:27.43645227-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:27.436546162-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:27.436188255-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:28.437602309-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:28.437694463-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:28.437330631-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:29.43857964-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:29.438632022-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:29.438432002-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:30.438986488-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:30.439080438-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:30.438715935-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:31.439711337-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:31.43980972-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:31.439461738-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:32.440513177-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:32.440603253-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:32.440262221-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:33.440633646-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:33.44073223-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:33.440371119-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:34.440749673-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:34.440842977-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:34.44048582-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:35.441521144-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:35.441650958-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:35.441280416-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:36.441837727-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:36.44192746-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:36.441594618-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:37.442815842-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:37.442905435-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:37.442583307-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:38.443159573-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:38.443256336-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:38.442889808-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:39.44404983-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:39.44415135-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:39.44380497-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:40.444371754-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:40.444472432-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:40.444136954-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:41.445108049-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:41.445203902-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:41.444852208-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:42.4459902-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:42.446050257-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:42.445813322-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:43.446898869-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:43.44701855-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:43.446589196-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:44.447842166-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:44.447949338-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:44.447554208-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:45.448733513-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:45.448831475-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:45.448493512-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:46.448954277-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:46.449046543-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:46.448705347-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:47.449468195-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:47.449557871-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:47.449285512-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:48.449995634-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:48.450085688-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:48.449762954-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:49.45033109-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:49.450381238-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:49.4502003-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:50.450611498-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:50.450699367-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:50.450346109-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:51.451724705-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:51.451816172-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:51.451456099-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:52.451812482-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:52.451913122-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:52.451573598-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:53.452878293-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:53.452940597-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:53.452672661-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:54.453879594-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:54.453977075-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:54.453642217-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:55.454769076-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:55.454864222-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:55.454470879-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:56.455439292-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:56.455500942-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:56.455283021-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:57.456353171-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:57.456444093-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:57.45609017-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:58.456918838-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:58.457012265-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:58.456607777-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:20:59.457244896-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:20:59.457337542-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:20:59.457046072-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:00.457908714-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:00.457965219-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:00.457719751-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:01.458366007-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:01.458458743-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:01.45809499-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:02.458611433-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:02.458734116-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:02.458341464-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:03.459108882-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:03.459199039-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:03.458841857-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:04.459289878-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:04.459396958-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:04.459006649-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:05.460053989-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:05.460181157-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:05.459776268-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:06.461062245-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:06.461184021-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:06.460718289-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:07.461718428-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:07.461858631-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:07.461433182-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:08.462383826-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:08.462505151-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:08.462111572-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:09.462944609-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:09.463066665-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:09.462659347-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:10.463900114-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:10.464016377-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:10.463606108-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:11.464049336-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:11.464140364-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:11.463800524-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:12.464631621-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:12.464757486-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:12.464353539-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:13.46533563-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:13.465431073-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:13.465051053-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:14.466312181-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:14.466369018-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:14.46613957-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:15.467475179-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:15.467569455-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:15.46722619-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:16.467826504-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:16.467922677-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:16.467552283-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:17.46849037-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:17.468553944-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:17.468293543-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:18.468585834-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:18.46867531-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:18.468366769-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:19.469644138-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:19.469735184-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:19.469377079-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:20.470537039-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:20.470660341-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:20.47025498-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:21.471253141-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:21.471350848-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:21.470973019-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:22.471969591-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:22.472063013-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:22.471766381-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:23.472960306-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:23.473060597-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:23.472720608-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:24.473321085-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:24.473455367-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:24.472997751-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:25.473901916-05:00] DEBUG [router]: Established transport exist. Type: sudph
[2024-03-23T08:21:25.473958934-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:25.473717538-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:26.017655994-05:00] DEBUG [tp:038240]: Stopped log loop
[2024-03-23T08:21:26.0176912-05:00] DEBUG readPacket [tp:038240]: Failed to read packet header. error="io: read/write on closed pipe"
[2024-03-23T08:21:26.017703777-05:00] DEBUG [tp:038240]: Stopped serving. remaining_tps=1 remote_pk=0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac tp_id=247984d4-4a1a-0eda-a67c-9e69bf0fb169 tp_index=2
[2024-03-23T08:21:26.017748597-05:00] WARN [tp:038240]: Failed to read packet, closing transport error="io: read/write on closed pipe" src="read_loop"
[2024-03-23T08:21:26.474691948-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: func = retrier, current_backoff = 1s Retrying...
[2024-03-23T08:21:26.499044916-05:00] DEBUG [tp:038240]: Error deleting transport error="404 Not Found: {"error":"transport not found"}" tp-id=247984d4-4a1a-0eda-a67c-9e69bf0fb169
[2024-03-23T08:21:28.0307293-05:00] DEBUG [transport_manager]: Initializing TP with ID 247984d4-4a1a-0eda-a67c-9e69bf0fb169
[2024-03-23T08:21:28.030832181-05:00] DEBUG [transport_manager]: Dialing transport to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac via sudph
[2024-03-23T08:21:29.461577925-05:00] DEBUG [sudph]: Resolved PK 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac to visor data {104.62.49.99:51817 false {51817 [127.0.0.1 192.168.0.8 ::1]}}
[2024-03-23T08:21:29.461650609-05:00] DEBUG [sudph]: Dialing 104.62.49.99:51817
[2024-03-23T08:21:29.461787414-05:00] DEBUG [sudph]: Dialed 104.62.49.99:51817
[2024-03-23T08:21:29.461828659-05:00] DEBUG [sudph]: Performing handshake with 104.62.49.99:51817
[2024-03-23T08:21:29.557008152-05:00] DEBUG [sudph]: Sent handshake to 104.62.49.99:51817, local addr 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c:49157, remote addr 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac:45
[2024-03-23T08:21:30.231229213-05:00] DEBUG [tp:038240]: Sent signal to 'mt.transportCh'.
[2024-03-23T08:21:30.231325072-05:00] DEBUG [transport_manager]: saved transport: remote(0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac) type(sudph) tpID(247984d4-4a1a-0eda-a67c-9e69bf0fb169)
[2024-03-23T08:21:30.231366343-05:00] DEBUG [router]: Requesting new routes from 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:30.231458131-05:00] DEBUG [tp:038240]: Serving. remote_pk=0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac tp_id=247984d4-4a1a-0eda-a67c-9e69bf0fb169 tp_index=2
[2024-03-23T08:21:30.843716204-05:00] DEBUG [router]: Found routes Forward: [[0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c -> 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac @ 247984d4-4a1a-0eda-a67c-9e69bf0fb169]]. Reverse [[0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac -> 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c @ 247984d4-4a1a-0eda-a67c-9e69bf0fb169]]
[2024-03-23T08:21:31.811901398-05:00] DEBUG [dmsgC]: Dialing session... remote_pk=03d5b55d1133b26485c664cf8b95cff6746d1e321c34e48c9fed293eff0d6d49e5
[2024-03-23T08:21:32.767452987-05:00] DEBUG [dmsgC]: Updating entry. entry=	version: 0.0.1
	sequence: 2
	registered at: 1711038265028773434
	static public key: 0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c
	signature: dc37920b0cf916f8cc007bc0851a973a9a1e0da1fa2fabba4cc55cbe6850a8ee59bd275c25acb468c0d74751529ba1024fdeeb77828e76415812f18a8ef91af201
	entry is registered as client. Related info: 
		delegated servers: 
			03d5b55d1133b26485c664cf8b95cff6746d1e321c34e48c9fed293eff0d6d49e5
			0281a102c82820e811368c8d028cf11b1a985043b726b1bcdb8fce89b27384b2cb
			03717576ada5b1744e395c66c2bb11cea73b0e23d0dcd54422139b1a7f12e962c4
			02a2d4c346dabd165fd555dfdba4a7f4d18786fe7e055e562397cd5102bdd7f8dd


[2024-03-23T08:21:33.24350547-05:00] DEBUG [dmsgC]: Serving session. remote_pk=03d5b55d1133b26485c664cf8b95cff6746d1e321c34e48c9fed293eff0d6d49e5
[2024-03-23T08:21:33.741674001-05:00] DEBUG [router]: handling setup request: setupPK(0324579f003e6b4048bae2def4365e634d8e0e3054a20fc7af49daf2a179658557)
[2024-03-23T08:21:35.50547349-05:00] DEBUG [router]: ROUTING TABLE CONTENTS: map[3:FWD(keyRtID:3, nxtRtID:3, nxtTpID:247984d4-4a1a-0eda-a67c-9e69bf0fb169, rAddr:0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac:3, lAddr:0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c:49224)]
[2024-03-23T08:21:35.505555356-05:00] DEBUG [router]: Save new Routing Rule with ID 3 FWD(keyRtID:3, nxtRtID:3, nxtTpID:247984d4-4a1a-0eda-a67c-9e69bf0fb169, rAddr:0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac:3, lAddr:0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c:49224)
[2024-03-23T08:21:35.505620984-05:00] DEBUG [router]: ROUTING TABLE CONTENTS: map[3:FWD(keyRtID:3, nxtRtID:3, nxtTpID:247984d4-4a1a-0eda-a67c-9e69bf0fb169, rAddr:0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac:3, lAddr:0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c:49224) 4:REV(keyRtID:4, rAddr:0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c:49224, lAddr:0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac:3)]
[2024-03-23T08:21:35.505676967-05:00] DEBUG [router]: Save new Routing Rule with ID 4 REV(keyRtID:4, rAddr:0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c:49224, lAddr:0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac:3)
[2024-03-23T08:21:35.505718881-05:00] DEBUG [router]: Saving route group rules with desc: rAddr:0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c:49224, lAddr:0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac:3
[2024-03-23T08:21:35.505844396-05:00] DEBUG [RouteGroup rAddr:0323272a60895f56aad82cb767fb5c413807adcf7c9fb0578b1b1c5807c7f29d4c:49224, lAddr:0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac:3]: Sent handshake via transport 247984d4-4a1a-0eda-a67c-9e69bf0fb169
[2024-03-23T08:21:35.621943652-05:00] DEBUG [router]: Created new routes to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac on port 49224
[2024-03-23T08:21:35.622402411-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: Connected to 0382406ebecec832a1c27dece7cf58425a1c98753c7b3be8428d4414eada0986ac
[2024-03-23T08:21:35.631556904-05:00] DEBUG (STDOUT) [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: Serving proxy client :1080
[2024-03-23T08:21:35.622636889-05:00] INFO [proc:skysocks-client:f40bbfff4dd9439b8cc9381ae716296b]: App skysocks-client is Running

image
@0pcom 0pcom added bug Something isn't working routing transports labels Mar 23, 2024
@0pcom
Copy link
Collaborator Author

0pcom commented Mar 23, 2024

#1758 is related

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug Something isn't working routing transports
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@0pcom